-- Logs begin at Wed 2025-09-17 14:47:45 EDT, end at Wed 2025-09-17 18:22:53 EDT. -- Sep 17 18:21:05 voliorbr1 volumio[22628]: info: [SAP] Parsed sessionId: 146140997 version: 127 from line: o=nax 146140997 127 IN IP4 10.11.7.75 Sep 17 18:21:05 voliorbr1 volumio[22628]: info: [SAP] Stream ID generation: 239.69.85.227:5004:146140997 -> dff13b4a Sep 17 18:21:05 voliorbr1 volumio[22628]: info: [SAP] Parsed sessionId: 286595182 version: 127 from line: o=nax 286595182 127 IN IP4 10.11.7.75 Sep 17 18:21:05 voliorbr1 volumio[22628]: info: [SAP] Stream ID generation: 239.69.85.222:5004:286595182 -> f1899ce2 Sep 17 18:21:05 voliorbr1 volumio[22628]: info: [SAP] Parsed sessionId: 309351199 version: 127 from line: o=nax 309351199 127 IN IP4 10.11.7.75 Sep 17 18:21:05 voliorbr1 volumio[22628]: info: [SAP] Stream ID generation: 239.69.85.220:5004:309351199 -> c6cf4a4b Sep 17 18:21:05 voliorbr1 volumio[22628]: info: [SAP] Parsed sessionId: 395539478 version: 127 from line: o=nax 395539478 127 IN IP4 10.11.7.75 Sep 17 18:21:05 voliorbr1 volumio[22628]: info: [SAP] Stream ID generation: 239.69.85.223:5004:395539478 -> d02e9e31 Sep 17 18:21:05 voliorbr1 volumio[22628]: info: [SAP] Parsed sessionId: 523760604 version: 127 from line: o=nax 523760604 127 IN IP4 10.11.7.75 Sep 17 18:21:05 voliorbr1 volumio[22628]: info: [SAP] Stream ID generation: 239.69.85.221:5004:523760604 -> a90b47a0 Sep 17 18:21:05 voliorbr1 volumio[22628]: info: [SAP] Parsed sessionId: 687139007 version: 127 from line: o=nax 687139007 127 IN IP4 10.11.7.75 Sep 17 18:21:05 voliorbr1 volumio[22628]: info: [SAP] Stream ID generation: 239.69.85.225:5004:687139007 -> b8d3d106 Sep 17 18:21:05 voliorbr1 volumio[22628]: info: [SAP] Parsed sessionId: 902015099 version: 127 from line: o=nax 902015099 127 IN IP4 10.11.7.75 Sep 17 18:21:05 voliorbr1 volumio[22628]: info: [SAP] Stream ID generation: 239.69.85.226:5004:902015099 -> 9daf3582 Sep 17 18:21:05 voliorbr1 volumio[22628]: info: [SAP] Parsed sessionId: 902413056 version: 127 from line: o=nax 902413056 127 IN IP4 10.11.7.75 Sep 17 18:21:05 voliorbr1 volumio[22628]: info: [SAP] Stream ID generation: 239.69.85.224:5004:902413056 -> f8d335bb Sep 17 18:21:15 voliorbr1 volumio[22628]: info: [SAP] Parsed sessionId: 239808144 version: 127 from line: o=nax 239808144 127 IN IP4 10.11.7.71 Sep 17 18:21:15 voliorbr1 volumio[22628]: info: [SAP] Stream ID generation: 239.69.85.205:5004:239808144 -> 089d4146 Sep 17 18:21:15 voliorbr1 volumio[22628]: info: [SAP] Parsed sessionId: 333974723 version: 127 from line: o=nax 333974723 127 IN IP4 10.11.7.71 Sep 17 18:21:15 voliorbr1 volumio[22628]: info: [SAP] Stream ID generation: 239.69.85.207:5004:333974723 -> b1ed656d Sep 17 18:21:15 voliorbr1 volumio[22628]: info: [SAP] Parsed sessionId: 518070308 version: 127 from line: o=nax 518070308 127 IN IP4 10.11.7.71 Sep 17 18:21:15 voliorbr1 volumio[22628]: info: [SAP] Stream ID generation: 239.69.86.200:5004:518070308 -> 2b234b35 Sep 17 18:21:15 voliorbr1 volumio[22628]: info: [SAP] Parsed sessionId: 528962874 version: 127 from line: o=nax 528962874 127 IN IP4 10.11.7.71 Sep 17 18:21:15 voliorbr1 volumio[22628]: info: [SAP] Stream ID generation: 239.69.85.201:5004:528962874 -> d1b8799e Sep 17 18:21:15 voliorbr1 volumio[22628]: info: [SAP] Parsed sessionId: 563614365 version: 127 from line: o=nax 563614365 127 IN IP4 10.11.7.71 Sep 17 18:21:15 voliorbr1 volumio[22628]: info: [SAP] Stream ID generation: 239.69.85.202:5004:563614365 -> e75d1a21 Sep 17 18:21:15 voliorbr1 volumio[22628]: info: [SAP] Parsed sessionId: 586996509 version: 127 from line: o=nax 586996509 127 IN IP4 10.11.7.71 Sep 17 18:21:15 voliorbr1 volumio[22628]: info: [SAP] Stream ID generation: 239.69.85.206:5004:586996509 -> 5d2af253 Sep 17 18:21:15 voliorbr1 volumio[22628]: info: [SAP] Parsed sessionId: 821074694 version: 127 from line: o=nax 821074694 127 IN IP4 10.11.7.71 Sep 17 18:21:15 voliorbr1 volumio[22628]: info: [SAP] Stream ID generation: 239.69.85.200:5004:821074694 -> 60d7ed2b Sep 17 18:21:15 voliorbr1 volumio[22628]: info: [SAP] Parsed sessionId: 839938395 version: 127 from line: o=nax 839938395 127 IN IP4 10.11.7.71 Sep 17 18:21:15 voliorbr1 volumio[22628]: info: [SAP] Stream ID generation: 239.69.85.203:5004:839938395 -> cafadb72 Sep 17 18:21:15 voliorbr1 volumio[22628]: info: [SAP] Parsed sessionId: 99619213 version: 127 from line: o=nax 99619213 127 IN IP4 10.11.7.71 Sep 17 18:21:15 voliorbr1 volumio[22628]: info: [SAP] Stream ID generation: 239.69.85.204:5004:99619213 -> bcff4c96 Sep 17 18:21:15 voliorbr1 volumio[22628]: info: [SAP] Parsed sessionId: 1028889 version: 1028899 from line: o=- 1028889 1028899 IN IP4 10.11.7.64 Sep 17 18:21:15 voliorbr1 volumio[22628]: info: [SAP] Stream ID generation: 239.69.3.171:5004:1028889 -> 73d22ac2 Sep 17 18:21:15 voliorbr1 volumio[22628]: info: [SAP] Parsed sessionId: 488443 version: 488453 from line: o=- 488443 488453 IN IP4 10.11.7.64 Sep 17 18:21:15 voliorbr1 volumio[22628]: info: [SAP] Stream ID generation: 239.69.180.57:5004:488443 -> b14511b3 Sep 17 18:21:15 voliorbr1 volumio[22628]: info: [SAP] Parsed sessionId: 1022333 version: 1022343 from line: o=- 1022333 1022343 IN IP4 10.11.7.64 Sep 17 18:21:15 voliorbr1 volumio[22628]: info: [SAP] Stream ID generation: 239.69.65.7:5004:1022333 -> 6c9848b3 Sep 17 18:21:15 voliorbr1 volumio[22628]: info: [SAP] Parsed sessionId: 674495 version: 674505 from line: o=- 674495 674505 IN IP4 10.11.7.64 Sep 17 18:21:15 voliorbr1 volumio[22628]: info: [SAP] Stream ID generation: 239.69.74.227:5004:674495 -> 6b2bcaac Sep 17 18:21:16 voliorbr1 volumio[22628]: info: [SAP] Parsed sessionId: 16138249 version: 127 from line: o=nax 16138249 127 IN IP4 10.11.7.74 Sep 17 18:21:16 voliorbr1 volumio[22628]: info: [SAP] Stream ID generation: 239.69.85.214:5004:16138249 -> 28eab0eb Sep 17 18:21:16 voliorbr1 volumio[22628]: info: [SAP] Parsed sessionId: 172332185 version: 127 from line: o=nax 172332185 127 IN IP4 10.11.7.74 Sep 17 18:21:16 voliorbr1 volumio[22628]: info: [SAP] Stream ID generation: 239.69.85.210:5004:172332185 -> b8802285 Sep 17 18:21:16 voliorbr1 volumio[22628]: info: [SAP] Parsed sessionId: 324703249 version: 127 from line: o=nax 324703249 127 IN IP4 10.11.7.74 Sep 17 18:21:16 voliorbr1 volumio[22628]: info: [SAP] Stream ID generation: 239.69.85.217:5004:324703249 -> aab96f60 Sep 17 18:21:16 voliorbr1 volumio[22628]: info: [SAP] Parsed sessionId: 417606903 version: 127 from line: o=nax 417606903 127 IN IP4 10.11.7.74 Sep 17 18:21:16 voliorbr1 volumio[22628]: info: [SAP] Stream ID generation: 239.69.85.211:5004:417606903 -> e604a4db Sep 17 18:21:16 voliorbr1 volumio[22628]: info: [SAP] Parsed sessionId: 421601304 version: 127 from line: o=nax 421601304 127 IN IP4 10.11.7.74 Sep 17 18:21:16 voliorbr1 volumio[22628]: info: [SAP] Stream ID generation: 239.69.85.213:5004:421601304 -> a0bfc762 Sep 17 18:21:16 voliorbr1 volumio[22628]: info: [SAP] Parsed sessionId: 513147893 version: 127 from line: o=nax 513147893 127 IN IP4 10.11.7.74 Sep 17 18:21:16 voliorbr1 volumio[22628]: info: [SAP] Stream ID generation: 239.69.85.212:5004:513147893 -> 5811a66f Sep 17 18:21:16 voliorbr1 volumio[22628]: info: [SAP] Parsed sessionId: 605167925 version: 127 from line: o=nax 605167925 127 IN IP4 10.11.7.74 Sep 17 18:21:16 voliorbr1 volumio[22628]: info: [SAP] Stream ID generation: 239.69.85.216:5004:605167925 -> 0beddd55 Sep 17 18:21:16 voliorbr1 volumio[22628]: info: [SAP] Parsed sessionId: 665098026 version: 127 from line: o=nax 665098026 127 IN IP4 10.11.7.74 Sep 17 18:21:16 voliorbr1 volumio[22628]: info: [SAP] Stream ID generation: 239.69.85.215:5004:665098026 -> 818262a6 Sep 17 18:21:18 voliorbr1 volumio[22628]: info: [SAP] Parsed sessionId: 681666 version: 681705 from line: o=- 681666 681705 IN IP4 10.11.7.57 Sep 17 18:21:18 voliorbr1 volumio[22628]: info: [SAP] Stream ID generation: 239.69.85.149:5004:681666 -> 25521d81 Sep 17 18:21:22 voliorbr1 volumio[22628]: info: [SAP] Parsed sessionId: 146140997 version: 127 from line: o=nax 146140997 127 IN IP4 10.11.7.75 Sep 17 18:21:22 voliorbr1 volumio[22628]: info: [SAP] Stream ID generation: 239.69.85.227:5004:146140997 -> dff13b4a Sep 17 18:21:22 voliorbr1 volumio[22628]: info: [SAP] Parsed sessionId: 286595182 version: 127 from line: o=nax 286595182 127 IN IP4 10.11.7.75 Sep 17 18:21:22 voliorbr1 volumio[22628]: info: [SAP] Stream ID generation: 239.69.85.222:5004:286595182 -> f1899ce2 Sep 17 18:21:22 voliorbr1 volumio[22628]: info: [SAP] Parsed sessionId: 309351199 version: 127 from line: o=nax 309351199 127 IN IP4 10.11.7.75 Sep 17 18:21:22 voliorbr1 volumio[22628]: info: [SAP] Stream ID generation: 239.69.85.220:5004:309351199 -> c6cf4a4b Sep 17 18:21:22 voliorbr1 volumio[22628]: info: [SAP] Parsed sessionId: 395539478 version: 127 from line: o=nax 395539478 127 IN IP4 10.11.7.75 Sep 17 18:21:22 voliorbr1 volumio[22628]: info: [SAP] Stream ID generation: 239.69.85.223:5004:395539478 -> d02e9e31 Sep 17 18:21:22 voliorbr1 volumio[22628]: info: [SAP] Parsed sessionId: 523760604 version: 127 from line: o=nax 523760604 127 IN IP4 10.11.7.75 Sep 17 18:21:22 voliorbr1 volumio[22628]: info: [SAP] Stream ID generation: 239.69.85.221:5004:523760604 -> a90b47a0 Sep 17 18:21:22 voliorbr1 volumio[22628]: info: [SAP] Parsed sessionId: 687139007 version: 127 from line: o=nax 687139007 127 IN IP4 10.11.7.75 Sep 17 18:21:22 voliorbr1 volumio[22628]: info: [SAP] Stream ID generation: 239.69.85.225:5004:687139007 -> b8d3d106 Sep 17 18:21:22 voliorbr1 volumio[22628]: info: [SAP] Parsed sessionId: 902015099 version: 127 from line: o=nax 902015099 127 IN IP4 10.11.7.75 Sep 17 18:21:22 voliorbr1 volumio[22628]: info: [SAP] Stream ID generation: 239.69.85.226:5004:902015099 -> 9daf3582 Sep 17 18:21:22 voliorbr1 volumio[22628]: info: [SAP] Parsed sessionId: 902413056 version: 127 from line: o=nax 902413056 127 IN IP4 10.11.7.75 Sep 17 18:21:22 voliorbr1 volumio[22628]: info: [SAP] Stream ID generation: 239.69.85.224:5004:902413056 -> f8d335bb Sep 17 18:21:26 voliorbr1 sudo[27096]: volumio : TTY=unknown ; PWD=/home/volumio/rtp-plugin ; USER=root ; COMMAND=/bin/cp /home/volumio/rtp-plugin/index.js /data/plugins/music_service/volumio-aes67/index.js Sep 17 18:21:26 voliorbr1 sudo[27096]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 18:21:26 voliorbr1 sudo[27096]: pam_unix(sudo:session): session closed for user root Sep 17 18:21:26 voliorbr1 sudo[27098]: volumio : TTY=unknown ; PWD=/home/volumio/rtp-plugin ; USER=root ; COMMAND=/bin/systemctl restart volumio Sep 17 18:21:26 voliorbr1 sudo[27098]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 18:21:26 voliorbr1 systemd[1]: Stopping Volumio Backend Module... Sep 17 18:21:26 voliorbr1 volumio-remote-updater[824]: [2025-09-17 18:21:26] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 17 18:21:26 voliorbr1 volumio-remote-updater[824]: [2025-09-17 18:21:26] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 17 18:21:26 voliorbr1 systemd[1]: Started dynamicswap service. Sep 17 18:21:26 voliorbr1 systemd[1]: volumio.service: Main process exited, code=killed, status=15/TERM Sep 17 18:21:26 voliorbr1 systemd[1]: volumio.service: Succeeded. Sep 17 18:21:26 voliorbr1 systemd[1]: Stopped Volumio Backend Module. Sep 17 18:21:26 voliorbr1 systemd[1]: Started Volumio Backend Module. Sep 17 18:21:26 voliorbr1 sudo[27098]: pam_unix(sudo:session): session closed for user root Sep 17 18:21:26 voliorbr1 systemd[1]: dynamicswap.service: Succeeded. Sep 17 18:21:27 voliorbr1 volumio[27104]: info: ------------------------------------------- Sep 17 18:21:27 voliorbr1 volumio[27104]: info: ----- Volumio3 ---- Sep 17 18:21:27 voliorbr1 volumio[27104]: info: ------------------------------------------- Sep 17 18:21:27 voliorbr1 volumio[27104]: info: ----- System startup ---- Sep 17 18:21:27 voliorbr1 volumio[27104]: info: ------------------------------------------- Sep 17 18:21:27 voliorbr1 volumio[27104]: info: MYVOLUMIO Environment detected Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Plugin folders cleanup Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Scanning into folder /volumio/app/plugins/ Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Scanning category audio_interface Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Scanning category miscellanea Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Scanning category music_service Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Scanning category plugins.json Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Scanning category system_controller Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Scanning category user_interface Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Scanning into folder /data/plugins/ Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Scanning category music_service Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Plugin folders cleanup completed Sep 17 18:21:27 voliorbr1 volumio[27104]: info: ------------------------------------------- Sep 17 18:21:27 voliorbr1 volumio[27104]: info: ----- Core plugins startup ---- Sep 17 18:21:27 voliorbr1 volumio[27104]: info: ------------------------------------------- Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Loading plugins from folder /volumio/app/plugins/ Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Adding plugin upnp to MyMusic Plugins Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Loading plugins from folder /data/plugins/ Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Loading plugin "system"... Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Loading plugin "appearance"... Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Loading plugin "network"... Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Refreshing Cached IP Addresses Sep 17 18:21:27 voliorbr1 sudo[27129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 17 18:21:27 voliorbr1 sudo[27129]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 18:21:27 voliorbr1 sudo[27129]: pam_unix(sudo:session): session closed for user root Sep 17 18:21:27 voliorbr1 sudo[27131]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 17 18:21:27 voliorbr1 sudo[27131]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 18:21:27 voliorbr1 sudo[27131]: pam_unix(sudo:session): session closed for user root Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Loading plugin "services"... Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Loading plugin "alsa_controller"... Sep 17 18:21:27 voliorbr1 sudo[27144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 17 18:21:27 voliorbr1 sudo[27144]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 18:21:27 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Loading plugin "wizard"... Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Loading plugin "networkfs"... Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Starting Udev Watcher for removable devices Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Ignoring mount for partition: boot Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Ignoring mount for partition: volumio Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Ignoring mount for partition: volumio_data Sep 17 18:21:27 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Loading plugin "volumio_command_line_client"... Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Loading plugin "upnp"... Sep 17 18:21:27 voliorbr1 volumio[27104]: info: [1758147687494] Starting Upmpd Daemon Sep 17 18:21:27 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Loading plugin "my_music"... Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Loading plugin "mpd"... Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Loading plugin "upnp_browser"... Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Loading plugin "alarm-clock"... Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Loading plugin "airplay_emulation"... Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Starting Shairport Sync Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Loading plugin "last_100"... Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Loading plugin "webradio"... Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Loading plugin "i2s_dacs"... Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Loading plugin "volumiodiscovery"... Sep 17 18:21:27 voliorbr1 volumio[27104]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 17 18:21:27 voliorbr1 node[27104]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 17 18:21:27 voliorbr1 node[27104]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 17 18:21:27 voliorbr1 volumio[27104]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 17 18:21:27 voliorbr1 volumio[27104]: *** WARNING *** For more information see Sep 17 18:21:27 voliorbr1 volumio[27104]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 17 18:21:27 voliorbr1 volumio[27104]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 17 18:21:27 voliorbr1 volumio[27104]: *** WARNING *** For more information see Sep 17 18:21:27 voliorbr1 node[27104]: *** WARNING *** For more information see Sep 17 18:21:27 voliorbr1 node[27104]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 17 18:21:27 voliorbr1 node[27104]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 17 18:21:27 voliorbr1 node[27104]: *** WARNING *** For more information see Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Discovery: Started advertising with name: voliorbr1 Sep 17 18:21:27 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Loading plugin "volumio-aes67"... Sep 17 18:21:27 voliorbr1 volumio[27104]: info: [AES67] Plugin starting... Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Loading plugin "outputs"... Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Loading plugin "albumart"... Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Plugin example_plugin is not enabled Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Loading plugin "inputs"... Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Loading plugin "updater_comm"... Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Plugin mpdemulation is not enabled Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Loading plugin "rest_api"... Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Loading plugin "websocket"... Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Starting Socket.io Server version 2.3.0 Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Loading i18n strings for locale en Sep 17 18:21:27 voliorbr1 volumio[27104]: Updating browse sources language Sep 17 18:21:27 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 17 18:21:27 voliorbr1 volumio[27104]: Forking 3 albumart workers Sep 17 18:21:27 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 17 18:21:27 voliorbr1 volumio[27104]: info: CoreCommandRouter::initPlayerControls Sep 17 18:21:27 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 18:21:27 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 18:21:27 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 18:21:27 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 18:21:27 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 18:21:27 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 18:21:27 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 18:21:27 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 18:21:27 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 17 18:21:27 voliorbr1 volumio[27104]: Express server listening on port 3000 Sep 17 18:21:27 voliorbr1 volumio[27104]: [Metrics] WebUI: 0s 917.28ms Sep 17 18:21:27 voliorbr1 volumio[27104]: info: CoreStateMachine::resetVolumioState Sep 17 18:21:27 voliorbr1 volumio[27104]: info: CoreStateMachine::getcurrentVolume Sep 17 18:21:27 voliorbr1 volumio[27104]: info: CoreCommandRouter::volumioRetrievevolume Sep 17 18:21:27 voliorbr1 volumio[27104]: info: [AES67] Plugin started successfully Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Volumio Network Manager: Network status updated: 3 Sep 17 18:21:27 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Reloading queue from file Sep 17 18:21:27 voliorbr1 volumio[27104]: info: CoreStateMachine::setRepeat true single undefined Sep 17 18:21:27 voliorbr1 volumio[27104]: info: CoreStateMachine::pushState Sep 17 18:21:27 voliorbr1 volumio[27104]: info: CorePlayQueue::getTrack 0 Sep 17 18:21:27 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:21:27 voliorbr1 volumio[27104]: info: CoreCommandRouter::volumioPushState Sep 17 18:21:27 voliorbr1 volumio[27104]: info: CoreStateMachine::setRandom null Sep 17 18:21:27 voliorbr1 volumio[27104]: info: CoreStateMachine::pushState Sep 17 18:21:27 voliorbr1 volumio[27104]: info: CorePlayQueue::getTrack 0 Sep 17 18:21:27 voliorbr1 volumio[27104]: info: CoreCommandRouter::volumioPushState Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Setting Device type: Raspberry PI Sep 17 18:21:27 voliorbr1 volumio[27104]: info: VolumeController:: Volume=0 Mute =false Sep 17 18:21:27 voliorbr1 volumio[27104]: info: CoreStateMachine::pushState Sep 17 18:21:27 voliorbr1 volumio[27104]: info: CorePlayQueue::getTrack 0 Sep 17 18:21:27 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:21:27 voliorbr1 volumio[27104]: info: CoreCommandRouter::volumioPushState Sep 17 18:21:27 voliorbr1 volumio[27104]: info: CoreStateMachine::updateTrackBlock Sep 17 18:21:27 voliorbr1 volumio[27104]: info: CorePlayQueue::getTrackBlock Sep 17 18:21:27 voliorbr1 volumio[27104]: info: CoreCommandRouter::volumioRetrievevolume Sep 17 18:21:27 voliorbr1 volumio[27104]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Sep 17 18:21:27 voliorbr1 volumio[27104]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d04170 Sep 17 18:21:27 voliorbr1 volumio[27104]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Completed loading Core Plugins Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Preparing to generate the ALSA configuration file Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Asound.conf file unchanged, so no further update is needed Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Output device has changed, restarting MPD Sep 17 18:21:27 voliorbr1 sudo[27200]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 17 18:21:27 voliorbr1 sudo[27200]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 18:21:27 voliorbr1 sudo[27200]: pam_unix(sudo:session): session closed for user root Sep 17 18:21:27 voliorbr1 volumio[27104]: info: Output device has changed, restarting Shairport Sync Sep 17 18:21:27 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 18:21:27 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 18:21:28 voliorbr1 sudo[27203]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 17 18:21:28 voliorbr1 sudo[27203]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 18:21:28 voliorbr1 systemd[1]: Stopping Music Player Daemon... Sep 17 18:21:28 voliorbr1 volumio[27104]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 17 18:21:28 voliorbr1 volumio[27104]: info: ___________ START PLUGINS ___________ Sep 17 18:21:28 voliorbr1 volumio[27104]: info: ControllerMpd::onStart: Initializing MPD Sep 17 18:21:28 voliorbr1 volumio[27104]: info: Creating MPD Configuration file Sep 17 18:21:28 voliorbr1 systemd[1]: mpd.service: Succeeded. Sep 17 18:21:28 voliorbr1 systemd[1]: Stopped Music Player Daemon. Sep 17 18:21:28 voliorbr1 sudo[27209]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 17 18:21:28 voliorbr1 volumio[27104]: info: [1758147688036] CoreMusicLibrary::Adding element Media Servers Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 17 18:21:28 voliorbr1 systemd[1]: Starting Music Player Daemon... Sep 17 18:21:28 voliorbr1 sudo[27209]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 18:21:28 voliorbr1 sudo[27209]: pam_unix(sudo:session): session closed for user root Sep 17 18:21:28 voliorbr1 sudo[27213]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 17 18:21:28 voliorbr1 sudo[27213]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 18:21:28 voliorbr1 volumio[27104]: Starting albumart workers Sep 17 18:21:28 voliorbr1 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 17 18:21:28 voliorbr1 systemd[1]: mpd.service: Succeeded. Sep 17 18:21:28 voliorbr1 volumio[27104]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 17 18:21:28 voliorbr1 systemd[1]: Stopped Music Player Daemon. Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 18:21:28 voliorbr1 systemd[1]: Starting Music Player Daemon... Sep 17 18:21:28 voliorbr1 volumio[27104]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 17 18:21:28 voliorbr1 volumio[27104]: info: [1758147688095] CoreMusicLibrary::Adding element Last_100 Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 17 18:21:28 voliorbr1 volumio[27104]: info: [1758147688097] CoreMusicLibrary::Adding element Webradio Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 17 18:21:28 voliorbr1 volumio[27104]: info: Initializing BBC Radios Sep 17 18:21:28 voliorbr1 sudo[27218]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 17 18:21:28 voliorbr1 sudo[27218]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 18:21:28 voliorbr1 sudo[27218]: pam_unix(sudo:session): session closed for user root Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 17 18:21:28 voliorbr1 volumio[27104]: info: [1758147688176] CoreMusicLibrary::Adding element AES67 Streams Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 17 18:21:28 voliorbr1 volumio[27104]: Starting albumart workers Sep 17 18:21:28 voliorbr1 volumio[27104]: Cannot find translation for source AES67 Streams Sep 17 18:21:28 voliorbr1 volumio[27104]: Starting albumart workers Sep 17 18:21:28 voliorbr1 volumio[27104]: warn: [AES67] Socket.io not available, websocket features disabled Sep 17 18:21:28 voliorbr1 volumio[27104]: info: [SAP] Discovery started Sep 17 18:21:28 voliorbr1 ptp4l[27269]: [16223.598] failed to set the clock status: Operation not permitted Sep 17 18:21:28 voliorbr1 ptp4l[27269]: [16223.598] failed to adjust the clock: Operation not permitted Sep 17 18:21:28 voliorbr1 ptp4l[27269]: [16223.598] socket failed: Operation not permitted Sep 17 18:21:28 voliorbr1 ptp4l[27269]: [16223.598] port 1: INITIALIZING to FAULTY on FAULT_DETECTED (FT_UNSPECIFIED) Sep 17 18:21:28 voliorbr1 ptp4l[27269]: [16223.598] uds: bind failed: Address already in use Sep 17 18:21:28 voliorbr1 ptp4l[27269]: [16223.598] port 0: INITIALIZING to FAULTY on FAULT_DETECTED (FT_UNSPECIFIED) Sep 17 18:21:28 voliorbr1 volumio[27104]: info: Volumio Calling Home Sep 17 18:21:28 voliorbr1 volumio[27104]: info: [SAP] Listening on 0.0.0.0:9875 Sep 17 18:21:28 voliorbr1 volumio[27104]: info: [PTP] ptp4l started Sep 17 18:21:28 voliorbr1 volumio[27104]: error: [PTP] phc2sys exited (code: 255, signal: null) Sep 17 18:21:28 voliorbr1 volumio[27104]: info: MPD Permissions set Sep 17 18:21:28 voliorbr1 volumio[27104]: info: MPD Permissions set Sep 17 18:21:28 voliorbr1 volumio[27104]: info: VolumeController:: Volume=0 Mute =false Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreStateMachine::pushState Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CorePlayQueue::getTrack 0 Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::volumioPushState Sep 17 18:21:28 voliorbr1 volumio[27104]: verbose: New Socket.io Connection to 10.11.7.81 from 10.11.1.73 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Sep 17 18:21:28 voliorbr1 volumio[27104]: error: error Sep 17 18:21:28 voliorbr1 volumio[27104]: error: error Sep 17 18:21:28 voliorbr1 volumio[27104]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::volumioGetVisibleSources Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::volumioGetState Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CorePlayQueue::getTrack 0 Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::volumioGetState Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CorePlayQueue::getTrack 0 Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 17 18:21:28 voliorbr1 volumio[27104]: info: Received Get System Info Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 17 18:21:28 voliorbr1 volumio[27104]: info: Discovery: Getting this device information Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::volumioGetState Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CorePlayQueue::getTrack 0 Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::volumioGetState Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CorePlayQueue::getTrack 0 Sep 17 18:21:28 voliorbr1 volumio[27104]: info: Listing playlists Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::volumioGetQueue Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreStateMachine::getQueue Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CorePlayQueue::getQueue Sep 17 18:21:28 voliorbr1 mpd[27232]: Sep 17 18:21 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 17 18:21:28 voliorbr1 systemd[1]: Started Music Player Daemon. Sep 17 18:21:28 voliorbr1 sudo[27213]: pam_unix(sudo:session): session closed for user root Sep 17 18:21:28 voliorbr1 sudo[27203]: pam_unix(sudo:session): session closed for user root Sep 17 18:21:28 voliorbr1 volumio[27104]: info: Completed starting Core Plugins Sep 17 18:21:28 voliorbr1 volumio[27104]: info: ------------------------------------------- Sep 17 18:21:28 voliorbr1 volumio[27104]: info: ----- MyVolumio plugins startup ---- Sep 17 18:21:28 voliorbr1 volumio[27104]: info: ------------------------------------------- Sep 17 18:21:28 voliorbr1 volumio[27104]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 17 18:21:28 voliorbr1 volumio[27104]: info: Volumio called home Sep 17 18:21:28 voliorbr1 volumio[27104]: info: Starting Shairport Sync Sep 17 18:21:28 voliorbr1 volumio[27104]: info: Starting Shairport Sync Sep 17 18:21:28 voliorbr1 sudo[27314]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 17 18:21:28 voliorbr1 sudo[27314]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 18:21:28 voliorbr1 volumio[27104]: info: Starting Shairport Sync Sep 17 18:21:28 voliorbr1 sudo[27317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 17 18:21:28 voliorbr1 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 17 18:21:28 voliorbr1 sudo[27317]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 18:21:28 voliorbr1 systemd[1]: shairport-sync.service: Succeeded. Sep 17 18:21:28 voliorbr1 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 17 18:21:28 voliorbr1 sudo[27320]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 17 18:21:28 voliorbr1 sudo[27320]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::volumioGetState Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CorePlayQueue::getTrack 0 Sep 17 18:21:28 voliorbr1 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 17 18:21:28 voliorbr1 sudo[27314]: pam_unix(sudo:session): session closed for user root Sep 17 18:21:28 voliorbr1 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 17 18:21:28 voliorbr1 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Sep 17 18:21:28 voliorbr1 systemd[1]: shairport-sync.service: Succeeded. Sep 17 18:21:28 voliorbr1 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 17 18:21:28 voliorbr1 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 17 18:21:28 voliorbr1 sudo[27317]: pam_unix(sudo:session): session closed for user root Sep 17 18:21:28 voliorbr1 sudo[27320]: pam_unix(sudo:session): session closed for user root Sep 17 18:21:28 voliorbr1 volumio[27104]: error: MPD error: The expression evaluated to a falsy value: Sep 17 18:21:28 voliorbr1 volumio[27104]: assert.ok(self.idling) Sep 17 18:21:28 voliorbr1 volumio[27104]: error: The expression evaluated to a falsy value: Sep 17 18:21:28 voliorbr1 volumio[27104]: assert.ok(self.idling) Sep 17 18:21:28 voliorbr1 volumio[27104]: verbose: New Socket.io Connection to 10.11.7.81 from 10.11.3.59 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Sep 17 18:21:28 voliorbr1 volumio[27104]: info: MPD running with PID27232 Sep 17 18:21:28 voliorbr1 volumio[27104]: ,establishing connection Sep 17 18:21:28 voliorbr1 volumio[27104]: error: updateQueue error: null Sep 17 18:21:28 voliorbr1 volumio[27104]: info: Shairport-Sync Started Sep 17 18:21:28 voliorbr1 volumio[27104]: Error adding Membership: Error: addMembership EINVAL Sep 17 18:21:28 voliorbr1 volumio[27104]: info: Shairport-Sync Started Sep 17 18:21:28 voliorbr1 volumio[27104]: info: Shairport-Sync Started Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 17 18:21:28 voliorbr1 volumio[27104]: error: updateQueue error: null Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::volumioGetVisibleSources Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::volumioGetState Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CorePlayQueue::getTrack 0 Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::volumioGetQueue Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreStateMachine::getQueue Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CorePlayQueue::getQueue Sep 17 18:21:28 voliorbr1 volumio[27104]: info: Listing playlists Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 17 18:21:28 voliorbr1 volumio[27104]: info: Received Get System Info Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 17 18:21:28 voliorbr1 volumio[27104]: info: Discovery: Getting this device information Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::volumioGetState Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CorePlayQueue::getTrack 0 Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::volumioGetState Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CorePlayQueue::getTrack 0 Sep 17 18:21:28 voliorbr1 volumio[27104]: info: Discovery: adding 42eae336-2999-453f-aff5-1ad15ac0026a Sep 17 18:21:28 voliorbr1 volumio[27104]: info: Discovery: Found device voliorbr1 Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::volumioGetState Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CorePlayQueue::getTrack 0 Sep 17 18:21:28 voliorbr1 volumio[27104]: info: Discovery: this is already registered, 42eae336-2999-453f-aff5-1ad15ac0026a Sep 17 18:21:28 voliorbr1 volumio[27104]: info: Discovery: Found device voliorbr1 Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CoreCommandRouter::volumioGetState Sep 17 18:21:28 voliorbr1 volumio[27104]: info: CorePlayQueue::getTrack 0 Sep 17 18:21:31 voliorbr1 sudo[27144]: pam_unix(sudo:session): session closed for user root Sep 17 18:21:31 voliorbr1 volumio-remote-updater[824]: [2025-09-17 18:21:31] [connect] Successful connection Sep 17 18:21:31 voliorbr1 volumio-remote-updater[824]: [2025-09-17 18:21:31] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1758147691 101 Sep 17 18:21:31 voliorbr1 volumio[27104]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 4 Sep 17 18:21:32 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 17 18:21:32 voliorbr1 volumio[27104]: info: Received Get System Info Sep 17 18:21:32 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 17 18:21:32 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 17 18:21:32 voliorbr1 volumio[27104]: info: Discovery: Getting this device information Sep 17 18:21:32 voliorbr1 volumio[27104]: info: CoreCommandRouter::volumioGetState Sep 17 18:21:32 voliorbr1 volumio[27104]: info: CorePlayQueue::getTrack 0 Sep 17 18:21:32 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 17 18:21:33 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Sep 17 18:21:33 voliorbr1 volumio[27104]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 239808144 version: 127 from line: o=nax 239808144 127 IN IP4 10.11.7.71 Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.205:5004:239808144 -> 089d4146 Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [SAP] Discovered stream: MediaStreamNax1Player6 from 10.11.7.71 Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [AES67] Stream discovered: MediaStreamNax1Player6 Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 333974723 version: 127 from line: o=nax 333974723 127 IN IP4 10.11.7.71 Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.207:5004:333974723 -> b1ed656d Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [SAP] Discovered stream: MediaStreamNax1Player8 from 10.11.7.71 Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [AES67] Stream discovered: MediaStreamNax1Player8 Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 518070308 version: 127 from line: o=nax 518070308 127 IN IP4 10.11.7.71 Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.86.200:5004:518070308 -> 2b234b35 Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [SAP] Discovered stream: NaxRobotLab from 10.11.7.71 Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [AES67] Stream discovered: NaxRobotLab Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 528962874 version: 127 from line: o=nax 528962874 127 IN IP4 10.11.7.71 Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.201:5004:528962874 -> d1b8799e Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [SAP] Discovered stream: MediaStreamNax1Player2 from 10.11.7.71 Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [AES67] Stream discovered: MediaStreamNax1Player2 Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 563614365 version: 127 from line: o=nax 563614365 127 IN IP4 10.11.7.71 Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.202:5004:563614365 -> e75d1a21 Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [SAP] Discovered stream: MediaStreamNax1Player3 from 10.11.7.71 Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [AES67] Stream discovered: MediaStreamNax1Player3 Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 586996509 version: 127 from line: o=nax 586996509 127 IN IP4 10.11.7.71 Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.206:5004:586996509 -> 5d2af253 Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [SAP] Discovered stream: MediaStreamNax1Player7 from 10.11.7.71 Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [AES67] Stream discovered: MediaStreamNax1Player7 Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 821074694 version: 127 from line: o=nax 821074694 127 IN IP4 10.11.7.71 Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.200:5004:821074694 -> 60d7ed2b Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [SAP] Discovered stream: MediaStreamNax1Player1 from 10.11.7.71 Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [AES67] Stream discovered: MediaStreamNax1Player1 Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 839938395 version: 127 from line: o=nax 839938395 127 IN IP4 10.11.7.71 Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.203:5004:839938395 -> cafadb72 Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [SAP] Discovered stream: MediaStreamNax1Player4 from 10.11.7.71 Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [AES67] Stream discovered: MediaStreamNax1Player4 Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 99619213 version: 127 from line: o=nax 99619213 127 IN IP4 10.11.7.71 Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.204:5004:99619213 -> bcff4c96 Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [SAP] Discovered stream: MediaStreamNax1Player5 from 10.11.7.71 Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [AES67] Stream discovered: MediaStreamNax1Player5 Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Sep 17 18:21:36 voliorbr1 volumio[27104]: info: Adding plugin bluetooth to MyMusic Plugins Sep 17 18:21:36 voliorbr1 volumio[27104]: info: Adding plugin multiroom to MyMusic Plugins Sep 17 18:21:36 voliorbr1 volumio[27104]: info: Adding plugin metavolumio to MyMusic Plugins Sep 17 18:21:36 voliorbr1 volumio[27104]: info: Adding plugin cd_controller to MyMusic Plugins Sep 17 18:21:36 voliorbr1 volumio[27104]: info: Adding plugin qobuzconnect to MyMusic Plugins Sep 17 18:21:36 voliorbr1 volumio[27104]: info: Adding plugin smart_inputs to MyMusic Plugins Sep 17 18:21:36 voliorbr1 volumio[27104]: info: Adding plugin tidalconnect to MyMusic Plugins Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Sep 17 18:21:36 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 18:21:36 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 18:21:36 voliorbr1 volumio[27104]: info: Starting MyVolumio Remote Streaming Endpoints Sep 17 18:21:36 voliorbr1 volumio[27104]: info: MyVolumio login type: Token Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Sep 17 18:21:36 voliorbr1 volumio[27104]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Sep 17 18:21:37 voliorbr1 volumio[27104]: info: Starting Streaming Service Transparent Proxy Sep 17 18:21:37 voliorbr1 volumio[27104]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Sep 17 18:21:37 voliorbr1 volumio[27104]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Sep 17 18:21:37 voliorbr1 volumio[27104]: info: Streaming services startup Sep 17 18:21:37 voliorbr1 volumio[27104]: info: Starting Streaming Daemon Sep 17 18:21:37 voliorbr1 sudo[27339]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 17 18:21:37 voliorbr1 sudo[27339]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 18:21:37 voliorbr1 sudo[27339]: pam_unix(sudo:session): session closed for user root Sep 17 18:21:37 voliorbr1 volumio[27104]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Sep 17 18:21:37 voliorbr1 volumio[27104]: error: Cannot start Volumio Streaming Daemon Sep 17 18:21:37 voliorbr1 volumio[27104]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 17 18:21:37 voliorbr1 volumio[27104]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 17 18:21:37 voliorbr1 volumio[27104]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Sep 17 18:21:37 voliorbr1 volumio[27104]: STREAMING PROXY: Starting server on port 3245 Sep 17 18:21:37 voliorbr1 volumio[27104]: Node JS runtime: 14 Sep 17 18:21:37 voliorbr1 sudo[27364]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 17 18:21:37 voliorbr1 sudo[27364]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 18:21:37 voliorbr1 sudo[27364]: pam_unix(sudo:session): session closed for user root Sep 17 18:21:37 voliorbr1 sudo[27367]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 17 18:21:37 voliorbr1 sudo[27367]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 18:21:37 voliorbr1 sudo[27367]: pam_unix(sudo:session): session closed for user root Sep 17 18:21:37 voliorbr1 sudo[27370]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 17 18:21:37 voliorbr1 sudo[27370]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 18:21:37 voliorbr1 sudo[27370]: pam_unix(sudo:session): session closed for user root Sep 17 18:21:37 voliorbr1 volumio[27104]: info: Upmpdcli Daemon Started Sep 17 18:21:37 voliorbr1 volumio[27104]: info: MyVolumio token set successfully Sep 17 18:21:37 voliorbr1 volumio[27104]: info: MYVOLUMIO: Adding device Sep 17 18:21:37 voliorbr1 volumio[27104]: info: MYVOLUMIO: Evaluating Server Sep 17 18:21:37 voliorbr1 volumio[27104]: info: MyVolumio status changed Sep 17 18:21:37 voliorbr1 volumio[27104]: info: Streaming services startup Sep 17 18:21:37 voliorbr1 volumio[27104]: info: Starting Streaming Daemon Sep 17 18:21:37 voliorbr1 volumio[27104]: info: Removing browser output: myVolumio user plan is not superstar Sep 17 18:21:37 voliorbr1 volumio[27104]: info: Removing audio output: Sep 17 18:21:37 voliorbr1 volumio[27104]: info: Stoppping Tunnel 1 Sep 17 18:21:37 voliorbr1 sudo[27390]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 17 18:21:37 voliorbr1 sudo[27390]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 18:21:37 voliorbr1 sudo[27390]: pam_unix(sudo:session): session closed for user root Sep 17 18:21:37 voliorbr1 sudo[27393]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Sep 17 18:21:37 voliorbr1 volumio[27104]: error: Cannot start Volumio Streaming Daemon Sep 17 18:21:37 voliorbr1 sudo[27393]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 18:21:37 voliorbr1 volumio[27104]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 17 18:21:37 voliorbr1 volumio[27104]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 17 18:21:37 voliorbr1 sudo[27393]: pam_unix(sudo:session): session closed for user root Sep 17 18:21:37 voliorbr1 volumio[27104]: info: Remote SSH Stopped Sep 17 18:21:38 voliorbr1 volumio[27104]: info: Setting Geolocation for MyVolumio to us1 Sep 17 18:21:38 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 18:21:38 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 18:21:38 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 18:21:38 voliorbr1 volumio[27104]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Sep 17 18:21:39 voliorbr1 volumio[27104]: info: Updating MyVolumio device info Sep 17 18:21:39 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 18:21:39 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 18:21:39 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 18:21:39 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 146140997 version: 127 from line: o=nax 146140997 127 IN IP4 10.11.7.75 Sep 17 18:21:39 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.227:5004:146140997 -> dff13b4a Sep 17 18:21:39 voliorbr1 volumio[27104]: info: [SAP] Discovered stream: MediaStreamNax3Player8 from 10.11.7.75 Sep 17 18:21:39 voliorbr1 volumio[27104]: info: [AES67] Stream discovered: MediaStreamNax3Player8 Sep 17 18:21:39 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 286595182 version: 127 from line: o=nax 286595182 127 IN IP4 10.11.7.75 Sep 17 18:21:39 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.222:5004:286595182 -> f1899ce2 Sep 17 18:21:39 voliorbr1 volumio[27104]: info: [SAP] Discovered stream: MediaStreamNax3Player3 from 10.11.7.75 Sep 17 18:21:39 voliorbr1 volumio[27104]: info: [AES67] Stream discovered: MediaStreamNax3Player3 Sep 17 18:21:39 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 309351199 version: 127 from line: o=nax 309351199 127 IN IP4 10.11.7.75 Sep 17 18:21:39 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.220:5004:309351199 -> c6cf4a4b Sep 17 18:21:39 voliorbr1 volumio[27104]: info: [SAP] Discovered stream: MediaStreamNax3Player1 from 10.11.7.75 Sep 17 18:21:39 voliorbr1 volumio[27104]: info: [AES67] Stream discovered: MediaStreamNax3Player1 Sep 17 18:21:39 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 395539478 version: 127 from line: o=nax 395539478 127 IN IP4 10.11.7.75 Sep 17 18:21:39 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.223:5004:395539478 -> d02e9e31 Sep 17 18:21:39 voliorbr1 volumio[27104]: info: [SAP] Discovered stream: MediaStreamNax3Player4 from 10.11.7.75 Sep 17 18:21:39 voliorbr1 volumio[27104]: info: [AES67] Stream discovered: MediaStreamNax3Player4 Sep 17 18:21:39 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 523760604 version: 127 from line: o=nax 523760604 127 IN IP4 10.11.7.75 Sep 17 18:21:39 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.221:5004:523760604 -> a90b47a0 Sep 17 18:21:39 voliorbr1 volumio[27104]: info: [SAP] Discovered stream: MediaStreamNax3Player2 from 10.11.7.75 Sep 17 18:21:39 voliorbr1 volumio[27104]: info: [AES67] Stream discovered: MediaStreamNax3Player2 Sep 17 18:21:39 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 687139007 version: 127 from line: o=nax 687139007 127 IN IP4 10.11.7.75 Sep 17 18:21:39 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.225:5004:687139007 -> b8d3d106 Sep 17 18:21:39 voliorbr1 volumio[27104]: info: [SAP] Discovered stream: MediaStreamNax3Player6 from 10.11.7.75 Sep 17 18:21:39 voliorbr1 volumio[27104]: info: [AES67] Stream discovered: MediaStreamNax3Player6 Sep 17 18:21:39 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 902015099 version: 127 from line: o=nax 902015099 127 IN IP4 10.11.7.75 Sep 17 18:21:39 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.226:5004:902015099 -> 9daf3582 Sep 17 18:21:39 voliorbr1 volumio[27104]: info: [SAP] Discovered stream: MediaStreamNax3Player7 from 10.11.7.75 Sep 17 18:21:39 voliorbr1 volumio[27104]: info: [AES67] Stream discovered: MediaStreamNax3Player7 Sep 17 18:21:39 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 902413056 version: 127 from line: o=nax 902413056 127 IN IP4 10.11.7.75 Sep 17 18:21:39 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.224:5004:902413056 -> f8d335bb Sep 17 18:21:39 voliorbr1 volumio[27104]: info: [SAP] Discovered stream: MediaStreamNax3Player5 from 10.11.7.75 Sep 17 18:21:39 voliorbr1 volumio[27104]: info: [AES67] Stream discovered: MediaStreamNax3Player5 Sep 17 18:21:39 voliorbr1 volumio[27104]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Sep 17 18:21:42 voliorbr1 volumio[27104]: info: MYVOLUMIO: Adding device Sep 17 18:21:42 voliorbr1 volumio[27104]: info: MYVOLUMIO: Evaluating Server Sep 17 18:21:43 voliorbr1 volumio[27104]: info: Setting Geolocation for MyVolumio to us1 Sep 17 18:21:43 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 18:21:43 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 18:21:43 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 18:21:43 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 16138249 version: 127 from line: o=nax 16138249 127 IN IP4 10.11.7.74 Sep 17 18:21:43 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.214:5004:16138249 -> 28eab0eb Sep 17 18:21:43 voliorbr1 volumio[27104]: info: [SAP] Discovered stream: MediaStreamNax2Player5 from 10.11.7.74 Sep 17 18:21:43 voliorbr1 volumio[27104]: info: [AES67] Stream discovered: MediaStreamNax2Player5 Sep 17 18:21:43 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 172332185 version: 127 from line: o=nax 172332185 127 IN IP4 10.11.7.74 Sep 17 18:21:43 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.210:5004:172332185 -> b8802285 Sep 17 18:21:43 voliorbr1 volumio[27104]: info: [SAP] Discovered stream: MediaStreamNax2Player1 from 10.11.7.74 Sep 17 18:21:43 voliorbr1 volumio[27104]: info: [AES67] Stream discovered: MediaStreamNax2Player1 Sep 17 18:21:43 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 324703249 version: 127 from line: o=nax 324703249 127 IN IP4 10.11.7.74 Sep 17 18:21:43 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.217:5004:324703249 -> aab96f60 Sep 17 18:21:43 voliorbr1 volumio[27104]: info: [SAP] Discovered stream: MediaStreamNax2Player8 from 10.11.7.74 Sep 17 18:21:43 voliorbr1 volumio[27104]: info: [AES67] Stream discovered: MediaStreamNax2Player8 Sep 17 18:21:43 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 417606903 version: 127 from line: o=nax 417606903 127 IN IP4 10.11.7.74 Sep 17 18:21:43 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.211:5004:417606903 -> e604a4db Sep 17 18:21:43 voliorbr1 volumio[27104]: info: [SAP] Discovered stream: MediaStreamNax2Player2 from 10.11.7.74 Sep 17 18:21:43 voliorbr1 volumio[27104]: info: [AES67] Stream discovered: MediaStreamNax2Player2 Sep 17 18:21:43 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 421601304 version: 127 from line: o=nax 421601304 127 IN IP4 10.11.7.74 Sep 17 18:21:43 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.213:5004:421601304 -> a0bfc762 Sep 17 18:21:43 voliorbr1 volumio[27104]: info: [SAP] Discovered stream: MediaStreamNax2Player4 from 10.11.7.74 Sep 17 18:21:43 voliorbr1 volumio[27104]: info: [AES67] Stream discovered: MediaStreamNax2Player4 Sep 17 18:21:43 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 513147893 version: 127 from line: o=nax 513147893 127 IN IP4 10.11.7.74 Sep 17 18:21:43 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.212:5004:513147893 -> 5811a66f Sep 17 18:21:43 voliorbr1 volumio[27104]: info: [SAP] Discovered stream: MediaStreamNax2Player3 from 10.11.7.74 Sep 17 18:21:43 voliorbr1 volumio[27104]: info: [AES67] Stream discovered: MediaStreamNax2Player3 Sep 17 18:21:43 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 605167925 version: 127 from line: o=nax 605167925 127 IN IP4 10.11.7.74 Sep 17 18:21:43 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.216:5004:605167925 -> 0beddd55 Sep 17 18:21:43 voliorbr1 volumio[27104]: info: [SAP] Discovered stream: MediaStreamNax2Player7 from 10.11.7.74 Sep 17 18:21:43 voliorbr1 volumio[27104]: info: [AES67] Stream discovered: MediaStreamNax2Player7 Sep 17 18:21:43 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 665098026 version: 127 from line: o=nax 665098026 127 IN IP4 10.11.7.74 Sep 17 18:21:43 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.215:5004:665098026 -> 818262a6 Sep 17 18:21:43 voliorbr1 volumio[27104]: info: [SAP] Discovered stream: MediaStreamNax2Player6 from 10.11.7.74 Sep 17 18:21:43 voliorbr1 volumio[27104]: info: [AES67] Stream discovered: MediaStreamNax2Player6 Sep 17 18:21:43 voliorbr1 volumio[27104]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Sep 17 18:21:44 voliorbr1 volumio[27104]: info: Updating MyVolumio device info Sep 17 18:21:44 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 18:21:44 voliorbr1 volumio[27104]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Sep 17 18:21:45 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 1028889 version: 1028899 from line: o=- 1028889 1028899 IN IP4 10.11.7.64 Sep 17 18:21:45 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.3.171:5004:1028889 -> 73d22ac2 Sep 17 18:21:45 voliorbr1 volumio[27104]: info: [SAP] Discovered stream: danterbr11-villa-tascom : 25 from 10.11.7.64 Sep 17 18:21:45 voliorbr1 volumio[27104]: info: [AES67] Stream discovered: danterbr11-villa-tascom : 25 Sep 17 18:21:45 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 488443 version: 488453 from line: o=- 488443 488453 IN IP4 10.11.7.64 Sep 17 18:21:45 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.180.57:5004:488443 -> b14511b3 Sep 17 18:21:45 voliorbr1 volumio[27104]: info: [SAP] Discovered stream: danterbr11-villa-tascom : 27 from 10.11.7.64 Sep 17 18:21:45 voliorbr1 volumio[27104]: info: [AES67] Stream discovered: danterbr11-villa-tascom : 27 Sep 17 18:21:45 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 1022333 version: 1022343 from line: o=- 1022333 1022343 IN IP4 10.11.7.64 Sep 17 18:21:45 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.65.7:5004:1022333 -> 6c9848b3 Sep 17 18:21:45 voliorbr1 volumio[27104]: info: [SAP] Discovered stream: danterbr11-villa-tascom : 29 from 10.11.7.64 Sep 17 18:21:45 voliorbr1 volumio[27104]: info: [AES67] Stream discovered: danterbr11-villa-tascom : 29 Sep 17 18:21:45 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 674495 version: 674505 from line: o=- 674495 674505 IN IP4 10.11.7.64 Sep 17 18:21:45 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.74.227:5004:674495 -> 6b2bcaac Sep 17 18:21:45 voliorbr1 volumio[27104]: info: [SAP] Discovered stream: danterbr11-villa-tascom : 31 from 10.11.7.64 Sep 17 18:21:45 voliorbr1 volumio[27104]: info: [AES67] Stream discovered: danterbr11-villa-tascom : 31 Sep 17 18:21:48 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 681666 version: 681705 from line: o=- 681666 681705 IN IP4 10.11.7.57 Sep 17 18:21:48 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.149:5004:681666 -> 25521d81 Sep 17 18:21:48 voliorbr1 volumio[27104]: info: [SAP] Discovered stream: danterbr3-villa-sonos : 2 from 10.11.7.57 Sep 17 18:21:48 voliorbr1 volumio[27104]: info: [AES67] Stream discovered: danterbr3-villa-sonos : 2 Sep 17 18:21:51 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 17 18:21:51 voliorbr1 volumio[27104]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 17 18:21:51 voliorbr1 volumio[27104]: info: CoreCommandRouter::volumioGetState Sep 17 18:21:51 voliorbr1 volumio[27104]: info: CorePlayQueue::getTrack 0 Sep 17 18:21:56 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 146140997 version: 127 from line: o=nax 146140997 127 IN IP4 10.11.7.75 Sep 17 18:21:56 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.227:5004:146140997 -> dff13b4a Sep 17 18:21:56 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 286595182 version: 127 from line: o=nax 286595182 127 IN IP4 10.11.7.75 Sep 17 18:21:56 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.222:5004:286595182 -> f1899ce2 Sep 17 18:21:56 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 309351199 version: 127 from line: o=nax 309351199 127 IN IP4 10.11.7.75 Sep 17 18:21:56 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.220:5004:309351199 -> c6cf4a4b Sep 17 18:21:56 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 395539478 version: 127 from line: o=nax 395539478 127 IN IP4 10.11.7.75 Sep 17 18:21:56 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.223:5004:395539478 -> d02e9e31 Sep 17 18:21:56 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 523760604 version: 127 from line: o=nax 523760604 127 IN IP4 10.11.7.75 Sep 17 18:21:56 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.221:5004:523760604 -> a90b47a0 Sep 17 18:21:56 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 687139007 version: 127 from line: o=nax 687139007 127 IN IP4 10.11.7.75 Sep 17 18:21:56 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.225:5004:687139007 -> b8d3d106 Sep 17 18:21:56 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 902015099 version: 127 from line: o=nax 902015099 127 IN IP4 10.11.7.75 Sep 17 18:21:56 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.226:5004:902015099 -> 9daf3582 Sep 17 18:21:56 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 902413056 version: 127 from line: o=nax 902413056 127 IN IP4 10.11.7.75 Sep 17 18:21:56 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.224:5004:902413056 -> f8d335bb Sep 17 18:21:56 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 18:21:56 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 18:21:56 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Sep 17 18:21:56 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Sep 17 18:21:56 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 17 18:21:56 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Sep 17 18:21:56 voliorbr1 volumio[27104]: info: CoreCommandRouter::volumioGetBrowseSources Sep 17 18:21:56 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 17 18:21:57 voliorbr1 volumio[27104]: error: MyVolumio Plugin failed to authenticate in a timely fashion Sep 17 18:21:57 voliorbr1 volumio[27104]: info: Completed starting MyVolumio Plugin Sep 17 18:21:57 voliorbr1 volumio[27104]: [Metrics] CommandRouter: 30s 192.05ms Sep 17 18:21:57 voliorbr1 volumio[27104]: info: CoreCommandRouter::volumiosetStartupVolume Sep 17 18:21:57 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 18:21:57 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 18:21:57 voliorbr1 volumio[27104]: info: CoreCommandRouter::Close All Modals sent Sep 17 18:21:57 voliorbr1 volumio[27104]: info: CoreCommandRouter::Close All Modals sent Sep 17 18:21:57 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 239808144 version: 127 from line: o=nax 239808144 127 IN IP4 10.11.7.71 Sep 17 18:21:57 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.205:5004:239808144 -> 089d4146 Sep 17 18:21:57 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 333974723 version: 127 from line: o=nax 333974723 127 IN IP4 10.11.7.71 Sep 17 18:21:57 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.207:5004:333974723 -> b1ed656d Sep 17 18:21:57 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 518070308 version: 127 from line: o=nax 518070308 127 IN IP4 10.11.7.71 Sep 17 18:21:57 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.86.200:5004:518070308 -> 2b234b35 Sep 17 18:21:57 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 528962874 version: 127 from line: o=nax 528962874 127 IN IP4 10.11.7.71 Sep 17 18:21:57 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.201:5004:528962874 -> d1b8799e Sep 17 18:21:57 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 563614365 version: 127 from line: o=nax 563614365 127 IN IP4 10.11.7.71 Sep 17 18:21:57 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.202:5004:563614365 -> e75d1a21 Sep 17 18:21:57 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 586996509 version: 127 from line: o=nax 586996509 127 IN IP4 10.11.7.71 Sep 17 18:21:57 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.206:5004:586996509 -> 5d2af253 Sep 17 18:21:57 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 821074694 version: 127 from line: o=nax 821074694 127 IN IP4 10.11.7.71 Sep 17 18:21:57 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.200:5004:821074694 -> 60d7ed2b Sep 17 18:21:57 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 839938395 version: 127 from line: o=nax 839938395 127 IN IP4 10.11.7.71 Sep 17 18:21:57 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.203:5004:839938395 -> cafadb72 Sep 17 18:21:57 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 99619213 version: 127 from line: o=nax 99619213 127 IN IP4 10.11.7.71 Sep 17 18:21:57 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.204:5004:99619213 -> bcff4c96 Sep 17 18:21:58 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 17 18:21:58 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 18:21:58 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 17 18:21:58 voliorbr1 volumio-remote-updater[824]: No test mode Sep 17 18:21:58 voliorbr1 volumio-remote-updater[824]: No alpha test mode Sep 17 18:21:58 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Sep 17 18:21:58 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Sep 17 18:21:58 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 17 18:21:58 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Sep 17 18:21:58 voliorbr1 volumio[27104]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Sep 17 18:21:58 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Sep 17 18:22:04 voliorbr1 volumio[27104]: info: BOOT COMPLETED Sep 17 18:22:04 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: volumio-aes67 , handleBrowseUri Sep 17 18:22:04 voliorbr1 volumio[27104]: info: Preload queue cleared Sep 17 18:22:05 voliorbr1 volumio[27104]: info: Preloading song: volumio-aes67://stream/089d4146 Sep 17 18:22:05 voliorbr1 volumio[27104]: info: Preloading song: volumio-aes67://stream/b1ed656d Sep 17 18:22:05 voliorbr1 volumio[27104]: info: Preloading song: volumio-aes67://stream/2b234b35 Sep 17 18:22:05 voliorbr1 volumio[27104]: info: Preloading song: volumio-aes67://stream/d1b8799e Sep 17 18:22:05 voliorbr1 volumio[27104]: info: Preloading song: volumio-aes67://stream/e75d1a21 Sep 17 18:22:05 voliorbr1 volumio[27104]: info: Preloading song: volumio-aes67://stream/5d2af253 Sep 17 18:22:05 voliorbr1 volumio[27104]: info: Preloading song: volumio-aes67://stream/60d7ed2b Sep 17 18:22:05 voliorbr1 volumio[27104]: info: Preloading song: volumio-aes67://stream/cafadb72 Sep 17 18:22:05 voliorbr1 volumio[27104]: info: Preloading song: volumio-aes67://stream/bcff4c96 Sep 17 18:22:05 voliorbr1 volumio[27104]: info: Preloading song: volumio-aes67://stream/dff13b4a Sep 17 18:22:05 voliorbr1 volumio[27104]: info: Preloading song: volumio-aes67://stream/f1899ce2 Sep 17 18:22:05 voliorbr1 volumio[27104]: info: Preloading song: volumio-aes67://stream/c6cf4a4b Sep 17 18:22:05 voliorbr1 volumio[27104]: info: Preloading song: volumio-aes67://stream/d02e9e31 Sep 17 18:22:05 voliorbr1 volumio[27104]: info: Preloading song: volumio-aes67://stream/a90b47a0 Sep 17 18:22:05 voliorbr1 volumio[27104]: info: Preloading song: volumio-aes67://stream/b8d3d106 Sep 17 18:22:05 voliorbr1 volumio[27104]: info: Preloading song: volumio-aes67://stream/9daf3582 Sep 17 18:22:05 voliorbr1 volumio[27104]: info: Preloading song: volumio-aes67://stream/f8d335bb Sep 17 18:22:05 voliorbr1 volumio[27104]: info: Preloading song: volumio-aes67://stream/28eab0eb Sep 17 18:22:05 voliorbr1 volumio[27104]: info: Preloading song: volumio-aes67://stream/b8802285 Sep 17 18:22:05 voliorbr1 volumio[27104]: info: Preloading song: volumio-aes67://stream/aab96f60 Sep 17 18:22:05 voliorbr1 volumio[27104]: info: Preloading song: volumio-aes67://stream/e604a4db Sep 17 18:22:05 voliorbr1 volumio[27104]: info: Preloading song: volumio-aes67://stream/a0bfc762 Sep 17 18:22:05 voliorbr1 volumio[27104]: info: Preloading song: volumio-aes67://stream/5811a66f Sep 17 18:22:05 voliorbr1 volumio[27104]: info: Preloading song: volumio-aes67://stream/0beddd55 Sep 17 18:22:05 voliorbr1 volumio[27104]: info: Preloading song: volumio-aes67://stream/818262a6 Sep 17 18:22:05 voliorbr1 volumio[27104]: info: Preloading song: volumio-aes67://stream/73d22ac2 Sep 17 18:22:05 voliorbr1 volumio[27104]: info: Preloading song: volumio-aes67://stream/b14511b3 Sep 17 18:22:05 voliorbr1 volumio[27104]: info: Preloading song: volumio-aes67://stream/6c9848b3 Sep 17 18:22:05 voliorbr1 volumio[27104]: info: Preloading song: volumio-aes67://stream/6b2bcaac Sep 17 18:22:05 voliorbr1 volumio[27104]: info: Preloading song: volumio-aes67://stream/25521d81 Sep 17 18:22:05 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/089d4146 in service volumio-aes67 Sep 17 18:22:05 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/b1ed656d in service volumio-aes67 Sep 17 18:22:05 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/2b234b35 in service volumio-aes67 Sep 17 18:22:05 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/d1b8799e in service volumio-aes67 Sep 17 18:22:05 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/e75d1a21 in service volumio-aes67 Sep 17 18:22:05 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/5d2af253 in service volumio-aes67 Sep 17 18:22:05 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/60d7ed2b in service volumio-aes67 Sep 17 18:22:05 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/cafadb72 in service volumio-aes67 Sep 17 18:22:05 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/bcff4c96 in service volumio-aes67 Sep 17 18:22:05 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/dff13b4a in service volumio-aes67 Sep 17 18:22:05 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/f1899ce2 in service volumio-aes67 Sep 17 18:22:05 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/c6cf4a4b in service volumio-aes67 Sep 17 18:22:05 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/d02e9e31 in service volumio-aes67 Sep 17 18:22:05 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/a90b47a0 in service volumio-aes67 Sep 17 18:22:05 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/b8d3d106 in service volumio-aes67 Sep 17 18:22:05 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/9daf3582 in service volumio-aes67 Sep 17 18:22:05 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/f8d335bb in service volumio-aes67 Sep 17 18:22:05 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/28eab0eb in service volumio-aes67 Sep 17 18:22:05 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/b8802285 in service volumio-aes67 Sep 17 18:22:06 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/aab96f60 in service volumio-aes67 Sep 17 18:22:06 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/e604a4db in service volumio-aes67 Sep 17 18:22:06 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/a0bfc762 in service volumio-aes67 Sep 17 18:22:06 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/5811a66f in service volumio-aes67 Sep 17 18:22:06 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/0beddd55 in service volumio-aes67 Sep 17 18:22:06 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/818262a6 in service volumio-aes67 Sep 17 18:22:06 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/73d22ac2 in service volumio-aes67 Sep 17 18:22:06 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/b14511b3 in service volumio-aes67 Sep 17 18:22:06 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/6c9848b3 in service volumio-aes67 Sep 17 18:22:06 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/6b2bcaac in service volumio-aes67 Sep 17 18:22:06 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/25521d81 in service volumio-aes67 Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Preload queue cleared Sep 17 18:22:07 voliorbr1 volumio[27104]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 17 18:22:07 voliorbr1 volumio[27104]: info: CoreStateMachine::ClearQueue Sep 17 18:22:07 voliorbr1 volumio[27104]: info: CoreStateMachine::stop Sep 17 18:22:07 voliorbr1 volumio[27104]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 17 18:22:07 voliorbr1 volumio[27104]: info: CorePlayQueue::clearPlayQueue Sep 17 18:22:07 voliorbr1 volumio[27104]: info: CorePlayQueue::saveQueue Sep 17 18:22:07 voliorbr1 volumio[27104]: info: CoreCommandRouter::volumioPushQueue Sep 17 18:22:07 voliorbr1 volumio[27104]: info: CoreStateMachine::addQueueItems Sep 17 18:22:07 voliorbr1 volumio[27104]: info: CorePlayQueue::addQueueItems Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Preload queue cleared Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Adding Item to queue: volumio-aes67://stream/089d4146 Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/089d4146 in service volumio-aes67 Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Adding Item to queue: volumio-aes67://stream/b1ed656d Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/b1ed656d in service volumio-aes67 Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Adding Item to queue: volumio-aes67://stream/2b234b35 Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/2b234b35 in service volumio-aes67 Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Adding Item to queue: volumio-aes67://stream/d1b8799e Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/d1b8799e in service volumio-aes67 Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Adding Item to queue: volumio-aes67://stream/e75d1a21 Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/e75d1a21 in service volumio-aes67 Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Adding Item to queue: volumio-aes67://stream/5d2af253 Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/5d2af253 in service volumio-aes67 Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Adding Item to queue: volumio-aes67://stream/60d7ed2b Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/60d7ed2b in service volumio-aes67 Sep 17 18:22:07 voliorbr1 volumio[27104]: info: CoreCommandRouter::volumioPushQueue Sep 17 18:22:07 voliorbr1 volumio[27104]: info: CorePlayQueue::saveQueue Sep 17 18:22:07 voliorbr1 volumio[27104]: info: CoreStateMachine::updateTrackBlock Sep 17 18:22:07 voliorbr1 volumio[27104]: info: CorePlayQueue::getTrackBlock Sep 17 18:22:07 voliorbr1 volumio[27104]: info: CoreCommandRouter::volumioPlay Sep 17 18:22:07 voliorbr1 volumio[27104]: info: CoreStateMachine::play index 6 Sep 17 18:22:07 voliorbr1 volumio[27104]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 17 18:22:07 voliorbr1 volumio[27104]: info: CoreStateMachine::addQueueItems Sep 17 18:22:07 voliorbr1 volumio[27104]: info: CorePlayQueue::addQueueItems Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Preload queue cleared Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Adding Item to queue: volumio-aes67://stream/cafadb72 Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/cafadb72 in service volumio-aes67 Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Adding Item to queue: volumio-aes67://stream/bcff4c96 Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/bcff4c96 in service volumio-aes67 Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Adding Item to queue: volumio-aes67://stream/dff13b4a Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/dff13b4a in service volumio-aes67 Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Adding Item to queue: volumio-aes67://stream/f1899ce2 Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/f1899ce2 in service volumio-aes67 Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Adding Item to queue: volumio-aes67://stream/c6cf4a4b Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/c6cf4a4b in service volumio-aes67 Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Adding Item to queue: volumio-aes67://stream/d02e9e31 Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/d02e9e31 in service volumio-aes67 Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Adding Item to queue: volumio-aes67://stream/a90b47a0 Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/a90b47a0 in service volumio-aes67 Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Adding Item to queue: volumio-aes67://stream/b8d3d106 Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/b8d3d106 in service volumio-aes67 Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Adding Item to queue: volumio-aes67://stream/9daf3582 Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/9daf3582 in service volumio-aes67 Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Adding Item to queue: volumio-aes67://stream/f8d335bb Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/f8d335bb in service volumio-aes67 Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Adding Item to queue: volumio-aes67://stream/28eab0eb Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/28eab0eb in service volumio-aes67 Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Adding Item to queue: volumio-aes67://stream/b8802285 Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/b8802285 in service volumio-aes67 Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Adding Item to queue: volumio-aes67://stream/aab96f60 Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/aab96f60 in service volumio-aes67 Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Adding Item to queue: volumio-aes67://stream/e604a4db Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/e604a4db in service volumio-aes67 Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Adding Item to queue: volumio-aes67://stream/a0bfc762 Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/a0bfc762 in service volumio-aes67 Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Adding Item to queue: volumio-aes67://stream/5811a66f Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/5811a66f in service volumio-aes67 Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Adding Item to queue: volumio-aes67://stream/0beddd55 Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/0beddd55 in service volumio-aes67 Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Adding Item to queue: volumio-aes67://stream/818262a6 Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/818262a6 in service volumio-aes67 Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Adding Item to queue: volumio-aes67://stream/73d22ac2 Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/73d22ac2 in service volumio-aes67 Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Adding Item to queue: volumio-aes67://stream/b14511b3 Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/b14511b3 in service volumio-aes67 Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Adding Item to queue: volumio-aes67://stream/6c9848b3 Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/6c9848b3 in service volumio-aes67 Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Adding Item to queue: volumio-aes67://stream/6b2bcaac Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/6b2bcaac in service volumio-aes67 Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Adding Item to queue: volumio-aes67://stream/25521d81 Sep 17 18:22:07 voliorbr1 volumio[27104]: info: Exploding uri volumio-aes67://stream/25521d81 in service volumio-aes67 Sep 17 18:22:07 voliorbr1 volumio[27104]: info: CoreStateMachine::stop Sep 17 18:22:07 voliorbr1 volumio[27104]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 17 18:22:07 voliorbr1 volumio[27104]: info: CoreStateMachine::play index undefined Sep 17 18:22:07 voliorbr1 volumio[27104]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 17 18:22:07 voliorbr1 volumio[27104]: info: CorePlayQueue::getTrack 6 Sep 17 18:22:07 voliorbr1 volumio[27104]: info: CoreStateMachine::startPlaybackTimer Sep 17 18:22:07 voliorbr1 volumio[27104]: info: CorePlayQueue::getTrack 6 Sep 17 18:22:07 voliorbr1 volumio[27104]: info: [AES67] clearAddPlayTrack: volumio-aes67://stream/60d7ed2b Sep 17 18:22:07 voliorbr1 volumio[27104]: info: [AES67] Found stream: MediaStreamNax1Player1 Sep 17 18:22:07 voliorbr1 volumio[27104]: 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:21:57.312Z","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:22:07 voliorbr1 volumio[27104]: info: [AES67] Stream config - name: MediaStreamNax1Player1, ip: 239.69.85.200, port: 5004 Sep 17 18:22:07 voliorbr1 volumio[27104]: info: [AES67] Joining stream: MediaStreamNax1Player1 Sep 17 18:22:07 voliorbr1 volumio[27104]: info: [AES67] Creating GStreamer pipeline for stream type: multicast Sep 17 18:22:07 voliorbr1 volumio[27104]: info: [GStreamer] Pipeline created (length=682) Sep 17 18:22:07 voliorbr1 volumio[27104]: info: [GStreamer] Pipeline: 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... Sep 17 18:22:07 voliorbr1 volumio[27104]: info: CoreCommandRouter::volumioPushQueue Sep 17 18:22:07 voliorbr1 volumio[27104]: info: CorePlayQueue::saveQueue Sep 17 18:22:07 voliorbr1 volumio[27104]: info: CoreStateMachine::updateTrackBlock Sep 17 18:22:07 voliorbr1 volumio[27104]: info: CorePlayQueue::getTrackBlock Sep 17 18:22:07 voliorbr1 volumio[27104]: info: [AES67] Pipeline created, starting... Sep 17 18:22:07 voliorbr1 volumio[27104]: info: [GStreamer] Starting with args count: 43 Sep 17 18:22:07 voliorbr1 volumio[27104]: 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:22:07 voliorbr1 volumio[27104]: info: [GStreamer] Pipeline started Sep 17 18:22:07 voliorbr1 volumio[27104]: info: [GStreamer] Live pipeline running Sep 17 18:22:07 voliorbr1 volumio[27104]: info: [AES67] Pipeline started successfully Sep 17 18:22:07 voliorbr1 volumio[27104]: info: [StreamStore] Last stream saved Sep 17 18:22:07 voliorbr1 volumio[27104]: info: [AES67] Pushing playing state Sep 17 18:22:07 voliorbr1 volumio[27104]: info: CoreStateMachine::setConsumeUpdateService volumio-aes67 Sep 17 18:22:07 voliorbr1 volumio[27104]: info: CoreCommandRouter::servicePushState Sep 17 18:22:07 voliorbr1 volumio[27104]: info: CorePlayQueue::getTrack 6 Sep 17 18:22:07 voliorbr1 volumio[27104]: verbose: STATE SERVICE {"status":"play","service":"volumio-aes67","type":"track","trackType":"aes67","seek":0,"position":0,"duration":0,"samplerate":48000,"bitdepth":24,"channels":2,"title":"MediaStreamNax1Player1","artist":"239.69.85.200:5004","album":"48kHz/24bit","uri":"","stream":true} Sep 17 18:22:07 voliorbr1 volumio[27104]: verbose: CURRENT POSITION 6 Sep 17 18:22:07 voliorbr1 volumio[27104]: info: CoreStateMachine::syncState stateService play Sep 17 18:22:07 voliorbr1 volumio[27104]: info: CoreStateMachine::syncState currentStatus stop Sep 17 18:22:07 voliorbr1 volumio[27104]: info: [AES67] Successfully joined stream Sep 17 18:22:10 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 16138249 version: 127 from line: o=nax 16138249 127 IN IP4 10.11.7.74 Sep 17 18:22:10 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.214:5004:16138249 -> 28eab0eb Sep 17 18:22:10 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 172332185 version: 127 from line: o=nax 172332185 127 IN IP4 10.11.7.74 Sep 17 18:22:10 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.210:5004:172332185 -> b8802285 Sep 17 18:22:10 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 324703249 version: 127 from line: o=nax 324703249 127 IN IP4 10.11.7.74 Sep 17 18:22:10 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.217:5004:324703249 -> aab96f60 Sep 17 18:22:10 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 417606903 version: 127 from line: o=nax 417606903 127 IN IP4 10.11.7.74 Sep 17 18:22:10 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.211:5004:417606903 -> e604a4db Sep 17 18:22:10 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 421601304 version: 127 from line: o=nax 421601304 127 IN IP4 10.11.7.74 Sep 17 18:22:10 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.213:5004:421601304 -> a0bfc762 Sep 17 18:22:10 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 513147893 version: 127 from line: o=nax 513147893 127 IN IP4 10.11.7.74 Sep 17 18:22:10 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.212:5004:513147893 -> 5811a66f Sep 17 18:22:10 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 605167925 version: 127 from line: o=nax 605167925 127 IN IP4 10.11.7.74 Sep 17 18:22:10 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.216:5004:605167925 -> 0beddd55 Sep 17 18:22:10 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 665098026 version: 127 from line: o=nax 665098026 127 IN IP4 10.11.7.74 Sep 17 18:22:10 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.215:5004:665098026 -> 818262a6 Sep 17 18:22:13 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 146140997 version: 127 from line: o=nax 146140997 127 IN IP4 10.11.7.75 Sep 17 18:22:13 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.227:5004:146140997 -> dff13b4a Sep 17 18:22:13 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 286595182 version: 127 from line: o=nax 286595182 127 IN IP4 10.11.7.75 Sep 17 18:22:13 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.222:5004:286595182 -> f1899ce2 Sep 17 18:22:13 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 309351199 version: 127 from line: o=nax 309351199 127 IN IP4 10.11.7.75 Sep 17 18:22:13 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.220:5004:309351199 -> c6cf4a4b Sep 17 18:22:13 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 395539478 version: 127 from line: o=nax 395539478 127 IN IP4 10.11.7.75 Sep 17 18:22:13 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.223:5004:395539478 -> d02e9e31 Sep 17 18:22:13 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 523760604 version: 127 from line: o=nax 523760604 127 IN IP4 10.11.7.75 Sep 17 18:22:13 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.221:5004:523760604 -> a90b47a0 Sep 17 18:22:13 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 687139007 version: 127 from line: o=nax 687139007 127 IN IP4 10.11.7.75 Sep 17 18:22:13 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.225:5004:687139007 -> b8d3d106 Sep 17 18:22:13 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 902015099 version: 127 from line: o=nax 902015099 127 IN IP4 10.11.7.75 Sep 17 18:22:13 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.226:5004:902015099 -> 9daf3582 Sep 17 18:22:13 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 902413056 version: 127 from line: o=nax 902413056 127 IN IP4 10.11.7.75 Sep 17 18:22:13 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.224:5004:902413056 -> f8d335bb Sep 17 18:22:15 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 1028889 version: 1028899 from line: o=- 1028889 1028899 IN IP4 10.11.7.64 Sep 17 18:22:15 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.3.171:5004:1028889 -> 73d22ac2 Sep 17 18:22:15 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 488443 version: 488453 from line: o=- 488443 488453 IN IP4 10.11.7.64 Sep 17 18:22:15 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.180.57:5004:488443 -> b14511b3 Sep 17 18:22:15 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 1022333 version: 1022343 from line: o=- 1022333 1022343 IN IP4 10.11.7.64 Sep 17 18:22:15 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.65.7:5004:1022333 -> 6c9848b3 Sep 17 18:22:15 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 674495 version: 674505 from line: o=- 674495 674505 IN IP4 10.11.7.64 Sep 17 18:22:15 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.74.227:5004:674495 -> 6b2bcaac Sep 17 18:22:18 voliorbr1 volumio[27104]: error: error Sep 17 18:22:18 voliorbr1 volumio[27104]: error: error Sep 17 18:22:18 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 239808144 version: 127 from line: o=nax 239808144 127 IN IP4 10.11.7.71 Sep 17 18:22:18 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.205:5004:239808144 -> 089d4146 Sep 17 18:22:18 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 333974723 version: 127 from line: o=nax 333974723 127 IN IP4 10.11.7.71 Sep 17 18:22:18 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.207:5004:333974723 -> b1ed656d Sep 17 18:22:18 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 518070308 version: 127 from line: o=nax 518070308 127 IN IP4 10.11.7.71 Sep 17 18:22:18 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.86.200:5004:518070308 -> 2b234b35 Sep 17 18:22:18 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 528962874 version: 127 from line: o=nax 528962874 127 IN IP4 10.11.7.71 Sep 17 18:22:18 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.201:5004:528962874 -> d1b8799e Sep 17 18:22:18 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 563614365 version: 127 from line: o=nax 563614365 127 IN IP4 10.11.7.71 Sep 17 18:22:18 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.202:5004:563614365 -> e75d1a21 Sep 17 18:22:18 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 586996509 version: 127 from line: o=nax 586996509 127 IN IP4 10.11.7.71 Sep 17 18:22:18 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.206:5004:586996509 -> 5d2af253 Sep 17 18:22:18 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 821074694 version: 127 from line: o=nax 821074694 127 IN IP4 10.11.7.71 Sep 17 18:22:18 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.200:5004:821074694 -> 60d7ed2b Sep 17 18:22:18 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 839938395 version: 127 from line: o=nax 839938395 127 IN IP4 10.11.7.71 Sep 17 18:22:18 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.203:5004:839938395 -> cafadb72 Sep 17 18:22:18 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 99619213 version: 127 from line: o=nax 99619213 127 IN IP4 10.11.7.71 Sep 17 18:22:18 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.204:5004:99619213 -> bcff4c96 Sep 17 18:22:18 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 681666 version: 681705 from line: o=- 681666 681705 IN IP4 10.11.7.57 Sep 17 18:22:18 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.149:5004:681666 -> 25521d81 Sep 17 18:22:30 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 146140997 version: 127 from line: o=nax 146140997 127 IN IP4 10.11.7.75 Sep 17 18:22:30 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.227:5004:146140997 -> dff13b4a Sep 17 18:22:30 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 286595182 version: 127 from line: o=nax 286595182 127 IN IP4 10.11.7.75 Sep 17 18:22:30 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.222:5004:286595182 -> f1899ce2 Sep 17 18:22:30 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 309351199 version: 127 from line: o=nax 309351199 127 IN IP4 10.11.7.75 Sep 17 18:22:30 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.220:5004:309351199 -> c6cf4a4b Sep 17 18:22:30 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 395539478 version: 127 from line: o=nax 395539478 127 IN IP4 10.11.7.75 Sep 17 18:22:30 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.223:5004:395539478 -> d02e9e31 Sep 17 18:22:30 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 523760604 version: 127 from line: o=nax 523760604 127 IN IP4 10.11.7.75 Sep 17 18:22:30 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.221:5004:523760604 -> a90b47a0 Sep 17 18:22:30 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 687139007 version: 127 from line: o=nax 687139007 127 IN IP4 10.11.7.75 Sep 17 18:22:30 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.225:5004:687139007 -> b8d3d106 Sep 17 18:22:30 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 902015099 version: 127 from line: o=nax 902015099 127 IN IP4 10.11.7.75 Sep 17 18:22:30 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.226:5004:902015099 -> 9daf3582 Sep 17 18:22:30 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 902413056 version: 127 from line: o=nax 902413056 127 IN IP4 10.11.7.75 Sep 17 18:22:30 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.224:5004:902413056 -> f8d335bb Sep 17 18:22:37 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 16138249 version: 127 from line: o=nax 16138249 127 IN IP4 10.11.7.74 Sep 17 18:22:37 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.214:5004:16138249 -> 28eab0eb Sep 17 18:22:37 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 172332185 version: 127 from line: o=nax 172332185 127 IN IP4 10.11.7.74 Sep 17 18:22:37 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.210:5004:172332185 -> b8802285 Sep 17 18:22:37 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 324703249 version: 127 from line: o=nax 324703249 127 IN IP4 10.11.7.74 Sep 17 18:22:37 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.217:5004:324703249 -> aab96f60 Sep 17 18:22:37 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 417606903 version: 127 from line: o=nax 417606903 127 IN IP4 10.11.7.74 Sep 17 18:22:37 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.211:5004:417606903 -> e604a4db Sep 17 18:22:37 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 421601304 version: 127 from line: o=nax 421601304 127 IN IP4 10.11.7.74 Sep 17 18:22:37 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.213:5004:421601304 -> a0bfc762 Sep 17 18:22:37 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 513147893 version: 127 from line: o=nax 513147893 127 IN IP4 10.11.7.74 Sep 17 18:22:37 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.212:5004:513147893 -> 5811a66f Sep 17 18:22:37 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 605167925 version: 127 from line: o=nax 605167925 127 IN IP4 10.11.7.74 Sep 17 18:22:37 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.216:5004:605167925 -> 0beddd55 Sep 17 18:22:37 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 665098026 version: 127 from line: o=nax 665098026 127 IN IP4 10.11.7.74 Sep 17 18:22:37 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.215:5004:665098026 -> 818262a6 Sep 17 18:22:39 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 239808144 version: 127 from line: o=nax 239808144 127 IN IP4 10.11.7.71 Sep 17 18:22:39 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.205:5004:239808144 -> 089d4146 Sep 17 18:22:39 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 333974723 version: 127 from line: o=nax 333974723 127 IN IP4 10.11.7.71 Sep 17 18:22:39 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.207:5004:333974723 -> b1ed656d Sep 17 18:22:39 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 518070308 version: 127 from line: o=nax 518070308 127 IN IP4 10.11.7.71 Sep 17 18:22:39 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.86.200:5004:518070308 -> 2b234b35 Sep 17 18:22:39 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 528962874 version: 127 from line: o=nax 528962874 127 IN IP4 10.11.7.71 Sep 17 18:22:39 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.201:5004:528962874 -> d1b8799e Sep 17 18:22:39 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 563614365 version: 127 from line: o=nax 563614365 127 IN IP4 10.11.7.71 Sep 17 18:22:39 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.202:5004:563614365 -> e75d1a21 Sep 17 18:22:39 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 586996509 version: 127 from line: o=nax 586996509 127 IN IP4 10.11.7.71 Sep 17 18:22:39 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.206:5004:586996509 -> 5d2af253 Sep 17 18:22:39 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 821074694 version: 127 from line: o=nax 821074694 127 IN IP4 10.11.7.71 Sep 17 18:22:39 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.200:5004:821074694 -> 60d7ed2b Sep 17 18:22:39 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 839938395 version: 127 from line: o=nax 839938395 127 IN IP4 10.11.7.71 Sep 17 18:22:39 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.203:5004:839938395 -> cafadb72 Sep 17 18:22:39 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 99619213 version: 127 from line: o=nax 99619213 127 IN IP4 10.11.7.71 Sep 17 18:22:39 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.204:5004:99619213 -> bcff4c96 Sep 17 18:22:45 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 1028889 version: 1028899 from line: o=- 1028889 1028899 IN IP4 10.11.7.64 Sep 17 18:22:45 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.3.171:5004:1028889 -> 73d22ac2 Sep 17 18:22:45 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 488443 version: 488453 from line: o=- 488443 488453 IN IP4 10.11.7.64 Sep 17 18:22:45 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.180.57:5004:488443 -> b14511b3 Sep 17 18:22:45 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 1022333 version: 1022343 from line: o=- 1022333 1022343 IN IP4 10.11.7.64 Sep 17 18:22:45 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.65.7:5004:1022333 -> 6c9848b3 Sep 17 18:22:45 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 674495 version: 674505 from line: o=- 674495 674505 IN IP4 10.11.7.64 Sep 17 18:22:45 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.74.227:5004:674495 -> 6b2bcaac Sep 17 18:22:47 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 146140997 version: 127 from line: o=nax 146140997 127 IN IP4 10.11.7.75 Sep 17 18:22:47 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.227:5004:146140997 -> dff13b4a Sep 17 18:22:47 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 286595182 version: 127 from line: o=nax 286595182 127 IN IP4 10.11.7.75 Sep 17 18:22:47 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.222:5004:286595182 -> f1899ce2 Sep 17 18:22:47 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 309351199 version: 127 from line: o=nax 309351199 127 IN IP4 10.11.7.75 Sep 17 18:22:47 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.220:5004:309351199 -> c6cf4a4b Sep 17 18:22:47 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 395539478 version: 127 from line: o=nax 395539478 127 IN IP4 10.11.7.75 Sep 17 18:22:47 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.223:5004:395539478 -> d02e9e31 Sep 17 18:22:47 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 523760604 version: 127 from line: o=nax 523760604 127 IN IP4 10.11.7.75 Sep 17 18:22:47 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.221:5004:523760604 -> a90b47a0 Sep 17 18:22:47 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 687139007 version: 127 from line: o=nax 687139007 127 IN IP4 10.11.7.75 Sep 17 18:22:47 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.225:5004:687139007 -> b8d3d106 Sep 17 18:22:47 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 902015099 version: 127 from line: o=nax 902015099 127 IN IP4 10.11.7.75 Sep 17 18:22:47 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.226:5004:902015099 -> 9daf3582 Sep 17 18:22:47 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 902413056 version: 127 from line: o=nax 902413056 127 IN IP4 10.11.7.75 Sep 17 18:22:47 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.224:5004:902413056 -> f8d335bb Sep 17 18:22:48 voliorbr1 volumio[27104]: info: [SAP] Parsed sessionId: 681666 version: 681705 from line: o=- 681666 681705 IN IP4 10.11.7.57 Sep 17 18:22:48 voliorbr1 volumio[27104]: info: [SAP] Stream ID generation: 239.69.85.149:5004:681666 -> 25521d81 Sep 17 18:22:53 voliorbr1 volumio[27104]: info: Preload queue cleared Sep 17 18:22:53 voliorbr1 volumio[27104]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 17 18:22:53 voliorbr1 volumio[27104]: info: CoreStateMachine::ClearQueue Sep 17 18:22:53 voliorbr1 volumio[27104]: info: CoreStateMachine::stop Sep 17 18:22:53 voliorbr1 volumio[27104]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 17 18:22:53 voliorbr1 volumio[27104]: info: CoreStateMachine::stPlaybackTimer Sep 17 18:22:53 voliorbr1 volumio[27104]: info: CoreStateMachine::updateTrackBlock Sep 17 18:22:53 voliorbr1 volumio[27104]: info: CorePlayQueue::getTrackBlock Sep 17 18:22:53 voliorbr1 volumio[27104]: info: CoreStateMachine::pushState Sep 17 18:22:53 voliorbr1 volumio[27104]: info: CorePlayQueue::getTrack 6 Sep 17 18:22:53 voliorbr1 volumio[27104]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 18:22:53 voliorbr1 volumio[27104]: info: CoreCommandRouter::volumioPushState Sep 17 18:22:53 voliorbr1 volumio[27104]: info: CoreStateMachine::serviceStop Sep 17 18:22:53 voliorbr1 volumio[27104]: info: CorePlayQueue::getTrack 6 Sep 17 18:22:53 voliorbr1 volumio[27104]: info: CoreCommandRouter::serviceStop Sep 17 18:22:53 voliorbr1 volumio[27104]: info: [AES67] Stop requested Sep 17 18:22:53 voliorbr1 volumio[27104]: info: [AES67] Pushing stopped state Sep 17 18:22:53 voliorbr1 volumio[27104]: info: CoreCommandRouter::servicePushState Sep 17 18:22:53 voliorbr1 volumio[27104]: info: CoreStateMachine::pushState Sep 17 18:22:53 voliorbr1 volumio[27104]: info: CorePlayQueue::getTrack 6 Sep 17 18:22:53 voliorbr1 volumio[27104]: info: CoreCommandRouter::volumioPushState Sep 17 18:22:53 voliorbr1 volumio[27104]: info: CorePlayQueue::getTrack 6 Sep 17 18:22:53 voliorbr1 volumio[27104]: verbose: STATE SERVICE {"status":"stop","service":"volumio-aes67"} Sep 17 18:22:53 voliorbr1 volumio[27104]: verbose: CURRENT POSITION 6 Sep 17 18:22:53 voliorbr1 volumio[27104]: info: CoreStateMachine::syncState stateService stop Sep 17 18:22:53 voliorbr1 volumio[27104]: info: CoreStateMachine::syncState currentStatus stop Sep 17 18:22:53 voliorbr1 volumio[27104]: info: CoreStateMachine::pushState Sep 17 18:22:53 voliorbr1 volumio[27104]: info: CorePlayQueue::getTrack 6 Sep 17 18:22:53 voliorbr1 volumio[27104]: info: CoreCommandRouter::volumioPushState Sep 17 18:22:53 voliorbr1 volumio[27104]: info: No code Sep 17 18:22:53 voliorbr1 volumio[27104]: info: CoreStateMachine::pushState Sep 17 18:22:53 voliorbr1 volumio[27104]: info: CorePlayQueue::getTrack 6 Sep 17 18:22:53 voliorbr1 volumio[27104]: info: CoreCommandRouter::volumioPushState Sep 17 18:22:53 voliorbr1 volumio[27104]: info: [AES67] Leaving stream Sep 17 18:22:53 voliorbr1 volumio[27104]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 17 18:22:53 voliorbr1 volumio[27104]: TypeError: self.gstreamerManager.stop(...).then(...).fail is not a function Sep 17 18:22:53 voliorbr1 volumio[27104]: at AES67Controller.leaveStream (/data/plugins/music_service/volumio-aes67/index.js:348:10) Sep 17 18:22:53 voliorbr1 volumio[27104]: at AES67Controller.stop (/data/plugins/music_service/volumio-aes67/index.js:570:17) Sep 17 18:22:53 voliorbr1 volumio[27104]: at CoreCommandRouter.serviceStop (/volumio/app/index.js:371:25) Sep 17 18:22:53 voliorbr1 volumio[27104]: at CoreStateMachine.serviceStop (/volumio/app/statemachine.js:1271:33) Sep 17 18:22:53 voliorbr1 volumio[27104]: at CoreStateMachine.stop (/volumio/app/statemachine.js:1246:19) Sep 17 18:22:53 voliorbr1 volumio[27104]: at CoreStateMachine.clearQueue (/volumio/app/statemachine.js:275:8) Sep 17 18:22:53 voliorbr1 volumio[27104]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:494:21) Sep 17 18:22:53 voliorbr1 volumio[27104]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:484:17) Sep 17 18:22:53 voliorbr1 volumio[27104]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:142:33) Sep 17 18:22:53 voliorbr1 volumio[27104]: at Socket.emit (events.js:315:20) Sep 17 18:22:53 voliorbr1 volumio[27104]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Sep 17 18:22:53 voliorbr1 volumio[27104]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Sep 17 18:22:53 voliorbr1 volumio[27104]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 17 18:22:53 voliorbr1 sudo[27619]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-17 18:21 Sep 17 18:22:53 voliorbr1 sudo[27619]: 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"