PDA

View Full Version : Duet and sync



dem
2008-03-07, 15:12
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.

Ross L
2008-03-07, 15:27
Weird indeed. I just synced a Transporter, SB2 and Squeezebox Receiver all with Jive and it sounds great.

Reproducible?

dem
2008-03-07, 15:37
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.

Ross L
2008-03-07, 15:44
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.

Ben Sandee
2008-03-07, 15:53
On Fri, Mar 7, 2008 at 4:37 PM, dem <
dem.35xc0n1204929601 (AT) no-mx (DOT) forums.slimdevices.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

dem
2008-03-07, 16:18
- 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.

dem
2008-03-07, 16:48
Here are some logs, with commentary by me preceded by ***. Player c2 is the SBR and f7 is the SB3.


*** 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

dem
2008-03-08, 07:45
Restarting SqueezeCenter resolved the problem. Weird. :-)