Dec 20 21:42:45 volumio ntpd[1249]: CLOCK: time stepped by 270.561657
Dec 20 21:42:45 volumio ntpd[1249]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes
Dec 20 21:42:45 volumio volumio[1470]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.073&uuid=078666b915fb561fe7c761a01d2a8e2d" http://updates.volumio.org/downloader-v1/track-device
Dec 20 21:42:45 volumio volumio[1470]: % Total % Received % Xferd Average Speed Time Time Time Current
Dec 20 21:42:45 volumio volumio[1470]: Dload Upload Total Spent Left Speed
Dec 20 21:42:45 volumio volumio[1470]: [132B blob data]
Dec 20 21:42:45 volumio volumio[1470]: retrying in 5 seconds, trial 0
Dec 20 21:42:45 volumio volumio[1470]: info: Volumio Calling Home
Dec 20 21:42:45 volumio kernel: usb usb3-port1: attempt power cycle
Dec 20 21:42:45 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken
Dec 20 21:42:46 volumio volumio[1470]: info: Volumio called home
Dec 20 21:42:46 volumio volumio[1470]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Dec 20 21:42:46 volumio volumio[1470]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Dec 20 21:42:46 volumio volumio[1470]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Dec 20 21:42:46 volumio volumio[1470]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Dec 20 21:42:46 volumio volumio[1470]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Dec 20 21:42:46 volumio volumio[1470]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Dec 20 21:42:46 volumio volumio[1470]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Dec 20 21:42:46 volumio volumio[1470]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Dec 20 21:42:46 volumio volumio[1470]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Dec 20 21:42:46 volumio volumio[1470]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Dec 20 21:42:46 volumio volumio[1470]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium
Dec 20 21:42:46 volumio volumio[1470]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Dec 20 21:42:46 volumio volumio[1470]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Dec 20 21:42:46 volumio volumio[1470]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Dec 20 21:42:46 volumio volumio[1470]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Dec 20 21:42:46 volumio volumio[1470]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Dec 20 21:42:46 volumio volumio[1470]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Dec 20 21:42:46 volumio volumio[1470]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Dec 20 21:42:46 volumio volumio[1470]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Dec 20 21:42:46 volumio volumio[1470]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Dec 20 21:42:46 volumio volumio[1470]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Dec 20 21:42:46 volumio volumio[1470]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar
Dec 20 21:42:46 volumio volumio[1470]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Dec 20 21:42:46 volumio volumio[1470]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Dec 20 21:42:46 volumio volumio[1470]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Dec 20 21:42:46 volumio volumio[1470]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Dec 20 21:42:46 volumio volumio[1470]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Dec 20 21:42:46 volumio volumio[1470]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Dec 20 21:42:46 volumio volumio[1470]: info: Adding plugin bluetooth to MyMusic Plugins
Dec 20 21:42:46 volumio volumio[1470]: info: Adding plugin multiroom to MyMusic Plugins
Dec 20 21:42:46 volumio volumio[1470]: info: Adding plugin metavolumio to MyMusic Plugins
Dec 20 21:42:46 volumio volumio[1470]: info: Adding plugin cd_controller to MyMusic Plugins
Dec 20 21:42:46 volumio volumio[1470]: info: Adding plugin qobuzconnect to MyMusic Plugins
Dec 20 21:42:46 volumio volumio[1470]: info: Adding plugin smart_inputs to MyMusic Plugins
Dec 20 21:42:46 volumio volumio[1470]: info: Adding plugin tidalconnect to MyMusic Plugins
Dec 20 21:42:46 volumio volumio[1470]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Dec 20 21:42:46 volumio volumio[1470]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Dec 20 21:42:46 volumio volumio[1470]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Dec 20 21:42:46 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 20 21:42:46 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 20 21:42:46 volumio volumio[1470]: info: Starting MyVolumio Remote Streaming Endpoints
Dec 20 21:42:46 volumio volumio[1470]: info: MyVolumio login type: Token
Dec 20 21:42:46 volumio volumio[1470]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Dec 20 21:42:46 volumio volumio[1470]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Dec 20 21:42:46 volumio kernel: usb usb1-port1: over-current change #49
Dec 20 21:42:46 volumio kernel: usb usb2-port1: over-current change #52
Dec 20 21:42:46 volumio kernel: usb usb4-port1: over-current change #52
Dec 20 21:42:47 volumio volumio[1470]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Dec 20 21:42:47 volumio volumio[1470]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Dec 20 21:42:47 volumio volumio[1470]: info: Streaming services startup
Dec 20 21:42:47 volumio volumio[1470]: info: Starting Streaming Daemon
Dec 20 21:42:47 volumio sudo[2214]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Dec 20 21:42:47 volumio sudo[2214]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 20 21:42:47 volumio volumio[1470]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Dec 20 21:42:47 volumio sudo[2214]: pam_unix(sudo:session): session closed for user root
Dec 20 21:42:47 volumio volumio[1470]: error: Cannot start Volumio Streaming Daemon
Dec 20 21:42:47 volumio volumio[1470]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Dec 20 21:42:47 volumio volumio[1470]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Dec 20 21:42:47 volumio kernel: usb usb1-port2: over-current change #41
Dec 20 21:42:47 volumio kernel: usb usb2-port1: over-current change #53
Dec 20 21:42:47 volumio kernel: usb usb4-port1: over-current change #53
Dec 20 21:42:47 volumio kernel: usb usb1-port1: over-current change #50
Dec 20 21:42:47 volumio kernel: usb usb3-port2: over-current change #15
Dec 20 21:42:47 volumio volumio[1470]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
Dec 20 21:42:47 volumio kernel: usb 3-2: new full-speed USB device number 26 using xhci-hcd
Dec 20 21:42:48 volumio kernel: usb 3-2: New USB device found, idVendor=1a86, idProduct=e5e3, bcdDevice= 0.00
Dec 20 21:42:48 volumio kernel: usb 3-2: New USB device strings: Mfr=1, Product=2, SerialNumber=0
Dec 20 21:42:48 volumio kernel: usb 3-2: Product: USB2IIC_CTP_CONTROL
Dec 20 21:42:48 volumio kernel: usb 3-2: Manufacturer: wch.cn
Dec 20 21:42:48 volumio kernel: input: wch.cn USB2IIC_CTP_CONTROL as /devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb3/3-2/3-2:1.0/0003:1A86:E5E3.0004/input/input9
Dec 20 21:42:48 volumio kernel: hid-multitouch 0003:1A86:E5E3.0004: input,hidraw0: USB HID v1.00 Device [wch.cn USB2IIC_CTP_CONTROL] on usb-xhci-hcd.1-2/input0
Dec 20 21:42:48 volumio kernel: usb usb3-port1: over-current change #16
Dec 20 21:42:49 volumio volumio[1470]: info: MyVolumio token set successfully
Dec 20 21:42:49 volumio volumio[1470]: info: MYVOLUMIO: Adding device
Dec 20 21:42:49 volumio volumio[1470]: info: MYVOLUMIO: Evaluating Server
Dec 20 21:42:49 volumio volumio[1470]: info: MyVolumio status changed
Dec 20 21:42:49 volumio volumio[1470]: info: Streaming services startup
Dec 20 21:42:49 volumio volumio[1470]: info: Starting Streaming Daemon
Dec 20 21:42:49 volumio volumio[1470]: info: Removing browser output: myVolumio user plan is not superstar
Dec 20 21:42:49 volumio volumio[1470]: info: Removing audio output:
Dec 20 21:42:49 volumio volumio[1470]: info: Stoppping Tunnel 1
Dec 20 21:42:49 volumio sudo[2242]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Dec 20 21:42:49 volumio sudo[2242]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 20 21:42:49 volumio sudo[2244]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service
Dec 20 21:42:49 volumio sudo[2244]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 20 21:42:49 volumio sudo[2242]: pam_unix(sudo:session): session closed for user root
Dec 20 21:42:49 volumio volumio[1470]: error: Cannot start Volumio Streaming Daemon
Dec 20 21:42:49 volumio volumio[1470]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Dec 20 21:42:49 volumio volumio[1470]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Dec 20 21:42:49 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Dec 20 21:42:49 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Dec 20 21:42:49 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Dec 20 21:42:49 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Dec 20 21:42:49 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Dec 20 21:42:49 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Dec 20 21:42:49 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Dec 20 21:42:49 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Dec 20 21:42:49 volumio sudo[2244]: pam_unix(sudo:session): session closed for user root
Dec 20 21:42:49 volumio volumio[1470]: info: Remote SSH Stopped
Dec 20 21:42:49 volumio volumio[1470]: info: Setting Geolocation for MyVolumio to as2
Dec 20 21:42:49 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 20 21:42:49 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 20 21:42:49 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 20 21:42:50 volumio kernel: usb usb3-port1: attempt power cycle
Dec 20 21:42:50 volumio volumio[1470]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Dec 20 21:42:50 volumio volumio[1470]: info: Updating MyVolumio device info
Dec 20 21:42:50 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 20 21:42:50 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 20 21:42:50 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 20 21:42:51 volumio volumio[1470]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Dec 20 21:42:51 volumio kernel: usb usb3-port1: unable to enumerate USB device
Dec 20 21:42:51 volumio kernel: usb 3-2: USB disconnect, device number 26
Dec 20 21:42:52 volumio kernel: usb 3-2: new full-speed USB device number 31 using xhci-hcd
Dec 20 21:42:52 volumio kernel: usb 3-2: New USB device found, idVendor=1a86, idProduct=e5e3, bcdDevice= 0.00
Dec 20 21:42:52 volumio kernel: usb 3-2: New USB device strings: Mfr=1, Product=2, SerialNumber=0
Dec 20 21:42:52 volumio kernel: usb 3-2: Product: USB2IIC_CTP_CONTROL
Dec 20 21:42:52 volumio kernel: usb 3-2: Manufacturer: wch.cn
Dec 20 21:42:52 volumio kernel: input: wch.cn USB2IIC_CTP_CONTROL as /devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb3/3-2/3-2:1.0/0003:1A86:E5E3.0005/input/input10
Dec 20 21:42:52 volumio kernel: hid-multitouch 0003:1A86:E5E3.0005: input,hidraw0: USB HID v1.00 Device [wch.cn USB2IIC_CTP_CONTROL] on usb-xhci-hcd.1-2/input0
Dec 20 21:42:53 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully.
Dec 20 21:42:54 volumio volumio[1470]: info: MYVOLUMIO: Adding device
Dec 20 21:42:54 volumio volumio[1470]: info: MYVOLUMIO: Evaluating Server
Dec 20 21:42:54 volumio volumio[1470]: info: Setting Geolocation for MyVolumio to as2
Dec 20 21:42:54 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 20 21:42:54 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 20 21:42:54 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 20 21:42:55 volumio volumio[1470]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Dec 20 21:42:55 volumio kernel: usb usb3-port1: over-current change #17
Dec 20 21:42:55 volumio kernel: usb usb1-port1: over-current change #51
Dec 20 21:42:55 volumio kernel: usb usb2-port1: over-current change #54
Dec 20 21:42:55 volumio kernel: usb usb4-port1: over-current change #54
Dec 20 21:42:55 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully.
Dec 20 21:42:55 volumio kernel: usb usb1-port2: over-current change #42
Dec 20 21:42:55 volumio volumio[1470]: info: AutoStart - Plugin is starting
Dec 20 21:42:55 volumio volumio[1470]: info: CoreCommandRouter::volumioGetQueue
Dec 20 21:42:55 volumio volumio[1470]: info: CoreStateMachine::getQueue
Dec 20 21:42:55 volumio volumio[1470]: info: CorePlayQueue::getQueue
Dec 20 21:42:55 volumio volumio[1470]: info: AutoStart - start playing
Dec 20 21:42:55 volumio volumio[1470]: info: AutoStart - start playing with no specific position
Dec 20 21:42:55 volumio volumio[1470]: info: CoreCommandRouter::volumioPlay
Dec 20 21:42:55 volumio volumio[1470]: info: CoreStateMachine::play index 0
Dec 20 21:42:55 volumio volumio[1470]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 20 21:42:55 volumio volumio[1470]: info: CoreStateMachine::stop
Dec 20 21:42:55 volumio volumio[1470]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 20 21:42:55 volumio volumio[1470]: info: CoreStateMachine::play index undefined
Dec 20 21:42:55 volumio volumio[1470]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 20 21:42:55 volumio volumio[1470]: info: CorePlayQueue::getTrack 0
Dec 20 21:42:55 volumio volumio[1470]: info: CoreStateMachine::startPlaybackTimer
Dec 20 21:42:55 volumio volumio[1470]: info: CorePlayQueue::getTrack 0
Dec 20 21:42:55 volumio volumio[1470]: verbose: ControllerMpd::sendMpdCommand stop
Dec 20 21:42:55 volumio volumio[1470]: info: sendMpdCommand stop took 0 milliseconds
Dec 20 21:42:55 volumio volumio[1470]: verbose: ControllerMpd::sendMpdCommand clear
Dec 20 21:42:55 volumio volumio[1470]: info:
Dec 20 21:42:55 volumio volumio[1470]: ---------------------------- MPD announces system playlist update
Dec 20 21:42:55 volumio volumio[1470]: info: Ignoring MPD Status Update
Dec 20 21:42:55 volumio volumio[1470]: info: sendMpdCommand clear took 1 milliseconds
Dec 20 21:42:55 volumio volumio[1470]: verbose: ControllerMpd::sendMpdCommand add "https://minisw.imbc.com/dsfm/_definst_/sfm.stream/playlist.m3u8?_lsu_sa_=6001CF1683793004EC46A5993FF13D4A75F237954807E25931A0E1a0E6693C364CaD23DF3DB2EE4C70A23541BEb991F1E21FA1439A5D773933E8F46D19CD30C6295E9954B21A787A796FCE8AD910BEF55913F6E5C27547003897D3AA4A1C3A8C080BA13C553EC3581A99033658CE595B"
Dec 20 21:42:55 volumio volumio[1470]: info:
Dec 20 21:42:55 volumio volumio[1470]: ---------------------------- MPD announces system playlist update
Dec 20 21:42:55 volumio volumio[1470]: info: Ignoring MPD Status Update
Dec 20 21:42:55 volumio volumio[1470]: info:
Dec 20 21:42:55 volumio volumio[1470]: ---------------------------- MPD announces system playlist update
Dec 20 21:42:55 volumio volumio[1470]: info: Ignoring MPD Status Update
Dec 20 21:42:55 volumio volumio[1470]: info:
Dec 20 21:42:55 volumio volumio[1470]: ---------------------------- MPD announces system playlist update
Dec 20 21:42:55 volumio volumio[1470]: info: Ignoring MPD Status Update
Dec 20 21:42:55 volumio volumio[1470]: error: updateQueue error: null
Dec 20 21:42:55 volumio volumio[1470]: info:
Dec 20 21:42:55 volumio volumio[1470]: ---------------------------- MPD announces system playlist update
Dec 20 21:42:55 volumio volumio[1470]: info: Ignoring MPD Status Update
Dec 20 21:42:55 volumio volumio[1470]: info: ------------------------------ 3ms
Dec 20 21:42:55 volumio volumio[1470]: info: sendMpdCommand add "https://minisw.imbc.com/dsfm/_definst_/sfm.stream/playlist.m3u8?_lsu_sa_=6001CF1683793004EC46A5993FF13D4A75F237954807E25931A0E1a0E6693C364CaD23DF3DB2EE4C70A23541BEb991F1E21FA1439A5D773933E8F46D19CD30C6295E9954B21A787A796FCE8AD910BEF55913F6E5C27547003897D3AA4A1C3A8C080BA13C553EC3581A99033658CE595B" took 3 milliseconds
Dec 20 21:42:55 volumio volumio[1470]: info: ------------------------------ 2ms
Dec 20 21:42:55 volumio volumio[1470]: info: ------------------------------ 2ms
Dec 20 21:42:55 volumio volumio[1470]: info: ------------------------------ 1ms
Dec 20 21:42:55 volumio volumio[1470]: verbose: ControllerMpd::sendMpdCommand play
Dec 20 21:42:55 volumio volumio[1470]: info:
Dec 20 21:42:55 volumio volumio[1470]: ---------------------------- MPD announces system playlist update
Dec 20 21:42:55 volumio volumio[1470]: info: Ignoring MPD Status Update
Dec 20 21:42:55 volumio volumio[1470]: info:
Dec 20 21:42:55 volumio volumio[1470]: ---------------------------- MPD announces system playlist update
Dec 20 21:42:55 volumio volumio[1470]: info: Ignoring MPD Status Update
Dec 20 21:42:55 volumio volumio[1470]: info:
Dec 20 21:42:55 volumio volumio[1470]: ---------------------------- MPD announces system playlist update
Dec 20 21:42:55 volumio volumio[1470]: info: Ignoring MPD Status Update
Dec 20 21:42:55 volumio volumio[1470]: info: ------------------------------ 5ms
Dec 20 21:42:55 volumio volumio[1470]: info: sendMpdCommand play took 2 milliseconds
Dec 20 21:42:55 volumio volumio[1470]: info: ------------------------------ 1ms
Dec 20 21:42:55 volumio volumio[1470]: info: ------------------------------ 1ms
Dec 20 21:42:55 volumio volumio[1470]: info: ------------------------------ 2ms
Dec 20 21:42:55 volumio volumio[1470]: info: ControllerMpd::getState
Dec 20 21:42:55 volumio volumio[1470]: verbose: ControllerMpd::sendMpdCommand status
Dec 20 21:42:55 volumio volumio[1470]: info: sendMpdCommand status took 2 milliseconds
Dec 20 21:42:55 volumio volumio[1470]: verbose: ControllerMpd::parseState
Dec 20 21:42:55 volumio volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 20 21:42:55 volumio volumio[1470]: info: sendMpdCommand playlistinfo took 0 milliseconds
Dec 20 21:42:55 volumio volumio[1470]: verbose: ControllerMpd::parseTrackInfo
Dec 20 21:42:55 volumio volumio[1470]: info: CorePlayQueue::getTrack 0
Dec 20 21:42:55 volumio volumio[1470]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"playlist.m3u8?_lsu_sa_=6001CF1683793004EC46A5993FF13D4A75F237954807E25931A0E1a0E6693C364CaD23DF3DB2EE4C70A23541BEb991F1E21FA1439A5D773933E8F46D19CD30C6295E9954B21A787A796FCE8AD910BEF55913F6E5C27547003897D3AA4A1C3A8C080BA13C553EC3581A99033658CE595B","artist":null,"album":null,"uri":"https://minisw.imbc.com/dsfm/_definst_/sfm.stream/playlist.m3u8?_lsu_sa_=6001CF1683793004EC46A5993FF13D4A75F237954807E25931A0E1a0E6693C364CaD23DF3DB2EE4C70A23541BEb991F1E21FA1439A5D773933E8F46D19CD30C6295E9954B21A787A796FCE8AD910BEF55913F6E5C27547003897D3AA4A1C3A8C080BA13C553EC3581A99033658CE595B","trackType":""}
Dec 20 21:42:55 volumio volumio[1470]: verbose: CURRENT POSITION 0
Dec 20 21:42:55 volumio volumio[1470]: info: CoreStateMachine::syncState stateService play
Dec 20 21:42:55 volumio volumio[1470]: info: CoreStateMachine::syncState currentStatus stop
Dec 20 21:42:55 volumio volumio[1470]: info: Pushing Favourites {"uri":"webmbc/0","favourite":false}
Dec 20 21:42:55 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2.
Dec 20 21:42:55 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD.
Dec 20 21:42:55 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD.
Dec 20 21:42:55 volumio sudo[2207]: pam_unix(sudo:session): session closed for user root
Dec 20 21:42:55 volumio volumio[1470]: info: Upmpdcli Daemon Started
Dec 20 21:42:55 volumio volumio[1470]: info: Updating MyVolumio device info
Dec 20 21:42:55 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 20 21:42:55 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 20 21:42:55 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 20 21:42:56 volumio volumio[1470]: info:
Dec 20 21:42:56 volumio volumio[1470]: ---------------------------- MPD announces state update: player
Dec 20 21:42:56 volumio volumio[1470]: info: ControllerMpd::getState
Dec 20 21:42:56 volumio volumio[1470]: verbose: ControllerMpd::sendMpdCommand status
Dec 20 21:42:56 volumio volumio[1470]: info:
Dec 20 21:42:56 volumio volumio[1470]: ---------------------------- MPD announces state update: player
Dec 20 21:42:56 volumio volumio[1470]: info: ControllerMpd::getState
Dec 20 21:42:56 volumio volumio[1470]: verbose: ControllerMpd::sendMpdCommand status
Dec 20 21:42:56 volumio volumio[1470]: info:
Dec 20 21:42:56 volumio volumio[1470]: ---------------------------- MPD announces state update: player
Dec 20 21:42:56 volumio volumio[1470]: info: ControllerMpd::getState
Dec 20 21:42:56 volumio volumio[1470]: verbose: ControllerMpd::sendMpdCommand status
Dec 20 21:42:56 volumio volumio[1470]: info:
Dec 20 21:42:56 volumio volumio[1470]: ---------------------------- MPD announces state update: player
Dec 20 21:42:56 volumio volumio[1470]: info: ControllerMpd::getState
Dec 20 21:42:56 volumio volumio[1470]: verbose: ControllerMpd::sendMpdCommand status
Dec 20 21:42:56 volumio volumio[1470]: info: sendMpdCommand status took 51 milliseconds
Dec 20 21:42:56 volumio volumio[1470]: verbose: ControllerMpd::parseState
Dec 20 21:42:56 volumio volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 20 21:42:56 volumio volumio[1470]: info:
Dec 20 21:42:56 volumio volumio[1470]: ---------------------------- MPD announces state update: player
Dec 20 21:42:56 volumio volumio[1470]: info: ControllerMpd::getState
Dec 20 21:42:56 volumio volumio[1470]: verbose: ControllerMpd::sendMpdCommand status
Dec 20 21:42:56 volumio volumio[1470]: info:
Dec 20 21:42:56 volumio volumio[1470]: ---------------------------- MPD announces state update: player
Dec 20 21:42:56 volumio volumio[1470]: info: ControllerMpd::getState
Dec 20 21:42:56 volumio volumio[1470]: verbose: ControllerMpd::sendMpdCommand status
Dec 20 21:42:56 volumio volumio[1470]: info: sendMpdCommand status took 54 milliseconds
Dec 20 21:42:56 volumio volumio[1470]: info: sendMpdCommand status took 54 milliseconds
Dec 20 21:42:56 volumio volumio[1470]: info: sendMpdCommand status took 53 milliseconds
Dec 20 21:42:56 volumio volumio[1470]: verbose: ControllerMpd::parseState
Dec 20 21:42:56 volumio volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 20 21:42:56 volumio volumio[1470]: verbose: ControllerMpd::parseState
Dec 20 21:42:56 volumio volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 20 21:42:56 volumio volumio[1470]: verbose: ControllerMpd::parseState
Dec 20 21:42:56 volumio volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 20 21:42:56 volumio volumio[1470]: info:
Dec 20 21:42:56 volumio volumio[1470]: ---------------------------- MPD announces state update: player
Dec 20 21:42:56 volumio volumio[1470]: info: ControllerMpd::getState
Dec 20 21:42:56 volumio volumio[1470]: verbose: ControllerMpd::sendMpdCommand status
Dec 20 21:42:56 volumio volumio[1470]: info:
Dec 20 21:42:56 volumio volumio[1470]: ---------------------------- MPD announces state update: player
Dec 20 21:42:56 volumio volumio[1470]: info: ControllerMpd::getState
Dec 20 21:42:56 volumio volumio[1470]: verbose: ControllerMpd::sendMpdCommand status
Dec 20 21:42:56 volumio volumio[1470]: info: sendMpdCommand playlistinfo took 4 milliseconds
Dec 20 21:42:56 volumio volumio[1470]: info: sendMpdCommand status took 4 milliseconds
Dec 20 21:42:56 volumio volumio[1470]: info: sendMpdCommand status took 4 milliseconds
Dec 20 21:42:56 volumio volumio[1470]: info: sendMpdCommand playlistinfo took 4 milliseconds
Dec 20 21:42:56 volumio volumio[1470]: info: sendMpdCommand playlistinfo took 3 milliseconds
Dec 20 21:42:56 volumio volumio[1470]: info: sendMpdCommand playlistinfo took 3 milliseconds
Dec 20 21:42:56 volumio volumio[1470]: info: sendMpdCommand status took 3 milliseconds
Dec 20 21:42:56 volumio volumio[1470]: info: sendMpdCommand status took 2 milliseconds
Dec 20 21:42:56 volumio volumio[1470]: verbose: ControllerMpd::parseTrackInfo
Dec 20 21:42:56 volumio volumio[1470]: verbose: ControllerMpd::parseState
Dec 20 21:42:56 volumio volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 20 21:42:56 volumio volumio[1470]: verbose: ControllerMpd::parseState
Dec 20 21:42:56 volumio volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 20 21:42:56 volumio volumio[1470]: verbose: ControllerMpd::parseTrackInfo
Dec 20 21:42:56 volumio volumio[1470]: verbose: ControllerMpd::parseTrackInfo
Dec 20 21:42:56 volumio volumio[1470]: verbose: ControllerMpd::parseTrackInfo
Dec 20 21:42:56 volumio volumio[1470]: verbose: ControllerMpd::parseState
Dec 20 21:42:56 volumio volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 20 21:42:56 volumio volumio[1470]: verbose: ControllerMpd::parseState
Dec 20 21:42:56 volumio volumio[1470]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 20 21:42:56 volumio volumio[1470]: info: ControllerMpd::pushState
Dec 20 21:42:56 volumio volumio[1470]: info: CoreCommandRouter::servicePushState
Dec 20 21:42:56 volumio volumio[1470]: info: CorePlayQueue::getTrack 0
Dec 20 21:42:56 volumio volumio[1470]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd
Dec 20 21:42:56 volumio volumio[1470]: info: ControllerMpd::pushState
Dec 20 21:42:56 volumio volumio[1470]: info: CoreCommandRouter::servicePushState
Dec 20 21:42:56 volumio volumio[1470]: info: CorePlayQueue::getTrack 0
Dec 20 21:42:56 volumio volumio[1470]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd
Dec 20 21:42:56 volumio volumio[1470]: info: ControllerMpd::pushState
Dec 20 21:42:56 volumio volumio[1470]: info: CoreCommandRouter::servicePushState
Dec 20 21:42:56 volumio volumio[1470]: info: CorePlayQueue::getTrack 0
Dec 20 21:42:56 volumio volumio[1470]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd
Dec 20 21:42:56 volumio volumio[1470]: info: ControllerMpd::pushState
Dec 20 21:42:56 volumio volumio[1470]: info: CoreCommandRouter::servicePushState
Dec 20 21:42:56 volumio volumio[1470]: info: CorePlayQueue::getTrack 0
Dec 20 21:42:56 volumio volumio[1470]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd
Dec 20 21:42:56 volumio volumio[1470]: info: ------------------------------ 62ms
Dec 20 21:42:56 volumio volumio[1470]: info: ------------------------------ 61ms
Dec 20 21:42:56 volumio volumio[1470]: info: ------------------------------ 61ms
Dec 20 21:42:56 volumio volumio[1470]: info: ------------------------------ 61ms
Dec 20 21:42:56 volumio volumio[1470]: info: sendMpdCommand playlistinfo took 3 milliseconds
Dec 20 21:42:56 volumio volumio[1470]: info: sendMpdCommand playlistinfo took 2 milliseconds
Dec 20 21:42:56 volumio volumio[1470]: info: sendMpdCommand playlistinfo took 2 milliseconds
Dec 20 21:42:56 volumio volumio[1470]: info: sendMpdCommand playlistinfo took 2 milliseconds
Dec 20 21:42:56 volumio volumio[1470]: verbose: ControllerMpd::parseTrackInfo
Dec 20 21:42:56 volumio volumio[1470]: verbose: ControllerMpd::parseTrackInfo
Dec 20 21:42:56 volumio volumio[1470]: verbose: ControllerMpd::parseTrackInfo
Dec 20 21:42:56 volumio volumio[1470]: verbose: ControllerMpd::parseTrackInfo
Dec 20 21:42:56 volumio volumio[1470]: info: ControllerMpd::pushState
Dec 20 21:42:56 volumio volumio[1470]: info: CoreCommandRouter::servicePushState
Dec 20 21:42:56 volumio volumio[1470]: info: CorePlayQueue::getTrack 0
Dec 20 21:42:56 volumio volumio[1470]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd
Dec 20 21:42:56 volumio volumio[1470]: info: ControllerMpd::pushState
Dec 20 21:42:56 volumio volumio[1470]: info: CoreCommandRouter::servicePushState
Dec 20 21:42:56 volumio volumio[1470]: info: CorePlayQueue::getTrack 0
Dec 20 21:42:56 volumio volumio[1470]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd
Dec 20 21:42:56 volumio volumio[1470]: info: ControllerMpd::pushState
Dec 20 21:42:56 volumio volumio[1470]: info: CoreCommandRouter::servicePushState
Dec 20 21:42:56 volumio volumio[1470]: info: CorePlayQueue::getTrack 0
Dec 20 21:42:56 volumio volumio[1470]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd
Dec 20 21:42:56 volumio volumio[1470]: info: ControllerMpd::pushState
Dec 20 21:42:56 volumio volumio[1470]: info: CoreCommandRouter::servicePushState
Dec 20 21:42:56 volumio volumio[1470]: info: CorePlayQueue::getTrack 0
Dec 20 21:42:56 volumio volumio[1470]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current personal_radio Received mpd
Dec 20 21:42:56 volumio volumio[1470]: info: ------------------------------ 10ms
Dec 20 21:42:56 volumio volumio[1470]: info: ------------------------------ 9ms
Dec 20 21:42:56 volumio volumio[1470]: info: ------------------------------ 8ms
Dec 20 21:42:56 volumio volumio[1470]: info: ------------------------------ 7ms
Dec 20 21:42:56 volumio volumio[1470]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Dec 20 21:42:57 volumio kernel: usb usb3-port1: attempt power cycle
Dec 20 21:42:57 volumio kernel: usb 3-2: reset full-speed USB device number 31 using xhci-hcd
Dec 20 21:42:59 volumio upmpdcli[2326]: writing RSA key
Dec 20 21:42:59 volumio kernel: usb usb3-port1: unable to enumerate USB device
Dec 20 21:42:59 volumio kernel: usb usb3-port2: over-current change #16
Dec 20 21:43:01 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Dec 20 21:43:01 volumio volumio[1470]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8
Dec 20 21:43:01 volumio volumio[1470]: info: CoreCommandRouter::volumioGetState
Dec 20 21:43:01 volumio volumio[1470]: info: CorePlayQueue::getTrack 0
Dec 20 21:43:01 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Dec 20 21:43:01 volumio volumio[1470]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined
Dec 20 21:43:01 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates
Dec 20 21:43:01 volumio volumio[1470]: info: Received Get System Version
Dec 20 21:43:01 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 20 21:43:01 volumio volumio[1470]: info: Received Get System Info
Dec 20 21:43:01 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Dec 20 21:43:01 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Dec 20 21:43:01 volumio volumio[1470]: info: Discovery: Getting this device information
Dec 20 21:43:01 volumio volumio[1470]: info: CoreCommandRouter::volumioGetState
Dec 20 21:43:01 volumio volumio[1470]: info: CorePlayQueue::getTrack 0
Dec 20 21:43:01 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Dec 20 21:43:03 volumio kernel: usb usb3-port1: over-current change #18
Dec 20 21:43:03 volumio kernel: usb usb1-port1: over-current change #52
Dec 20 21:43:03 volumio kernel: usb usb4-port1: over-current change #55
Dec 20 21:43:03 volumio kernel: usb usb2-port1: over-current change #55
Dec 20 21:43:03 volumio kernel: usb usb1-port2: over-current change #43
Dec 20 21:43:05 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 20 21:43:05 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 20 21:43:05 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Dec 20 21:43:05 volumio volumio-remote-updater[1052]: Test mode enabled
Dec 20 21:43:05 volumio volumio-remote-updater[1052]: Alpha mode disabled
Dec 20 21:43:05 volumio volumio-remote-updater[1052]: Alpha legacy test mode disabled
Dec 20 21:43:05 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Dec 20 21:43:05 volumio kernel: usb usb3-port1: attempt power cycle
Dec 20 21:43:06 volumio kernel: usb 3-2: reset full-speed USB device number 31 using xhci-hcd
Dec 20 21:43:06 volumio volumio[1470]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"NEW ADDITION
\n\n- Habemus wireless!
\n
\nFIX
\n\n- Unified dhcp client settings
\n- MPD service monitoring
\n- Custom Waveshare DSI panel kernel module
\n
\n","title":"Update v4.082","updateavailable":true}
Dec 20 21:43:06 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Dec 20 21:43:06 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 20 21:43:06 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 20 21:43:06 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Dec 20 21:43:06 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Dec 20 21:43:06 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Dec 20 21:43:06 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Dec 20 21:43:06 volumio volumio[1470]: info: CoreCommandRouter::volumioGetBrowseSources
Dec 20 21:43:06 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 20 21:43:07 volumio volumio[1470]: error: MyVolumio Plugin failed to authenticate in a timely fashion
Dec 20 21:43:07 volumio volumio[1470]: info: Completed starting MyVolumio Plugin
Dec 20 21:43:07 volumio volumio[1470]: [Metrics] CommandRouter: 35s 411.62ms
Dec 20 21:43:07 volumio volumio[1470]: info: CoreCommandRouter::volumiosetStartupVolume
Dec 20 21:43:07 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 20 21:43:07 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 20 21:43:07 volumio volumio[1470]: info: CoreCommandRouter::Close All Modals sent
Dec 20 21:43:07 volumio volumio[1470]: info: CoreCommandRouter::Close All Modals sent
Dec 20 21:43:07 volumio volumio[1470]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav
Dec 20 21:43:07 volumio volumio[1470]: aplay: main:831: audio open error: Device or resource busy
Dec 20 21:43:08 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Dec 20 21:43:08 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Dec 20 21:43:08 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Dec 20 21:43:08 volumio kernel: usb usb3-port1: unable to enumerate USB device
Dec 20 21:43:08 volumio kernel: usb usb3-port2: over-current change #17
Dec 20 21:43:08 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service...
Dec 20 21:43:09 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully.
Dec 20 21:43:09 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service.
Dec 20 21:43:13 volumio volumio[1470]: info: CoreCommandRouter::getUIConfigOnPlugin
Dec 20 21:43:13 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Dec 20 21:43:13 volumio kernel: usb usb1-port1: over-current change #53
Dec 20 21:43:13 volumio kernel: usb usb3-port1: over-current change #19
Dec 20 21:43:13 volumio kernel: usb usb4-port1: over-current change #56
Dec 20 21:43:13 volumio kernel: usb usb2-port1: over-current change #56
Dec 20 21:43:13 volumio kernel: usb usb1-port2: over-current change #44
Dec 20 21:43:14 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork
Dec 20 21:43:14 volumio sudo[2446]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0
Dec 20 21:43:14 volumio sudo[2446]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 20 21:43:14 volumio sudo[2451]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0
Dec 20 21:43:14 volumio sudo[2451]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 20 21:43:14 volumio sudo[2451]: pam_unix(sudo:session): session closed for user root
Dec 20 21:43:14 volumio sudo[2458]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0
Dec 20 21:43:14 volumio sudo[2458]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 20 21:43:14 volumio sudo[2458]: pam_unix(sudo:session): session closed for user root
Dec 20 21:43:14 volumio sudo[2446]: pam_unix(sudo:session): session closed for user root
Dec 20 21:43:14 volumio sudo[2469]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Dec 20 21:43:14 volumio sudo[2469]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 20 21:43:14 volumio sudo[2464]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0
Dec 20 21:43:14 volumio sudo[2464]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 20 21:43:14 volumio sudo[2472]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Dec 20 21:43:14 volumio sudo[2469]: pam_unix(sudo:session): session closed for user root
Dec 20 21:43:14 volumio sudo[2472]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 20 21:43:14 volumio sudo[2464]: pam_unix(sudo:session): session closed for user root
Dec 20 21:43:14 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache
Dec 20 21:43:14 volumio sudo[2472]: pam_unix(sudo:session): session closed for user root
Dec 20 21:43:14 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
Dec 20 21:43:14 volumio volumio[1470]: info: BOOT COMPLETED
Dec 20 21:43:14 volumio sudo[2478]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Dec 20 21:43:14 volumio sudo[2478]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 20 21:43:14 volumio sudo[2478]: pam_unix(sudo:session): session closed for user root
Dec 20 21:43:14 volumio volumio[1470]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan
Dec 20 21:43:14 volumio volumio[1470]: wlan0 Interface doesn't support scanning : Network is down
Dec 20 21:43:14 volumio volumio[1470]: info: Cannot use regular scanning, forcing with ap-force
Dec 20 21:43:14 volumio sudo[2482]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force
Dec 20 21:43:14 volumio sudo[2482]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 20 21:43:14 volumio sudo[2482]: pam_unix(sudo:session): session closed for user root
Dec 20 21:43:14 volumio volumio[1470]: command failed: Network is down (-100)
Dec 20 21:43:14 volumio volumio[1470]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force
Dec 20 21:43:14 volumio volumio[1470]: command failed: Network is down (-100)
Dec 20 21:43:15 volumio kernel: usb usb3-port1: attempt power cycle
Dec 20 21:43:16 volumio kernel: usb 3-2: reset full-speed USB device number 31 using xhci-hcd
Dec 20 21:43:18 volumio kernel: usb usb3-port1: unable to enumerate USB device
Dec 20 21:43:18 volumio kernel: usb usb3-port2: over-current change #18
Dec 20 21:43:34 volumio volumio[1470]: info: CALLMETHOD: system_controller network saveWirelessNet [object Object]
Dec 20 21:43:34 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNet
Dec 20 21:43:34 volumio sudo[2559]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/network/interfaces
Dec 20 21:43:34 volumio sudo[2559]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 20 21:43:34 volumio sudo[2559]: pam_unix(sudo:session): session closed for user root
Dec 20 21:43:34 volumio sudo[2561]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/dhcpcd.conf
Dec 20 21:43:34 volumio sudo[2561]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 20 21:43:34 volumio sudo[2561]: pam_unix(sudo:session): session closed for user root
Dec 20 21:43:34 volumio volumio[1470]: info: Permissions for /etc/network/interfaces set
Dec 20 21:43:34 volumio volumio[1470]: info: Restarting Wireless Network
Dec 20 21:43:34 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart
Dec 20 21:43:34 volumio volumio[1470]: info: Discovery: Restarting Advertising due to device name change
Dec 20 21:43:34 volumio sudo[2564]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service
Dec 20 21:43:34 volumio sudo[2564]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 20 21:43:34 volumio systemd[1]: Stopping wireless.service - Wireless Services...
Dec 20 21:43:34 volumio systemd[1]: wireless.service: Deactivated successfully.
Dec 20 21:43:34 volumio systemd[1]: Stopped wireless.service - Wireless Services.
Dec 20 21:43:34 volumio systemd[1]: Starting wireless.service - Wireless Services...
Dec 20 21:43:35 volumio wireless.js[2566]: WIRELESS.JS: Single Network Mode enabled, only one network device can be active at a time between ethernet and wireless
Dec 20 21:43:35 volumio wireless.js[2566]: WIRELESS.JS: Wired network status changed to: ---connected---
Dec 20 21:43:35 volumio wireless.js[2566]: WIRELESS.JS: Wireless.js initializing wireless flow
Dec 20 21:43:35 volumio wireless.js[2566]: WIRELESS.JS: Cleaning previous...
Dec 20 21:43:35 volumio sudo[2586]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0
Dec 20 21:43:35 volumio sudo[2586]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Dec 20 21:43:35 volumio sudo[2586]: pam_unix(sudo:session): session closed for user root
Dec 20 21:43:35 volumio sudo[2588]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down
Dec 20 21:43:35 volumio sudo[2588]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Dec 20 21:43:35 volumio sudo[2588]: pam_unix(sudo:session): session closed for user root
Dec 20 21:43:35 volumio wireless.js[2566]: WIRELESS.JS: Stopped aP
Dec 20 21:43:35 volumio sudo[2596]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up
Dec 20 21:43:35 volumio sudo[2596]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 20 21:43:35 volumio sudo[2596]: pam_unix(sudo:session): session closed for user root
Dec 20 21:43:35 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled
Dec 20 21:43:35 volumio sudo[2598]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get
Dec 20 21:43:35 volumio sudo[2598]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 20 21:43:35 volumio sudo[2598]: pam_unix(sudo:session): session closed for user root
Dec 20 21:43:35 volumio sudo[2606]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up
Dec 20 21:43:35 volumio sudo[2606]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 20 21:43:35 volumio sudo[2606]: pam_unix(sudo:session): session closed for user root
Dec 20 21:43:35 volumio sudo[2608]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan
Dec 20 21:43:35 volumio sudo[2608]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 20 21:43:35 volumio volumio[1470]: info: UPDATER: Scheduling automatic update
Dec 20 21:43:35 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStartTime
Dec 20 21:43:35 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStopTime
Dec 20 21:43:35 volumio volumio[1470]: info: UPDATER: Auto update will take place at: Sun Dec 21 2025 03:08:34 GMT+0900 (Korean Standard Time)
Dec 20 21:43:35 volumio volumio[1470]: info: Discovery: A device disappeared from network
Dec 20 21:43:35 volumio volumio[1470]: info: Discovery: Device volumio disappeared from network
Dec 20 21:43:35 volumio volumio[1470]: info: Discovery: A device disappeared from network
Dec 20 21:43:38 volumio sudo[2608]: pam_unix(sudo:session): session closed for user root
Dec 20 21:43:38 volumio wireless.js[2566]: WIRELESS.JS: SETTING APPROPRIATE REG DOMAIN: US
Dec 20 21:43:38 volumio sudo[2621]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up
Dec 20 21:43:38 volumio sudo[2621]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 20 21:43:38 volumio sudo[2621]: pam_unix(sudo:session): session closed for user root
Dec 20 21:43:38 volumio sudo[2623]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set US
Dec 20 21:43:38 volumio sudo[2623]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 20 21:43:38 volumio sudo[2623]: pam_unix(sudo:session): session closed for user root
Dec 20 21:43:38 volumio wireless.js[2566]: WIRELESS.JS: SUCCESSFULLY SET NEW REGDOMAIN: US
Dec 20 21:43:38 volumio wireless.js[2566]: WIRELESS.JS: Single Network Mode: Wired network active, not starting wireless flow
Dec 20 21:43:38 volumio wireless.js[2566]: WIRELESS.JS: Notified systemd about wireless ready
Dec 20 21:43:38 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled
Dec 20 21:43:38 volumio systemd[1]: Started wireless.service - Wireless Services.
Dec 20 21:43:38 volumio sudo[2564]: pam_unix(sudo:session): session closed for user root
Dec 20 21:43:39 volumio volumio[1470]: info: Discovery: Started advertising with name: Volumio
Dec 20 21:43:40 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Dec 20 21:43:40 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Dec 20 21:43:40 volumio volumio[1470]: info: Discovery: Getting this device information
Dec 20 21:43:40 volumio volumio[1470]: info: CoreCommandRouter::volumioGetState
Dec 20 21:43:40 volumio volumio[1470]: info: CorePlayQueue::getTrack 0
Dec 20 21:43:40 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Dec 20 21:43:40 volumio volumio[1470]: verbose: New Socket.io Connection to 192.168.0.38:3000 from 192.168.0.149 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9
Dec 20 21:43:40 volumio volumio[1470]: info: Discovery: adding a856deef-94b1-4001-8b5a-69be678cb3d1
Dec 20 21:43:40 volumio volumio[1470]: info: Discovery: Found device Volumio
Dec 20 21:43:40 volumio volumio[1470]: info: CoreCommandRouter::volumioGetState
Dec 20 21:43:40 volumio volumio[1470]: info: CorePlayQueue::getTrack 0
Dec 20 21:43:40 volumio volumio[1470]: info: Discovery: this is already registered, a856deef-94b1-4001-8b5a-69be678cb3d1
Dec 20 21:43:40 volumio volumio[1470]: info: Discovery: Found device Volumio
Dec 20 21:43:40 volumio volumio[1470]: info: CoreCommandRouter::volumioGetState
Dec 20 21:43:40 volumio volumio[1470]: info: CorePlayQueue::getTrack 0
Dec 20 21:43:40 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Dec 20 21:43:40 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Dec 20 21:43:43 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection
Dec 20 21:43:43 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo
Dec 20 21:43:43 volumio sudo[2675]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Dec 20 21:43:43 volumio sudo[2675]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 20 21:43:43 volumio sudo[2675]: pam_unix(sudo:session): session closed for user root
Dec 20 21:43:48 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart
Dec 20 21:43:48 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart
Dec 20 21:43:48 volumio volumio[1470]: info: Refreshing Cached IP Addresses
Dec 20 21:43:48 volumio sudo[2703]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli
Dec 20 21:43:48 volumio sudo[2703]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 20 21:43:48 volumio sudo[2705]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Dec 20 21:43:48 volumio sudo[2705]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 20 21:43:48 volumio sudo[2707]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Dec 20 21:43:48 volumio sudo[2707]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 20 21:43:48 volumio sudo[2705]: pam_unix(sudo:session): session closed for user root
Dec 20 21:43:48 volumio sudo[2707]: pam_unix(sudo:session): session closed for user root
Dec 20 21:43:48 volumio sudo[2703]: pam_unix(sudo:session): session closed for user root
Dec 20 21:43:48 volumio volumio[1470]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8
Dec 20 21:43:48 volumio volumio[1470]: info: CoreCommandRouter::getUIConfigOnPlugin
Dec 20 21:43:48 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Dec 20 21:43:48 volumio systemd[1]: upmpdcli.service: Deactivated successfully.
Dec 20 21:43:48 volumio systemd[1]: upmpdcli.service: Consumed 3.738s CPU time.
Dec 20 21:43:48 volumio volumio[1470]: info: CoreCommandRouter::volumioGetState
Dec 20 21:43:48 volumio volumio[1470]: info: CorePlayQueue::getTrack 0
Dec 20 21:43:48 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Dec 20 21:43:48 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Dec 20 21:43:48 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Dec 20 21:43:48 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Dec 20 21:43:48 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork
Dec 20 21:43:48 volumio sudo[2732]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0
Dec 20 21:43:48 volumio sudo[2727]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0
Dec 20 21:43:48 volumio sudo[2732]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 20 21:43:48 volumio sudo[2727]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 20 21:43:48 volumio sudo[2727]: pam_unix(sudo:session): session closed for user root
Dec 20 21:43:48 volumio sudo[2732]: pam_unix(sudo:session): session closed for user root
Dec 20 21:43:48 volumio sudo[2744]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0
Dec 20 21:43:48 volumio sudo[2744]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 20 21:43:48 volumio sudo[2744]: pam_unix(sudo:session): session closed for user root
Dec 20 21:43:48 volumio sudo[2740]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0
Dec 20 21:43:48 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache
Dec 20 21:43:48 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
Dec 20 21:43:48 volumio sudo[2740]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 20 21:43:48 volumio sudo[2750]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Dec 20 21:43:48 volumio sudo[2750]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 20 21:43:48 volumio sudo[2754]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Dec 20 21:43:48 volumio sudo[2754]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 20 21:43:48 volumio sudo[2754]: pam_unix(sudo:session): session closed for user root
Dec 20 21:43:48 volumio sudo[2740]: pam_unix(sudo:session): session closed for user root
Dec 20 21:43:48 volumio sudo[2750]: pam_unix(sudo:session): session closed for user root
Dec 20 21:43:48 volumio sudo[2759]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Dec 20 21:43:48 volumio sudo[2759]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Dec 20 21:43:50 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Dec 20 21:43:50 volumio volumio[1470]: info: Received Get System Info
Dec 20 21:43:50 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Dec 20 21:43:50 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Dec 20 21:43:50 volumio volumio[1470]: info: Discovery: Getting this device information
Dec 20 21:43:50 volumio volumio[1470]: info: CoreCommandRouter::volumioGetState
Dec 20 21:43:50 volumio volumio[1470]: info: CorePlayQueue::getTrack 0
Dec 20 21:43:50 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Dec 20 21:43:50 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Dec 20 21:43:51 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Dec 20 21:43:51 volumio volumio[1470]: info: Received Get System Info
Dec 20 21:43:51 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Dec 20 21:43:51 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Dec 20 21:43:51 volumio volumio[1470]: info: Discovery: Getting this device information
Dec 20 21:43:51 volumio volumio[1470]: info: CoreCommandRouter::volumioGetState
Dec 20 21:43:51 volumio volumio[1470]: info: CorePlayQueue::getTrack 0
Dec 20 21:43:51 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Dec 20 21:43:51 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Dec 20 21:43:51 volumio sudo[2759]: pam_unix(sudo:session): session closed for user root
Dec 20 21:43:52 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Dec 20 21:43:52 volumio volumio[1470]: info: Received Get System Info
Dec 20 21:43:52 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Dec 20 21:43:52 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Dec 20 21:43:52 volumio volumio[1470]: info: Discovery: Getting this device information
Dec 20 21:43:52 volumio volumio[1470]: info: CoreCommandRouter::volumioGetState
Dec 20 21:43:52 volumio volumio[1470]: info: CorePlayQueue::getTrack 0
Dec 20 21:43:52 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Dec 20 21:43:53 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Dec 20 21:43:53 volumio volumio[1470]: info: Received Get System Info
Dec 20 21:43:53 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Dec 20 21:43:53 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Dec 20 21:43:53 volumio volumio[1470]: info: Discovery: Getting this device information
Dec 20 21:43:53 volumio volumio[1470]: info: CoreCommandRouter::volumioGetState
Dec 20 21:43:53 volumio volumio[1470]: info: CorePlayQueue::getTrack 0
Dec 20 21:43:53 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Dec 20 21:43:54 volumio volumio[1470]: info: CoreCommandRouter::volumioGetVisibleSources
Dec 20 21:43:54 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Dec 20 21:43:54 volumio volumio[1470]: info: CoreCommandRouter::volumioGetQueue
Dec 20 21:43:54 volumio volumio[1470]: info: CoreStateMachine::getQueue
Dec 20 21:43:54 volumio volumio[1470]: info: CorePlayQueue::getQueue
Dec 20 21:43:54 volumio volumio[1470]: info: Listing playlists
Dec 20 21:43:54 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Dec 20 21:43:54 volumio volumio[1470]: info: Received Get System Info
Dec 20 21:43:54 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Dec 20 21:43:54 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Dec 20 21:43:54 volumio volumio[1470]: info: Discovery: Getting this device information
Dec 20 21:43:54 volumio volumio[1470]: info: CoreCommandRouter::volumioGetState
Dec 20 21:43:54 volumio volumio[1470]: info: CorePlayQueue::getTrack 0
Dec 20 21:43:54 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Dec 20 21:43:55 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri
Dec 20 21:43:55 volumio volumio[1470]: info: Preload queue cleared
Dec 20 21:43:56 volumio volumio[1470]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri
Dec 20 21:43:56 volumio volumio[1470]: info: Preload queue cleared
Dec 20 21:43:56 volumio volumio[1470]: info: Preloading song: webmbc/0
Dec 20 21:43:56 volumio volumio[1470]: info: Preloading song: webmbc/1
Dec 20 21:43:56 volumio volumio[1470]: info: Preloading song: webmbc/2
Dec 20 21:43:57 volumio volumio[1470]: info: Exploding uri webmbc/0 in service personal_radio
Dec 20 21:43:57 volumio volumio[1470]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 20 21:43:57 volumio volumio[1470]: TypeError: Cannot read properties of null (reading 'startsWith')
Dec 20 21:43:57 volumio volumio[1470]: at /data/plugins/music_service/personal_radio/index.js:675:41
Dec 20 21:43:57 volumio volumio[1470]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5)
Dec 20 21:43:57 volumio volumio[1470]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 20 21:43:57 volumio sudo[2820]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-20 21:42'
Dec 20 21:43:57 volumio sudo[2820]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)"
NAME="Raspbian GNU/Linux"
VERSION_ID="12"
VERSION="12 (bookworm)"
VERSION_CODENAME=bookworm
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="cfdc872d40b979cca36352a7333c940c38dc64b5"
VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd"
VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2"
VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025"
VOLUMIO_VERSION="4.073"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"