-- Logs begin at Thu 2019-02-14 05:11:59 EST, end at Mon 2024-04-29 14:24:52 EDT. -- Apr 29 14:23:06 oldtimey volumio[839]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Apr 29 14:23:06 oldtimey volumio[839]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Apr 29 14:23:06 oldtimey volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 29 14:23:06 oldtimey volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 29 14:23:06 oldtimey volumio[839]: info: Starting MyVolumio Remote Streaming Endpoints Apr 29 14:23:06 oldtimey volumio[839]: info: MyVolumio login type: Token Apr 29 14:23:06 oldtimey volumio[839]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Apr 29 14:23:06 oldtimey volumio[839]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Apr 29 14:23:07 oldtimey nmbd[619]: [2024/04/29 14:23:07.171782, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Apr 29 14:23:07 oldtimey nmbd[619]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.86.103 for name WORKGROUP<1d>. Apr 29 14:23:07 oldtimey nmbd[619]: This response was from IP 192.168.86.54, reporting an IP address of 192.168.86.54. Apr 29 14:23:08 oldtimey volumio[839]: info: Starting Streaming Service Transparent Proxy Apr 29 14:23:08 oldtimey volumio[839]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Apr 29 14:23:08 oldtimey volumio[839]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Apr 29 14:23:08 oldtimey volumio[839]: info: Streaming services startup Apr 29 14:23:08 oldtimey volumio[839]: info: Starting Streaming Daemon Apr 29 14:23:08 oldtimey volumio[839]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Apr 29 14:23:08 oldtimey volumio[839]: info: Getting Spotify volume Apr 29 14:23:08 oldtimey sudo[1391]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 29 14:23:08 oldtimey sudo[1391]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 14:23:08 oldtimey sudo[1391]: pam_unix(sudo:session): session closed for user root Apr 29 14:23:08 oldtimey volumio[839]: error: Cannot start Volumio Streaming Daemon Apr 29 14:23:08 oldtimey volumio[839]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 29 14:23:08 oldtimey volumio[839]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 29 14:23:08 oldtimey volumio[839]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 13 Apr 29 14:23:08 oldtimey volumio[839]: info: CoreCommandRouter::volumioGetState Apr 29 14:23:08 oldtimey volumio[839]: info: CorePlayQueue::getTrack 0 Apr 29 14:23:08 oldtimey volumio[839]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Apr 29 14:23:09 oldtimey volumio[839]: info: MyVolumio token set successfully Apr 29 14:23:09 oldtimey volumio[839]: info: MYVOLUMIO: Adding device Apr 29 14:23:09 oldtimey volumio[839]: info: MYVOLUMIO: Evaluating Server Apr 29 14:23:09 oldtimey volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 29 14:23:09 oldtimey volumio[839]: info: Discovery: Getting this device information Apr 29 14:23:09 oldtimey volumio[839]: info: CoreCommandRouter::volumioGetState Apr 29 14:23:09 oldtimey volumio[839]: info: CorePlayQueue::getTrack 0 Apr 29 14:23:09 oldtimey volumio[839]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 29 14:23:09 oldtimey volumio[839]: STREAMING PROXY: Starting server on port 3245 Apr 29 14:23:09 oldtimey volumio[839]: Node JS runtime: 14 Apr 29 14:23:10 oldtimey volumio[839]: info: MyVolumio status changed Apr 29 14:23:10 oldtimey volumio[839]: info: Streaming services startup Apr 29 14:23:10 oldtimey volumio[839]: info: Starting Streaming Daemon Apr 29 14:23:10 oldtimey volumio[839]: info: Removing browser output: myVolumio user plan is not superstar Apr 29 14:23:10 oldtimey volumio[839]: info: Removing audio output: Apr 29 14:23:10 oldtimey volumio[839]: info: Stoppping Tunnel 1 Apr 29 14:23:10 oldtimey sudo[1445]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 29 14:23:10 oldtimey sudo[1445]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 14:23:10 oldtimey sudo[1447]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Apr 29 14:23:10 oldtimey sudo[1447]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 29 14:23:10 oldtimey sudo[1445]: pam_unix(sudo:session): session closed for user root Apr 29 14:23:10 oldtimey volumio[839]: error: Cannot start Volumio Streaming Daemon Apr 29 14:23:10 oldtimey volumio[839]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 29 14:23:10 oldtimey volumio[839]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 29 14:23:10 oldtimey sudo[1447]: pam_unix(sudo:session): session closed for user root Apr 29 14:23:10 oldtimey volumio[839]: info: Remote SSH Stopped Apr 29 14:23:10 oldtimey volumio[839]: info: Setting Geolocation for MyVolumio to us1 Apr 29 14:23:10 oldtimey volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 29 14:23:10 oldtimey volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 29 14:23:10 oldtimey volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 29 14:23:11 oldtimey volumio[839]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Apr 29 14:23:11 oldtimey volumio[839]: info: Updating MyVolumio device info Apr 29 14:23:11 oldtimey volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 29 14:23:11 oldtimey volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 29 14:23:11 oldtimey volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 29 14:23:12 oldtimey volumio[839]: error: [now-playing] API endpoint weather/fetchInfo returned error: Error: Could not access weather service: missing geographic coordinates. Apr 29 14:23:12 oldtimey volumio[839]: at WeatherAPI.fetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:106:19) Apr 29 14:23:12 oldtimey volumio[839]: at Object.api (/data/plugins/user_interface/now_playing/dist/app/Handler.js:113:37) Apr 29 14:23:12 oldtimey volumio[839]: at /data/plugins/user_interface/now_playing/dist/app/Router.js:47:13 Apr 29 14:23:12 oldtimey volumio[839]: at Layer.handle [as handle_request] (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/layer.js:95:5) Apr 29 14:23:12 oldtimey volumio[839]: at next (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/route.js:144:13) Apr 29 14:23:12 oldtimey volumio[839]: at Route.dispatch (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/route.js:114:3) Apr 29 14:23:12 oldtimey volumio[839]: at Layer.handle [as handle_request] (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/layer.js:95:5) Apr 29 14:23:12 oldtimey volumio[839]: at /data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:284:15 Apr 29 14:23:12 oldtimey volumio[839]: at param (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:365:14) Apr 29 14:23:12 oldtimey volumio[839]: at param (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:376:14) Apr 29 14:23:12 oldtimey volumio[839]: at Function.process_params (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:421:3) Apr 29 14:23:12 oldtimey volumio[839]: at next (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:280:10) Apr 29 14:23:12 oldtimey volumio[839]: at Function.handle (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:175:3) Apr 29 14:23:12 oldtimey volumio[839]: at router (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/index.js:47:12) Apr 29 14:23:12 oldtimey volumio[839]: at Layer.handle [as handle_request] (/data/plugins/user_interface/now_playing/node_modules/express/lib/router/layer.js:95:5) Apr 29 14:23:12 oldtimey volumio[839]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 14 Apr 29 14:23:12 oldtimey volumio[839]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Apr 29 14:23:13 oldtimey volumio[839]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Apr 29 14:23:13 oldtimey volumio[839]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Apr 29 14:23:13 oldtimey volumio[839]: info: Listing playlists Apr 29 14:23:13 oldtimey volumio[839]: info: CoreCommandRouter::volumioGetQueue Apr 29 14:23:13 oldtimey volumio[839]: info: CoreStateMachine::getQueue Apr 29 14:23:13 oldtimey volumio[839]: info: CorePlayQueue::getQueue Apr 29 14:23:13 oldtimey volumio[839]: info: CoreCommandRouter::volumioGetVisibleSources Apr 29 14:23:13 oldtimey volumio[839]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 29 14:23:13 oldtimey volumio[839]: info: CoreCommandRouter::volumioGetState Apr 29 14:23:13 oldtimey volumio[839]: info: CorePlayQueue::getTrack 0 Apr 29 14:23:15 oldtimey volumio[839]: info: MYVOLUMIO: Adding device Apr 29 14:23:15 oldtimey volumio[839]: info: MYVOLUMIO: Evaluating Server Apr 29 14:23:15 oldtimey volumio[839]: info: CoreCommandRouter::volumioToggle Apr 29 14:23:15 oldtimey volumio[839]: info: CorePlayQueue::getTrack 0 Apr 29 14:23:15 oldtimey volumio[839]: info: CoreStateMachine::play index undefined Apr 29 14:23:15 oldtimey volumio[839]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 29 14:23:15 oldtimey volumio[839]: info: CorePlayQueue::getTrack 0 Apr 29 14:23:15 oldtimey volumio[839]: info: CoreStateMachine::startPlaybackTimer Apr 29 14:23:15 oldtimey volumio[839]: info: CorePlayQueue::getTrack 0 Apr 29 14:23:15 oldtimey volumio[839]: info: [ytmusic-play] clearAddPlayTrack: ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Thunderstorm%20Sounds%3A%201%20Hour%20Thunder%22%2C%22artist%22%3A%22SleepTherapy%22%2C%22album%22%3A%22Thunderstorm%20Sounds%3A%201%20Hour%20Thunder%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2FSYDTpRNh_3Psk8cG5c3eCUol4QKCXGW5EkzYWFFUiTZ9Ob_5J2ojqb7C39nytRyn7gu-hpgLXep5u5tyUg%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22tJ-pxOObruY%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22tJ-pxOObruY%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D Apr 29 14:23:15 oldtimey volumio[839]: info: [ytmusic] InnertubeLoader: creating Innertube instance... Apr 29 14:23:15 oldtimey volumio[839]: info: Setting Geolocation for MyVolumio to us1 Apr 29 14:23:15 oldtimey volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 29 14:23:15 oldtimey volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 29 14:23:15 oldtimey volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 29 14:23:16 oldtimey volumio[839]: info: [ytmusic] InnertubeLoader: creating Auth instance... Apr 29 14:23:16 oldtimey volumio[839]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 29 14:23:16 oldtimey volumio[839]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 29 14:23:16 oldtimey volumio[839]: info: [ytmusic] InnertubeLoader: Auth instance created (status: signed in) Apr 29 14:23:16 oldtimey volumio[839]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Apr 29 14:23:16 oldtimey volumio[839]: info: Updating MyVolumio device info Apr 29 14:23:16 oldtimey volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 29 14:23:16 oldtimey volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 29 14:23:16 oldtimey volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 29 14:23:17 oldtimey volumio[839]: info: [ytmusic] Preferred format is Opus Apr 29 14:23:17 oldtimey volumio[839]: info: CorePlayQueue::getTrack 0 Apr 29 14:23:17 oldtimey volumio[839]: verbose: ControllerMpd::sendMpdCommand stop Apr 29 14:23:17 oldtimey volumio[839]: info: sendMpdCommand stop took 14 milliseconds Apr 29 14:23:17 oldtimey volumio[839]: verbose: ControllerMpd::sendMpdCommand clear Apr 29 14:23:17 oldtimey volumio[839]: info: Apr 29 14:23:17 oldtimey volumio[839]: ---------------------------- MPD announces system playlist update Apr 29 14:23:17 oldtimey volumio[839]: info: Ignoring MPD Status Update Apr 29 14:23:17 oldtimey volumio[839]: info: sendMpdCommand clear took 7 milliseconds Apr 29 14:23:17 oldtimey volumio[839]: verbose: ControllerMpd::sendMpdCommand addid "https://rr5---sn-8xgp1vo-cvne.googlevideo.com/videoplayback?expire=1714436596&ei=lOUvZsOXObywkucP27CNsA8&ip=98.110.230.62&id=o-APFe9tCOovT32-sC945JOkss__6dc5856W_cXjfys3cw&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=Dg&mm=31%2C29&mn=sn-8xgp1vo-cvne%2Csn-8xgp1vo-ab56&ms=au%2Crdu&mv=m&mvi=5&pl=18&ctier=A&pfa=5&gcr=us&initcwndbps=942500&hightc=yes&siu=1&bui=AWRWj2Rla3CEFuLT7z36CY9mzHk7EnruHaZueCdgZSJOQdd81FU8mJ6Bm1MSFxGUZbmXImTxqw&spc=UWF9f-4NKrO81YvYDyqPeW0mS4pYKk0A7JPSvSL2XTspJdQUykNjtpqL8QM0&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=5kmazj6pjlj0zexyAEWWvzoQ&gir=yes&clen=55599297&dur=3600.041&lmt=1565331076385729&mt=1714414631&fvip=2&keepalive=yes&c=WEB_REMIX&sefc=1&txp=5531432&n=hD9msZ3oDPXJfw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHWaYeowRgIhAOKN4wTd91-dEocxZ3boLC0oD05QixceQzwFvQk9oYBaAiEAwUwiONSvXJw4NmuObgFR58ts9DKfaK724PCGW-v_CgE%3D&sig=AJfQdSswRQIgZpyeEXb4EWI8ciOnAEM5TPaEcQNjGwZ_Z_Cu6frdLR0CIQC2pQmEJZG1_j4SD9bPfLKRw7vplptW_Dwk359Zp4gK2A%3D%3D&cver=1.20211213.00.00&t.YouTube" Apr 29 14:23:17 oldtimey volumio[839]: info: Apr 29 14:23:17 oldtimey volumio[839]: ---------------------------- MPD announces system playlist update Apr 29 14:23:17 oldtimey volumio[839]: info: Ignoring MPD Status Update Apr 29 14:23:17 oldtimey volumio[839]: info: Apr 29 14:23:17 oldtimey volumio[839]: ---------------------------- MPD announces system playlist update Apr 29 14:23:17 oldtimey volumio[839]: info: Ignoring MPD Status Update Apr 29 14:23:17 oldtimey volumio[839]: error: updateQueue error: null Apr 29 14:23:17 oldtimey volumio[839]: info: Apr 29 14:23:17 oldtimey volumio[839]: ---------------------------- MPD announces system playlist update Apr 29 14:23:17 oldtimey volumio[839]: info: Ignoring MPD Status Update Apr 29 14:23:17 oldtimey volumio[839]: info: ------------------------------ 23ms Apr 29 14:23:17 oldtimey volumio[839]: info: sendMpdCommand addid "https://rr5---sn-8xgp1vo-cvne.googlevideo.com/videoplayback?expire=1714436596&ei=lOUvZsOXObywkucP27CNsA8&ip=98.110.230.62&id=o-APFe9tCOovT32-sC945JOkss__6dc5856W_cXjfys3cw&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=Dg&mm=31%2C29&mn=sn-8xgp1vo-cvne%2Csn-8xgp1vo-ab56&ms=au%2Crdu&mv=m&mvi=5&pl=18&ctier=A&pfa=5&gcr=us&initcwndbps=942500&hightc=yes&siu=1&bui=AWRWj2Rla3CEFuLT7z36CY9mzHk7EnruHaZueCdgZSJOQdd81FU8mJ6Bm1MSFxGUZbmXImTxqw&spc=UWF9f-4NKrO81YvYDyqPeW0mS4pYKk0A7JPSvSL2XTspJdQUykNjtpqL8QM0&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=5kmazj6pjlj0zexyAEWWvzoQ&gir=yes&clen=55599297&dur=3600.041&lmt=1565331076385729&mt=1714414631&fvip=2&keepalive=yes&c=WEB_REMIX&sefc=1&txp=5531432&n=hD9msZ3oDPXJfw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHWaYeowRgIhAOKN4wTd91-dEocxZ3boLC0oD05QixceQzwFvQk9oYBaAiEAwUwiONSvXJw4NmuObgFR58ts9DKfaK724PCGW-v_CgE%3D&sig=AJfQdSswRQIgZpyeEXb4EWI8ciOnAEM5TPaEcQNjGwZ_Z_Cu6frdLR0CIQC2pQmEJZG1_j4SD9bPfLKRw7vplptW_Dwk359Zp4gK2A%3D%3D&cver=1.20211213.00.00&t.YouTube" took 20 milliseconds Apr 29 14:23:17 oldtimey volumio[839]: info: ------------------------------ 15ms Apr 29 14:23:17 oldtimey volumio[839]: info: ------------------------------ 13ms Apr 29 14:23:17 oldtimey volumio[839]: verbose: MPD COMMAND [object Object] Apr 29 14:23:17 oldtimey volumio[839]: verbose: MPD COMMAND [object Object] Apr 29 14:23:17 oldtimey volumio[839]: verbose: MPD COMMAND [object Object] Apr 29 14:23:17 oldtimey volumio[839]: info: Apr 29 14:23:17 oldtimey volumio[839]: ---------------------------- MPD announces system playlist update Apr 29 14:23:17 oldtimey volumio[839]: info: Ignoring MPD Status Update Apr 29 14:23:17 oldtimey volumio[839]: info: Apr 29 14:23:17 oldtimey volumio[839]: ---------------------------- MPD announces system playlist update Apr 29 14:23:17 oldtimey volumio[839]: info: Ignoring MPD Status Update Apr 29 14:23:17 oldtimey volumio[839]: info: Apr 29 14:23:17 oldtimey volumio[839]: ---------------------------- MPD announces system playlist update Apr 29 14:23:17 oldtimey volumio[839]: info: Ignoring MPD Status Update Apr 29 14:23:17 oldtimey volumio[839]: info: ------------------------------ 24ms Apr 29 14:23:17 oldtimey volumio[839]: info: ------------------------------ 11ms Apr 29 14:23:17 oldtimey volumio[839]: info: ------------------------------ 10ms Apr 29 14:23:17 oldtimey volumio[839]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 29 14:23:17 oldtimey volumio[839]: verbose: ControllerMpd::sendMpdCommand play Apr 29 14:23:17 oldtimey volumio[839]: info: Apr 29 14:23:17 oldtimey volumio[839]: ---------------------------- MPD announces system playlist update Apr 29 14:23:17 oldtimey volumio[839]: info: Ignoring MPD Status Update Apr 29 14:23:17 oldtimey volumio[839]: info: Apr 29 14:23:17 oldtimey volumio[839]: ---------------------------- MPD announces system playlist update Apr 29 14:23:17 oldtimey volumio[839]: info: Ignoring MPD Status Update Apr 29 14:23:17 oldtimey volumio[839]: info: ------------------------------ 17ms Apr 29 14:23:17 oldtimey volumio[839]: info: sendMpdCommand play took 12 milliseconds Apr 29 14:23:17 oldtimey volumio[839]: info: ------------------------------ 10ms Apr 29 14:23:17 oldtimey volumio[839]: info: ------------------------------ 8ms Apr 29 14:23:17 oldtimey volumio[839]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Apr 29 14:23:18 oldtimey volumio[839]: info: Apr 29 14:23:18 oldtimey volumio[839]: ---------------------------- MPD announces state update: player Apr 29 14:23:18 oldtimey volumio[839]: info: ControllerMpd::getState Apr 29 14:23:18 oldtimey volumio[839]: verbose: ControllerMpd::sendMpdCommand status Apr 29 14:23:18 oldtimey volumio[839]: info: Apr 29 14:23:18 oldtimey volumio[839]: ---------------------------- MPD announces state update: player Apr 29 14:23:18 oldtimey volumio[839]: info: ControllerMpd::getState Apr 29 14:23:18 oldtimey volumio[839]: verbose: ControllerMpd::sendMpdCommand status Apr 29 14:23:18 oldtimey volumio[839]: info: Apr 29 14:23:18 oldtimey volumio[839]: ---------------------------- MPD announces state update: player Apr 29 14:23:18 oldtimey volumio[839]: info: ControllerMpd::getState Apr 29 14:23:18 oldtimey volumio[839]: verbose: ControllerMpd::sendMpdCommand status Apr 29 14:23:18 oldtimey volumio[839]: info: Apr 29 14:23:18 oldtimey volumio[839]: ---------------------------- MPD announces state update: player Apr 29 14:23:18 oldtimey volumio[839]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 29 14:23:18 oldtimey volumio[839]: info: sendMpdCommand status took 69 milliseconds Apr 29 14:23:18 oldtimey volumio[839]: info: ControllerMpd::getState Apr 29 14:23:18 oldtimey volumio[839]: verbose: ControllerMpd::sendMpdCommand status Apr 29 14:23:18 oldtimey volumio[839]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 29 14:23:18 oldtimey volumio[839]: info: sendMpdCommand status took 60 milliseconds Apr 29 14:23:18 oldtimey volumio[839]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 29 14:23:18 oldtimey volumio[839]: info: sendMpdCommand status took 58 milliseconds Apr 29 14:23:18 oldtimey volumio[839]: verbose: ControllerMpd::parseState Apr 29 14:23:18 oldtimey volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 14:23:18 oldtimey volumio[839]: verbose: ControllerMpd::parseState Apr 29 14:23:18 oldtimey volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 14:23:18 oldtimey volumio[839]: verbose: ControllerMpd::parseState Apr 29 14:23:18 oldtimey volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 14:23:18 oldtimey volumio[839]: info: Apr 29 14:23:18 oldtimey volumio[839]: ---------------------------- MPD announces state update: player Apr 29 14:23:18 oldtimey volumio[839]: info: ControllerMpd::getState Apr 29 14:23:18 oldtimey volumio[839]: verbose: ControllerMpd::sendMpdCommand status Apr 29 14:23:18 oldtimey volumio[839]: info: Apr 29 14:23:18 oldtimey volumio[839]: ---------------------------- MPD announces state update: player Apr 29 14:23:18 oldtimey volumio[839]: info: ControllerMpd::getState Apr 29 14:23:18 oldtimey volumio[839]: verbose: ControllerMpd::sendMpdCommand status Apr 29 14:23:19 oldtimey volumio[839]: info: sendMpdCommand clearerror took 100 milliseconds Apr 29 14:23:19 oldtimey volumio[839]: info: sendMpdCommand status took 124 milliseconds Apr 29 14:23:19 oldtimey volumio[839]: info: sendMpdCommand clearerror took 123 milliseconds Apr 29 14:23:19 oldtimey volumio[839]: info: sendMpdCommand clearerror took 114 milliseconds Apr 29 14:23:19 oldtimey volumio[839]: info: sendMpdCommand playlistinfo took 87 milliseconds Apr 29 14:23:19 oldtimey volumio[839]: info: sendMpdCommand playlistinfo took 86 milliseconds Apr 29 14:23:19 oldtimey volumio[839]: info: sendMpdCommand playlistinfo took 84 milliseconds Apr 29 14:23:19 oldtimey volumio[839]: info: sendMpdCommand status took 80 milliseconds Apr 29 14:23:19 oldtimey volumio[839]: info: sendMpdCommand status took 79 milliseconds Apr 29 14:23:19 oldtimey volumio[839]: verbose: ControllerMpd::parseState Apr 29 14:23:19 oldtimey volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 14:23:19 oldtimey volumio[839]: verbose: ControllerMpd::parseTrackInfo Apr 29 14:23:19 oldtimey volumio[839]: verbose: ControllerMpd::parseTrackInfo Apr 29 14:23:19 oldtimey volumio[839]: verbose: ControllerMpd::parseTrackInfo Apr 29 14:23:19 oldtimey volumio[839]: verbose: ControllerMpd::parseState Apr 29 14:23:19 oldtimey volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 14:23:19 oldtimey volumio[839]: verbose: ControllerMpd::parseState Apr 29 14:23:19 oldtimey volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 29 14:23:19 oldtimey volumio[839]: info: ControllerMpd::pushState Apr 29 14:23:19 oldtimey volumio[839]: info: CoreCommandRouter::servicePushState Apr 29 14:23:19 oldtimey volumio[839]: info: CorePlayQueue::getTrack 0 Apr 29 14:23:19 oldtimey volumio[839]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":3600,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Thunderstorm Sounds: 1 Hour Thunder","artist":"SleepTherapy","album":"Thunderstorm Sounds: 1 Hour Thunder","uri":"https://rr5---sn-8xgp1vo-cvne.googlevideo.com/videoplayback?expire=1714436596&ei=lOUvZsOXObywkucP27CNsA8&ip=98.110.230.62&id=o-APFe9tCOovT32-sC945JOkss__6dc5856W_cXjfys3cw&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=Dg&mm=31%2C29&mn=sn-8xgp1vo-cvne%2Csn-8xgp1vo-ab56&ms=au%2Crdu&mv=m&mvi=5&pl=18&ctier=A&pfa=5&gcr=us&initcwndbps=942500&hightc=yes&siu=1&bui=AWRWj2Rla3CEFuLT7z36CY9mzHk7EnruHaZueCdgZSJOQdd81FU8mJ6Bm1MSFxGUZbmXImTxqw&spc=UWF9f-4NKrO81YvYDyqPeW0mS4pYKk0A7JPSvSL2XTspJdQUykNjtpqL8QM0&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=5kmazj6pjlj0zexyAEWWvzoQ&gir=yes&clen=55599297&dur=3600.041&lmt=1565331076385729&mt=1714414631&fvip=2&keepalive=yes&c=WEB_REMIX&sefc=1&txp=5531432&n=hD9msZ3oDPXJfw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHWaYeowRgIhAOKN4wTd91-dEocxZ3boLC0oD05QixceQzwFvQk9oYBaAiEAwUwiONSvXJw4NmuObgFR58ts9DKfaK724PCGW-v_CgE%3D&sig=AJfQdSswRQIgZpyeEXb4EWI8ciOnAEM5TPaEcQNjGwZ_Z_Cu6frdLR0CIQC2pQmEJZG1_j4SD9bPfLKRw7vplptW_Dwk359Zp4gK2A%3D%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Apr 29 14:23:19 oldtimey volumio[839]: verbose: CURRENT POSITION 0 Apr 29 14:23:19 oldtimey volumio[839]: info: CoreStateMachine::syncState stateService pause Apr 29 14:23:19 oldtimey volumio[839]: info: CoreStateMachine::syncState currentStatus stop Apr 29 14:23:19 oldtimey volumio[839]: info: CoreStateMachine::pushState Apr 29 14:23:19 oldtimey volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 14:23:19 oldtimey volumio[839]: info: CoreCommandRouter::volumioPushState Apr 29 14:23:19 oldtimey volumio[839]: info: ControllerMpd::pushState Apr 29 14:23:19 oldtimey volumio[839]: info: CoreCommandRouter::servicePushState Apr 29 14:23:19 oldtimey volumio[839]: info: CorePlayQueue::getTrack 0 Apr 29 14:23:19 oldtimey volumio[839]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":3600,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Thunderstorm Sounds: 1 Hour Thunder","artist":"SleepTherapy","album":"Thunderstorm Sounds: 1 Hour Thunder","uri":"https://rr5---sn-8xgp1vo-cvne.googlevideo.com/videoplayback?expire=1714436596&ei=lOUvZsOXObywkucP27CNsA8&ip=98.110.230.62&id=o-APFe9tCOovT32-sC945JOkss__6dc5856W_cXjfys3cw&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=Dg&mm=31%2C29&mn=sn-8xgp1vo-cvne%2Csn-8xgp1vo-ab56&ms=au%2Crdu&mv=m&mvi=5&pl=18&ctier=A&pfa=5&gcr=us&initcwndbps=942500&hightc=yes&siu=1&bui=AWRWj2Rla3CEFuLT7z36CY9mzHk7EnruHaZueCdgZSJOQdd81FU8mJ6Bm1MSFxGUZbmXImTxqw&spc=UWF9f-4NKrO81YvYDyqPeW0mS4pYKk0A7JPSvSL2XTspJdQUykNjtpqL8QM0&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=5kmazj6pjlj0zexyAEWWvzoQ&gir=yes&clen=55599297&dur=3600.041&lmt=1565331076385729&mt=1714414631&fvip=2&keepalive=yes&c=WEB_REMIX&sefc=1&txp=5531432&n=hD9msZ3oDPXJfw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHWaYeowRgIhAOKN4wTd91-dEocxZ3boLC0oD05QixceQzwFvQk9oYBaAiEAwUwiONSvXJw4NmuObgFR58ts9DKfaK724PCGW-v_CgE%3D&sig=AJfQdSswRQIgZpyeEXb4EWI8ciOnAEM5TPaEcQNjGwZ_Z_Cu6frdLR0CIQC2pQmEJZG1_j4SD9bPfLKRw7vplptW_Dwk359Zp4gK2A%3D%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Apr 29 14:23:19 oldtimey volumio[839]: verbose: CURRENT POSITION 0 Apr 29 14:23:19 oldtimey volumio[839]: info: CoreStateMachine::syncState stateService pause Apr 29 14:23:19 oldtimey volumio[839]: info: CoreStateMachine::syncState currentStatus stop Apr 29 14:23:19 oldtimey volumio[839]: info: CoreStateMachine::pushState Apr 29 14:23:19 oldtimey volumio[839]: info: CoreCommandRouter::volumioPushState Apr 29 14:23:19 oldtimey volumio[839]: info: ControllerMpd::pushState Apr 29 14:23:19 oldtimey volumio[839]: info: CoreCommandRouter::servicePushState Apr 29 14:23:19 oldtimey volumio[839]: info: CorePlayQueue::getTrack 0 Apr 29 14:23:19 oldtimey volumio[839]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":3600,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Thunderstorm Sounds: 1 Hour Thunder","artist":"SleepTherapy","album":"Thunderstorm Sounds: 1 Hour Thunder","uri":"https://rr5---sn-8xgp1vo-cvne.googlevideo.com/videoplayback?expire=1714436596&ei=lOUvZsOXObywkucP27CNsA8&ip=98.110.230.62&id=o-APFe9tCOovT32-sC945JOkss__6dc5856W_cXjfys3cw&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=Dg&mm=31%2C29&mn=sn-8xgp1vo-cvne%2Csn-8xgp1vo-ab56&ms=au%2Crdu&mv=m&mvi=5&pl=18&ctier=A&pfa=5&gcr=us&initcwndbps=942500&hightc=yes&siu=1&bui=AWRWj2Rla3CEFuLT7z36CY9mzHk7EnruHaZueCdgZSJOQdd81FU8mJ6Bm1MSFxGUZbmXImTxqw&spc=UWF9f-4NKrO81YvYDyqPeW0mS4pYKk0A7JPSvSL2XTspJdQUykNjtpqL8QM0&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=5kmazj6pjlj0zexyAEWWvzoQ&gir=yes&clen=55599297&dur=3600.041&lmt=1565331076385729&mt=1714414631&fvip=2&keepalive=yes&c=WEB_REMIX&sefc=1&txp=5531432&n=hD9msZ3oDPXJfw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHWaYeowRgIhAOKN4wTd91-dEocxZ3boLC0oD05QixceQzwFvQk9oYBaAiEAwUwiONSvXJw4NmuObgFR58ts9DKfaK724PCGW-v_CgE%3D&sig=AJfQdSswRQIgZpyeEXb4EWI8ciOnAEM5TPaEcQNjGwZ_Z_Cu6frdLR0CIQC2pQmEJZG1_j4SD9bPfLKRw7vplptW_Dwk359Zp4gK2A%3D%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Apr 29 14:23:19 oldtimey volumio[839]: verbose: CURRENT POSITION 0 Apr 29 14:23:19 oldtimey volumio[839]: info: CoreStateMachine::syncState stateService pause Apr 29 14:23:19 oldtimey volumio[839]: info: CoreStateMachine::syncState currentStatus stop Apr 29 14:23:19 oldtimey volumio[839]: info: CoreStateMachine::pushState Apr 29 14:23:19 oldtimey volumio[839]: info: CoreCommandRouter::volumioPushState Apr 29 14:23:19 oldtimey volumio[839]: info: ------------------------------ 391ms Apr 29 14:23:19 oldtimey volumio[839]: info: ------------------------------ 382ms Apr 29 14:23:19 oldtimey volumio[839]: info: ------------------------------ 372ms Apr 29 14:23:19 oldtimey volumio[839]: info: CoreCommandRouter::volumioGetState Apr 29 14:23:19 oldtimey volumio[839]: info: sendMpdCommand playlistinfo took 203 milliseconds Apr 29 14:23:19 oldtimey volumio[839]: info: sendMpdCommand playlistinfo took 187 milliseconds Apr 29 14:23:19 oldtimey volumio[839]: info: sendMpdCommand playlistinfo took 187 milliseconds Apr 29 14:23:19 oldtimey volumio[839]: verbose: ControllerMpd::parseTrackInfo Apr 29 14:23:19 oldtimey volumio[839]: verbose: ControllerMpd::parseTrackInfo Apr 29 14:23:19 oldtimey volumio[839]: verbose: ControllerMpd::parseTrackInfo Apr 29 14:23:19 oldtimey volumio[839]: info: ControllerMpd::pushState Apr 29 14:23:19 oldtimey volumio[839]: info: CoreCommandRouter::servicePushState Apr 29 14:23:19 oldtimey volumio[839]: info: CorePlayQueue::getTrack 0 Apr 29 14:23:19 oldtimey volumio[839]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":3600,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Thunderstorm Sounds: 1 Hour Thunder","artist":"SleepTherapy","album":"Thunderstorm Sounds: 1 Hour Thunder","uri":"https://rr5---sn-8xgp1vo-cvne.googlevideo.com/videoplayback?expire=1714436596&ei=lOUvZsOXObywkucP27CNsA8&ip=98.110.230.62&id=o-APFe9tCOovT32-sC945JOkss__6dc5856W_cXjfys3cw&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=Dg&mm=31%2C29&mn=sn-8xgp1vo-cvne%2Csn-8xgp1vo-ab56&ms=au%2Crdu&mv=m&mvi=5&pl=18&ctier=A&pfa=5&gcr=us&initcwndbps=942500&hightc=yes&siu=1&bui=AWRWj2Rla3CEFuLT7z36CY9mzHk7EnruHaZueCdgZSJOQdd81FU8mJ6Bm1MSFxGUZbmXImTxqw&spc=UWF9f-4NKrO81YvYDyqPeW0mS4pYKk0A7JPSvSL2XTspJdQUykNjtpqL8QM0&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=5kmazj6pjlj0zexyAEWWvzoQ&gir=yes&clen=55599297&dur=3600.041&lmt=1565331076385729&mt=1714414631&fvip=2&keepalive=yes&c=WEB_REMIX&sefc=1&txp=5531432&n=hD9msZ3oDPXJfw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHWaYeowRgIhAOKN4wTd91-dEocxZ3boLC0oD05QixceQzwFvQk9oYBaAiEAwUwiONSvXJw4NmuObgFR58ts9DKfaK724PCGW-v_CgE%3D&sig=AJfQdSswRQIgZpyeEXb4EWI8ciOnAEM5TPaEcQNjGwZ_Z_Cu6frdLR0CIQC2pQmEJZG1_j4SD9bPfLKRw7vplptW_Dwk359Zp4gK2A%3D%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Apr 29 14:23:19 oldtimey volumio[839]: verbose: CURRENT POSITION 0 Apr 29 14:23:19 oldtimey volumio[839]: info: CoreStateMachine::syncState stateService pause Apr 29 14:23:19 oldtimey volumio[839]: info: CoreStateMachine::syncState currentStatus stop Apr 29 14:23:19 oldtimey volumio[839]: info: CoreStateMachine::pushState Apr 29 14:23:19 oldtimey volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 29 14:23:19 oldtimey volumio[839]: info: CoreCommandRouter::volumioPushState Apr 29 14:23:19 oldtimey volumio[839]: info: ControllerMpd::pushState Apr 29 14:23:19 oldtimey volumio[839]: info: CoreCommandRouter::servicePushState Apr 29 14:23:19 oldtimey volumio[839]: info: CorePlayQueue::getTrack 0 Apr 29 14:23:19 oldtimey volumio[839]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":3600,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Thunderstorm Sounds: 1 Hour Thunder","artist":"SleepTherapy","album":"Thunderstorm Sounds: 1 Hour Thunder","uri":"https://rr5---sn-8xgp1vo-cvne.googlevideo.com/videoplayback?expire=1714436596&ei=lOUvZsOXObywkucP27CNsA8&ip=98.110.230.62&id=o-APFe9tCOovT32-sC945JOkss__6dc5856W_cXjfys3cw&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=Dg&mm=31%2C29&mn=sn-8xgp1vo-cvne%2Csn-8xgp1vo-ab56&ms=au%2Crdu&mv=m&mvi=5&pl=18&ctier=A&pfa=5&gcr=us&initcwndbps=942500&hightc=yes&siu=1&bui=AWRWj2Rla3CEFuLT7z36CY9mzHk7EnruHaZueCdgZSJOQdd81FU8mJ6Bm1MSFxGUZbmXImTxqw&spc=UWF9f-4NKrO81YvYDyqPeW0mS4pYKk0A7JPSvSL2XTspJdQUykNjtpqL8QM0&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=5kmazj6pjlj0zexyAEWWvzoQ&gir=yes&clen=55599297&dur=3600.041&lmt=1565331076385729&mt=1714414631&fvip=2&keepalive=yes&c=WEB_REMIX&sefc=1&txp=5531432&n=hD9msZ3oDPXJfw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHWaYeowRgIhAOKN4wTd91-dEocxZ3boLC0oD05QixceQzwFvQk9oYBaAiEAwUwiONSvXJw4NmuObgFR58ts9DKfaK724PCGW-v_CgE%3D&sig=AJfQdSswRQIgZpyeEXb4EWI8ciOnAEM5TPaEcQNjGwZ_Z_Cu6frdLR0CIQC2pQmEJZG1_j4SD9bPfLKRw7vplptW_Dwk359Zp4gK2A%3D%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Apr 29 14:23:19 oldtimey volumio[839]: verbose: CURRENT POSITION 0 Apr 29 14:23:19 oldtimey volumio[839]: info: CoreStateMachine::syncState stateService pause Apr 29 14:23:19 oldtimey volumio[839]: info: CoreStateMachine::syncState currentStatus stop Apr 29 14:23:19 oldtimey volumio[839]: info: CoreStateMachine::pushState Apr 29 14:23:19 oldtimey volumio[839]: info: CoreCommandRouter::volumioPushState Apr 29 14:23:19 oldtimey volumio[839]: info: ControllerMpd::pushState Apr 29 14:23:19 oldtimey volumio[839]: info: CoreCommandRouter::servicePushState Apr 29 14:23:19 oldtimey volumio[839]: info: CorePlayQueue::getTrack 0 Apr 29 14:23:19 oldtimey volumio[839]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":3600,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Thunderstorm Sounds: 1 Hour Thunder","artist":"SleepTherapy","album":"Thunderstorm Sounds: 1 Hour Thunder","uri":"https://rr5---sn-8xgp1vo-cvne.googlevideo.com/videoplayback?expire=1714436596&ei=lOUvZsOXObywkucP27CNsA8&ip=98.110.230.62&id=o-APFe9tCOovT32-sC945JOkss__6dc5856W_cXjfys3cw&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=Dg&mm=31%2C29&mn=sn-8xgp1vo-cvne%2Csn-8xgp1vo-ab56&ms=au%2Crdu&mv=m&mvi=5&pl=18&ctier=A&pfa=5&gcr=us&initcwndbps=942500&hightc=yes&siu=1&bui=AWRWj2Rla3CEFuLT7z36CY9mzHk7EnruHaZueCdgZSJOQdd81FU8mJ6Bm1MSFxGUZbmXImTxqw&spc=UWF9f-4NKrO81YvYDyqPeW0mS4pYKk0A7JPSvSL2XTspJdQUykNjtpqL8QM0&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=5kmazj6pjlj0zexyAEWWvzoQ&gir=yes&clen=55599297&dur=3600.041&lmt=1565331076385729&mt=1714414631&fvip=2&keepalive=yes&c=WEB_REMIX&sefc=1&txp=5531432&n=hD9msZ3oDPXJfw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHWaYeowRgIhAOKN4wTd91-dEocxZ3boLC0oD05QixceQzwFvQk9oYBaAiEAwUwiONSvXJw4NmuObgFR58ts9DKfaK724PCGW-v_CgE%3D&sig=AJfQdSswRQIgZpyeEXb4EWI8ciOnAEM5TPaEcQNjGwZ_Z_Cu6frdLR0CIQC2pQmEJZG1_j4SD9bPfLKRw7vplptW_Dwk359Zp4gK2A%3D%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Apr 29 14:23:19 oldtimey volumio[839]: verbose: CURRENT POSITION 0 Apr 29 14:23:19 oldtimey volumio[839]: info: CoreStateMachine::syncState stateService pause Apr 29 14:23:19 oldtimey volumio[839]: info: CoreStateMachine::syncState currentStatus stop Apr 29 14:23:19 oldtimey volumio[839]: info: CoreStateMachine::pushState Apr 29 14:23:19 oldtimey volumio[839]: info: CoreCommandRouter::volumioPushState Apr 29 14:23:19 oldtimey volumio[839]: info: ------------------------------ 511ms Apr 29 14:23:19 oldtimey volumio[839]: info: ------------------------------ 438ms Apr 29 14:23:19 oldtimey volumio[839]: info: ------------------------------ 437ms Apr 29 14:23:19 oldtimey volumio[839]: info: [ASDebug] CurState: PrevState: stop Apr 29 14:23:19 oldtimey volumio[839]: info: [ASDebug] Togle GPIO: ON Apr 29 14:23:19 oldtimey volumio[839]: info: [ASDebug] CurState: undefined PrevState: Apr 29 14:23:19 oldtimey volumio[839]: info: [ASDebug] Togle GPIO: ON Apr 29 14:23:19 oldtimey volumio[839]: info: [ASDebug] CurState: undefined PrevState: undefined Apr 29 14:23:19 oldtimey volumio[839]: info: [ASDebug] CurState: PrevState: undefined Apr 29 14:23:19 oldtimey volumio[839]: info: [ASDebug] Togle GPIO: ON Apr 29 14:23:19 oldtimey volumio[839]: info: [ASDebug] CurState: undefined PrevState: Apr 29 14:23:19 oldtimey volumio[839]: info: [ASDebug] Togle GPIO: ON Apr 29 14:23:19 oldtimey volumio[839]: info: [ASDebug] CurState: undefined PrevState: undefined Apr 29 14:23:19 oldtimey volumio[839]: info: CoreCommandRouter::volumioGetState Apr 29 14:23:19 oldtimey volumio[975]: .................................................................................................................................................++++ Apr 29 14:23:21 oldtimey volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 29 14:23:21 oldtimey volumio[839]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 15 Apr 29 14:23:21 oldtimey volumio[839]: info: CoreCommandRouter::volumioGetState Apr 29 14:23:23 oldtimey volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 29 14:23:23 oldtimey volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 29 14:23:23 oldtimey volumio[839]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 29 14:23:23 oldtimey volumio[839]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 29 14:23:23 oldtimey volumio-remote-updater[514]: No test mode Apr 29 14:23:23 oldtimey volumio-remote-updater[514]: No alpha test mode Apr 29 14:23:24 oldtimey volumio[839]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 29 14:23:24 oldtimey volumio[839]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 29 14:23:26 oldtimey volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 14:23:26 oldtimey volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 14:23:26 oldtimey volumio[839]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Apr 29 14:23:26 oldtimey volumio[839]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Apr 29 14:23:26 oldtimey volumio[839]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 29 14:23:26 oldtimey volumio[839]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 29 14:23:26 oldtimey volumio[839]: info: CoreCommandRouter::volumioGetBrowseSources Apr 29 14:23:26 oldtimey volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 29 14:23:28 oldtimey volumio[839]: error: MyVolumio Plugin failed to authenticate in a timely fashion Apr 29 14:23:28 oldtimey volumio[839]: info: Completed starting MyVolumio Plugin Apr 29 14:23:28 oldtimey volumio[839]: info: BOOT COMPLETED Apr 29 14:23:28 oldtimey volumio[839]: [Metrics] CommandRouter: 81s 284.80ms Apr 29 14:23:28 oldtimey volumio[839]: info: CoreCommandRouter::volumiosetStartupVolume Apr 29 14:23:28 oldtimey volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 29 14:23:28 oldtimey volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 29 14:23:28 oldtimey volumio[839]: info: CoreCommandRouter::Close All Modals sent Apr 29 14:23:28 oldtimey volumio[839]: info: CoreCommandRouter::Close All Modals sent Apr 29 14:23:28 oldtimey volumio[839]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Apr 29 14:23:28 oldtimey volumio[839]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card Apr 29 14:23:28 oldtimey volumio[839]: aplay: main:828: audio open error: No such device Apr 29 14:23:29 oldtimey volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 29 14:23:29 oldtimey volumio[839]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 29 14:23:29 oldtimey volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 29 14:23:39 oldtimey volumio[975]: .........................................................................................................................................................................++++ Apr 29 14:23:39 oldtimey volumio[975]: e is 65537 (0x010001) Apr 29 14:23:39 oldtimey volumio[975]: writing RSA key Apr 29 14:23:43 oldtimey volumio[839]: error: error Apr 29 14:23:43 oldtimey volumio[839]: error: error Apr 29 14:23:43 oldtimey volumio[839]: error: error Apr 29 14:23:43 oldtimey volumio[839]: error: error Apr 29 14:23:43 oldtimey volumio[839]: error: error Apr 29 14:23:43 oldtimey volumio[839]: error: error Apr 29 14:23:43 oldtimey volumio[839]: error: error Apr 29 14:23:43 oldtimey volumio[839]: error: error Apr 29 14:23:44 oldtimey volumio[839]: error: error Apr 29 14:23:44 oldtimey volumio[839]: error: error Apr 29 14:23:44 oldtimey volumio[839]: error: error Apr 29 14:23:44 oldtimey volumio[839]: error: error Apr 29 14:23:44 oldtimey volumio[839]: error: error Apr 29 14:23:44 oldtimey volumio[839]: error: error Apr 29 14:23:44 oldtimey volumio[839]: error: error Apr 29 14:23:44 oldtimey volumio[839]: error: error Apr 29 14:23:44 oldtimey volumio[839]: error: error Apr 29 14:23:44 oldtimey volumio[839]: error: error Apr 29 14:23:58 oldtimey ntpd[644]: Soliciting pool server 167.248.62.201 Apr 29 14:23:59 oldtimey ntpd[644]: Soliciting pool server 104.167.241.253 Apr 29 14:24:03 oldtimey ntpd[644]: Soliciting pool server 74.50.98.131 Apr 29 14:24:03 oldtimey ntpd[644]: Soliciting pool server 69.164.213.136 Apr 29 14:24:04 oldtimey ntpd[644]: Soliciting pool server 108.181.220.94 Apr 29 14:24:04 oldtimey ntpd[644]: Soliciting pool server 74.6.168.72 Apr 29 14:24:05 oldtimey ntpd[644]: Soliciting pool server 2603:301f:1b08:2090::10 Apr 29 14:24:05 oldtimey ntpd[644]: Soliciting pool server 138.68.201.49 Apr 29 14:24:32 oldtimey volumio[839]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 29 14:24:32 oldtimey volumio[839]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 29 14:24:33 oldtimey volumio[839]: error: error Apr 29 14:24:33 oldtimey volumio[839]: error: error Apr 29 14:24:33 oldtimey volumio[839]: error: error Apr 29 14:24:33 oldtimey volumio[839]: error: error Apr 29 14:24:33 oldtimey volumio[839]: error: error Apr 29 14:24:33 oldtimey volumio[839]: error: error Apr 29 14:24:33 oldtimey volumio[839]: error: error Apr 29 14:24:33 oldtimey volumio[839]: error: error Apr 29 14:24:33 oldtimey volumio[839]: error: error Apr 29 14:24:33 oldtimey volumio[839]: error: error Apr 29 14:24:34 oldtimey volumio[839]: error: error Apr 29 14:24:34 oldtimey volumio[839]: error: error Apr 29 14:24:34 oldtimey volumio[839]: error: error Apr 29 14:24:34 oldtimey volumio[839]: error: error Apr 29 14:24:34 oldtimey volumio[839]: error: error Apr 29 14:24:34 oldtimey volumio[839]: error: error Apr 29 14:24:34 oldtimey volumio[839]: error: error Apr 29 14:24:34 oldtimey volumio[839]: error: error Apr 29 14:24:40 oldtimey volumio[839]: info: Starting Uninstall of plugin system_controller - ampswitch Apr 29 14:24:40 oldtimey volumio[839]: info: Uninstalling plugin ampswitch Apr 29 14:24:40 oldtimey volumio[839]: info: [ASDebug] Port: 21 Apr 29 14:24:40 oldtimey volumio[839]: info: [ASDebug] Inverted: true Apr 29 14:24:40 oldtimey volumio[839]: info: [ASDebug] Delay: 720 Apr 29 14:24:51 oldtimey volumio[839]: info: Starting Uninstall of plugin system_controller - ampswitch Apr 29 14:24:51 oldtimey volumio[839]: info: Uninstalling plugin ampswitch Apr 29 14:24:51 oldtimey volumio[839]: info: [ASDebug] Port: 21 Apr 29 14:24:51 oldtimey volumio[839]: info: [ASDebug] Inverted: true Apr 29 14:24:51 oldtimey volumio[839]: info: [ASDebug] Delay: 720 Apr 29 14:24:51 oldtimey volumio[839]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 29 14:24:51 oldtimey volumio[839]: Error: EBADF: bad file descriptor, close Apr 29 14:24:51 oldtimey volumio[839]: at Object.closeSync (fs.js:439:3) Apr 29 14:24:51 oldtimey volumio[839]: at Object.closeSync (/volumio/node_modules/graceful-fs/graceful-fs.js:74:20) Apr 29 14:24:51 oldtimey volumio[839]: at Object.closeSync (/data/plugins/system_hardware/gpio-buttons/node_modules/graceful-fs/graceful-fs.js:51:27) Apr 29 14:24:51 oldtimey volumio[839]: at Gpio.unexport (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:307:8) Apr 29 14:24:51 oldtimey volumio[839]: at AmpSwitchController.freeGPIO (/data/plugins/system_controller/ampswitch/index.js:234:19) Apr 29 14:24:51 oldtimey volumio[839]: at AmpSwitchController.onStop (/data/plugins/system_controller/ampswitch/index.js:84:10) Apr 29 14:24:51 oldtimey volumio[839]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Apr 29 14:24:51 oldtimey volumio[839]: at PluginManager.unInstallPlugin (/volumio/app/pluginmanager.js:1449:10) Apr 29 14:24:51 oldtimey volumio[839]: at CoreCommandRouter.unInstallPlugin (/volumio/app/index.js:1459:22) Apr 29 14:24:51 oldtimey volumio[839]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1218:45) Apr 29 14:24:51 oldtimey volumio[839]: at Socket.emit (events.js:315:20) Apr 29 14:24:51 oldtimey volumio[839]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Apr 29 14:24:51 oldtimey volumio[839]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Apr 29 14:24:51 oldtimey volumio[839]: errno: -9, Apr 29 14:24:51 oldtimey volumio[839]: syscall: 'close', Apr 29 14:24:51 oldtimey volumio[839]: code: 'EBADF' Apr 29 14:24:51 oldtimey volumio[839]: } Apr 29 14:24:51 oldtimey volumio[839]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 29 14:24:52 oldtimey sudo[1792]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-29 14:23 Apr 29 14:24:52 oldtimey sudo[1792]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"