Duet and sync

Collapse
X
 
  • Time
  • Show
Clear All
new posts
  • dem
    Senior Member
    • Nov 2005
    • 204

    Duet and sync

    Just received my Duet and I love it. My new Audioengine A2's are no longer lonely. They look and sound great together.

    But I'm having a strange problem with sync that I don't recall seeing mentioned before. When I sync the Receiver to my SB3 (or vice-versa) from the Controller the Receiver starts playing each song exactly 30 seconds after the SB3.

    Weird.
    Dave
    Squeezeboxes: 3 (a.k.a. Classic), Duet, Boom
  • Ross L
    bum
    • Mar 2006
    • 404

    #2
    Weird indeed. I just synced a Transporter, SB2 and Squeezebox Receiver all with Jive and it sounds great.

    Reproducible?
    Ross L

    Comment

    • dem
      Senior Member
      • Nov 2005
      • 204

      #3
      Originally posted by Ross L
      Reproducible?
      So far. I've tried 4 different syncs, 3 from the Controller and 1 from the browser with the same result. I haven't tried any serious troubleshooting yet, like restarting the server or syncing sources other than local files.
      Dave
      Squeezeboxes: 3 (a.k.a. Classic), Duet, Boom

      Comment

      • Ross L
        bum
        • Mar 2006
        • 404

        #4
        Originally posted by dem
        I haven't tried any serious troubleshooting yet, like restarting the server or syncing sources other than local files.
        If you manage to narrow down what might be causing it let me know I'd be happy to try reproducing. I'll give it a shot at home this weekend and see how it goes.
        Ross L

        Comment

        • Ben Sandee

          #5
          Duet and sync

          On Fri, Mar 7, 2008 at 4:37 PM, dem <
          dem.35xc0n1204929601 (AT) no-mx (DOT...limdevices.com> wrote:

          >
          > Ross L;276608 Wrote:
          > > Reproducible?

          > So far. I've tried 4 different syncs, 3 from the Controller and 1 from
          > the browser with the same result. I haven't tried any serious
          > troubleshooting yet, like restarting the server or syncing sources
          > other than local files.



          Can you say what the values are for each player (from Player Settings):
          - Player Start Delay
          - Player Audio Delay
          - Minimum Synchronization Adjustment
          - Network Packet Latency

          I had some problems with these values being migrated incorrectly and being
          off by a factor of 1000 due to changing from seconds to ms. This is
          unlikely to be the problem for you.

          You might want to enable player.sync debugging logging and capture a log for
          diagnostic purposes.

          Also, what is the network topology (wired, wireless).

          Ben

          Comment

          • dem
            Senior Member
            • Nov 2005
            • 204

            #6
            Originally posted by Ben Sandee
            - Player Start Delay
            - Player Audio Delay
            - Minimum Synchronization Adjustment
            - Network Packet Latency
            I didn't think to look at those settings. They're 0, 0, 30, 2 for both players.

            30. Hmmm. :-)

            The SB3 is wired and the Receiver is wireless with a signal strength of 64. The Receiver has no problems with FLAC files.

            I should note that I've been running SC7 for many weeks, updating roughly once a week. It's been many weeks since I did a completely fresh install by first deleting the saved prefs, so maybe it's some nightly-to-nightly pref migration issue.

            I'll have a look at the debug option.

            Thanks.
            Dave
            Squeezeboxes: 3 (a.k.a. Classic), Duet, Boom

            Comment

            • dem
              Senior Member
              • Nov 2005
              • 204

              #7
              Here are some logs, with commentary by me preceded by ***. Player c2 is the SBR and f7 is the SB3.
              Code:
              *** Enable sync logging. Both players stopped.
              [08-03-07 18:21:08.0054] Slim::Player::Player::trackJiffiesEpoch (819) 00:04:20:16:0d:c2 adjust jiffies epoch +0.005s
              [08-03-07 18:21:33.0065] Slim::Player::Player::trackJiffiesEpoch (819) 00:04:20:16:0d:c2 adjust jiffies epoch +0.005s
              [08-03-07 18:21:58.0057] Slim::Player::Player::trackJiffiesEpoch (819) 00:04:20:16:0d:c2 adjust jiffies epoch +0.005s
              [08-03-07 18:22:23.0053] Slim::Player::Player::trackJiffiesEpoch (819) 00:04:20:16:0d:c2 adjust jiffies epoch +0.005s
              [08-03-07 18:22:48.0051] Slim::Player::Player::trackJiffiesEpoch (819) 00:04:20:16:0d:c2 adjust jiffies epoch +0.005s
              [08-03-07 18:23:13.0052] Slim::Player::Player::trackJiffiesEpoch (819) 00:04:20:16:0d:c2 adjust jiffies epoch +0.005s
              *** Begin unsynced play on SB3 (f7)
              [08-03-07 18:23:25.4888] Slim::Player::Player::trackJiffiesEpoch (819) 00:04:20:06:21:f7 adjust jiffies epoch +0.001s
              [08-03-07 18:23:27.9848] Slim::Player::Player::trackJiffiesEpoch (795) 00:04:20:06:21:f7 adjust jiffies epoch -0.001s
              [08-03-07 18:23:38.0185] Slim::Player::Player::trackJiffiesEpoch (819) 00:04:20:16:0d:c2 adjust jiffies epoch +0.005s
              [08-03-07 18:23:39.1414] Slim::Player::Player::trackJiffiesEpoch (819) 00:04:20:06:21:f7 adjust jiffies epoch +0.004s
              [08-03-07 18:23:43.0209] Slim::Player::Player::trackJiffiesEpoch (795) 00:04:20:06:21:f7 adjust jiffies epoch -0.002s
              *** Enable sync, music restarts on SB3, SBR (c2) is silent but LED is bright
              [08-03-07 18:23:43.9123] Slim::Player::Sync::isSyncedWith (403) 00:04:20:06:21:f7: is NOT synced with 00:04:20:16:0d:c2
              [08-03-07 18:23:45.9838] Slim::Player::Player::trackJiffiesEpoch (795) 00:04:20:06:21:f7 adjust jiffies epoch -0.002s
              [08-03-07 18:23:47.2746] Slim::Player::Sync::sync (250) 00:04:20:16:0d:c2: syncing to buddy-or-master: 00:04:20:06:21:f7
              [08-03-07 18:23:47.3930] Slim::Player::Sync::saveSyncPrefs (298) Saving 00:04:20:16:0d:c2 as a slave to 806251119
              [08-03-07 18:23:47.4096] Slim::Player::Sync::checkSync (496) 00:04:20:16:0d:c2 checking buffer fullness: 0 (threshold: 4096)
              [08-03-07 18:23:47.4129] Slim::Player::Sync::checkSync (496) 00:04:20:16:0d:c2 checking buffer fullness: 0 (threshold: 4096)
              [08-03-07 18:23:47.4161] Slim::Player::Sync::checkSync (496) 00:04:20:16:0d:c2 checking buffer fullness: 0 (threshold: 4096)
              [08-03-07 18:23:47.4193] Slim::Player::Sync::checkSync (496) 00:04:20:16:0d:c2 checking buffer fullness: 0 (threshold: 4096)
              [08-03-07 18:23:47.4225] Slim::Player::Sync::checkSync (496) 00:04:20:16:0d:c2 checking buffer fullness: 0 (threshold: 4096)
              [08-03-07 18:23:47.4256] Slim::Player::Sync::checkSync (496) 00:04:20:16:0d:c2 checking buffer fullness: 0 (threshold: 4096)
              [08-03-07 18:23:47.4278] Slim::Player::Player::trackJiffiesEpoch (819) 00:04:20:16:0d:c2 adjust jiffies epoch +0.005s
              [08-03-07 18:23:47.4306] Slim::Player::Sync::checkSync (496) 00:04:20:16:0d:c2 checking buffer fullness: 0 (threshold: 4096)
              *** 12 similar messages removed to reduce size of this post
              [08-03-07 18:23:47.5746] Slim::Player::Player::trackJiffiesEpoch (819) 00:04:20:06:21:f7 adjust jiffies epoch +0.005s
              [08-03-07 18:23:47.5774] Slim::Player::Sync::checkSync (496) 00:04:20:06:21:f7 checking buffer fullness: 4436 (threshold: 4096)
              [08-03-07 18:23:47.5788] Slim::Player::Sync::checkSync (505) 00:04:20:06:21:f7 is ready to sync
              [08-03-07 18:23:47.5958] Slim::Player::Sync::checkSync (496) 00:04:20:16:0d:c2 checking buffer fullness: 4436 (threshold: 4096)
              [08-03-07 18:23:47.5974] Slim::Player::Sync::checkSync (505) 00:04:20:16:0d:c2 is ready to sync
              [08-03-07 18:23:47.5989] Slim::Player::Sync::checkSync (530) all clients ready to sync now. unpausing them.
              [08-03-07 18:23:47.6005] Slim::Player::Squeezebox2::startAt (835) 00:04:20:16:0d:c2 startAt: 13945860
              [08-03-07 18:23:47.6035] Slim::Player::Squeezebox2::startAt (835) 00:04:20:06:21:f7 startAt: 2231298678
              [08-03-07 18:23:47.6890] Slim::Player::Sync::checkSync (609) 00:04:20:06:21:f7 bailing as no playPoint
              [08-03-07 18:23:48.9844] Slim::Player::Player::trackJiffiesEpoch (795) 00:04:20:06:21:f7 adjust jiffies epoch -0.004s
              [08-03-07 18:23:48.9869] Slim::Player::Sync::checkSync (609) 00:04:20:16:0d:c2 bailing as no playPoint
              [08-03-07 18:23:49.9854] Slim::Player::Sync::checkSync (609) 00:04:20:16:0d:c2 bailing as no playPoint
              [08-03-07 18:23:50.9870] Slim::Player::Sync::checkSync (609) 00:04:20:16:0d:c2 bailing as no playPoint
              [08-03-07 18:23:51.9848] Slim::Player::Sync::checkSync (609) 00:04:20:16:0d:c2 bailing as no playPoint
              [08-03-07 18:23:52.9848] Slim::Player::Sync::checkSync (609) 00:04:20:16:0d:c2 bailing as no playPoint
              [08-03-07 18:23:53.9847] Slim::Player::Sync::checkSync (609) 00:04:20:16:0d:c2 bailing as no playPoint
              [08-03-07 18:23:54.9845] Slim::Player::Sync::checkSync (609) 00:04:20:16:0d:c2 bailing as no playPoint
              [08-03-07 18:23:55.9845] Slim::Player::Sync::checkSync (609) 00:04:20:16:0d:c2 bailing as no playPoint
              [08-03-07 18:23:56.9845] Slim::Player::Sync::checkSync (609) 00:04:20:16:0d:c2 bailing as no playPoint
              [08-03-07 18:23:57.9845] Slim::Player::Sync::checkSync (609) 00:04:20:16:0d:c2 bailing as no playPoint
              [08-03-07 18:23:58.0056] Slim::Player::Player::trackJiffiesEpoch (819) 00:04:20:16:0d:c2 adjust jiffies epoch +0.005s
              [08-03-07 18:23:58.9847] Slim::Player::Sync::checkSync (609) 00:04:20:16:0d:c2 bailing as no playPoint
              *** 17 similar messages removed in order to reduce size of this post
              [08-03-07 18:24:16.9843] Slim::Player::Sync::checkSync (620) 
              *** SBR starts approximately here
              00:04:20:16:0d:c2 bailing as playPoint too old: 29.0948209762573s
              [08-03-07 18:24:17.5215] Slim::Player::Player::trackJiffiesEpoch (819) 00:04:20:16:0d:c2 adjust jiffies epoch +0.005s
              [08-03-07 18:24:17.9844] Slim::Player::Sync::checkSync (620) 00:04:20:16:0d:c2 bailing as playPoint too old: 29.0899648666382s
              [08-03-07 18:24:18.9845] Slim::Player::Sync::checkSync (620) 00:04:20:16:0d:c2 bailing as playPoint too old: 29.0897679328918s
              [08-03-07 18:24:19.9854] Slim::Player::Sync::checkSync (620) 00:04:20:16:0d:c2 bailing as playPoint too old: 29.0909607410431s
              [08-03-07 18:24:20.9847] Slim::Player::Sync::checkSync (620) 00:04:20:16:0d:c2 bailing as playPoint too old: 29.0902028083801s
              [08-03-07 18:24:21.9845] Slim::Player::Sync::checkSync (620) 00:04:20:16:0d:c2 bailing as playPoint too old: 29.0898969173431s
              [08-03-07 18:24:22.9844] Slim::Player::Sync::checkSync (620) 00:04:20:16:0d:c2 bailing as playPoint too old: 29.0899367332458s
              [08-03-07 18:24:23.5216] Slim::Player::Player::trackJiffiesEpoch (819) 00:04:20:16:0d:c2 adjust jiffies epoch +0.005s
              [08-03-07 18:24:23.9843] Slim::Player::Sync::checkSync (620) 00:04:20:16:0d:c2 bailing as playPoint too old: 29.084844827652s
              [08-03-07 18:24:24.9843] Slim::Player::Sync::checkSync (620) 00:04:20:16:0d:c2 bailing as playPoint too old: 29.0848326683044s
              [08-03-07 18:24:25.9844] Slim::Player::Sync::checkSync (620) 00:04:20:16:0d:c2 bailing as playPoint too old: 29.0848217010498s
              [08-03-07 18:24:26.9843] Slim::Player::Sync::checkSync (620) 00:04:20:16:0d:c2 bailing as playPoint too old: 29.0848336219788s
              [08-03-07 18:24:27.9842] Slim::Player::Sync::checkSync (620) 00:04:20:16:0d:c2 bailing as playPoint too old: 29.0846977233887s
              [08-03-07 18:24:28.9844] Slim::Player::Sync::checkSync (620) 00:04:20:16:0d:c2 bailing as playPoint too old: 29.0847327709198s
              [08-03-07 18:24:29.9841] Slim::Player::Sync::checkSync (620) 00:04:20:16:0d:c2 bailing as playPoint too old: 29.0846486091614s
              [08-03-07 18:24:30.9841] Slim::Player::Sync::checkSync (620) 00:04:20:16:0d:c2 bailing as playPoint too old: 29.0846717357635s
              [08-03-07 18:24:31.5215] Slim::Player::Player::trackJiffiesEpoch (819) 00:04:20:16:0d:c2 adjust jiffies epoch +0.005s
              [08-03-07 18:24:31.9842] Slim::Player::Sync::checkSync (620) 00:04:20:16:0d:c2 bailing as playPoint too old: 29.0797786712646s
              [08-03-07 18:24:32.9841] Slim::Player::Sync::checkSync (620) 00:04:20:16:0d:c2 bailing as playPoint too old: 29.0796325206757s
              [08-03-07 18:24:33.9848] Slim::Player::Sync::checkSync (620) 00:04:20:16:0d:c2 bailing as playPoint too old: 29.0802977085114s
              [08-03-07 18:24:34.9841] Slim::Player::Sync::checkSync (620) 00:04:20:16:0d:c2 bailing as playPoint too old: 29.0796597003937s
              [08-03-07 18:24:35.9842] Slim::Player::Sync::checkSync (620) 00:04:20:16:0d:c2 bailing as playPoint too old: 29.079630613327s
              [08-03-07 18:24:36.9841] Slim::Player::Sync::checkSync (620) 00:04:20:16:0d:c2 bailing as playPoint too old: 29.0795855522156s
              [08-03-07 18:24:37.9841] Slim::Player::Sync::checkSync (620) 00:04:20:16:0d:c2 bailing as playPoint too old: 29.0796625614166s
              [08-03-07 18:24:38.0066] Slim::Player::Player::trackJiffiesEpoch (819) 00:04:20:16:0d:c2 adjust jiffies epoch +0.005s
              [08-03-07 18:24:38.9842] Slim::Player::Sync::checkSync (620) 00:04:20:16:0d:c2 bailing as playPoint too old: 29.0747015476227s
              *** 7 similar messages removed to reduce size of this post
              [08-03-07 18:24:45.3664] Slim::Player::Player::trackJiffiesEpoch (819) 00:04:20:16:0d:c2 adjust jiffies epoch +0.005s
              [08-03-07 18:24:45.7268] Slim::Player::Player::trackJiffiesEpoch (819) 00:04:20:16:0d:c2 adjust jiffies epoch +0.005s
              [08-03-07 18:24:58.0086] Slim::Player::Player::trackJiffiesEpoch (819) 00:04:20:06:21:f7 adjust jiffies epoch +0.001s
              *** I press Pause
              Dave
              Squeezeboxes: 3 (a.k.a. Classic), Duet, Boom

              Comment

              • dem
                Senior Member
                • Nov 2005
                • 204

                #8
                Restarting SqueezeCenter resolved the problem. Weird. :-)
                Dave
                Squeezeboxes: 3 (a.k.a. Classic), Duet, Boom

                Comment

                Working...