-- Logs begin at Wed 2025-09-17 15:59:11 EDT, end at Wed 2025-09-17 18:58:27 EDT. -- Sep 17 18:57:00 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 37000ms Sep 17 18:57:00 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 37000ms Sep 17 18:57:00 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:00 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:00 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:00 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:01 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 38000ms Sep 17 18:57:01 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 38000ms Sep 17 18:57:01 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:01 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:01 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:01 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:02 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 39000ms Sep 17 18:57:02 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 39000ms Sep 17 18:57:02 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:02 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:02 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:02 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:03 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 40000ms Sep 17 18:57:03 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 40000ms Sep 17 18:57:03 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:03 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:03 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:03 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:04 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 41000ms Sep 17 18:57:04 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 41000ms Sep 17 18:57:04 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:04 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:04 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:04 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:04 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 146140997 version: 127 from line: o=nax 146140997 127 IN IP4 10.11.7.75 Sep 17 18:57:04 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.227:5004:146140997 -> dff13b4a Sep 17 18:57:04 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 286595182 version: 127 from line: o=nax 286595182 127 IN IP4 10.11.7.75 Sep 17 18:57:04 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.222:5004:286595182 -> f1899ce2 Sep 17 18:57:04 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 309351199 version: 127 from line: o=nax 309351199 127 IN IP4 10.11.7.75 Sep 17 18:57:04 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.220:5004:309351199 -> c6cf4a4b Sep 17 18:57:04 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 395539478 version: 127 from line: o=nax 395539478 127 IN IP4 10.11.7.75 Sep 17 18:57:04 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.223:5004:395539478 -> d02e9e31 Sep 17 18:57:04 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 523760604 version: 127 from line: o=nax 523760604 127 IN IP4 10.11.7.75 Sep 17 18:57:04 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.221:5004:523760604 -> a90b47a0 Sep 17 18:57:04 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 687139007 version: 127 from line: o=nax 687139007 127 IN IP4 10.11.7.75 Sep 17 18:57:04 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.225:5004:687139007 -> b8d3d106 Sep 17 18:57:04 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 902015099 version: 127 from line: o=nax 902015099 127 IN IP4 10.11.7.75 Sep 17 18:57:04 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.226:5004:902015099 -> 9daf3582 Sep 17 18:57:04 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 902413056 version: 127 from line: o=nax 902413056 127 IN IP4 10.11.7.75 Sep 17 18:57:04 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.224:5004:902413056 -> f8d335bb Sep 17 18:57:05 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 42000ms Sep 17 18:57:05 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 42000ms Sep 17 18:57:05 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:05 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:05 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:05 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:06 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 43000ms Sep 17 18:57:06 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 43000ms Sep 17 18:57:06 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:06 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:06 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:06 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:07 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 44000ms Sep 17 18:57:07 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 44000ms Sep 17 18:57:07 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:07 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:07 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:07 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:08 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 45000ms Sep 17 18:57:08 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 45000ms Sep 17 18:57:08 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:08 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:08 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:08 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:09 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 46000ms Sep 17 18:57:09 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 46000ms Sep 17 18:57:09 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:09 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:09 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:09 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:10 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 47000ms Sep 17 18:57:10 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 47000ms Sep 17 18:57:10 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:10 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:10 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:10 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:11 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 48000ms Sep 17 18:57:11 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 48000ms Sep 17 18:57:11 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:11 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:11 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:11 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:12 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 49000ms Sep 17 18:57:12 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 49000ms Sep 17 18:57:12 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:12 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:12 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:12 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:13 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 50000ms Sep 17 18:57:13 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 50000ms Sep 17 18:57:13 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:13 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:13 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:13 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:14 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 51000ms Sep 17 18:57:14 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 51000ms Sep 17 18:57:14 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:14 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:14 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:14 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:15 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 52000ms Sep 17 18:57:15 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 52000ms Sep 17 18:57:15 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:15 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:15 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:15 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:15 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 1028889 version: 1028899 from line: o=- 1028889 1028899 IN IP4 10.11.7.64 Sep 17 18:57:15 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.3.171:5004:1028889 -> 73d22ac2 Sep 17 18:57:15 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 488443 version: 488453 from line: o=- 488443 488453 IN IP4 10.11.7.64 Sep 17 18:57:15 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.180.57:5004:488443 -> b14511b3 Sep 17 18:57:15 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 1022333 version: 1022343 from line: o=- 1022333 1022343 IN IP4 10.11.7.64 Sep 17 18:57:15 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.65.7:5004:1022333 -> 6c9848b3 Sep 17 18:57:15 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 674495 version: 674505 from line: o=- 674495 674505 IN IP4 10.11.7.64 Sep 17 18:57:15 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.74.227:5004:674495 -> 6b2bcaac Sep 17 18:57:16 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 53000ms Sep 17 18:57:16 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 53000ms Sep 17 18:57:16 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:16 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:16 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:16 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:16 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 16138249 version: 127 from line: o=nax 16138249 127 IN IP4 10.11.7.74 Sep 17 18:57:16 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.214:5004:16138249 -> 28eab0eb Sep 17 18:57:16 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 172332185 version: 127 from line: o=nax 172332185 127 IN IP4 10.11.7.74 Sep 17 18:57:16 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.210:5004:172332185 -> b8802285 Sep 17 18:57:16 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 324703249 version: 127 from line: o=nax 324703249 127 IN IP4 10.11.7.74 Sep 17 18:57:16 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.217:5004:324703249 -> aab96f60 Sep 17 18:57:16 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 417606903 version: 127 from line: o=nax 417606903 127 IN IP4 10.11.7.74 Sep 17 18:57:16 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.211:5004:417606903 -> e604a4db Sep 17 18:57:16 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 421601304 version: 127 from line: o=nax 421601304 127 IN IP4 10.11.7.74 Sep 17 18:57:16 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.213:5004:421601304 -> a0bfc762 Sep 17 18:57:16 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 513147893 version: 127 from line: o=nax 513147893 127 IN IP4 10.11.7.74 Sep 17 18:57:16 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.212:5004:513147893 -> 5811a66f Sep 17 18:57:16 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 605167925 version: 127 from line: o=nax 605167925 127 IN IP4 10.11.7.74 Sep 17 18:57:16 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.216:5004:605167925 -> 0beddd55 Sep 17 18:57:16 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 665098026 version: 127 from line: o=nax 665098026 127 IN IP4 10.11.7.74 Sep 17 18:57:16 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.215:5004:665098026 -> 818262a6 Sep 17 18:57:17 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 54000ms Sep 17 18:57:17 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 54000ms Sep 17 18:57:17 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:17 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:17 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:17 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:18 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 55000ms Sep 17 18:57:18 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 55000ms Sep 17 18:57:18 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:18 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:18 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:18 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:18 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 681666 version: 681705 from line: o=- 681666 681705 IN IP4 10.11.7.57 Sep 17 18:57:18 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.149:5004:681666 -> 25521d81 Sep 17 18:57:18 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 239808144 version: 127 from line: o=nax 239808144 127 IN IP4 10.11.7.71 Sep 17 18:57:18 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.205:5004:239808144 -> 089d4146 Sep 17 18:57:18 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 333974723 version: 127 from line: o=nax 333974723 127 IN IP4 10.11.7.71 Sep 17 18:57:18 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.207:5004:333974723 -> b1ed656d Sep 17 18:57:18 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 518070308 version: 127 from line: o=nax 518070308 127 IN IP4 10.11.7.71 Sep 17 18:57:18 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.86.200:5004:518070308 -> 2b234b35 Sep 17 18:57:18 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 528962874 version: 127 from line: o=nax 528962874 127 IN IP4 10.11.7.71 Sep 17 18:57:18 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.201:5004:528962874 -> d1b8799e Sep 17 18:57:18 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 563614365 version: 127 from line: o=nax 563614365 127 IN IP4 10.11.7.71 Sep 17 18:57:18 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.202:5004:563614365 -> e75d1a21 Sep 17 18:57:18 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 586996509 version: 127 from line: o=nax 586996509 127 IN IP4 10.11.7.71 Sep 17 18:57:18 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.206:5004:586996509 -> 5d2af253 Sep 17 18:57:18 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 821074694 version: 127 from line: o=nax 821074694 127 IN IP4 10.11.7.71 Sep 17 18:57:18 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.200:5004:821074694 -> 60d7ed2b Sep 17 18:57:18 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 839938395 version: 127 from line: o=nax 839938395 127 IN IP4 10.11.7.71 Sep 17 18:57:18 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.203:5004:839938395 -> cafadb72 Sep 17 18:57:18 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 99619213 version: 127 from line: o=nax 99619213 127 IN IP4 10.11.7.71 Sep 17 18:57:18 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.204:5004:99619213 -> bcff4c96 Sep 17 18:57:19 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 56000ms Sep 17 18:57:19 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 56000ms Sep 17 18:57:19 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:19 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:19 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:19 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:20 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 57000ms Sep 17 18:57:20 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 57000ms Sep 17 18:57:20 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:20 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:20 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:20 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:21 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 58000ms Sep 17 18:57:21 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 58000ms Sep 17 18:57:21 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:21 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:21 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:21 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:21 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 146140997 version: 127 from line: o=nax 146140997 127 IN IP4 10.11.7.75 Sep 17 18:57:21 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.227:5004:146140997 -> dff13b4a Sep 17 18:57:21 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 286595182 version: 127 from line: o=nax 286595182 127 IN IP4 10.11.7.75 Sep 17 18:57:21 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.222:5004:286595182 -> f1899ce2 Sep 17 18:57:21 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 309351199 version: 127 from line: o=nax 309351199 127 IN IP4 10.11.7.75 Sep 17 18:57:21 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.220:5004:309351199 -> c6cf4a4b Sep 17 18:57:21 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 395539478 version: 127 from line: o=nax 395539478 127 IN IP4 10.11.7.75 Sep 17 18:57:21 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.223:5004:395539478 -> d02e9e31 Sep 17 18:57:21 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 523760604 version: 127 from line: o=nax 523760604 127 IN IP4 10.11.7.75 Sep 17 18:57:21 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.221:5004:523760604 -> a90b47a0 Sep 17 18:57:21 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 687139007 version: 127 from line: o=nax 687139007 127 IN IP4 10.11.7.75 Sep 17 18:57:21 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.225:5004:687139007 -> b8d3d106 Sep 17 18:57:21 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 902015099 version: 127 from line: o=nax 902015099 127 IN IP4 10.11.7.75 Sep 17 18:57:21 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.226:5004:902015099 -> 9daf3582 Sep 17 18:57:21 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 902413056 version: 127 from line: o=nax 902413056 127 IN IP4 10.11.7.75 Sep 17 18:57:21 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.224:5004:902413056 -> f8d335bb Sep 17 18:57:22 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 59000ms Sep 17 18:57:22 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 59000ms Sep 17 18:57:22 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:22 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:22 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:22 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:23 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 60000ms Sep 17 18:57:23 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 60000ms Sep 17 18:57:23 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:23 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:23 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:23 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:24 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 61000ms Sep 17 18:57:24 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 61000ms Sep 17 18:57:24 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:24 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:24 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:24 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:25 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 62000ms Sep 17 18:57:25 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 62000ms Sep 17 18:57:25 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:25 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:25 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:25 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:26 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 63000ms Sep 17 18:57:26 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 63000ms Sep 17 18:57:26 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:26 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:26 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:26 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:27 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 64000ms Sep 17 18:57:27 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 64000ms Sep 17 18:57:27 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:27 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:27 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:27 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:28 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 65000ms Sep 17 18:57:28 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 65000ms Sep 17 18:57:28 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:28 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:28 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:28 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:29 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 66000ms Sep 17 18:57:29 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 66000ms Sep 17 18:57:29 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:29 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:29 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:29 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:30 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 67000ms Sep 17 18:57:30 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 67000ms Sep 17 18:57:30 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:30 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:30 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:30 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:31 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 68000ms Sep 17 18:57:31 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 68000ms Sep 17 18:57:31 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:31 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:31 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:31 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:31 voliorbr1 volumio[3074]: error: error Sep 17 18:57:31 voliorbr1 volumio[3074]: error: error Sep 17 18:57:32 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 69000ms Sep 17 18:57:32 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 69000ms Sep 17 18:57:32 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:32 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:32 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:32 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:33 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 70000ms Sep 17 18:57:33 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 70000ms Sep 17 18:57:33 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:33 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:33 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:33 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:34 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 71000ms Sep 17 18:57:34 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 71000ms Sep 17 18:57:34 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:34 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:34 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:34 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:35 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 72000ms Sep 17 18:57:35 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 72000ms Sep 17 18:57:35 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:35 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:35 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:35 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:36 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 73000ms Sep 17 18:57:36 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 73000ms Sep 17 18:57:36 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:36 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:36 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:36 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 74000ms Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 74000ms Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Preload queue cleared Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreStateMachine::ClearQueue Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreStateMachine::stop Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreStateMachine::serviceStop Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreCommandRouter::serviceStop Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] Stop requested Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] === REPORT PLAYBACK STOPPED === Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] Seek timer stopped Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] Building state - status: stop, seek: 0ms Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] Stop state to push: Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CorePlayQueue::getTrack 6 Sep 17 18:57:37 voliorbr1 volumio[3074]: verbose: STATE SERVICE {"status":"stop","service":"volumio-aes67","type":"track","trackType":"aes67","title":"MediaStreamNax1Player2","artist":"239.69.85.201:5004","album":"48kHz/24bit","uri":"","albumart":"/albumart?sourceicon=music_service/volumio-aes67/icon.svg","duration":0,"seek":0,"samplerate":"48000","bitdepth":"24","channels":2,"random":false,"repeat":false,"repeatSingle":false,"consume":false,"disableUiControls":false,"stream":true} Sep 17 18:57:37 voliorbr1 volumio[3074]: verbose: CURRENT POSITION 6 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreStateMachine::syncState stateService stop Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreStateMachine::syncState currentStatus play Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreStateMachine::play index undefined Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] === STOP STATE PUSHED SUCCESSFULLY === Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] Leaving stream Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CorePlayQueue::clearPlayQueue Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CorePlayQueue::saveQueue Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushQueue Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreStateMachine::addQueueItems Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CorePlayQueue::addQueueItems Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Preload queue cleared Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/73d22ac2 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/73d22ac2 in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/b14511b3 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/b14511b3 in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/6c9848b3 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/6c9848b3 in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/6b2bcaac Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/6b2bcaac in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/25521d81 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/25521d81 in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/60d7ed2b Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/60d7ed2b in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CorePlayQueue::getTrack 7 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushQueue Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CorePlayQueue::saveQueue Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreStateMachine::updateTrackBlock Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CorePlayQueue::getTrackBlock Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPlay Sep 17 18:57:37 voliorbr1 volumio[3074]: verbose: UNSET VOLATILE: Service: undefined Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] unsetVol callback Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] Stop requested Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] === REPORT PLAYBACK STOPPED === Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] Building state - status: stop, seek: 0ms Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] Stop state to push: Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CorePlayQueue::getTrack 0 Sep 17 18:57:37 voliorbr1 volumio[3074]: verbose: STATE SERVICE {"status":"stop","service":"volumio-aes67","type":"track","trackType":"aes67","title":"MediaStreamNax1Player2","artist":"239.69.85.201:5004","album":"48kHz/24bit","uri":"","albumart":"/albumart?sourceicon=music_service/volumio-aes67/icon.svg","duration":0,"seek":0,"samplerate":"48000","bitdepth":"24","channels":2,"random":false,"repeat":false,"repeatSingle":false,"consume":false,"disableUiControls":false,"stream":true} Sep 17 18:57:37 voliorbr1 volumio[3074]: verbose: CURRENT POSITION 0 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreStateMachine::syncState stateService stop Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreStateMachine::syncState currentStatus stop Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:37 voliorbr1 volumio[3074]: info: No code Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] === STOP STATE PUSHED SUCCESSFULLY === Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] Leaving stream Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreStateMachine::play index 5 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreStateMachine::addQueueItems Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CorePlayQueue::addQueueItems Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Preload queue cleared Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/d1b8799e Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/d1b8799e in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/e75d1a21 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/e75d1a21 in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/cafadb72 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/cafadb72 in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/bcff4c96 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/bcff4c96 in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/089d4146 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/089d4146 in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/5d2af253 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/5d2af253 in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/b1ed656d Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/b1ed656d in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/b8802285 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/b8802285 in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/e604a4db Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/e604a4db in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/5811a66f Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/5811a66f in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/a0bfc762 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/a0bfc762 in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/28eab0eb Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/28eab0eb in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/818262a6 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/818262a6 in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/0beddd55 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/0beddd55 in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/aab96f60 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/aab96f60 in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/c6cf4a4b Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/c6cf4a4b in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/a90b47a0 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/a90b47a0 in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/f1899ce2 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/f1899ce2 in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/d02e9e31 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/d02e9e31 in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/f8d335bb Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/f8d335bb in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/b8d3d106 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/b8d3d106 in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/9daf3582 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/9daf3582 in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/dff13b4a Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/dff13b4a in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/2b234b35 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/2b234b35 in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreStateMachine::stop Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreStateMachine::play index undefined Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CorePlayQueue::getTrack 5 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreStateMachine::startPlaybackTimer Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CorePlayQueue::getTrack 5 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] === CLEAR ADD PLAY TRACK === Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] Track URI: volumio-aes67://stream/60d7ed2b Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] Track object: Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] Found stream: MediaStreamNax1Player1 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] Raw stream data: {"id":"60d7ed2b","name":"MediaStreamNax1Player1","source":"10.11.7.71","multicast":"239.69.85.200","port":5004,"channels":2,"rate":48000,"depth":24,"payloadType":96,"ptime":1,"ttl":32,"lastSeen":"2025-09-17T22:57:18.790Z","sdp":"v=0\r\no=nax 821074694 127 IN IP4 10.11.7.71\r\ns=MediaStreamNax1Player1\r\nc=IN IP4 239.69.85.200/32\r\nt=0 0\r\nm=audio 5004 RTP/AVP 96\r\ni=2 channels: Tx Left, Tx Right\r\na=recvonly\r\na=rtpmap:96 L24/48000/2\r\na=ptime:1\r\na=ts-refclk:ptp=IEEE1588-2008:2C-CF-67-FF-FE-8C-75-6A:0\r\na=mediaclk:direct=0\r\n"} Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] Stream config - name: MediaStreamNax1Player1, ip: 239.69.85.200, port: 5004 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] Joining stream: MediaStreamNax1Player1 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] Leaving stream Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushQueue Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CorePlayQueue::saveQueue Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreStateMachine::updateTrackBlock Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CorePlayQueue::getTrackBlock Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [GStreamer] Pipeline exited (code: null, signal: SIGTERM) Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] Creating GStreamer pipeline for stream type: multicast Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [GStreamer] Creating pipeline for stream type: multicast Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [GStreamer] Stream config: {"type":"multicast","ip":"239.69.85.200","port":5004,"rate":48000,"depth":24,"channels":2,"payloadType":96,"ptime":1} Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [GStreamer] Pipeline created (length=682) Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [GStreamer] Full pipeline string: Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [GStreamer] udpsrc address=239.69.85.200 port=5004 auto-multicast=true multicast-iface=eth0 caps=application/x-rtp,media=(string)audio,payload=(int)96,clock-rate=(int)48000,encoding-name=(string)L24,channels=(int)2 ! rtpjitterbuffer latency=50 do-lost=true do-retransmission=false max-misorder-time=50 max-dropout-time=100 max-rtcp-rtp-time-diff=150 ! rtpL24depay ! audioconvert ! audioresample quality=4 ! audio/x-raw, format=(string)S32LE, rate=(int)48000, channels=(int)2 ! queue max-size-buffers=0 max-size-bytes=0 max-size-time=50000000 leaky=downstream ! alsasink device=hw:0 sync=true buffer-time=50000000 latency-time=12500000 provide-clock=false slave-method=skew drift-tolerance=40000 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] Pipeline created, starting... Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [GStreamer] Starting with args count: 43 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [GStreamer] First 10 args: -v | -e | udpsrc | address=239.69.85.200 | port=5004 | auto-multicast=true | multicast-iface=eth0 | caps=application/x-rtp,media=(string)audio,payload=(int)96,clock-rate=(int)48000,encoding-name=(string)L24,channels=(int)2 | ! | rtpjitterbuffer Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [GStreamer] Full pipeline command: Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [GStreamer] gst-launch-1.0 -v -e udpsrc address=239.69.85.200 port=5004 auto-multicast=true multicast-iface=eth0 caps=application/x-rtp,media=(string)audio,payload=(int)96,clock-rate=(int)48000,encoding-name=(string)L24,channels=(int)2 ! rtpjitterbuffer latency=50 do-lost=true do-retransmission=false max-misorder-time=50 max-dropout-time=100 max-rtcp-rtp-time-diff=150 ! rtpL24depay ! audioconvert ! audioresample quality=4 ! audio/x-raw, format=(string)S32LE, rate=(int)48000, channels=(int)2 ! queue max-size-buffers=0 max-size-bytes=0 max-size-time=50000000 leaky=downstream ! alsasink device=hw:0 sync=true buffer-time=50000000 latency-time=12500000 provide-clock=false slave-method=skew drift-tolerance=40000 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [GStreamer] Pipeline started Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [GStreamer] Live pipeline running Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] Pipeline started successfully Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] Saving stream config: Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [StreamStore] Last stream saved Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] About to report playback started... Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] === REPORT PLAYBACK STARTED === Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] Current stream data: Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] Setting volatile service to volumio-aes67... Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreStateMachine::setConsumeUpdateService volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] Seek timer started Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 0ms Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] State object to push: Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] === PLAYBACK STATE PUSHED SUCCESSFULLY === Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] Finished reporting playback started Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Preload queue cleared Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreStateMachine::ClearQueue Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreStateMachine::stop Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreStateMachine::serviceStop Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreCommandRouter::serviceStop Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] Stop requested Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] === REPORT PLAYBACK STOPPED === Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] Seek timer stopped Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] Building state - status: stop, seek: 0ms Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] Stop state to push: Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CorePlayQueue::getTrack 5 Sep 17 18:57:37 voliorbr1 volumio[3074]: verbose: STATE SERVICE {"status":"stop","service":"volumio-aes67","type":"track","trackType":"aes67","title":"MediaStreamNax1Player1","artist":"239.69.85.200:5004","album":"48kHz/24bit","uri":"","albumart":"/albumart?sourceicon=music_service/volumio-aes67/icon.svg","duration":0,"seek":0,"samplerate":"48000","bitdepth":"24","channels":2,"random":false,"repeat":false,"repeatSingle":false,"consume":false,"disableUiControls":false,"stream":true} Sep 17 18:57:37 voliorbr1 volumio[3074]: verbose: CURRENT POSITION 5 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreStateMachine::syncState stateService stop Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreStateMachine::syncState currentStatus play Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreStateMachine::play index undefined Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] === STOP STATE PUSHED SUCCESSFULLY === Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] Leaving stream Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CorePlayQueue::clearPlayQueue Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CorePlayQueue::saveQueue Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushQueue Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreStateMachine::addQueueItems Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CorePlayQueue::addQueueItems Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Preload queue cleared Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/73d22ac2 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/73d22ac2 in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/b14511b3 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/b14511b3 in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/6c9848b3 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/6c9848b3 in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/6b2bcaac Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/6b2bcaac in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/25521d81 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/25521d81 in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/60d7ed2b Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/60d7ed2b in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CorePlayQueue::getTrack 6 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushQueue Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CorePlayQueue::saveQueue Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreStateMachine::updateTrackBlock Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CorePlayQueue::getTrackBlock Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPlay Sep 17 18:57:37 voliorbr1 volumio[3074]: verbose: UNSET VOLATILE: Service: undefined Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] unsetVol callback Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] Stop requested Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] === REPORT PLAYBACK STOPPED === Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] Building state - status: stop, seek: 0ms Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] Stop state to push: Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CorePlayQueue::getTrack 0 Sep 17 18:57:37 voliorbr1 volumio[3074]: verbose: STATE SERVICE {"status":"stop","service":"volumio-aes67","type":"track","trackType":"aes67","title":"MediaStreamNax1Player1","artist":"239.69.85.200:5004","album":"48kHz/24bit","uri":"","albumart":"/albumart?sourceicon=music_service/volumio-aes67/icon.svg","duration":0,"seek":0,"samplerate":"48000","bitdepth":"24","channels":2,"random":false,"repeat":false,"repeatSingle":false,"consume":false,"disableUiControls":false,"stream":true} Sep 17 18:57:37 voliorbr1 volumio[3074]: verbose: CURRENT POSITION 0 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreStateMachine::syncState stateService stop Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreStateMachine::syncState currentStatus stop Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:37 voliorbr1 volumio[3074]: info: No code Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] === STOP STATE PUSHED SUCCESSFULLY === Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] Leaving stream Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreStateMachine::play index 5 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreStateMachine::addQueueItems Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CorePlayQueue::addQueueItems Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Preload queue cleared Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/d1b8799e Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/d1b8799e in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/e75d1a21 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/e75d1a21 in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/cafadb72 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/cafadb72 in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/bcff4c96 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/bcff4c96 in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/089d4146 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/089d4146 in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/5d2af253 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/5d2af253 in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/b1ed656d Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/b1ed656d in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/b8802285 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/b8802285 in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/e604a4db Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/e604a4db in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/5811a66f Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/5811a66f in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/a0bfc762 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/a0bfc762 in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/28eab0eb Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/28eab0eb in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/818262a6 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/818262a6 in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/0beddd55 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/0beddd55 in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/aab96f60 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/aab96f60 in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/c6cf4a4b Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/c6cf4a4b in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/a90b47a0 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/a90b47a0 in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/f1899ce2 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/f1899ce2 in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/d02e9e31 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/d02e9e31 in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/f8d335bb Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/f8d335bb in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/b8d3d106 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/b8d3d106 in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/9daf3582 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/9daf3582 in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/dff13b4a Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/dff13b4a in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Adding Item to queue: volumio-aes67://stream/2b234b35 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: Exploding uri volumio-aes67://stream/2b234b35 in service volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreStateMachine::stop Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreStateMachine::play index undefined Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CorePlayQueue::getTrack 5 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreStateMachine::startPlaybackTimer Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CorePlayQueue::getTrack 5 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] === CLEAR ADD PLAY TRACK === Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] Track URI: volumio-aes67://stream/60d7ed2b Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] Track object: Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] Found stream: MediaStreamNax1Player1 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] Raw stream data: {"id":"60d7ed2b","name":"MediaStreamNax1Player1","source":"10.11.7.71","multicast":"239.69.85.200","port":5004,"channels":2,"rate":48000,"depth":24,"payloadType":96,"ptime":1,"ttl":32,"lastSeen":"2025-09-17T22:57:18.790Z","sdp":"v=0\r\no=nax 821074694 127 IN IP4 10.11.7.71\r\ns=MediaStreamNax1Player1\r\nc=IN IP4 239.69.85.200/32\r\nt=0 0\r\nm=audio 5004 RTP/AVP 96\r\ni=2 channels: Tx Left, Tx Right\r\na=recvonly\r\na=rtpmap:96 L24/48000/2\r\na=ptime:1\r\na=ts-refclk:ptp=IEEE1588-2008:2C-CF-67-FF-FE-8C-75-6A:0\r\na=mediaclk:direct=0\r\n"} Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] Stream config - name: MediaStreamNax1Player1, ip: 239.69.85.200, port: 5004 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] Joining stream: MediaStreamNax1Player1 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] Leaving stream Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushQueue Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CorePlayQueue::saveQueue Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreStateMachine::updateTrackBlock Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CorePlayQueue::getTrackBlock Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [GStreamer] Pipeline exited (code: null, signal: SIGTERM) Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] Creating GStreamer pipeline for stream type: multicast Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [GStreamer] Creating pipeline for stream type: multicast Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [GStreamer] Stream config: {"type":"multicast","ip":"239.69.85.200","port":5004,"rate":48000,"depth":24,"channels":2,"payloadType":96,"ptime":1} Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [GStreamer] Pipeline created (length=682) Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [GStreamer] Full pipeline string: Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [GStreamer] udpsrc address=239.69.85.200 port=5004 auto-multicast=true multicast-iface=eth0 caps=application/x-rtp,media=(string)audio,payload=(int)96,clock-rate=(int)48000,encoding-name=(string)L24,channels=(int)2 ! rtpjitterbuffer latency=50 do-lost=true do-retransmission=false max-misorder-time=50 max-dropout-time=100 max-rtcp-rtp-time-diff=150 ! rtpL24depay ! audioconvert ! audioresample quality=4 ! audio/x-raw, format=(string)S32LE, rate=(int)48000, channels=(int)2 ! queue max-size-buffers=0 max-size-bytes=0 max-size-time=50000000 leaky=downstream ! alsasink device=hw:0 sync=true buffer-time=50000000 latency-time=12500000 provide-clock=false slave-method=skew drift-tolerance=40000 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] Pipeline created, starting... Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [GStreamer] Starting with args count: 43 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [GStreamer] First 10 args: -v | -e | udpsrc | address=239.69.85.200 | port=5004 | auto-multicast=true | multicast-iface=eth0 | caps=application/x-rtp,media=(string)audio,payload=(int)96,clock-rate=(int)48000,encoding-name=(string)L24,channels=(int)2 | ! | rtpjitterbuffer Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [GStreamer] Full pipeline command: Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [GStreamer] gst-launch-1.0 -v -e udpsrc address=239.69.85.200 port=5004 auto-multicast=true multicast-iface=eth0 caps=application/x-rtp,media=(string)audio,payload=(int)96,clock-rate=(int)48000,encoding-name=(string)L24,channels=(int)2 ! rtpjitterbuffer latency=50 do-lost=true do-retransmission=false max-misorder-time=50 max-dropout-time=100 max-rtcp-rtp-time-diff=150 ! rtpL24depay ! audioconvert ! audioresample quality=4 ! audio/x-raw, format=(string)S32LE, rate=(int)48000, channels=(int)2 ! queue max-size-buffers=0 max-size-bytes=0 max-size-time=50000000 leaky=downstream ! alsasink device=hw:0 sync=true buffer-time=50000000 latency-time=12500000 provide-clock=false slave-method=skew drift-tolerance=40000 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [GStreamer] Pipeline started Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [GStreamer] Live pipeline running Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] Pipeline started successfully Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] Saving stream config: Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [StreamStore] Last stream saved Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] About to report playback started... Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] === REPORT PLAYBACK STARTED === Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] Current stream data: Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] Setting volatile service to volumio-aes67... Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreStateMachine::setConsumeUpdateService volumio-aes67 Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] Seek timer started Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 0ms Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] State object to push: Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:37 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] === PLAYBACK STATE PUSHED SUCCESSFULLY === Sep 17 18:57:37 voliorbr1 volumio[3074]: info: [AES67] Finished reporting playback started Sep 17 18:57:38 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 146140997 version: 127 from line: o=nax 146140997 127 IN IP4 10.11.7.75 Sep 17 18:57:38 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.227:5004:146140997 -> dff13b4a Sep 17 18:57:38 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 286595182 version: 127 from line: o=nax 286595182 127 IN IP4 10.11.7.75 Sep 17 18:57:38 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.222:5004:286595182 -> f1899ce2 Sep 17 18:57:38 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 309351199 version: 127 from line: o=nax 309351199 127 IN IP4 10.11.7.75 Sep 17 18:57:38 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.220:5004:309351199 -> c6cf4a4b Sep 17 18:57:38 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 395539478 version: 127 from line: o=nax 395539478 127 IN IP4 10.11.7.75 Sep 17 18:57:38 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.223:5004:395539478 -> d02e9e31 Sep 17 18:57:38 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 523760604 version: 127 from line: o=nax 523760604 127 IN IP4 10.11.7.75 Sep 17 18:57:38 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.221:5004:523760604 -> a90b47a0 Sep 17 18:57:38 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 687139007 version: 127 from line: o=nax 687139007 127 IN IP4 10.11.7.75 Sep 17 18:57:38 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.225:5004:687139007 -> b8d3d106 Sep 17 18:57:38 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 902015099 version: 127 from line: o=nax 902015099 127 IN IP4 10.11.7.75 Sep 17 18:57:38 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.226:5004:902015099 -> 9daf3582 Sep 17 18:57:38 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 902413056 version: 127 from line: o=nax 902413056 127 IN IP4 10.11.7.75 Sep 17 18:57:38 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.224:5004:902413056 -> f8d335bb Sep 17 18:57:38 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 1000ms Sep 17 18:57:38 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 1000ms Sep 17 18:57:38 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:38 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:38 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:38 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:39 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 239808144 version: 127 from line: o=nax 239808144 127 IN IP4 10.11.7.71 Sep 17 18:57:39 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.205:5004:239808144 -> 089d4146 Sep 17 18:57:39 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 333974723 version: 127 from line: o=nax 333974723 127 IN IP4 10.11.7.71 Sep 17 18:57:39 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.207:5004:333974723 -> b1ed656d Sep 17 18:57:39 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 518070308 version: 127 from line: o=nax 518070308 127 IN IP4 10.11.7.71 Sep 17 18:57:39 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.86.200:5004:518070308 -> 2b234b35 Sep 17 18:57:39 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 528962874 version: 127 from line: o=nax 528962874 127 IN IP4 10.11.7.71 Sep 17 18:57:39 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.201:5004:528962874 -> d1b8799e Sep 17 18:57:39 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 563614365 version: 127 from line: o=nax 563614365 127 IN IP4 10.11.7.71 Sep 17 18:57:39 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.202:5004:563614365 -> e75d1a21 Sep 17 18:57:39 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 586996509 version: 127 from line: o=nax 586996509 127 IN IP4 10.11.7.71 Sep 17 18:57:39 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.206:5004:586996509 -> 5d2af253 Sep 17 18:57:39 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 821074694 version: 127 from line: o=nax 821074694 127 IN IP4 10.11.7.71 Sep 17 18:57:39 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.200:5004:821074694 -> 60d7ed2b Sep 17 18:57:39 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 839938395 version: 127 from line: o=nax 839938395 127 IN IP4 10.11.7.71 Sep 17 18:57:39 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.203:5004:839938395 -> cafadb72 Sep 17 18:57:39 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 99619213 version: 127 from line: o=nax 99619213 127 IN IP4 10.11.7.71 Sep 17 18:57:39 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.204:5004:99619213 -> bcff4c96 Sep 17 18:57:39 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 2000ms Sep 17 18:57:39 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 2000ms Sep 17 18:57:39 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:39 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:39 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:39 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:40 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 3000ms Sep 17 18:57:40 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 3000ms Sep 17 18:57:40 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:40 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:40 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:40 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:41 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 4000ms Sep 17 18:57:41 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 4000ms Sep 17 18:57:41 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:41 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:41 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:41 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:42 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 5000ms Sep 17 18:57:42 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 5000ms Sep 17 18:57:42 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:42 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:42 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:42 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:43 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 16138249 version: 127 from line: o=nax 16138249 127 IN IP4 10.11.7.74 Sep 17 18:57:43 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.214:5004:16138249 -> 28eab0eb Sep 17 18:57:43 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 172332185 version: 127 from line: o=nax 172332185 127 IN IP4 10.11.7.74 Sep 17 18:57:43 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.210:5004:172332185 -> b8802285 Sep 17 18:57:43 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 324703249 version: 127 from line: o=nax 324703249 127 IN IP4 10.11.7.74 Sep 17 18:57:43 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.217:5004:324703249 -> aab96f60 Sep 17 18:57:43 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 417606903 version: 127 from line: o=nax 417606903 127 IN IP4 10.11.7.74 Sep 17 18:57:43 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.211:5004:417606903 -> e604a4db Sep 17 18:57:43 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 421601304 version: 127 from line: o=nax 421601304 127 IN IP4 10.11.7.74 Sep 17 18:57:43 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.213:5004:421601304 -> a0bfc762 Sep 17 18:57:43 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 513147893 version: 127 from line: o=nax 513147893 127 IN IP4 10.11.7.74 Sep 17 18:57:43 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.212:5004:513147893 -> 5811a66f Sep 17 18:57:43 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 605167925 version: 127 from line: o=nax 605167925 127 IN IP4 10.11.7.74 Sep 17 18:57:43 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.216:5004:605167925 -> 0beddd55 Sep 17 18:57:43 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 665098026 version: 127 from line: o=nax 665098026 127 IN IP4 10.11.7.74 Sep 17 18:57:43 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.215:5004:665098026 -> 818262a6 Sep 17 18:57:43 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 6000ms Sep 17 18:57:43 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 6000ms Sep 17 18:57:43 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:43 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:43 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:43 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:44 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 7000ms Sep 17 18:57:44 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 7000ms Sep 17 18:57:44 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:44 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:44 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:44 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:45 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 1028889 version: 1028899 from line: o=- 1028889 1028899 IN IP4 10.11.7.64 Sep 17 18:57:45 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.3.171:5004:1028889 -> 73d22ac2 Sep 17 18:57:45 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 488443 version: 488453 from line: o=- 488443 488453 IN IP4 10.11.7.64 Sep 17 18:57:45 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.180.57:5004:488443 -> b14511b3 Sep 17 18:57:45 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 1022333 version: 1022343 from line: o=- 1022333 1022343 IN IP4 10.11.7.64 Sep 17 18:57:45 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.65.7:5004:1022333 -> 6c9848b3 Sep 17 18:57:45 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 674495 version: 674505 from line: o=- 674495 674505 IN IP4 10.11.7.64 Sep 17 18:57:45 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.74.227:5004:674495 -> 6b2bcaac Sep 17 18:57:45 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 8000ms Sep 17 18:57:45 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 8000ms Sep 17 18:57:45 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:45 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:45 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:45 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:46 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 9000ms Sep 17 18:57:46 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 9000ms Sep 17 18:57:46 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:46 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:46 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:46 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:47 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 10000ms Sep 17 18:57:47 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 10000ms Sep 17 18:57:47 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:47 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:47 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:47 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:48 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 681666 version: 681705 from line: o=- 681666 681705 IN IP4 10.11.7.57 Sep 17 18:57:48 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.149:5004:681666 -> 25521d81 Sep 17 18:57:48 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 11000ms Sep 17 18:57:48 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 11000ms Sep 17 18:57:48 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:48 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:48 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:48 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:49 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 12000ms Sep 17 18:57:49 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 12000ms Sep 17 18:57:49 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:49 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:49 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:49 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:50 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 13000ms Sep 17 18:57:50 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 13000ms Sep 17 18:57:50 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:50 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:50 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:50 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:51 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 14000ms Sep 17 18:57:51 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 14000ms Sep 17 18:57:51 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:51 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:51 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:51 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:52 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 15000ms Sep 17 18:57:52 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 15000ms Sep 17 18:57:52 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:52 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:52 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:52 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:53 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 16000ms Sep 17 18:57:53 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 16000ms Sep 17 18:57:53 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:53 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:53 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:53 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:54 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 17000ms Sep 17 18:57:54 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 17000ms Sep 17 18:57:54 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:54 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:54 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:54 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:55 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 146140997 version: 127 from line: o=nax 146140997 127 IN IP4 10.11.7.75 Sep 17 18:57:55 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.227:5004:146140997 -> dff13b4a Sep 17 18:57:55 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 286595182 version: 127 from line: o=nax 286595182 127 IN IP4 10.11.7.75 Sep 17 18:57:55 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.222:5004:286595182 -> f1899ce2 Sep 17 18:57:55 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 309351199 version: 127 from line: o=nax 309351199 127 IN IP4 10.11.7.75 Sep 17 18:57:55 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.220:5004:309351199 -> c6cf4a4b Sep 17 18:57:55 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 395539478 version: 127 from line: o=nax 395539478 127 IN IP4 10.11.7.75 Sep 17 18:57:55 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.223:5004:395539478 -> d02e9e31 Sep 17 18:57:55 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 523760604 version: 127 from line: o=nax 523760604 127 IN IP4 10.11.7.75 Sep 17 18:57:55 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.221:5004:523760604 -> a90b47a0 Sep 17 18:57:55 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 687139007 version: 127 from line: o=nax 687139007 127 IN IP4 10.11.7.75 Sep 17 18:57:55 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.225:5004:687139007 -> b8d3d106 Sep 17 18:57:55 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 902015099 version: 127 from line: o=nax 902015099 127 IN IP4 10.11.7.75 Sep 17 18:57:55 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.226:5004:902015099 -> 9daf3582 Sep 17 18:57:55 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 902413056 version: 127 from line: o=nax 902413056 127 IN IP4 10.11.7.75 Sep 17 18:57:55 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.224:5004:902413056 -> f8d335bb Sep 17 18:57:55 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 18000ms Sep 17 18:57:55 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 18000ms Sep 17 18:57:55 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:55 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:55 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:55 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:56 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumio-aes67 , handleBrowseUri Sep 17 18:57:56 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 19000ms Sep 17 18:57:56 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 19000ms Sep 17 18:57:56 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:56 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:56 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:56 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:57 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 20000ms Sep 17 18:57:57 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 20000ms Sep 17 18:57:57 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:57 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:57 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:57 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:58 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 21000ms Sep 17 18:57:58 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 21000ms Sep 17 18:57:58 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:58 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:58 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:58 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:57:59 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 22000ms Sep 17 18:57:59 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 22000ms Sep 17 18:57:59 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:57:59 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:57:59 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:57:59 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:58:00 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 239808144 version: 127 from line: o=nax 239808144 127 IN IP4 10.11.7.71 Sep 17 18:58:00 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.205:5004:239808144 -> 089d4146 Sep 17 18:58:00 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 333974723 version: 127 from line: o=nax 333974723 127 IN IP4 10.11.7.71 Sep 17 18:58:00 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.207:5004:333974723 -> b1ed656d Sep 17 18:58:00 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 518070308 version: 127 from line: o=nax 518070308 127 IN IP4 10.11.7.71 Sep 17 18:58:00 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.86.200:5004:518070308 -> 2b234b35 Sep 17 18:58:00 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 528962874 version: 127 from line: o=nax 528962874 127 IN IP4 10.11.7.71 Sep 17 18:58:00 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.201:5004:528962874 -> d1b8799e Sep 17 18:58:00 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 563614365 version: 127 from line: o=nax 563614365 127 IN IP4 10.11.7.71 Sep 17 18:58:00 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.202:5004:563614365 -> e75d1a21 Sep 17 18:58:00 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 586996509 version: 127 from line: o=nax 586996509 127 IN IP4 10.11.7.71 Sep 17 18:58:00 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.206:5004:586996509 -> 5d2af253 Sep 17 18:58:00 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 821074694 version: 127 from line: o=nax 821074694 127 IN IP4 10.11.7.71 Sep 17 18:58:00 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.200:5004:821074694 -> 60d7ed2b Sep 17 18:58:00 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 839938395 version: 127 from line: o=nax 839938395 127 IN IP4 10.11.7.71 Sep 17 18:58:00 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.203:5004:839938395 -> cafadb72 Sep 17 18:58:00 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 99619213 version: 127 from line: o=nax 99619213 127 IN IP4 10.11.7.71 Sep 17 18:58:00 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.204:5004:99619213 -> bcff4c96 Sep 17 18:58:00 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 23000ms Sep 17 18:58:00 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 23000ms Sep 17 18:58:00 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:58:00 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:58:00 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:58:00 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:58:01 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 24000ms Sep 17 18:58:01 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 24000ms Sep 17 18:58:01 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:58:01 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:58:01 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:58:01 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:58:02 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumio-aes67 , handleBrowseUri Sep 17 18:58:02 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 25000ms Sep 17 18:58:02 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 25000ms Sep 17 18:58:02 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:58:02 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:58:02 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:58:02 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:58:03 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 26000ms Sep 17 18:58:03 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 26000ms Sep 17 18:58:03 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:58:03 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:58:03 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:58:03 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:58:04 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 27000ms Sep 17 18:58:04 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 27000ms Sep 17 18:58:04 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:58:04 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:58:04 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:58:04 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:58:05 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 28000ms Sep 17 18:58:05 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 28000ms Sep 17 18:58:05 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:58:05 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:58:05 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:58:05 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:58:06 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 29000ms Sep 17 18:58:06 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 29000ms Sep 17 18:58:06 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:58:06 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:58:06 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:58:06 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:58:07 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 30000ms Sep 17 18:58:07 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 30000ms Sep 17 18:58:07 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:58:07 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:58:07 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:58:07 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:58:08 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 31000ms Sep 17 18:58:08 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 31000ms Sep 17 18:58:08 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:58:08 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:58:08 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:58:08 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:58:09 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 32000ms Sep 17 18:58:09 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 32000ms Sep 17 18:58:09 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:58:09 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:58:09 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:58:09 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:58:10 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 16138249 version: 127 from line: o=nax 16138249 127 IN IP4 10.11.7.74 Sep 17 18:58:10 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.214:5004:16138249 -> 28eab0eb Sep 17 18:58:10 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 172332185 version: 127 from line: o=nax 172332185 127 IN IP4 10.11.7.74 Sep 17 18:58:10 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.210:5004:172332185 -> b8802285 Sep 17 18:58:10 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 324703249 version: 127 from line: o=nax 324703249 127 IN IP4 10.11.7.74 Sep 17 18:58:10 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.217:5004:324703249 -> aab96f60 Sep 17 18:58:10 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 417606903 version: 127 from line: o=nax 417606903 127 IN IP4 10.11.7.74 Sep 17 18:58:10 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.211:5004:417606903 -> e604a4db Sep 17 18:58:10 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 421601304 version: 127 from line: o=nax 421601304 127 IN IP4 10.11.7.74 Sep 17 18:58:10 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.213:5004:421601304 -> a0bfc762 Sep 17 18:58:10 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 513147893 version: 127 from line: o=nax 513147893 127 IN IP4 10.11.7.74 Sep 17 18:58:10 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.212:5004:513147893 -> 5811a66f Sep 17 18:58:10 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 605167925 version: 127 from line: o=nax 605167925 127 IN IP4 10.11.7.74 Sep 17 18:58:10 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.216:5004:605167925 -> 0beddd55 Sep 17 18:58:10 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 665098026 version: 127 from line: o=nax 665098026 127 IN IP4 10.11.7.74 Sep 17 18:58:10 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.215:5004:665098026 -> 818262a6 Sep 17 18:58:10 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 33000ms Sep 17 18:58:10 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 33000ms Sep 17 18:58:10 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:58:10 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:58:10 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:58:10 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:58:11 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 34000ms Sep 17 18:58:11 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 34000ms Sep 17 18:58:11 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:58:11 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:58:11 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:58:11 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:58:12 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 146140997 version: 127 from line: o=nax 146140997 127 IN IP4 10.11.7.75 Sep 17 18:58:12 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.227:5004:146140997 -> dff13b4a Sep 17 18:58:12 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 286595182 version: 127 from line: o=nax 286595182 127 IN IP4 10.11.7.75 Sep 17 18:58:12 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.222:5004:286595182 -> f1899ce2 Sep 17 18:58:12 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 309351199 version: 127 from line: o=nax 309351199 127 IN IP4 10.11.7.75 Sep 17 18:58:12 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.220:5004:309351199 -> c6cf4a4b Sep 17 18:58:12 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 395539478 version: 127 from line: o=nax 395539478 127 IN IP4 10.11.7.75 Sep 17 18:58:12 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.223:5004:395539478 -> d02e9e31 Sep 17 18:58:12 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 523760604 version: 127 from line: o=nax 523760604 127 IN IP4 10.11.7.75 Sep 17 18:58:12 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.221:5004:523760604 -> a90b47a0 Sep 17 18:58:12 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 687139007 version: 127 from line: o=nax 687139007 127 IN IP4 10.11.7.75 Sep 17 18:58:12 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.225:5004:687139007 -> b8d3d106 Sep 17 18:58:12 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 902015099 version: 127 from line: o=nax 902015099 127 IN IP4 10.11.7.75 Sep 17 18:58:12 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.226:5004:902015099 -> 9daf3582 Sep 17 18:58:12 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 902413056 version: 127 from line: o=nax 902413056 127 IN IP4 10.11.7.75 Sep 17 18:58:12 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.224:5004:902413056 -> f8d335bb Sep 17 18:58:12 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 35000ms Sep 17 18:58:12 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 35000ms Sep 17 18:58:12 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:58:12 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:58:12 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:58:12 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:58:13 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 36000ms Sep 17 18:58:13 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 36000ms Sep 17 18:58:13 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:58:13 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:58:13 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:58:13 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:58:14 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 37000ms Sep 17 18:58:14 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 37000ms Sep 17 18:58:14 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:58:14 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:58:14 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:58:14 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:58:15 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 1028889 version: 1028899 from line: o=- 1028889 1028899 IN IP4 10.11.7.64 Sep 17 18:58:15 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.3.171:5004:1028889 -> 73d22ac2 Sep 17 18:58:15 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 488443 version: 488453 from line: o=- 488443 488453 IN IP4 10.11.7.64 Sep 17 18:58:15 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.180.57:5004:488443 -> b14511b3 Sep 17 18:58:15 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 1022333 version: 1022343 from line: o=- 1022333 1022343 IN IP4 10.11.7.64 Sep 17 18:58:15 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.65.7:5004:1022333 -> 6c9848b3 Sep 17 18:58:15 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 674495 version: 674505 from line: o=- 674495 674505 IN IP4 10.11.7.64 Sep 17 18:58:15 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.74.227:5004:674495 -> 6b2bcaac Sep 17 18:58:15 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 38000ms Sep 17 18:58:15 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 38000ms Sep 17 18:58:15 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:58:15 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:58:15 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:58:15 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:58:16 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 39000ms Sep 17 18:58:16 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 39000ms Sep 17 18:58:16 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:58:16 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:58:16 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:58:16 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:58:17 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 40000ms Sep 17 18:58:17 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 40000ms Sep 17 18:58:17 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:58:17 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:58:17 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:58:17 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:58:18 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 681666 version: 681705 from line: o=- 681666 681705 IN IP4 10.11.7.57 Sep 17 18:58:18 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.149:5004:681666 -> 25521d81 Sep 17 18:58:18 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 41000ms Sep 17 18:58:18 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 41000ms Sep 17 18:58:18 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:58:18 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:58:18 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:58:18 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:58:19 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 42000ms Sep 17 18:58:19 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 42000ms Sep 17 18:58:19 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:58:19 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:58:19 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:58:19 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:58:20 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 43000ms Sep 17 18:58:20 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 43000ms Sep 17 18:58:20 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:58:20 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:58:20 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:58:20 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:58:21 voliorbr1 volumio[3074]: error: error Sep 17 18:58:21 voliorbr1 volumio[3074]: error: error Sep 17 18:58:21 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 239808144 version: 127 from line: o=nax 239808144 127 IN IP4 10.11.7.71 Sep 17 18:58:21 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.205:5004:239808144 -> 089d4146 Sep 17 18:58:21 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 333974723 version: 127 from line: o=nax 333974723 127 IN IP4 10.11.7.71 Sep 17 18:58:21 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.207:5004:333974723 -> b1ed656d Sep 17 18:58:21 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 518070308 version: 127 from line: o=nax 518070308 127 IN IP4 10.11.7.71 Sep 17 18:58:21 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.86.200:5004:518070308 -> 2b234b35 Sep 17 18:58:21 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 528962874 version: 127 from line: o=nax 528962874 127 IN IP4 10.11.7.71 Sep 17 18:58:21 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.201:5004:528962874 -> d1b8799e Sep 17 18:58:21 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 563614365 version: 127 from line: o=nax 563614365 127 IN IP4 10.11.7.71 Sep 17 18:58:21 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.202:5004:563614365 -> e75d1a21 Sep 17 18:58:21 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 586996509 version: 127 from line: o=nax 586996509 127 IN IP4 10.11.7.71 Sep 17 18:58:21 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.206:5004:586996509 -> 5d2af253 Sep 17 18:58:21 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 821074694 version: 127 from line: o=nax 821074694 127 IN IP4 10.11.7.71 Sep 17 18:58:21 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.200:5004:821074694 -> 60d7ed2b Sep 17 18:58:21 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 839938395 version: 127 from line: o=nax 839938395 127 IN IP4 10.11.7.71 Sep 17 18:58:21 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.203:5004:839938395 -> cafadb72 Sep 17 18:58:21 voliorbr1 volumio[3074]: info: [SAP] Parsed sessionId: 99619213 version: 127 from line: o=nax 99619213 127 IN IP4 10.11.7.71 Sep 17 18:58:21 voliorbr1 volumio[3074]: info: [SAP] Stream ID generation: 239.69.85.204:5004:99619213 -> bcff4c96 Sep 17 18:58:21 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 44000ms Sep 17 18:58:21 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 44000ms Sep 17 18:58:21 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:58:21 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:58:21 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:58:21 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:58:22 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 45000ms Sep 17 18:58:22 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 45000ms Sep 17 18:58:22 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:58:22 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:58:22 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:58:22 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:58:23 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 46000ms Sep 17 18:58:23 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 46000ms Sep 17 18:58:23 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:58:23 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:58:23 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:58:23 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:58:24 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 47000ms Sep 17 18:58:24 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 47000ms Sep 17 18:58:24 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:58:24 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:58:24 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:58:24 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:58:25 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 48000ms Sep 17 18:58:25 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 48000ms Sep 17 18:58:25 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:58:25 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:58:25 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:58:25 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:58:26 voliorbr1 volumio[3074]: info: [AES67] Building state - status: play, seek: 49000ms Sep 17 18:58:26 voliorbr1 volumio[3074]: info: [AES67] Timer pushing state - seek position: 49000ms Sep 17 18:58:26 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:58:26 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:58:26 voliorbr1 volumio[3074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:58:26 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:58:27 voliorbr1 volumio[3074]: info: Sep 17 18:58:27 voliorbr1 volumio[3074]: ---------------------------- Client requests Volumio play at index 0 Sep 17 18:58:27 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPlay Sep 17 18:58:27 voliorbr1 volumio[3074]: verbose: UNSET VOLATILE: Service: volumio-aes67 Sep 17 18:58:27 voliorbr1 volumio[3074]: info: [AES67] unsetVol callback Sep 17 18:58:27 voliorbr1 volumio[3074]: info: [AES67] Stop requested Sep 17 18:58:27 voliorbr1 volumio[3074]: info: [AES67] === REPORT PLAYBACK STOPPED === Sep 17 18:58:27 voliorbr1 volumio[3074]: info: [AES67] Seek timer stopped Sep 17 18:58:27 voliorbr1 volumio[3074]: info: [AES67] Building state - status: stop, seek: 0ms Sep 17 18:58:27 voliorbr1 volumio[3074]: info: [AES67] Stop state to push: Sep 17 18:58:27 voliorbr1 volumio[3074]: info: CoreCommandRouter::servicePushState Sep 17 18:58:27 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:58:27 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:58:27 voliorbr1 volumio[3074]: info: CorePlayQueue::getTrack 5 Sep 17 18:58:27 voliorbr1 volumio[3074]: verbose: STATE SERVICE {"status":"stop","service":"volumio-aes67","type":"track","trackType":"aes67","title":"MediaStreamNax1Player1","artist":"239.69.85.200:5004","album":"48kHz/24bit","uri":"","albumart":"/albumart?sourceicon=music_service/volumio-aes67/icon.svg","duration":0,"seek":0,"samplerate":"48000","bitdepth":"24","channels":2,"random":false,"repeat":false,"repeatSingle":false,"consume":false,"disableUiControls":false,"stream":true} Sep 17 18:58:27 voliorbr1 volumio[3074]: verbose: CURRENT POSITION 5 Sep 17 18:58:27 voliorbr1 volumio[3074]: info: CoreStateMachine::syncState stateService stop Sep 17 18:58:27 voliorbr1 volumio[3074]: info: CoreStateMachine::syncState currentStatus play Sep 17 18:58:27 voliorbr1 volumio[3074]: info: CoreStateMachine::play index undefined Sep 17 18:58:27 voliorbr1 volumio[3074]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 17 18:58:27 voliorbr1 volumio[3074]: info: CoreStateMachine::pushState Sep 17 18:58:27 voliorbr1 volumio[3074]: info: CoreCommandRouter::volumioPushState Sep 17 18:58:27 voliorbr1 volumio[3074]: info: [AES67] === STOP STATE PUSHED SUCCESSFULLY === Sep 17 18:58:27 voliorbr1 volumio[3074]: info: [AES67] Leaving stream Sep 17 18:58:27 voliorbr1 volumio[3074]: info: CoreStateMachine::play index 0 Sep 17 18:58:27 voliorbr1 volumio[3074]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 17 18:58:27 voliorbr1 volumio[3074]: info: CorePlayQueue::getTrack 6 Sep 17 18:58:27 voliorbr1 volumio[3074]: info: CoreStateMachine::startPlaybackTimer Sep 17 18:58:27 voliorbr1 volumio[3074]: info: CorePlayQueue::getTrack 6 Sep 17 18:58:27 voliorbr1 volumio[3074]: info: [AES67] === CLEAR ADD PLAY TRACK === Sep 17 18:58:27 voliorbr1 volumio[3074]: info: [AES67] Track URI: volumio-aes67://stream/d1b8799e Sep 17 18:58:27 voliorbr1 volumio[3074]: info: [AES67] Track object: Sep 17 18:58:27 voliorbr1 volumio[3074]: info: [AES67] Found stream: MediaStreamNax1Player2 Sep 17 18:58:27 voliorbr1 volumio[3074]: info: [AES67] Raw stream data: {"id":"d1b8799e","name":"MediaStreamNax1Player2","source":"10.11.7.71","multicast":"239.69.85.201","port":5004,"channels":2,"rate":48000,"depth":24,"payloadType":96,"ptime":1,"ttl":32,"lastSeen":"2025-09-17T22:58:21.794Z","sdp":"v=0\r\no=nax 528962874 127 IN IP4 10.11.7.71\r\ns=MediaStreamNax1Player2\r\nc=IN IP4 239.69.85.201/32\r\nt=0 0\r\nm=audio 5004 RTP/AVP 96\r\ni=2 channels: Tx Left, Tx Right\r\na=recvonly\r\na=rtpmap:96 L24/48000/2\r\na=ptime:1\r\na=ts-refclk:ptp=IEEE1588-2008:2C-CF-67-FF-FE-8C-75-6A:0\r\na=mediaclk:direct=0\r\n"} Sep 17 18:58:27 voliorbr1 volumio[3074]: info: [AES67] Stream config - name: MediaStreamNax1Player2, ip: 239.69.85.201, port: 5004 Sep 17 18:58:27 voliorbr1 volumio[3074]: info: [AES67] Joining stream: MediaStreamNax1Player2 Sep 17 18:58:27 voliorbr1 volumio[3074]: info: [AES67] Leaving stream Sep 17 18:58:27 voliorbr1 volumio[3074]: info: CoreStateMachine::stop Sep 17 18:58:27 voliorbr1 volumio[3074]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 17 18:58:27 voliorbr1 volumio[3074]: info: CoreStateMachine::pushError Sep 17 18:58:27 voliorbr1 volumio[3074]: info: self.leaveStream(...).then(...).then(...).catch is not a function Sep 17 18:58:27 voliorbr1 volumio[3074]: info: CoreStateMachine::play index undefined Sep 17 18:58:27 voliorbr1 volumio[3074]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 17 18:58:27 voliorbr1 volumio[3074]: info: CorePlayQueue::getTrack 0 Sep 17 18:58:27 voliorbr1 volumio[3074]: info: CoreStateMachine::startPlaybackTimer Sep 17 18:58:27 voliorbr1 volumio[3074]: info: CorePlayQueue::getTrack 0 Sep 17 18:58:27 voliorbr1 volumio[3074]: info: [AES67] === CLEAR ADD PLAY TRACK === Sep 17 18:58:27 voliorbr1 volumio[3074]: info: [AES67] Track URI: volumio-aes67://stream/73d22ac2 Sep 17 18:58:27 voliorbr1 volumio[3074]: info: [AES67] Track object: Sep 17 18:58:27 voliorbr1 volumio[3074]: info: [AES67] Found stream: danterbr11-villa-tascom : 25 Sep 17 18:58:27 voliorbr1 volumio[3074]: info: [AES67] Raw stream data: {"id":"73d22ac2","name":"danterbr11-villa-tascom : 25","source":"10.11.7.64","multicast":"239.69.3.171","port":5004,"channels":2,"rate":48000,"depth":24,"payloadType":97,"ptime":1,"ttl":32,"lastSeen":"2025-09-17T22:58:15.468Z","sdp":"v=0\r\no=- 1028889 1028899 IN IP4 10.11.7.64\r\ns=danterbr11-villa-tascom : 25\r\ni=2 channels: 07, 08\r\nc=IN IP4 239.69.3.171/32\r\nt=0 0\r\na=keywds:Dante\r\na=recvonly\r\nm=audio 5004 RTP/AVP 97\r\na=rtpmap:97 L24/48000/2\r\na=ptime:1\r\na=ts-refclk:ptp=IEEE1588-2008:2C-CF-67-FF-FE-8C-75-6A:0\r\na=mediaclk:direct=0\r\n"} Sep 17 18:58:27 voliorbr1 volumio[3074]: info: [AES67] Stream config - name: danterbr11-villa-tascom : 25, ip: 239.69.3.171, port: 5004 Sep 17 18:58:27 voliorbr1 volumio[3074]: info: [AES67] Joining stream: danterbr11-villa-tascom : 25 Sep 17 18:58:27 voliorbr1 volumio[3074]: info: [AES67] Leaving stream Sep 17 18:58:27 voliorbr1 volumio[3074]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 17 18:58:27 voliorbr1 volumio[3074]: TypeError: self.leaveStream(...).then(...).then(...).catch is not a function Sep 17 18:58:27 voliorbr1 volumio[3074]: at AES67Controller.joinStream (/data/plugins/music_service/volumio-aes67/index.js:222:19) Sep 17 18:58:27 voliorbr1 volumio[3074]: at AES67Controller.clearAddPlayTrack (/data/plugins/music_service/volumio-aes67/index.js:614:18) Sep 17 18:58:27 voliorbr1 volumio[3074]: at Promise._successFn (/volumio/app/statemachine.js:1026:24) Sep 17 18:58:27 voliorbr1 volumio[3074]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Sep 17 18:58:27 voliorbr1 volumio[3074]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Sep 17 18:58:27 voliorbr1 volumio[3074]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 17 18:58:27 voliorbr1 sudo[4017]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-17 18:57 Sep 17 18:58:27 voliorbr1 sudo[4017]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"