-- Logs begin at Thu 2019-02-14 10:11:59 GMT, end at Mon 2024-10-28 19:30:47 GMT. -- Oct 28 19:29:39 volumio3 volumio[4329]: info: CALLMETHOD: system_hardware gpio_control saveConfig [object Object] Oct 28 19:29:39 volumio3 volumio[4329]: info: CoreCommandRouter::executeOnPlugin: gpio_control , saveConfig Oct 28 19:29:39 volumio3 volumio[4329]: info: CoreCommandRouter::volumioGetState Oct 28 19:29:39 volumio3 volumio[4329]: info: CorePlayQueue::getTrack 0 Oct 28 19:29:48 volumio3 volumio[4329]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Oct 28 19:29:48 volumio3 volumio[4329]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 28 19:29:48 volumio3 volumio[4329]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 28 19:29:53 volumio3 volumio[4329]: info: [ASDebug] Port: 2 Oct 28 19:29:53 volumio3 volumio[4329]: info: [ASDebug] Inverted: true Oct 28 19:29:53 volumio3 volumio[4329]: info: [ASDebug] Delay: 720 Oct 28 19:29:57 volumio3 volumio[4329]: info: [ASDebug] Port: 2 Oct 28 19:29:57 volumio3 volumio[4329]: info: [ASDebug] Inverted: true Oct 28 19:29:57 volumio3 volumio[4329]: info: [ASDebug] Delay: 720 Oct 28 19:29:57 volumio3 volumio[4329]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 28 19:29:57 volumio3 volumio[4329]: Error: EBADF: bad file descriptor, close Oct 28 19:29:57 volumio3 volumio[4329]: at Object.closeSync (fs.js:439:3) Oct 28 19:29:57 volumio3 volumio[4329]: at Object.closeSync (/volumio/node_modules/graceful-fs/graceful-fs.js:74:20) Oct 28 19:29:57 volumio3 volumio[4329]: at Gpio.unexport (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:307:8) Oct 28 19:29:57 volumio3 volumio[4329]: at AmpSwitchController.freeGPIO (/data/plugins/system_controller/ampswitch/index.js:234:19) Oct 28 19:29:57 volumio3 volumio[4329]: at AmpSwitchController.onStop (/data/plugins/system_controller/ampswitch/index.js:84:10) Oct 28 19:29:57 volumio3 volumio[4329]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:454:30) Oct 28 19:29:57 volumio3 volumio[4329]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1859:8) Oct 28 19:29:57 volumio3 volumio[4329]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1492:29) Oct 28 19:29:57 volumio3 volumio[4329]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1237:47) Oct 28 19:29:57 volumio3 volumio[4329]: at Socket.emit (events.js:315:20) Oct 28 19:29:57 volumio3 volumio[4329]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Oct 28 19:29:57 volumio3 volumio[4329]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Oct 28 19:29:57 volumio3 volumio[4329]: errno: -9, Oct 28 19:29:57 volumio3 volumio[4329]: syscall: 'close', Oct 28 19:29:57 volumio3 volumio[4329]: code: 'EBADF' Oct 28 19:29:57 volumio3 volumio[4329]: } Oct 28 19:29:57 volumio3 volumio[4329]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 28 19:29:57 volumio3 sudo[4598]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-28 19:28 Oct 28 19:29:57 volumio3 sudo[4598]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 19:29:57 volumio3 sudo[4598]: pam_unix(sudo:session): session closed for user root Oct 28 19:29:58 volumio3 volumio-remote-updater[427]: [2024-10-28 19:29:58] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 28 19:29:58 volumio3 volumio-remote-updater[427]: [2024-10-28 19:29:58] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 28 19:29:58 volumio3 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 28 19:29:58 volumio3 systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 28 19:29:58 volumio3 systemd[1]: Started dynamicswap service. Oct 28 19:29:58 volumio3 systemd[1]: dynamicswap.service: Succeeded. Oct 28 19:29:58 volumio3 systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Oct 28 19:29:58 volumio3 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. Oct 28 19:29:58 volumio3 systemd[1]: Started dynamicswap service. Oct 28 19:29:58 volumio3 systemd[1]: Stopped Volumio Backend Module. Oct 28 19:29:58 volumio3 systemd[1]: Started Volumio Backend Module. Oct 28 19:29:58 volumio3 systemd[1]: dynamicswap.service: Succeeded. Oct 28 19:30:00 volumio3 volumio[4612]: info: ------------------------------------------- Oct 28 19:30:00 volumio3 volumio[4612]: info: ----- Volumio3 ---- Oct 28 19:30:00 volumio3 volumio[4612]: info: ------------------------------------------- Oct 28 19:30:00 volumio3 volumio[4612]: info: ----- System startup ---- Oct 28 19:30:00 volumio3 volumio[4612]: info: ------------------------------------------- Oct 28 19:30:02 volumio3 volumio[4612]: info: MYVOLUMIO Environment detected Oct 28 19:30:02 volumio3 volumio[4612]: info: Plugin folders cleanup Oct 28 19:30:02 volumio3 volumio[4612]: info: Scanning into folder /volumio/app/plugins/ Oct 28 19:30:02 volumio3 volumio[4612]: info: Scanning category audio_interface Oct 28 19:30:02 volumio3 volumio[4612]: info: Scanning category miscellanea Oct 28 19:30:02 volumio3 volumio[4612]: info: Scanning category music_service Oct 28 19:30:02 volumio3 volumio[4612]: info: Scanning category plugins.json Oct 28 19:30:02 volumio3 volumio[4612]: info: Scanning category system_controller Oct 28 19:30:02 volumio3 volumio[4612]: info: Scanning category user_interface Oct 28 19:30:02 volumio3 volumio[4612]: info: Scanning into folder /data/plugins/ Oct 28 19:30:02 volumio3 volumio[4612]: info: Scanning category music_service Oct 28 19:30:02 volumio3 volumio[4612]: info: Scanning category system_controller Oct 28 19:30:02 volumio3 volumio[4612]: info: Scanning category system_hardware Oct 28 19:30:02 volumio3 volumio[4612]: info: Plugin folders cleanup completed Oct 28 19:30:02 volumio3 volumio[4612]: info: ------------------------------------------- Oct 28 19:30:02 volumio3 volumio[4612]: info: ----- Core plugins startup ---- Oct 28 19:30:02 volumio3 volumio[4612]: info: ------------------------------------------- Oct 28 19:30:02 volumio3 volumio[4612]: info: Loading plugins from folder /volumio/app/plugins/ Oct 28 19:30:02 volumio3 volumio[4612]: info: Adding plugin upnp to MyMusic Plugins Oct 28 19:30:02 volumio3 volumio[4612]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 28 19:30:02 volumio3 volumio[4612]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 28 19:30:02 volumio3 volumio[4612]: info: Loading plugins from folder /data/plugins/ Oct 28 19:30:02 volumio3 volumio[4612]: info: Loading plugin "system"... Oct 28 19:30:02 volumio3 volumio[4612]: info: Loading plugin "ampswitch"... Oct 28 19:30:02 volumio3 volumio[4612]: info: Applying required configuration parameters for plugin ampswitch Oct 28 19:30:02 volumio3 volumio[4612]: info: Loading plugin "appearance"... Oct 28 19:30:03 volumio3 volumio-remote-updater[427]: [2024-10-28 19:30:03] [connect] Successful connection Oct 28 19:30:04 volumio3 volumio[4612]: info: Loading plugin "network"... Oct 28 19:30:04 volumio3 volumio[4612]: info: Refreshing Cached IP Addresses Oct 28 19:30:04 volumio3 sudo[4637]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 28 19:30:04 volumio3 sudo[4637]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 19:30:04 volumio3 volumio[4612]: info: Loading plugin "services"... Oct 28 19:30:04 volumio3 sudo[4637]: pam_unix(sudo:session): session closed for user root Oct 28 19:30:04 volumio3 volumio[4612]: info: Loading plugin "alsa_controller"... Oct 28 19:30:04 volumio3 sudo[4639]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 28 19:30:04 volumio3 sudo[4639]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 19:30:04 volumio3 sudo[4639]: pam_unix(sudo:session): session closed for user root Oct 28 19:30:04 volumio3 volumio[4612]: info: Loading plugin "wizard"... Oct 28 19:30:04 volumio3 volumio[4612]: info: Loading plugin "volumio_command_line_client"... Oct 28 19:30:04 volumio3 volumio[4612]: info: Loading plugin "upnp"... Oct 28 19:30:04 volumio3 volumio[4612]: info: [1730143804651] Starting Upmpd Daemon Oct 28 19:30:04 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 28 19:30:04 volumio3 volumio[4612]: info: Loading plugin "my_music"... Oct 28 19:30:04 volumio3 volumio[4612]: info: Loading plugin "mpd"... Oct 28 19:30:05 volumio3 volumio[4612]: info: Creating MPD Configuration file Oct 28 19:30:05 volumio3 volumio[4612]: info: Loading plugin "upnp_browser"... Oct 28 19:30:05 volumio3 sudo[4647]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 28 19:30:05 volumio3 sudo[4647]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 19:30:05 volumio3 sudo[4647]: pam_unix(sudo:session): session closed for user root Oct 28 19:30:05 volumio3 sudo[4649]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 28 19:30:05 volumio3 sudo[4649]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 19:30:05 volumio3 systemd[1]: Stopping Music Player Daemon... Oct 28 19:30:05 volumio3 systemd[1]: mpd.service: Succeeded. Oct 28 19:30:05 volumio3 systemd[1]: Stopped Music Player Daemon. Oct 28 19:30:05 volumio3 systemd[1]: Starting Music Player Daemon... Oct 28 19:30:05 volumio3 volumio[4612]: info: Loading plugin "networkfs"... Oct 28 19:30:06 volumio3 volumio[4612]: info: Starting Udev Watcher for removable devices Oct 28 19:30:06 volumio3 volumio[4612]: info: Ignoring mount for partition: boot Oct 28 19:30:06 volumio3 volumio[4612]: info: Ignoring mount for partition: volumio Oct 28 19:30:06 volumio3 volumio[4612]: info: Ignoring mount for partition: volumio_data Oct 28 19:30:06 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 28 19:30:06 volumio3 volumio[4612]: info: Loading plugin "alarm-clock"... Oct 28 19:30:06 volumio3 volumio[4612]: info: Loading plugin "airplay_emulation"... Oct 28 19:30:06 volumio3 volumio[4612]: info: Starting Shairport Sync Oct 28 19:30:06 volumio3 volumio[4612]: info: Loading plugin "last_100"... Oct 28 19:30:06 volumio3 volumio[4612]: info: Loading plugin "webradio"... Oct 28 19:30:07 volumio3 volumio[4612]: info: Loading plugin "i2s_dacs"... Oct 28 19:30:07 volumio3 volumio[4612]: info: I2S DAC not set, start Auto-detection Oct 28 19:30:07 volumio3 mpd[4654]: Oct 28 19:30 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 28 19:30:07 volumio3 mpd[4654]: Oct 28 19:30 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Oct 28 19:30:07 volumio3 mpd[4654]: Oct 28 19:30 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Oct 28 19:30:07 volumio3 volumio[4612]: info: Loading plugin "volumiodiscovery"... Oct 28 19:30:07 volumio3 systemd[1]: Started Music Player Daemon. Oct 28 19:30:07 volumio3 sudo[4649]: pam_unix(sudo:session): session closed for user root Oct 28 19:30:07 volumio3 volumio[4612]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 28 19:30:07 volumio3 volumio[4612]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 28 19:30:07 volumio3 volumio[4612]: *** WARNING *** For more information see Oct 28 19:30:07 volumio3 volumio[4612]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 28 19:30:07 volumio3 node[4612]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 28 19:30:07 volumio3 volumio[4612]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 28 19:30:07 volumio3 volumio[4612]: *** WARNING *** For more information see Oct 28 19:30:07 volumio3 node[4612]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 28 19:30:07 volumio3 node[4612]: *** WARNING *** For more information see Oct 28 19:30:07 volumio3 node[4612]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 28 19:30:07 volumio3 node[4612]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 28 19:30:07 volumio3 node[4612]: *** WARNING *** For more information see Oct 28 19:30:07 volumio3 volumio[4612]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 28 19:30:07 volumio3 volumio[4612]: info: Discovery: Started advertising with name: Volumio3 Oct 28 19:30:07 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 28 19:30:07 volumio3 volumio[4612]: info: Plugin spop is not enabled Oct 28 19:30:07 volumio3 volumio[4612]: info: Plugin gpio-buttons is not enabled Oct 28 19:30:07 volumio3 volumio[4612]: info: Loading plugin "outputs"... Oct 28 19:30:07 volumio3 volumio[4612]: info: Loading plugin "albumart"... Oct 28 19:30:07 volumio3 volumio[4612]: info: Plugin example_plugin is not enabled Oct 28 19:30:07 volumio3 volumio[4612]: info: Loading plugin "inputs"... Oct 28 19:30:07 volumio3 volumio[4612]: info: Loading plugin "updater_comm"... Oct 28 19:30:07 volumio3 volumio[4612]: info: Plugin mpdemulation is not enabled Oct 28 19:30:07 volumio3 volumio[4612]: info: Loading plugin "rest_api"... Oct 28 19:30:07 volumio3 volumio[4612]: info: Loading plugin "websocket"... Oct 28 19:30:07 volumio3 volumio[4612]: info: Plugin volspotconnect2 is not enabled Oct 28 19:30:07 volumio3 volumio[4612]: info: Loading plugin "gpio_control"... Oct 28 19:30:07 volumio3 volumio[4612]: Forking 3 albumart workers Oct 28 19:30:08 volumio3 volumio[4612]: info: Applying required configuration parameters for plugin gpio_control Oct 28 19:30:08 volumio3 volumio[4612]: info: Plugin mpd_oled is not enabled Oct 28 19:30:08 volumio3 volumio[4612]: info: Loading i18n strings for locale en Oct 28 19:30:08 volumio3 volumio[4612]: Updating browse sources language Oct 28 19:30:08 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 28 19:30:08 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 28 19:30:08 volumio3 volumio[4612]: info: CoreCommandRouter::initPlayerControls Oct 28 19:30:08 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 19:30:08 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 19:30:08 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 19:30:08 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 19:30:08 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 19:30:08 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 19:30:08 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 19:30:08 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 19:30:08 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 28 19:30:08 volumio3 volumio[4612]: Express server listening on port 3000 Oct 28 19:30:08 volumio3 volumio[4612]: [Metrics] WebUI: 8s 188.68ms Oct 28 19:30:08 volumio3 volumio[4612]: info: CoreStateMachine::resetVolumioState Oct 28 19:30:08 volumio3 volumio[4612]: info: CoreStateMachine::getcurrentVolume Oct 28 19:30:08 volumio3 volumio[4612]: info: CoreCommandRouter::volumioRetrievevolume Oct 28 19:30:08 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 19:30:08 volumio3 volumio[4612]: info: MPD Permissions set Oct 28 19:30:08 volumio3 volumio[4612]: verbose: New Socket.io Connection to 192.168.1.158 from 192.168.1.78 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/130.0.0.0 Safari/537.36 Total Clients: 1 Oct 28 19:30:08 volumio3 volumio[4612]: verbose: New Socket.io Connection to 192.168.1.158 from 192.168.1.78 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/130.0.0.0 Safari/537.36 Total Clients: 2 Oct 28 19:30:08 volumio3 volumio[4612]: verbose: New Socket.io Connection to 192.168.1.158 from 192.168.1.78 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/130.0.0.0 Safari/537.36 Total Clients: 3 Oct 28 19:30:08 volumio3 volumio[4612]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4 Oct 28 19:30:08 volumio3 volumio[4612]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 5 Oct 28 19:30:08 volumio3 volumio[4612]: info: MPD running with PID4654 Oct 28 19:30:08 volumio3 volumio[4612]: ,establishing connection Oct 28 19:30:08 volumio3 volumio[4612]: Starting albumart workers Oct 28 19:30:08 volumio3 volumio[4612]: Starting albumart workers Oct 28 19:30:08 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 19:30:08 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 19:30:08 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 19:30:08 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 19:30:08 volumio3 volumio[4612]: Starting albumart workers Oct 28 19:30:08 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 19:30:08 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 19:30:08 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 19:30:08 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 19:30:09 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 19:30:09 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 19:30:09 volumio3 volumio[4612]: info: Reloading queue from file Oct 28 19:30:09 volumio3 volumio[4612]: error: updateQueue error: null Oct 28 19:30:09 volumio3 volumio[4612]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Oct 28 19:30:09 volumio3 volumio[4612]: info: VolumeController:: Volume=39 Mute =false Oct 28 19:30:09 volumio3 volumio[4612]: info: CoreStateMachine::pushState Oct 28 19:30:09 volumio3 volumio[4612]: info: CorePlayQueue::getTrack 0 Oct 28 19:30:09 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 19:30:09 volumio3 volumio[4612]: info: CoreCommandRouter::volumioPushState Oct 28 19:30:09 volumio3 volumio[4612]: info: CoreStateMachine::updateTrackBlock Oct 28 19:30:09 volumio3 volumio[4612]: info: CorePlayQueue::getTrackBlock Oct 28 19:30:09 volumio3 volumio[4612]: info: CoreCommandRouter::volumioRetrievevolume Oct 28 19:30:09 volumio3 volumio[4612]: info: CoreStateMachine::setRepeat null single undefined Oct 28 19:30:09 volumio3 volumio[4612]: info: CoreStateMachine::pushState Oct 28 19:30:09 volumio3 volumio[4612]: info: CorePlayQueue::getTrack 0 Oct 28 19:30:09 volumio3 volumio[4612]: info: CoreCommandRouter::volumioPushState Oct 28 19:30:09 volumio3 volumio[4612]: info: CoreStateMachine::setRandom null Oct 28 19:30:09 volumio3 volumio[4612]: info: CoreStateMachine::pushState Oct 28 19:30:09 volumio3 volumio[4612]: info: CorePlayQueue::getTrack 0 Oct 28 19:30:09 volumio3 volumio[4612]: info: CoreCommandRouter::volumioPushState Oct 28 19:30:09 volumio3 volumio[4612]: info: Setting Device type: Raspberry PI Oct 28 19:30:09 volumio3 volumio[4612]: error: updateQueue error: null Oct 28 19:30:09 volumio3 volumio[4612]: info: Completed loading Core Plugins Oct 28 19:30:09 volumio3 volumio[4612]: info: Preparing to generate the ALSA configuration file Oct 28 19:30:09 volumio3 volumio[4612]: info: Discovery: adding 97ae6ba3-85a0-45e0-9d25-3d71e36432b1 Oct 28 19:30:09 volumio3 volumio[4612]: info: mDNS: Found device Volumio3 Oct 28 19:30:09 volumio3 volumio[4612]: info: CoreCommandRouter::volumioGetState Oct 28 19:30:09 volumio3 volumio[4612]: info: CorePlayQueue::getTrack 0 Oct 28 19:30:09 volumio3 volumio[4612]: info: VolumeController:: Volume=39 Mute =false Oct 28 19:30:09 volumio3 volumio[4612]: info: CoreStateMachine::pushState Oct 28 19:30:09 volumio3 volumio[4612]: info: CorePlayQueue::getTrack 0 Oct 28 19:30:09 volumio3 volumio[4612]: info: CoreCommandRouter::volumioPushState Oct 28 19:30:09 volumio3 volumio[4612]: info: Asound.conf file unchanged, so no further update is needed Oct 28 19:30:09 volumio3 volumio[4612]: info: Output device has changed, restarting MPD Oct 28 19:30:09 volumio3 volumio[4612]: info: Output device has changed, restarting Shairport Sync Oct 28 19:30:09 volumio3 sudo[4724]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 28 19:30:09 volumio3 sudo[4724]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 19:30:09 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 19:30:09 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 19:30:09 volumio3 sudo[4724]: pam_unix(sudo:session): session closed for user root Oct 28 19:30:09 volumio3 sudo[4726]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 28 19:30:09 volumio3 sudo[4726]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 19:30:09 volumio3 systemd[1]: Stopping Music Player Daemon... Oct 28 19:30:09 volumio3 volumio[4612]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 28 19:30:09 volumio3 volumio[4612]: info: ___________ START PLUGINS ___________ Oct 28 19:30:10 volumio3 systemd[1]: mpd.service: Succeeded. Oct 28 19:30:10 volumio3 systemd[1]: Stopped Music Player Daemon. Oct 28 19:30:10 volumio3 systemd[1]: Starting Music Player Daemon... Oct 28 19:30:10 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 28 19:30:10 volumio3 volumio[4612]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 28 19:30:10 volumio3 volumio[4612]: info: [1730143810047] CoreMusicLibrary::Adding element Media Servers Oct 28 19:30:10 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 28 19:30:10 volumio3 volumio[4612]: Cannot find translation for sourceMedia Servers Oct 28 19:30:10 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 19:30:10 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 19:30:10 volumio3 volumio[4612]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 28 19:30:10 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 19:30:10 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 19:30:10 volumio3 volumio[4612]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 28 19:30:10 volumio3 volumio[4612]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 28 19:30:10 volumio3 volumio[4612]: info: [1730143810199] CoreMusicLibrary::Adding element Last_100 Oct 28 19:30:10 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 28 19:30:10 volumio3 volumio[4612]: Cannot find translation for sourceMedia Servers Oct 28 19:30:10 volumio3 volumio[4612]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 28 19:30:10 volumio3 volumio[4612]: info: [1730143810204] CoreMusicLibrary::Adding element Webradio Oct 28 19:30:10 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 28 19:30:10 volumio3 volumio[4612]: Cannot find translation for sourceMedia Servers Oct 28 19:30:10 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 28 19:30:10 volumio3 volumio[4612]: info: Volumio Calling Home Oct 28 19:30:10 volumio3 volumio[4612]: info: Completed starting Core Plugins Oct 28 19:30:10 volumio3 volumio[4612]: info: ------------------------------------------- Oct 28 19:30:10 volumio3 volumio[4612]: info: ----- MyVolumio plugins startup ---- Oct 28 19:30:10 volumio3 volumio[4612]: info: ------------------------------------------- Oct 28 19:30:10 volumio3 volumio[4612]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 28 19:30:10 volumio3 volumio[4612]: info: [ASDebug] CurState: stop PrevState: na Oct 28 19:30:10 volumio3 volumio[4612]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Oct 28 19:30:10 volumio3 volumio[4612]: info: [ASDebug] CurState: stop PrevState: na Oct 28 19:30:10 volumio3 volumio[4612]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Oct 28 19:30:10 volumio3 volumio[4612]: info: CoreCommandRouter::volumioGetState Oct 28 19:30:10 volumio3 volumio[4612]: info: CorePlayQueue::getTrack 0 Oct 28 19:30:10 volumio3 volumio[4612]: info: CoreCommandRouter::volumioGetState Oct 28 19:30:10 volumio3 volumio[4612]: info: CorePlayQueue::getTrack 0 Oct 28 19:30:10 volumio3 volumio[4612]: info: MPD Permissions set Oct 28 19:30:10 volumio3 volumio[4612]: info: Volumio called home Oct 28 19:30:10 volumio3 volumio[4612]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 6 Oct 28 19:30:10 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 19:30:10 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 19:30:10 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 19:30:10 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 19:30:10 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 19:30:10 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 19:30:10 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 19:30:10 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 19:30:10 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 19:30:10 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 19:30:10 volumio3 volumio[4612]: info: Starting Shairport Sync Oct 28 19:30:10 volumio3 volumio[4612]: info: Starting Shairport Sync Oct 28 19:30:10 volumio3 volumio[4612]: info: Starting Shairport Sync Oct 28 19:30:10 volumio3 sudo[4764]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 28 19:30:10 volumio3 sudo[4764]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 19:30:10 volumio3 sudo[4766]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 28 19:30:10 volumio3 sudo[4766]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 19:30:10 volumio3 sudo[4770]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 28 19:30:10 volumio3 sudo[4770]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 19:30:10 volumio3 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 28 19:30:10 volumio3 systemd[1]: shairport-sync.service: Succeeded. Oct 28 19:30:10 volumio3 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 28 19:30:10 volumio3 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 28 19:30:10 volumio3 sudo[4766]: pam_unix(sudo:session): session closed for user root Oct 28 19:30:10 volumio3 sudo[4764]: pam_unix(sudo:session): session closed for user root Oct 28 19:30:10 volumio3 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 28 19:30:10 volumio3 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Oct 28 19:30:10 volumio3 systemd[1]: shairport-sync.service: Succeeded. Oct 28 19:30:10 volumio3 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 28 19:30:11 volumio3 volumio[4612]: info: Shairport-Sync Started Oct 28 19:30:11 volumio3 volumio[4612]: Error adding Membership: Error: addMembership EINVAL Oct 28 19:30:11 volumio3 volumio[4612]: info: Shairport-Sync Started Oct 28 19:30:10 volumio3 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 28 19:30:11 volumio3 sudo[4770]: pam_unix(sudo:session): session closed for user root Oct 28 19:30:11 volumio3 volumio[4612]: info: CoreCommandRouter::volumioGetState Oct 28 19:30:11 volumio3 volumio[4612]: info: CorePlayQueue::getTrack 0 Oct 28 19:30:11 volumio3 volumio[4612]: info: Shairport-Sync Started Oct 28 19:30:11 volumio3 volumio[4612]: info: [ASDebug] CurState: stop PrevState: na Oct 28 19:30:11 volumio3 volumio[4612]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Oct 28 19:30:11 volumio3 mpd[4758]: Oct 28 19:30 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 28 19:30:11 volumio3 mpd[4758]: Oct 28 19:30 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Oct 28 19:30:11 volumio3 mpd[4758]: Oct 28 19:30 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Oct 28 19:30:11 volumio3 systemd[1]: Started Music Player Daemon. Oct 28 19:30:11 volumio3 sudo[4726]: pam_unix(sudo:session): session closed for user root Oct 28 19:30:11 volumio3 volumio[4612]: error: updateQueue error: null Oct 28 19:30:11 volumio3 volumio[4612]: info: [ASDebug] Togle GPIO: OFF Oct 28 19:30:12 volumio3 volumio[4612]: verbose: New Socket.io Connection to 192.168.1.158 from 192.168.1.78 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/130.0.0.0 Safari/537.36 Total Clients: 7 Oct 28 19:30:12 volumio3 volumio[4612]: info: CoreCommandRouter::volumioGetState Oct 28 19:30:12 volumio3 volumio[4612]: info: CorePlayQueue::getTrack 0 Oct 28 19:30:12 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 28 19:30:12 volumio3 volumio[4612]: info: CoreCommandRouter::volumioGetVisibleSources Oct 28 19:30:12 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 28 19:30:12 volumio3 volumio[4612]: info: Listing playlists Oct 28 19:30:14 volumio3 sudo[4779]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 28 19:30:14 volumio3 sudo[4779]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 19:30:14 volumio3 sudo[4779]: pam_unix(sudo:session): session closed for user root Oct 28 19:30:14 volumio3 sudo[4781]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 28 19:30:14 volumio3 sudo[4781]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 19:30:14 volumio3 sudo[4781]: pam_unix(sudo:session): session closed for user root Oct 28 19:30:14 volumio3 sudo[4785]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 28 19:30:14 volumio3 sudo[4785]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 19:30:14 volumio3 sudo[4785]: pam_unix(sudo:session): session closed for user root Oct 28 19:30:14 volumio3 volumio[4612]: info: Upmpdcli Daemon Started Oct 28 19:30:15 volumio3 volumio-remote-updater[427]: [2024-10-28 19:30:15] [connect] Successful connection Oct 28 19:30:15 volumio3 volumio-remote-updater[427]: [2024-10-28 19:30:15] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1730143815 101 Oct 28 19:30:15 volumio3 volumio[4612]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 8 Oct 28 19:30:16 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Oct 28 19:30:16 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 28 19:30:18 volumio3 volumio[4612]: info: CoreCommandRouter::Close All Modals sent Oct 28 19:30:18 volumio3 volumio[4612]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 28 19:30:18 volumio3 volumio[4612]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 28 19:30:18 volumio3 volumio[4612]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 28 19:30:18 volumio3 volumio[4612]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 28 19:30:18 volumio3 volumio[4612]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 28 19:30:18 volumio3 volumio[4612]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 28 19:30:18 volumio3 volumio[4612]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 28 19:30:18 volumio3 volumio[4612]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 28 19:30:18 volumio3 volumio[4612]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 28 19:30:18 volumio3 volumio[4612]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 28 19:30:18 volumio3 volumio[4612]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 28 19:30:18 volumio3 volumio[4612]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 28 19:30:18 volumio3 volumio[4612]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 28 19:30:18 volumio3 volumio[4612]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 28 19:30:18 volumio3 volumio[4612]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 28 19:30:18 volumio3 volumio[4612]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 28 19:30:18 volumio3 volumio[4612]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 28 19:30:18 volumio3 volumio[4612]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 28 19:30:18 volumio3 volumio[4612]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 28 19:30:18 volumio3 volumio[4612]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 28 19:30:18 volumio3 volumio[4612]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 28 19:30:18 volumio3 volumio[4612]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 28 19:30:18 volumio3 volumio[4612]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 28 19:30:18 volumio3 volumio[4612]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 28 19:30:18 volumio3 volumio[4612]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 28 19:30:18 volumio3 volumio[4612]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 28 19:30:18 volumio3 volumio[4612]: info: Adding plugin bluetooth to MyMusic Plugins Oct 28 19:30:18 volumio3 volumio[4612]: info: Adding plugin multiroom to MyMusic Plugins Oct 28 19:30:18 volumio3 volumio[4612]: info: Adding plugin cd_controller to MyMusic Plugins Oct 28 19:30:18 volumio3 volumio[4612]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 28 19:30:18 volumio3 volumio[4612]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 28 19:30:18 volumio3 volumio[4612]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 28 19:30:20 volumio3 volumio[4612]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 28 19:30:20 volumio3 volumio[4612]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 28 19:30:20 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 19:30:20 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 19:30:20 volumio3 volumio[4612]: info: Starting MyVolumio Remote Streaming Endpoints Oct 28 19:30:20 volumio3 volumio[4612]: info: MyVolumio login type: Token Oct 28 19:30:20 volumio3 volumio[4612]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 28 19:30:20 volumio3 volumio[4612]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 28 19:30:20 volumio3 volumio[4612]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 28 19:30:20 volumio3 volumio[4612]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 28 19:30:20 volumio3 volumio[4612]: info: Streaming services startup Oct 28 19:30:20 volumio3 volumio[4612]: info: Starting Streaming Daemon Oct 28 19:30:20 volumio3 sudo[4789]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 28 19:30:20 volumio3 sudo[4789]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 19:30:20 volumio3 systemd[1]: Stopping Volumio Streaming Daemon... Oct 28 19:30:20 volumio3 systemd[1]: volumio-streaming-daemon.service: Main process exited, code=killed, status=15/TERM Oct 28 19:30:20 volumio3 systemd[1]: volumio-streaming-daemon.service: Succeeded. Oct 28 19:30:20 volumio3 systemd[1]: Stopped Volumio Streaming Daemon. Oct 28 19:30:20 volumio3 systemd[1]: Started Volumio Streaming Daemon. Oct 28 19:30:20 volumio3 sudo[4789]: pam_unix(sudo:session): session closed for user root Oct 28 19:30:20 volumio3 volumio-streaming-daemon[4795]: ############################ Oct 28 19:30:20 volumio3 volumio-streaming-daemon[4795]: # Volumio Streaming Daemon # Oct 28 19:30:20 volumio3 volumio-streaming-daemon[4795]: # Running on port 7777 # Oct 28 19:30:20 volumio3 volumio-streaming-daemon[4795]: ############################ Oct 28 19:30:20 volumio3 volumio-streaming-daemon[4795]: Environment: production Oct 28 19:30:20 volumio3 volumio[4612]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 28 19:30:20 volumio3 volumio[4612]: info: [ASDebug] Setting UI defaults Oct 28 19:30:20 volumio3 volumio[4612]: info: [ASDebug] Port: 2 Oct 28 19:30:20 volumio3 volumio[4612]: info: [ASDebug] Inverted: true Oct 28 19:30:20 volumio3 volumio[4612]: info: [ASDebug] Latched: false Oct 28 19:30:20 volumio3 volumio[4612]: info: [ASDebug] On pulse width: 500 Oct 28 19:30:20 volumio3 volumio[4612]: info: [ASDebug] Off pulse width: 500 Oct 28 19:30:21 volumio3 volumio[4612]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 28 19:30:21 volumio3 volumio[4612]: info: MYVOLUMIO: Adding device Oct 28 19:30:21 volumio3 volumio[4612]: info: MYVOLUMIO: Evaluating Server Oct 28 19:30:22 volumio3 volumio[4612]: info: MyVolumio status changed Oct 28 19:30:22 volumio3 volumio[4612]: info: Streaming services startup Oct 28 19:30:22 volumio3 volumio[4612]: info: Re-Starting Streaming Daemon Oct 28 19:30:22 volumio3 volumio[4612]: info: Removing browser output: myVolumio user plan is not superstar Oct 28 19:30:22 volumio3 volumio[4612]: info: Removing audio output: Oct 28 19:30:22 volumio3 volumio[4612]: info: Stoppping Tunnel 1 Oct 28 19:30:22 volumio3 sudo[4820]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Oct 28 19:30:22 volumio3 sudo[4820]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 19:30:22 volumio3 volumio-streaming-daemon[4795]: Restarting daemon Oct 28 19:30:22 volumio3 volumio-streaming-daemon[4795]: Environment: production Oct 28 19:30:22 volumio3 sudo[4820]: pam_unix(sudo:session): session closed for user root Oct 28 19:30:22 volumio3 volumio[4612]: info: Remote SSH Stopped Oct 28 19:30:22 volumio3 volumio[4612]: info: Setting Geolocation for MyVolumio to eu3 Oct 28 19:30:22 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 19:30:22 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 19:30:22 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 19:30:22 volumio3 volumio-streaming-daemon[4795]: Environment: production Oct 28 19:30:22 volumio3 volumio[4612]: info: Fetching Streaming Services browse cache Oct 28 19:30:23 volumio3 volumio-streaming-daemon[4795]: Environment: production Oct 28 19:30:23 volumio3 volumio[4612]: info: Fetching Streaming Services browse cache Oct 28 19:30:23 volumio3 volumio[4612]: info: Updating MyVolumio device info Oct 28 19:30:23 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 19:30:23 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 19:30:23 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 19:30:27 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 28 19:30:27 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 28 19:30:32 volumio3 volumio[4612]: info: Starting Uninstall of plugin system_controller - ampswitch Oct 28 19:30:32 volumio3 volumio[4612]: info: Uninstalling plugin ampswitch Oct 28 19:30:32 volumio3 volumio[4612]: info: [ASDebug] Port: 2 Oct 28 19:30:32 volumio3 volumio[4612]: info: [ASDebug] Inverted: true Oct 28 19:30:32 volumio3 volumio[4612]: info: [ASDebug] Delay: 720 Oct 28 19:30:40 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 28 19:30:40 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 19:30:40 volumio3 volumio[4612]: error: MyVolumio Plugin failed to authenticate in a timely fashion Oct 28 19:30:40 volumio3 volumio[4612]: info: Completed starting MyVolumio Plugin Oct 28 19:30:40 volumio3 volumio[4612]: info: BOOT COMPLETED Oct 28 19:30:40 volumio3 volumio[4612]: [Metrics] CommandRouter: 39s 539.58ms Oct 28 19:30:40 volumio3 volumio[4612]: info: CoreCommandRouter::volumiosetStartupVolume Oct 28 19:30:40 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 19:30:40 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 19:30:40 volumio3 volumio[4612]: info: CoreCommandRouter::Close All Modals sent Oct 28 19:30:40 volumio3 volumio[4612]: info: CoreCommandRouter::Close All Modals sent Oct 28 19:30:41 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 28 19:30:41 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 28 19:30:41 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 28 19:30:45 volumio3 volumio[4612]: info: CoreCommandRouter::volumioPlay Oct 28 19:30:45 volumio3 volumio[4612]: verbose: UNSET VOLATILE Oct 28 19:30:45 volumio3 volumio[4612]: info: CoreStateMachine::play index undefined Oct 28 19:30:45 volumio3 volumio[4612]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 28 19:30:45 volumio3 volumio[4612]: info: CorePlayQueue::getTrack 0 Oct 28 19:30:45 volumio3 volumio[4612]: info: CoreStateMachine::startPlaybackTimer Oct 28 19:30:45 volumio3 volumio[4612]: info: CorePlayQueue::getTrack 0 Oct 28 19:30:45 volumio3 volumio[4612]: info: [1730143845858] ControllerWebradio::clearAddPlayTrack Oct 28 19:30:45 volumio3 volumio[4612]: verbose: ControllerMpd::sendMpdCommand stop Oct 28 19:30:45 volumio3 volumio[4612]: info: sendMpdCommand stop took 3 milliseconds Oct 28 19:30:45 volumio3 volumio[4612]: verbose: ControllerMpd::sendMpdCommand clear Oct 28 19:30:45 volumio3 volumio[4612]: info: Oct 28 19:30:45 volumio3 volumio[4612]: ---------------------------- MPD announces system playlist update Oct 28 19:30:45 volumio3 volumio[4612]: info: Ignoring MPD Status Update Oct 28 19:30:45 volumio3 volumio[4612]: info: sendMpdCommand clear took 4 milliseconds Oct 28 19:30:45 volumio3 volumio[4612]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1785029" Oct 28 19:30:45 volumio3 volumio[4612]: error: updateQueue error: null Oct 28 19:30:45 volumio3 volumio[4612]: info: ------------------------------ 9ms Oct 28 19:30:46 volumio3 volumio[4612]: info: Oct 28 19:30:46 volumio3 volumio[4612]: ---------------------------- MPD announces system playlist update Oct 28 19:30:46 volumio3 volumio[4612]: info: Ignoring MPD Status Update Oct 28 19:30:46 volumio3 volumio[4612]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1785029" took 249 milliseconds Oct 28 19:30:46 volumio3 volumio[4612]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 28 19:30:46 volumio3 volumio[4612]: verbose: ControllerMpd::sendMpdCommand play Oct 28 19:30:46 volumio3 volumio[4612]: info: ------------------------------ 8ms Oct 28 19:30:46 volumio3 volumio[4612]: info: sendMpdCommand play took 6 milliseconds Oct 28 19:30:46 volumio3 volumio[4612]: info: Oct 28 19:30:46 volumio3 volumio[4612]: ---------------------------- MPD announces state update: player Oct 28 19:30:46 volumio3 volumio[4612]: info: ControllerMpd::getState Oct 28 19:30:46 volumio3 volumio[4612]: verbose: ControllerMpd::sendMpdCommand status Oct 28 19:30:46 volumio3 volumio[4612]: info: Oct 28 19:30:46 volumio3 volumio[4612]: ---------------------------- MPD announces state update: player Oct 28 19:30:46 volumio3 volumio[4612]: info: sendMpdCommand status took 18 milliseconds Oct 28 19:30:46 volumio3 volumio[4612]: info: ControllerMpd::getState Oct 28 19:30:46 volumio3 volumio[4612]: verbose: ControllerMpd::sendMpdCommand status Oct 28 19:30:46 volumio3 volumio[4612]: verbose: ControllerMpd::parseState Oct 28 19:30:46 volumio3 volumio[4612]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 28 19:30:46 volumio3 volumio[4612]: info: sendMpdCommand status took 7 milliseconds Oct 28 19:30:46 volumio3 volumio[4612]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 28 19:30:46 volumio3 volumio[4612]: verbose: ControllerMpd::parseState Oct 28 19:30:46 volumio3 volumio[4612]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 28 19:30:46 volumio3 volumio[4612]: verbose: ControllerMpd::parseTrackInfo Oct 28 19:30:46 volumio3 volumio[4612]: info: ControllerMpd::pushState Oct 28 19:30:46 volumio3 volumio[4612]: info: CoreCommandRouter::servicePushState Oct 28 19:30:46 volumio3 volumio[4612]: info: CorePlayQueue::getTrack 0 Oct 28 19:30:46 volumio3 volumio[4612]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"autodj","artist":"(#1 - 75/9999) Chillhop & LoFi HipHop Radio / / Chillsky Beats","album":null,"uri":"http://hyades.shoutca.st:8043/autodj","trackType":""} Oct 28 19:30:46 volumio3 volumio[4612]: verbose: CURRENT POSITION 0 Oct 28 19:30:46 volumio3 volumio[4612]: info: CoreStateMachine::syncState stateService play Oct 28 19:30:46 volumio3 volumio[4612]: info: CoreStateMachine::syncState currentStatus stop Oct 28 19:30:46 volumio3 volumio[4612]: info: CoreStateMachine::pushState Oct 28 19:30:46 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 19:30:46 volumio3 volumio[4612]: info: CoreCommandRouter::volumioPushState Oct 28 19:30:46 volumio3 volumio[4612]: info: ------------------------------ 78ms Oct 28 19:30:46 volumio3 volumio[4612]: info: Oct 28 19:30:46 volumio3 volumio[4612]: ---------------------------- MPD announces system playlist update Oct 28 19:30:46 volumio3 volumio[4612]: info: Ignoring MPD Status Update Oct 28 19:30:46 volumio3 volumio[4612]: info: Oct 28 19:30:46 volumio3 volumio[4612]: ---------------------------- MPD announces state update: player Oct 28 19:30:46 volumio3 volumio[4612]: info: sendMpdCommand playlistinfo took 48 milliseconds Oct 28 19:30:46 volumio3 volumio[4612]: info: ControllerMpd::getState Oct 28 19:30:46 volumio3 volumio[4612]: verbose: ControllerMpd::sendMpdCommand status Oct 28 19:30:46 volumio3 volumio[4612]: verbose: ControllerMpd::parseTrackInfo Oct 28 19:30:46 volumio3 volumio[4612]: info: ControllerMpd::pushState Oct 28 19:30:46 volumio3 volumio[4612]: info: CoreCommandRouter::servicePushState Oct 28 19:30:46 volumio3 volumio[4612]: info: CorePlayQueue::getTrack 0 Oct 28 19:30:46 volumio3 volumio[4612]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"autodj","artist":"(#1 - 75/9999) Chillhop & LoFi HipHop Radio / / Chillsky Beats","album":null,"uri":"http://hyades.shoutca.st:8043/autodj","trackType":""} Oct 28 19:30:46 volumio3 volumio[4612]: verbose: CURRENT POSITION 0 Oct 28 19:30:46 volumio3 volumio[4612]: info: CoreStateMachine::syncState stateService play Oct 28 19:30:46 volumio3 volumio[4612]: info: CoreStateMachine::syncState currentStatus play Oct 28 19:30:46 volumio3 volumio[4612]: info: Received an update from plugin. extracting info from payload Oct 28 19:30:46 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 28 19:30:46 volumio3 volumio[4612]: info: CoreStateMachine::pushState Oct 28 19:30:46 volumio3 volumio[4612]: info: CorePlayQueue::getTrack 0 Oct 28 19:30:46 volumio3 volumio[4612]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 19:30:46 volumio3 volumio[4612]: info: CoreCommandRouter::volumioPushState Oct 28 19:30:46 volumio3 volumio[4612]: info: CoreStateMachine::pushState Oct 28 19:30:46 volumio3 volumio[4612]: info: CoreCommandRouter::volumioPushState Oct 28 19:30:46 volumio3 volumio[4612]: info: ------------------------------ 116ms Oct 28 19:30:46 volumio3 volumio[4612]: info: [ASDebug] CurState: PrevState: stop Oct 28 19:30:46 volumio3 volumio[4612]: info: [ASDebug] Togle GPIO: ON Oct 28 19:30:46 volumio3 volumio[4612]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 28 19:30:46 volumio3 volumio[4612]: Error: EBADF: bad file descriptor, write Oct 28 19:30:46 volumio3 volumio[4612]: at Object.writeSync (fs.js:711:3) Oct 28 19:30:46 volumio3 volumio[4612]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) Oct 28 19:30:46 volumio3 volumio[4612]: at AmpSwitchController.on (/data/plugins/system_controller/ampswitch/index.js:201:23) Oct 28 19:30:46 volumio3 volumio[4612]: at AmpSwitchController.parseStatus (/data/plugins/system_controller/ampswitch/index.js:182:86) Oct 28 19:30:46 volumio3 volumio[4612]: at Socket.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) Oct 28 19:30:46 volumio3 volumio[4612]: at Socket.onevent (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/socket.js:278:10) Oct 28 19:30:46 volumio3 volumio[4612]: at Socket.onpacket (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/socket.js:236:12) Oct 28 19:30:46 volumio3 volumio[4612]: at Manager. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) Oct 28 19:30:46 volumio3 volumio[4612]: at Manager.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) Oct 28 19:30:46 volumio3 volumio[4612]: at Manager.ondecoded (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/manager.js:349:8) Oct 28 19:30:46 volumio3 volumio[4612]: at Decoder. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) Oct 28 19:30:46 volumio3 volumio[4612]: at Decoder.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) Oct 28 19:30:46 volumio3 volumio[4612]: at Decoder.add (/data/plugins/system_controller/ampswitch/node_modules/socket.io-parser/index.js:251:12) Oct 28 19:30:46 volumio3 volumio[4612]: at Manager.ondata (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/manager.js:339:16) Oct 28 19:30:46 volumio3 volumio[4612]: at Socket. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) Oct 28 19:30:46 volumio3 volumio[4612]: at Socket.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) { Oct 28 19:30:46 volumio3 volumio[4612]: errno: -9, Oct 28 19:30:46 volumio3 volumio[4612]: syscall: 'write', Oct 28 19:30:46 volumio3 volumio[4612]: code: 'EBADF' Oct 28 19:30:46 volumio3 volumio[4612]: } Oct 28 19:30:46 volumio3 volumio[4612]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 28 19:30:47 volumio3 sudo[4845]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-28 19:29 Oct 28 19:30:47 volumio3 sudo[4845]: 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="51a522a0fb55c1bb9391fe373b08507527c94c95" VOLUMIO_FE_VERSION="c24266cbcdb448eb1c9463664e6c8a43e38231af" VOLUMIO_FE3_VERSION="4e93a5cb22ce7b9e53df90f7b2c3f3d1b2d6d7c2" VOLUMIO_BE_VERSION="8b3e6f24b6d055dc597c25250dded8bef566af7b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 02 Dec 2021 06:37:00 PM CET" VOLUMIO_VERSION="3.163" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="6797fd9e7818a7259d13472fff06b043"