-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Sun 2024-11-24 16:05:45 CET. -- Nov 24 16:04:09 pi-z83 go-librespot[1233]: time="2024-11-24T16:04:09+01:00" level=debug msg="fetched chunk 7/8, size: 524288" uri="spotify:track:2ggqfj97qyiORmXoVFzP5j" Nov 24 16:04:15 pi-z83 volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 24 16:04:15 pi-z83 volumio[983]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 24 16:04:15 pi-z83 volumio[983]: info: Discovery: Getting this device information Nov 24 16:04:15 pi-z83 volumio[983]: info: CoreCommandRouter::volumioGetState Nov 24 16:04:15 pi-z83 volumio[983]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 24 16:04:23 pi-z83 go-librespot[1233]: time="2024-11-24T16:04:23+01:00" level=debug msg="fetched chunk 8/8, size: 157696" uri="spotify:track:2ggqfj97qyiORmXoVFzP5j" Nov 24 16:04:45 pi-z83 volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 24 16:04:45 pi-z83 volumio[983]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 24 16:04:45 pi-z83 volumio[983]: info: Discovery: Getting this device information Nov 24 16:04:45 pi-z83 volumio[983]: info: CoreCommandRouter::volumioGetState Nov 24 16:04:45 pi-z83 volumio[983]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 24 16:04:47 pi-z83 go-librespot[1233]: time="2024-11-24T16:04:47+01:00" level=debug msg="prefetching next track" uri="spotify:track:3SFP4QwlxhPAugKIwnc0JO" Nov 24 16:04:47 pi-z83 go-librespot[1233]: time="2024-11-24T16:04:47+01:00" level=debug msg="selected format OGG_VORBIS_320 (68195ca4be5697f682b946c48230ab10e7f8c3df)" uri="spotify:track:3SFP4QwlxhPAugKIwnc0JO" Nov 24 16:04:47 pi-z83 go-librespot[1233]: time="2024-11-24T16:04:47+01:00" level=debug msg="requested aes key for file 68195ca4be5697f682b946c48230ab10e7f8c3df, gid: 3SFP4QwlxhPAugKIwnc0JO" Nov 24 16:04:47 pi-z83 go-librespot[1233]: time="2024-11-24T16:04:47+01:00" level=debug msg="fetched first chunk of 27, total size is 14137972 bytes" uri="spotify:track:3SFP4QwlxhPAugKIwnc0JO" Nov 24 16:04:47 pi-z83 go-librespot[1233]: time="2024-11-24T16:04:47+01:00" level=trace msg="seek to 0ms (diff: -105ms, samples: 0, bytes: 4960)" uri="spotify:track:3SFP4QwlxhPAugKIwnc0JO" Nov 24 16:04:47 pi-z83 go-librespot[1233]: time="2024-11-24T16:04:47+01:00" level=info msg="prefetched track \"Spiritual Level Of Gang Shit\" (duration: 293372ms)" uri="spotify:track:3SFP4QwlxhPAugKIwnc0JO" Nov 24 16:04:47 pi-z83 go-librespot[1233]: time="2024-11-24T16:04:47+01:00" level=debug msg="fetched chunk 1/26, size: 524288" uri="spotify:track:3SFP4QwlxhPAugKIwnc0JO" Nov 24 16:04:47 pi-z83 go-librespot[1233]: time="2024-11-24T16:04:47+01:00" level=debug msg="fetched chunk 3/26, size: 524288" uri="spotify:track:3SFP4QwlxhPAugKIwnc0JO" Nov 24 16:04:47 pi-z83 go-librespot[1233]: time="2024-11-24T16:04:47+01:00" level=debug msg="fetched chunk 2/26, size: 524288" uri="spotify:track:3SFP4QwlxhPAugKIwnc0JO" Nov 24 16:05:07 pi-z83 volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 24 16:05:07 pi-z83 volumio[983]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 24 16:05:07 pi-z83 volumio[983]: info: Discovery: Getting this device information Nov 24 16:05:07 pi-z83 volumio[983]: info: CoreCommandRouter::volumioGetState Nov 24 16:05:07 pi-z83 volumio[983]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 24 16:05:07 pi-z83 volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 24 16:05:07 pi-z83 volumio[983]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 24 16:05:07 pi-z83 volumio[983]: info: Discovery: Getting this device information Nov 24 16:05:07 pi-z83 volumio[983]: info: CoreCommandRouter::volumioGetState Nov 24 16:05:07 pi-z83 volumio[983]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 24 16:05:08 pi-z83 volumio[983]: verbose: New Socket.io Connection to 192.168.1.193 from 192.168.1.135 UA: Mozilla/5.0 (Linux; Android 14; SM-S918B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/130.0.6723.108 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Nov 24 16:05:08 pi-z83 volumio[983]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 24 16:05:08 pi-z83 volumio[983]: info: CoreCommandRouter::volumioGetVisibleSources Nov 24 16:05:08 pi-z83 volumio[983]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 24 16:05:08 pi-z83 volumio[983]: info: CoreCommandRouter::volumioGetState Nov 24 16:05:08 pi-z83 volumio[983]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 24 16:05:08 pi-z83 volumio[983]: info: CoreCommandRouter::volumioGetQueue Nov 24 16:05:08 pi-z83 volumio[983]: info: CoreStateMachine::getQueue Nov 24 16:05:08 pi-z83 volumio[983]: info: CorePlayQueue::getQueue Nov 24 16:05:08 pi-z83 volumio[983]: info: Listing playlists Nov 24 16:05:08 pi-z83 volumio[983]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 24 16:05:08 pi-z83 volumio[983]: info: Received Get System Info Nov 24 16:05:08 pi-z83 volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 24 16:05:08 pi-z83 volumio[983]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 24 16:05:08 pi-z83 volumio[983]: info: Discovery: Getting this device information Nov 24 16:05:08 pi-z83 volumio[983]: info: CoreCommandRouter::volumioGetState Nov 24 16:05:08 pi-z83 volumio[983]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 24 16:05:08 pi-z83 volumio[983]: info: CoreCommandRouter::volumioGetState Nov 24 16:05:08 pi-z83 volumio[983]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Nov 24 16:05:08 pi-z83 volumio[983]: verbose: New Socket.io Connection to 192.168.1.193:3000 from 192.168.1.135 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Nov 24 16:05:15 pi-z83 volumio[983]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 24 16:05:15 pi-z83 volumio[983]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 24 16:05:15 pi-z83 volumio[983]: info: Discovery: Getting this device information Nov 24 16:05:15 pi-z83 volumio[983]: info: CoreCommandRouter::volumioGetState Nov 24 16:05:15 pi-z83 volumio[983]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 24 16:05:16 pi-z83 go-librespot[1233]: time="2024-11-24T16:05:16+01:00" level=trace msg="emitting websocket event: not_playing" Nov 24 16:05:16 pi-z83 go-librespot[1233]: time="2024-11-24T16:05:16+01:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:3SFP4QwlxhPAugKIwnc0JO" Nov 24 16:05:16 pi-z83 volumio[983]: SPOTIFY: received: {"type":"not_playing","data":{"uri":"spotify:track:2ggqfj97qyiORmXoVFzP5j","play_origin":"collection-songs"}} Nov 24 16:05:16 pi-z83 volumio[983]: error: Failed to decode event: not_playing Nov 24 16:05:16 pi-z83 go-librespot[1233]: time="2024-11-24T16:05:16+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 24 16:05:16 pi-z83 go-librespot[1233]: time="2024-11-24T16:05:16+01:00" level=trace msg="emitting websocket event: will_play" Nov 24 16:05:16 pi-z83 volumio[983]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:3SFP4QwlxhPAugKIwnc0JO","play_origin":"collection-songs"}} Nov 24 16:05:16 pi-z83 go-librespot[1233]: time="2024-11-24T16:05:16+01:00" level=info msg="loaded track \"Spiritual Level Of Gang Shit\" (paused: false, position: 0ms, duration: 293372ms, prefetched: true)" uri="spotify:track:3SFP4QwlxhPAugKIwnc0JO" Nov 24 16:05:16 pi-z83 go-librespot[1233]: time="2024-11-24T16:05:16+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 24 16:05:16 pi-z83 go-librespot[1233]: time="2024-11-24T16:05:16+01:00" level=trace msg="scheduling prefetch in 263s" Nov 24 16:05:16 pi-z83 go-librespot[1233]: time="2024-11-24T16:05:16+01:00" level=trace msg="emitting websocket event: metadata" Nov 24 16:05:16 pi-z83 volumio[983]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:3SFP4QwlxhPAugKIwnc0JO","name":"Spiritual Level Of Gang Shit","artist_names":["Soul Glo","McKinley Dixon","lojii"],"album_name":"Diaspora Problems","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02f0d7c2b2353888a0a706dfcb","position":0,"duration":293372,"release_date":"year:2022 month:3 day:25","track_number":12,"disc_number":1}} Nov 24 16:05:16 pi-z83 go-librespot[1233]: time="2024-11-24T16:05:16+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 24 16:05:16 pi-z83 go-librespot[1233]: time="2024-11-24T16:05:16+01:00" level=trace msg="emitting websocket event: playing" Nov 24 16:05:16 pi-z83 volumio[983]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:3SFP4QwlxhPAugKIwnc0JO","play_origin":"collection-songs"}} Nov 24 16:05:16 pi-z83 volumio[983]: SPOTIFY: PUSH STATE SPOTIFY Nov 24 16:05:16 pi-z83 volumio[983]: SPOTIFY: {"status":"play","service":"spop","title":"Spiritual Level Of Gang Shit","artist":"Soul Glo, McKinley Dixon, lojii","album":"Diaspora Problems","albumart":"https://i.scdn.co/image/ab67616d00001e02f0d7c2b2353888a0a706dfcb","uri":"spotify:track:3SFP4QwlxhPAugKIwnc0JO","trackType":"spotify","seek":0,"duration":293,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Nov 24 16:05:16 pi-z83 volumio[983]: info: CoreCommandRouter::servicePushState Nov 24 16:05:16 pi-z83 volumio[983]: info: CoreStateMachine::pushState Nov 24 16:05:16 pi-z83 volumio[983]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 24 16:05:16 pi-z83 volumio[983]: info: CoreCommandRouter::volumioPushState Nov 24 16:05:16 pi-z83 volumio[983]: SPOTIFY: RECEIVED VOLUMIO VOLUME 99 Nov 24 16:05:16 pi-z83 volumio[983]: SPOTIFY: SPOTIFY VOLUME 98 Nov 24 16:05:16 pi-z83 volumio[983]: SPOTIFY: VOLUMIO VOLUME 99 Nov 24 16:05:16 pi-z83 volumio[983]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 24 16:05:16 pi-z83 volumio[983]: SPOTIFY: PUSH STATE SPOTIFY Nov 24 16:05:16 pi-z83 volumio[983]: SPOTIFY: {"status":"play","service":"spop","title":"Spiritual Level Of Gang Shit","artist":"Soul Glo, McKinley Dixon, lojii","album":"Diaspora Problems","albumart":"https://i.scdn.co/image/ab67616d00001e02f0d7c2b2353888a0a706dfcb","uri":"spotify:track:3SFP4QwlxhPAugKIwnc0JO","trackType":"spotify","seek":1000,"duration":293,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Nov 24 16:05:16 pi-z83 volumio[983]: info: CoreCommandRouter::servicePushState Nov 24 16:05:16 pi-z83 volumio[983]: info: CoreStateMachine::pushState Nov 24 16:05:16 pi-z83 volumio[983]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 24 16:05:16 pi-z83 volumio[983]: info: CoreCommandRouter::volumioPushState Nov 24 16:05:16 pi-z83 volumio[983]: SPOTIFY: RECEIVED VOLUMIO VOLUME 99 Nov 24 16:05:16 pi-z83 volumio[983]: SPOTIFY: SPOTIFY VOLUME 98 Nov 24 16:05:16 pi-z83 volumio[983]: SPOTIFY: VOLUMIO VOLUME 99 Nov 24 16:05:16 pi-z83 volumio[983]: SPOTIFY: DELTA VOLUME ENOUGH: false Nov 24 16:05:24 pi-z83 volumio[983]: info: Enabling plugin ampswitch Nov 24 16:05:24 pi-z83 volumio[983]: info: Loading plugin "ampswitch"... Nov 24 16:05:25 pi-z83 volumio[983]: info: PLUGIN START: ampswitch Nov 24 16:05:25 pi-z83 volumio[983]: info: Done. Nov 24 16:05:25 pi-z83 volumio[983]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 24 16:05:25 pi-z83 volumio[983]: info: [ASDebug] Setting UI defaults Nov 24 16:05:25 pi-z83 volumio[983]: info: [ASDebug] Port: 24 Nov 24 16:05:25 pi-z83 volumio[983]: info: [ASDebug] Inverted: false Nov 24 16:05:25 pi-z83 volumio[983]: info: [ASDebug] Latched: false Nov 24 16:05:25 pi-z83 volumio[983]: info: [ASDebug] On pulse width: 500 Nov 24 16:05:25 pi-z83 volumio[983]: info: [ASDebug] Off pulse width: 500 Nov 24 16:05:25 pi-z83 volumio[983]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Nov 24 16:05:25 pi-z83 volumio[983]: info: CoreCommandRouter::volumioGetState Nov 24 16:05:25 pi-z83 volumio[983]: info: [ASDebug] CurState: play PrevState: na Nov 24 16:05:25 pi-z83 volumio[983]: info: [ASDebug] Togle GPIO: ON Nov 24 16:05:25 pi-z83 volumio[983]: info: [ASDebug] CurState: play PrevState: play Nov 24 16:05:33 pi-z83 go-librespot[1233]: time="2024-11-24T16:05:33+01:00" level=debug msg="fetched chunk 4/26, size: 524288" uri="spotify:track:3SFP4QwlxhPAugKIwnc0JO" Nov 24 16:05:36 pi-z83 volumio[983]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 24 16:05:36 pi-z83 volumio[983]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 24 16:05:39 pi-z83 volumio[983]: info: [ASDebug] Port: 24 Nov 24 16:05:39 pi-z83 volumio[983]: info: [ASDebug] Inverted: false Nov 24 16:05:39 pi-z83 volumio[983]: info: [ASDebug] Delay: 720 Nov 24 16:05:43 pi-z83 volumio[983]: info: [ASDebug] Port: 24 Nov 24 16:05:43 pi-z83 volumio[983]: info: [ASDebug] Inverted: false Nov 24 16:05:43 pi-z83 volumio[983]: info: [ASDebug] Delay: 720 Nov 24 16:05:43 pi-z83 volumio[983]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 24 16:05:43 pi-z83 volumio[983]: Error: EBADF: bad file descriptor, close Nov 24 16:05:43 pi-z83 volumio[983]: at Object.closeSync (fs.js:439:3) Nov 24 16:05:43 pi-z83 volumio[983]: at Object.closeSync (/volumio/node_modules/graceful-fs/graceful-fs.js:74:20) Nov 24 16:05:43 pi-z83 volumio[983]: at Object.closeSync (/data/plugins/system_controller/ampswitch/node_modules/graceful-fs/graceful-fs.js:51:27) Nov 24 16:05:43 pi-z83 volumio[983]: at Gpio.unexport (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:307:8) Nov 24 16:05:43 pi-z83 volumio[983]: at AmpSwitchController.freeGPIO (/data/plugins/system_controller/ampswitch/index.js:242:19) Nov 24 16:05:43 pi-z83 volumio[983]: at AmpSwitchController.onStop (/data/plugins/system_controller/ampswitch/index.js:90:10) Nov 24 16:05:43 pi-z83 volumio[983]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Nov 24 16:05:43 pi-z83 volumio[983]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Nov 24 16:05:43 pi-z83 volumio[983]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1537:29) Nov 24 16:05:43 pi-z83 volumio[983]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1387:47) Nov 24 16:05:43 pi-z83 volumio[983]: at Socket.emit (events.js:315:20) Nov 24 16:05:43 pi-z83 volumio[983]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Nov 24 16:05:43 pi-z83 volumio[983]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Nov 24 16:05:43 pi-z83 volumio[983]: errno: -9, Nov 24 16:05:43 pi-z83 volumio[983]: syscall: 'close', Nov 24 16:05:43 pi-z83 volumio[983]: code: 'EBADF' Nov 24 16:05:43 pi-z83 volumio[983]: } Nov 24 16:05:43 pi-z83 volumio[983]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 24 16:05:45 pi-z83 go-librespot[1233]: time="2024-11-24T16:05:45+01:00" level=debug msg="fetched chunk 5/26, size: 524288" uri="spotify:track:3SFP4QwlxhPAugKIwnc0JO" Nov 24 16:05:45 pi-z83 sudo[3185]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-24 16:04 Nov 24 16:05:45 pi-z83 sudo[3185]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"