epoch1970 wrote: 
> (my posts are too long. This is the continuation of the previous one)
> EDIT 5: Happened again. Started a BBC stream, very quickly audio stopped
> on the AE, then the Loop had a pause and continued solo.> 
Code:
--------------------
  >   > [16-02-14 19:56:41.1348] Slim::Player::Squeezebox2::startAt (1078) 
aa:aa:12:8b:8e:43 startAt: 3777256085
  > [16-02-14 19:56:41.1374] Slim::Player::Squeezebox2::startAt (1078) 
aa:aa:0e:12:43:6b startAt: 3777256085
  > [16-02-14 19:56:41.1530] Slim::Player::StreamingController::_CheckSync 
(498) aa:aa:12:8b:8e:43 bailing as no playPoint
  > [16-02-14 19:56:42.1401] Slim::Player::StreamingController::_CheckSync 
(498) aa:aa:12:8b:8e:43 bailing as no playPoint
  > [16-02-14 19:56:44.0056] Slim::Player::StreamingController::_CheckSync 
(526) playPoints: aa:aa:12:8b:8e:43: 1455476202.522, aa:aa:0e:12:43:6b:    +6
  > [16-02-14 19:56:46.0085] Slim::Player::StreamingController::_CheckSync 
(526) playPoints: aa:aa:12:8b:8e:43: 1455476202.527, aa:aa:0e:12:43:6b:    +0
  > [16-02-14 19:56:47.0057] Slim::Player::StreamingController::_CheckSync 
(526) playPoints: aa:aa:12:8b:8e:43: 1455476202.529, aa:aa:0e:12:43:6b:    -1
  > [16-02-14 19:56:48.0057] Slim::Player::StreamingController::_CheckSync 
(526) playPoints: aa:aa:12:8b:8e:43: 1455476202.523, aa:aa:0e:12:43:6b:    +7
  > [16-02-14 19:56:49.0064] Slim::Player::StreamingController::_CheckSync 
(526) playPoints: aa:aa:12:8b:8e:43: 1455476202.526, aa:aa:0e:12:43:6b:    +0
  > [16-02-14 19:56:50.0718] Slim::Player::StreamingController::_CheckSync 
(526) playPoints: aa:aa:12:8b:8e:43: 1455476202.529, aa:aa:0e:12:43:6b:    +0
  > [16-02-14 19:56:52.0048] Slim::Player::StreamingController::_CheckSync 
(526) playPoints: aa:aa:12:8b:8e:43: 1455476202.523, aa:aa:0e:12:43:6b:    +6
  > [16-02-14 19:56:53.0060] Slim::Player::StreamingController::_CheckSync 
(526) playPoints: aa:aa:12:8b:8e:43: 1455476202.526, aa:aa:0e:12:43:6b:    +0
  > [16-02-14 19:56:54.0070] Slim::Player::StreamingController::_CheckSync 
(526) playPoints: aa:aa:12:8b:8e:43: 1455476202.529, aa:aa:0e:12:43:6b:    +0
  > [16-02-14 19:56:55.0037] Slim::Player::StreamingController::_CheckSync 
(526) playPoints: aa:aa:12:8b:8e:43: 1455476202.520, aa:aa:0e:12:43:6b:    +7
  > [16-02-14 19:56:56.0045] Slim::Player::StreamingController::_CheckSync 
(526) playPoints: aa:aa:12:8b:8e:43: 1455476202.523, aa:aa:0e:12:43:6b:    +0
  > [16-02-14 19:56:57.0054] Slim::Player::StreamingController::_CheckSync 
(526) playPoints: aa:aa:12:8b:8e:43: 1455476202.526, aa:aa:0e:12:43:6b:    +0
  > [16-02-14 19:56:58.0067] Slim::Player::StreamingController::_CheckSync 
(526) playPoints: aa:aa:12:8b:8e:43: 1455476202.522, aa:aa:0e:12:43:6b:    +7
  > [16-02-14 19:56:59.0045] Slim::Player::StreamingController::_CheckSync 
(526) playPoints: aa:aa:12:8b:8e:43: 1455476202.522, aa:aa:0e:12:43:6b:    +7
  > [16-02-14 19:57:00.0054] Slim::Player::StreamingController::_CheckSync 
(526) playPoints: aa:aa:12:8b:8e:43: 1455476202.525, aa:aa:0e:12:43:6b:    +0
  > [16-02-14 19:57:02.0455] Slim::Player::StreamingController::_CheckSync 
(526) playPoints: aa:aa:12:8b:8e:43: 1455476202.523, aa:aa:0e:12:43:6b:    +7
  > [16-02-14 19:57:03.0048] Slim::Player::StreamingController::_CheckSync 
(526) playPoints: aa:aa:12:8b:8e:43: 1455476202.521, aa:aa:0e:12:43:6b:    -1
  > [16-02-14 19:57:04.0032] Slim::Player::StreamingController::_CheckSync 
(526) playPoints: aa:aa:12:8b:8e:43: 1455476202.524, aa:aa:0e:12:43:6b:    +0
  > [16-02-14 19:57:05.0040] Slim::Player::StreamingController::_CheckSync 
(526) playPoints: aa:aa:12:8b:8e:43: 1455476202.519, aa:aa:0e:12:43:6b:    +7
  > [16-02-14 19:57:06.0052] Slim::Player::StreamingController::_CheckSync 
(526) playPoints: aa:aa:12:8b:8e:43: 1455476202.523, aa:aa:0e:12:43:6b:    +7
  > [16-02-14 19:57:07.0039] Slim::Player::StreamingController::_CheckSync 
(526) playPoints: aa:aa:12:8b:8e:43: 1455476202.523, aa:aa:0e:12:43:6b:    +0
  > [16-02-14 19:57:08.0042] Slim::Player::StreamingController::_CheckSync 
(526) playPoints: aa:aa:12:8b:8e:43: 1455476202.526, aa:aa:0e:12:43:6b:    +0
  > [16-02-14 19:57:09.0054] Slim::Player::StreamingController::_CheckSync 
(526) playPoints: aa:aa:12:8b:8e:43: 1455476202.522, aa:aa:0e:12:43:6b:    +7
  > [16-02-14 19:57:10.0066] Slim::Player::StreamingController::_CheckSync 
(526) playPoints: aa:aa:12:8b:8e:43: 1455476202.525, aa:aa:0e:12:43:6b:    +0
  > [16-02-14 19:57:11.0039] Slim::Player::StreamingController::_CheckSync 
(526) playPoints: aa:aa:12:8b:8e:43: 1455476202.524, aa:aa:0e:12:43:6b:    +0
  > [16-02-14 19:57:12.0044] Slim::Player::StreamingController::_CheckSync 
(526) playPoints: aa:aa:12:8b:8e:43: 1455476202.519, aa:aa:0e:12:43:6b:    +7
  > [16-02-14 19:57:13.0055] Slim::Player::StreamingController::_CheckSync 
(526) playPoints: aa:aa:12:8b:8e:43: 1455476202.523, aa:aa:0e:12:43:6b:    +7
  > [16-02-14 19:57:14.0067] Slim::Player::StreamingController::_CheckSync 
(526) playPoints: aa:aa:12:8b:8e:43: 1455476202.526, aa:aa:0e:12:43:6b:    +0
  > [16-02-14 19:57:14.8083] Slim::Player::StreamingController::playerInactive 
(2065) aa:aa:12:8b:8e:43 sync group now has: aa:aa:0e:12:43:6b,aa:aa:12:8b:8e:43
  > [16-02-14 19:57:14.8086] Slim::Player::StreamingController::playerInactive 
(2066) aa:aa:12:8b:8e:43 active players are: aa:aa:12:8b:8e:43
  > [16-02-14 19:57:44.6496] Slim::Player::StreamingController::playerActive 
(2022) aa:aa:12:8b:8e:43 sync group now has: aa:aa:0e:12:43:6b,aa:aa:12:8b:8e:43
  > [16-02-14 19:57:44.6498] Slim::Player::StreamingController::playerActive 
(2023) aa:aa:12:8b:8e:43 active players are: aa:aa:12:8b:8e:43,aa:aa:0e:12:43:6b
  > [16-02-14 19:57:44.6501] Slim::Player::StreamingController::playerActive 
(2027) aa:aa:12:8b:8e:43 restart play
  > [16-02-14 19:57:44.8124] Slim::Player::Player::trackJiffiesEpoch (928) 
aa:aa:0e:12:43:6b adjust jiffies epoch +1451698946.068s
  > [16-02-14 19:57:45.2893] Slim::Player::Player::trackJiffiesEpoch (928) 
aa:aa:0e:12:43:6b adjust jiffies epoch -0.014s
  > [16-02-14 19:57:45.5851] Slim::Player::Player::trackJiffiesEpoch (928) 
aa:aa:0e:12:43:6b adjust jiffies epoch -0.005s
  > [16-02-14 19:57:45.7455] Slim::Player::Squeezebox2::startAt (1078) 
aa:aa:12:8b:8e:43 startAt: 3777320696
  > [16-02-14 19:57:45.7486] Slim::Player::Squeezebox2::startAt (1078) 
aa:aa:0e:12:43:6b startAt: 3777320696
  > [16-02-14 19:57:45.7673] Slim::Player::StreamingController::_CheckSync 
(498) aa:aa:12:8b:8e:43 bailing as no playPoint
  > [16-02-14 19:57:46.7469] Slim::Player::StreamingController::_CheckSync 
(498) aa:aa:12:8b:8e:43 bailing as no playPoint
  > [16-02-14 19:57:48.0056] Slim::Player::StreamingController::_CheckSync 
(526) playPoints: aa:aa:12:8b:8e:43: 1455476267.127, aa:aa:0e:12:43:6b:    +8
  > [16-02-14 19:57:49.0072] Slim::Player::StreamingController::_CheckSync 
(526) playPoints: aa:aa:12:8b:8e:43: 1455476267.131, aa:aa:0e:12:43:6b:    +8
  > [16-02-14 19:57:50.0082] Slim::Player::StreamingController::_CheckSync 
(526) playPoints: aa:aa:12:8b:8e:43: 1455476267.135, aa:aa:0e:12:43:6b:    +7
  > [16-02-14 19:57:51.0042] Slim::Player::StreamingController::_CheckSync 
(526) playPoints: aa:aa:12:8b:8e:43: 1455476267.125, aa:aa:0e:12:43:6b:    +7
  > [16-02-14 19:57:52.0049] Slim::Player::StreamingController::_CheckSync 
(526) playPoints: aa:aa:12:8b:8e:43: 1455476267.127, aa:aa:0e:12:43:6b:    +8
--------------------
> > 
> 
> EDIT6: Curious. When everything runs fine the trace doesn't show time
> jumps at all.> 
Code:
--------------------
  >   > [16-02-14 20:07:29.0042] Slim::Player::StreamingController::_CheckSync 
(526) playPoints: aa:aa:12:8b:8e:43: 1455476331.971, aa:aa:0e:12:43:6b:    +0
  > [16-02-14 20:07:30.0053] Slim::Player::StreamingController::_CheckSync 
(526) playPoints: aa:aa:12:8b:8e:43: 1455476331.967, aa:aa:0e:12:43:6b:    +0
  > [16-02-14 20:07:31.0065] Slim::Player::StreamingController::_CheckSync 
(526) playPoints: aa:aa:12:8b:8e:43: 1455476331.970, aa:aa:0e:12:43:6b:    +0
  > [16-02-14 20:07:32.0043] Slim::Player::StreamingController::_CheckSync 
(526) playPoints: aa:aa:12:8b:8e:43: 1455476331.967, aa:aa:0e:12:43:6b:    +0
  > [16-02-14 20:07:33.0031] Slim::Player::StreamingController::_CheckSync 
(526) playPoints: aa:aa:12:8b:8e:43: 1455476331.970, aa:aa:0e:12:43:6b:    +0
--------------------
> > Could the players be in turn jumping in opposite directions when sync
> degrades, until a player drops?

Hey, thanks so much for pointing me to that direction - there is
something wrong (from time to time) with reported timing ! I never
thought about logging the LMS side of things ...



LMS 7.7.5 - 5xRadio, 3xBoom, 4xDuet, 1xTouch, 1 SB2. Sonos 2xPLAY:1,
PLAY:3, PLAY:5, Marantz NR1603, JBL OnBeat, XBMC, Foobar2000, XBoxOne,
JRiver 21, Chromecast Audio, Chromecast v1, Pi B2, Pi B+, 2xPi A+,
Odroid-C1, Cubie2
------------------------------------------------------------------------
philippe_44's Profile: http://forums.slimdevices.com/member.php?userid=17261
View this thread: http://forums.slimdevices.com/showthread.php?t=105114

_______________________________________________
plugins mailing list
[email protected]
http://lists.slimdevices.com/mailman/listinfo/plugins

Reply via email to