-- 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"