Duet/Squeezeslave sync problem

[ThomP]

Hi. I just got my Duet and it is amazing!

However, I have one issue: I am running Squeezeslave (0.7.5) on the pc hosting Squeezecenter and the latest version of Squeezecenter. When I syncronize Squeezeslave and the receiver it seams to work, and the first track plays fine. When the next track starts, it plays syncronized for appr. 5 seconds, then the receiver pauses and then resumes playback with a 1 second delay. This repeats for each track so the delay just keeps increasing.

Is this some sort of buffering issue (there is no pause when the receiver plays "standalone")? and is there any way to fix it?

[ThomP]

I have investigated a little. I realized that the delay doesn't increase for each song - every song starts in sync and plays in sync for about 5-10 seconds. I have also made a debug log, so I hope someone might be able to interpret the results:

[08-04-04 17:12:30.7967] Slim::Player::Sync::checkSync (505) 00:09:6b:d1:63:3d is ready to sync
[08-04-04 17:12:30.7973] Slim::Player::Sync::checkSync (530) all clients ready to sync now. unpausing them.
[08-04-04 17:12:30.7984] Slim::Player::Squeezebox2::startAt (835) 00:04:20:16:22:a0 startAt: 342075069
[08-04-04 17:12:30.8125] Slim::Player::Sync::checkSync (609) 00:04:20:16:22:a0 bailing as no playPoint
[08-04-04 17:12:31.0307] Slim::Player::Player::trackJiffiesEpoch (795) 00:04:20:16:22:a0 adjust jiffies epoch -0.004s
[08-04-04 17:12:32.2133] Slim::Player::Sync::checkSync (609) 00:09:6b:d1:63:3d bailing as no playPoint
[08-04-04 17:12:33.2058] Slim::Player::Sync::checkSync (609) 00:09:6b:d1:63:3d bailing as no playPoint
[08-04-04 17:12:34.2101] Slim::Player::Sync::checkSync (609) 00:09:6b:d1:63:3d bailing as no playPoint
[08-04-04 17:12:35.2056] Slim::Player::Sync::checkSync (609) 00:09:6b:d1:63:3d bailing as no playPoint
[08-04-04 17:12:36.2078] Slim::Player::Sync::checkSync (609) 00:09:6b:d1:63:3d bailing as no playPoint
[08-04-04 17:12:37.2060] Slim::Player::Sync::checkSync (609) 00:09:6b:d1:63:3d bailing as no playPoint
[08-04-04 17:12:38.2094] Slim::Player::Sync::checkSync (609) 00:09:6b:d1:63:3d bailing as no playPoint
[08-04-04 17:12:39.2059] Slim::Player::Sync::checkSync (609) 00:09:6b:d1:63:3d bailing as no playPoint
[08-04-04 17:12:40.2105] Slim::Player::Sync::checkSync (609) 00:09:6b:d1:63:3d bailing as no playPoint
[08-04-04 17:12:41.2057] Slim::Player::Sync::checkSync (637) playPoints: 00:04:20:16:22:a0: 1207321951.121, 00:09:6b:d1:63:3d: +3048
[08-04-04 17:12:41.2064] Slim::Player::Sync::checkSync (674) 00:04:20:16:22:a0 resync: pauseFor 3048ms
[08-04-04 17:12:45.2059] Slim::Player::Sync::checkSync (637) playPoints: 00:04:20:16:22:a0: 1207321954.164, 00:09:6b:d1:63:3d:   +10
[08-04-04 17:12:46.2107] Slim::Player::Sync::checkSync (637) playPoints: 00:04:20:16:22:a0: 1207321954.164, 00:09:6b:d1:63:3d:   +13
[08-04-04 17:12:47.2057] Slim::Player::Sync::checkSync (637) playPoints: 00:04:20:16:22:a0: 1207321954.163, 00:09:6b:d1:63:3d:   +15
[08-04-04 17:12:48.2096] Slim::Player::Sync::checkSync (637) playPoints: 00:04:20:16:22:a0: 1207321954.164, 00:09:6b:d1:63:3d:   +17

This pattern repeats every time a new song starts playing (I've seen pauses between 600 and 4500 ms). Does it have to do with the "Bailing" messages? Is Squeezeslave sending wrong information?