-- Logs begin at Thu 2019-02-14 12:11:59 EET, end at Wed 2024-09-04 17:16:40 EEST. -- Sep 04 17:15:35 volumio sudo[3690]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 04 17:15:35 volumio sudo[3690]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 17:15:35 volumio sudo[3690]: pam_unix(sudo:session): session closed for user root Sep 04 17:15:35 volumio sudo[3693]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 04 17:15:35 volumio sudo[3693]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 17:15:35 volumio sudo[3693]: pam_unix(sudo:session): session closed for user root Sep 04 17:15:35 volumio volumio[1063]: verbose: New Socket.io Connection to 192.168.1.25 from 192.168.1.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Sep 04 17:15:35 volumio volumio[1063]: info: CoreCommandRouter::volumioGetVisibleSources Sep 04 17:15:35 volumio volumio[1063]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 04 17:15:35 volumio volumio[1063]: info: CoreCommandRouter::volumioGetState Sep 04 17:15:35 volumio volumio[1063]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 04 17:15:35 volumio volumio[1063]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 04 17:15:35 volumio volumio[1063]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 04 17:15:35 volumio volumio[1063]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 04 17:15:35 volumio sudo[3696]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 04 17:15:35 volumio sudo[3696]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 17:15:35 volumio sudo[3696]: pam_unix(sudo:session): session closed for user root Sep 04 17:15:35 volumio sudo[3699]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 04 17:15:35 volumio sudo[3699]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 17:15:35 volumio sudo[3699]: pam_unix(sudo:session): session closed for user root Sep 04 17:15:35 volumio volumio[1063]: verbose: New Socket.io Connection to 192.168.1.25 from 192.168.1.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Sep 04 17:15:35 volumio volumio[1063]: info: CoreCommandRouter::volumioGetState Sep 04 17:15:35 volumio volumio[1063]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 04 17:15:35 volumio volumio[1063]: info: Listing playlists Sep 04 17:15:35 volumio volumio[1063]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 04 17:15:35 volumio volumio[1063]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 04 17:15:35 volumio volumio[1063]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 04 17:15:35 volumio volumio[1063]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 04 17:15:37 volumio volumio[1063]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 04 17:15:37 volumio volumio[1063]: info: Received Get System Info Sep 04 17:15:37 volumio volumio[1063]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 04 17:15:37 volumio volumio[1063]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 04 17:15:37 volumio volumio[1063]: info: Discovery: Getting this device information Sep 04 17:15:37 volumio volumio[1063]: info: CoreCommandRouter::volumioGetState Sep 04 17:15:37 volumio volumio[1063]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 04 17:15:38 volumio volumio[1063]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 04 17:15:38 volumio volumio[1063]: info: Received Get System Info Sep 04 17:15:38 volumio volumio[1063]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 04 17:15:38 volumio volumio[1063]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 04 17:15:38 volumio volumio[1063]: info: Discovery: Getting this device information Sep 04 17:15:38 volumio volumio[1063]: info: CoreCommandRouter::volumioGetState Sep 04 17:15:38 volumio volumio[1063]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 04 17:15:40 volumio volumio[1063]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 04 17:15:40 volumio volumio[1063]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 04 17:15:45 volumio volumio[1063]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 04 17:15:54 volumio volumio[1063]: info: Received OAUTH Data Sep 04 17:15:54 volumio volumio[1063]: info: Executing Spotify Oauth Login Sep 04 17:15:54 volumio volumio[1063]: info: Saving Spotify Refresh Token Sep 04 17:15:54 volumio sudo[3742]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 04 17:15:54 volumio sudo[3742]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 17:15:54 volumio sudo[3742]: pam_unix(sudo:session): session closed for user root Sep 04 17:15:54 volumio sudo[3745]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 04 17:15:54 volumio sudo[3745]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 17:15:54 volumio sudo[3745]: pam_unix(sudo:session): session closed for user root Sep 04 17:15:54 volumio volumio[1063]: verbose: New Socket.io Connection to 192.168.1.25 from 192.168.1.86 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Sep 04 17:15:54 volumio volumio[1063]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 04 17:15:54 volumio volumio[1063]: SPOTIFY: BQCikNZeDA4lfUexa0wTA9xcKAjCMzopuQqfNkzV_bvV-ikMG2u78LrmPHu_BmSXFtItqVBpysaIInvSjcAbOPUjlpwcldrzckeRwOgVV5D4PL0fFsZziAbxDZ40cqSChRZCd2qmdcryMRB-ZZpcgNSk9DErnyNJOjF468Ihg6teEjAEXexELkDm7FkdnDqYM10Hh9yZ_RVlZfn9bZqYulL4aainTEPrmSNQxR6u4xmrpmcWm-OhNnsrqm0jLrjVq3C589jH7nqEC1k Sep 04 17:15:54 volumio volumio[1063]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 04 17:15:54 volumio volumio[1063]: info: New Spotify access token = BQCikNZeDA4lfUexa0wTA9xcKAjCMzopuQqfNkzV_bvV-ikMG2u78LrmPHu_BmSXFtItqVBpysaIInvSjcAbOPUjlpwcldrzckeRwOgVV5D4PL0fFsZziAbxDZ40cqSChRZCd2qmdcryMRB-ZZpcgNSk9DErnyNJOjF468Ihg6teEjAEXexELkDm7FkdnDqYM10Hh9yZ_RVlZfn9bZqYulL4aainTEPrmSNQxR6u4xmrpmcWm-OhNnsrqm0jLrjVq3C589jH7nqEC1k Sep 04 17:15:54 volumio volumio[1063]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 04 17:15:54 volumio volumio[1063]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 04 17:15:54 volumio volumio[1063]: info: CoreCommandRouter::volumioGetState Sep 04 17:15:54 volumio volumio[1063]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 04 17:15:54 volumio volumio[1063]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 04 17:15:54 volumio volumio[1063]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 04 17:15:54 volumio volumio[1063]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 04 17:15:55 volumio volumio[1063]: SPOTIFY: User informations: {"display_name":"Toacse Ioan","external_urls":{"spotify":"https://open.spotify.com/user/31dus4yzx4ix4stsr2kvpfji3wj4"},"href":"https://api.spotify.com/v1/users/31dus4yzx4ix4stsr2kvpfji3wj4","id":"31dus4yzx4ix4stsr2kvpfji3wj4","images":[{"url":"https://platform-lookaside.fbsbx.com/platform/profilepic/?asid=2376357459061323&height=50&width=50&ext=1728050824&hash=Aba1UOelojUljrvcdSLBLgnj","height":64,"width":64},{"url":"https://platform-lookaside.fbsbx.com/platform/profilepic/?asid=2376357459061323&height=300&width=300&ext=1728050824&hash=AbY2y0_Yaje82WNA_HC1LMq-","height":300,"width":300}],"type":"user","uri":"spotify:user:31dus4yzx4ix4stsr2kvpfji3wj4","followers":{"href":null,"total":2},"country":"RO","product":"free","explicit_content":{"filter_enabled":false,"filter_locked":false},"email":"ioantoacse@yahoo.com"} Sep 04 17:15:55 volumio volumio[1063]: info: Creating Spotify config file Sep 04 17:15:55 volumio volumio[1063]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 17:15:55 volumio volumio[1063]: info: Spotify config file written Sep 04 17:15:55 volumio sudo[3748]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 04 17:15:55 volumio sudo[3748]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 17:15:55 volumio systemd[1]: Stopping go-librespot Daemon... Sep 04 17:15:55 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 04 17:15:55 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Sep 04 17:15:55 volumio systemd[1]: Stopped go-librespot Daemon. Sep 04 17:15:55 volumio volumio[1063]: info: Connection to go-librespot Websocket closed Sep 04 17:15:55 volumio systemd[1]: Started go-librespot Daemon. Sep 04 17:15:55 volumio go-librespot[3750]: Librespot-go daemon starting... Sep 04 17:15:55 volumio sudo[3748]: pam_unix(sudo:session): session closed for user root Sep 04 17:15:55 volumio go-librespot[3750]: time="2024-09-04T17:15:55+03:00" level=info msg="generated new device id: 39646b767725cd1de0c80121f98064832feaaefe" Sep 04 17:15:55 volumio go-librespot[3750]: time="2024-09-04T17:15:55+03:00" level=debug msg="stored credentials not found" Sep 04 17:15:55 volumio go-librespot[3750]: time="2024-09-04T17:15:55+03:00" level=debug msg="fetched new accesspoints: [ap2-gew1.spotify.com:4070 ap2-gew1.spotify.com:443 ap2-gew1.spotify.com:80 ap2-gue1.spotify.com:4070 ap2-gew4.spotify.com:443 ap2-gae2.spotify.com:80]" Sep 04 17:15:55 volumio go-librespot[3750]: time="2024-09-04T17:15:55+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 04 17:15:55 volumio go-librespot[3750]: time="2024-09-04T17:15:55+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 04 17:15:55 volumio go-librespot[3750]: time="2024-09-04T17:15:55+03:00" level=debug msg="zeroconf server listening on port 37253" Sep 04 17:15:55 volumio volumio[1063]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 04 17:15:55 volumio volumio[1063]: SPOTIFY: BQBM0E_x9nIE1gTkyHZFmlEhMPtnoHHItughftNGkZki6ydPwEffBaLBWsNFXCH5ZvffqpgTS_aXVEr8e5GgDP68NZP_a3UN4XRA_Xlvlrufy-P7CKfaRZO0OiYH4hEM5uZydKseSxrUWdC206H4rUpLEhpA0MtXZkSLftCHAwH5nK072Zw98XkjfsMgjmabrsh1loYzw_R05w_qjK16FX8QFWPrEbraGO16pBgyEWCINDhGE49lUyJgBclJDhzoswEuSdXmGHC4wyQ Sep 04 17:15:55 volumio volumio[1063]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Sep 04 17:15:55 volumio volumio[1063]: info: New Spotify access token = BQBM0E_x9nIE1gTkyHZFmlEhMPtnoHHItughftNGkZki6ydPwEffBaLBWsNFXCH5ZvffqpgTS_aXVEr8e5GgDP68NZP_a3UN4XRA_Xlvlrufy-P7CKfaRZO0OiYH4hEM5uZydKseSxrUWdC206H4rUpLEhpA0MtXZkSLftCHAwH5nK072Zw98XkjfsMgjmabrsh1loYzw_R05w_qjK16FX8QFWPrEbraGO16pBgyEWCINDhGE49lUyJgBclJDhzoswEuSdXmGHC4wyQ Sep 04 17:15:55 volumio volumio[1063]: info: Spotify credentials grant success - running version from March 24, 2019 Sep 04 17:15:55 volumio go-librespot[3750]: time="2024-09-04T17:15:55+03:00" level=debug msg="obtained new client token: AAAf9ILXvpzqqQN9hos3ZPRK+HbfB1tyu73MfehbEbxRtoZ9/fSnsZb0nPlJlgUDo9DzO6Bz9Ecs+0ECwjocERW2xGoDjVTP50NmPlHyw86SXkM7bgNq5oTk7qRGOPFdLgp2gXK4WODB9ad0+bstHMTBGZGMFtLwzrz406UtZiYDD8Mxu1VVjMRbtCeA/6o2wKFP/VbDVWHX7iOPZvJPIgZtquIOc3scY3wu5f0tG7Dze3IIXHPIoEU6tVM=" Sep 04 17:15:55 volumio volumio[1063]: SPOTIFY: User informations: {"display_name":"Toacse Ioan","external_urls":{"spotify":"https://open.spotify.com/user/31dus4yzx4ix4stsr2kvpfji3wj4"},"href":"https://api.spotify.com/v1/users/31dus4yzx4ix4stsr2kvpfji3wj4","id":"31dus4yzx4ix4stsr2kvpfji3wj4","images":[{"url":"https://platform-lookaside.fbsbx.com/platform/profilepic/?asid=2376357459061323&height=50&width=50&ext=1728050824&hash=Aba1UOelojUljrvcdSLBLgnj","height":64,"width":64},{"url":"https://platform-lookaside.fbsbx.com/platform/profilepic/?asid=2376357459061323&height=300&width=300&ext=1728050824&hash=AbY2y0_Yaje82WNA_HC1LMq-","height":300,"width":300}],"type":"user","uri":"spotify:user:31dus4yzx4ix4stsr2kvpfji3wj4","followers":{"href":null,"total":2},"country":"RO","product":"free","explicit_content":{"filter_enabled":false,"filter_locked":false},"email":"ioantoacse@yahoo.com"} Sep 04 17:15:55 volumio volumio[1063]: info: Spotify Successfully logged in Sep 04 17:15:55 volumio volumio[1063]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 04 17:15:55 volumio volumio[1063]: info: [1725459355702] CoreMusicLibrary::Adding element Spotify Sep 04 17:15:55 volumio volumio[1063]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 04 17:15:55 volumio volumio[1063]: Cannot find translation for source YouTube Music Sep 04 17:15:55 volumio volumio[1063]: Cannot find translation for source Spotify Sep 04 17:15:56 volumio volumio[1063]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 04 17:15:56 volumio volumio[1063]: info: Received Get System Info Sep 04 17:15:56 volumio volumio[1063]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 04 17:15:56 volumio volumio[1063]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 04 17:15:56 volumio volumio[1063]: info: Discovery: Getting this device information Sep 04 17:15:56 volumio volumio[1063]: info: CoreCommandRouter::volumioGetState Sep 04 17:15:56 volumio volumio[1063]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 04 17:15:56 volumio go-librespot[3750]: time="2024-09-04T17:15:56+03:00" level=debug msg="completed keyexchange" Sep 04 17:15:57 volumio volumio[1063]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 04 17:15:57 volumio volumio[1063]: info: Received Get System Info Sep 04 17:15:57 volumio volumio[1063]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 04 17:15:57 volumio volumio[1063]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 04 17:15:57 volumio volumio[1063]: info: Discovery: Getting this device information Sep 04 17:15:57 volumio volumio[1063]: info: CoreCommandRouter::volumioGetState Sep 04 17:15:57 volumio volumio[1063]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 04 17:15:57 volumio go-librespot[3750]: time="2024-09-04T17:15:57+03:00" level=debug msg="completed challenge" Sep 04 17:15:57 volumio go-librespot[3750]: time="2024-09-04T17:15:57+03:00" level=debug msg="authenticated as 31dus4yzx4ix4stsr2kvpfji3wj4" Sep 04 17:15:57 volumio go-librespot[3750]: time="2024-09-04T17:15:57+03:00" level=debug msg="authenticated as 31dus4yzx4ix4stsr2kvpfji3wj4" Sep 04 17:15:57 volumio go-librespot[3750]: time="2024-09-04T17:15:57+03:00" level=debug msg="dealer connection opened" Sep 04 17:15:57 volumio go-librespot[3750]: time="2024-09-04T17:15:57+03:00" level=debug msg="stored credentials for 31dus4yzx4ix4stsr2kvpfji3wj4" Sep 04 17:15:57 volumio go-librespot[3750]: time="2024-09-04T17:15:57+03:00" level=debug msg="initializing zeroconf session, username: 31dus4yzx4ix4stsr2kvpfji3wj4" Sep 04 17:15:57 volumio go-librespot[3750]: time="2024-09-04T17:15:57+03:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 04 17:15:57 volumio go-librespot[3750]: time="2024-09-04T17:15:57+03:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 04 17:15:57 volumio go-librespot[3750]: time="2024-09-04T17:15:57+03:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 04 17:15:57 volumio go-librespot[3750]: time="2024-09-04T17:15:57+03:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 04 17:15:57 volumio go-librespot[3750]: time="2024-09-04T17:15:57+03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 493" Sep 04 17:15:57 volumio go-librespot[3750]: time="2024-09-04T17:15:57+03:00" level=debug msg="autoplay enabled: false" Sep 04 17:15:57 volumio go-librespot[3750]: time="2024-09-04T17:15:57+03:00" level=debug msg="received connection id: ZDdkMmJiMzMtMWU3ZS00YzI5LTgyYWItYzIxZjNlZjBjN2JlK2RlYWxlcit0Y3A6Ly8wYWNhNDAwNC5pcC5nZXcxLnNwb3RpZnkubmV0OjU3MDArQTFDNjQ2OUZGODkwQzgwM0FGODE3NjJCRkQwQzM2N0YzNjU4NDlBMDg3Q0NDQzM3NTFGQjc3Mjg0NjYxRTYxMQ==" Sep 04 17:15:58 volumio go-librespot[3750]: time="2024-09-04T17:15:58+03:00" level=debug msg="put connect state because NEW_DEVICE" Sep 04 17:15:58 volumio volumio[1063]: info: Initializing connection to go-librespot Websocket Sep 04 17:15:58 volumio go-librespot[3750]: time="2024-09-04T17:15:58+03:00" level=debug msg="new websocket client" Sep 04 17:15:58 volumio volumio[1063]: info: Connection to go-librespot Websocket established Sep 04 17:15:58 volumio volumio[1063]: info: go-librespot daemon successfully initialized Sep 04 17:16:01 volumio volumio[1063]: info: Getting Spotify volume Sep 04 17:16:01 volumio volumio[1063]: info: Spotify volume: 100 Sep 04 17:16:01 volumio volumio[1063]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Sep 04 17:16:01 volumio volumio[1063]: info: CoreCommandRouter::volumioGetState Sep 04 17:16:01 volumio volumio[1063]: SPOTIFY: RECEIVED VOLUMIO VOLUME 92 Sep 04 17:16:01 volumio volumio[1063]: SPOTIFY: SPOTIFY VOLUME 100 Sep 04 17:16:01 volumio volumio[1063]: SPOTIFY: VOLUMIO VOLUME 92 Sep 04 17:16:01 volumio volumio[1063]: SPOTIFY: DELTA VOLUME ENOUGH: true Sep 04 17:16:01 volumio volumio[1063]: info: Setting Spotify Volume from Volumio: 92 Sep 04 17:16:01 volumio volumio[1063]: info: Initializing connection to go-librespot Websocket Sep 04 17:16:01 volumio go-librespot[3750]: time="2024-09-04T17:16:01+03:00" level=debug msg="new websocket client" Sep 04 17:16:01 volumio volumio[1063]: info: Connection to go-librespot Websocket established Sep 04 17:16:02 volumio volumio[1063]: SPOTIFY: SETTING SPOTIFY VOLUME 92 Sep 04 17:16:02 volumio volumio[1063]: info: Sending Spotify command with payload to local API: /player/volume Sep 04 17:16:02 volumio go-librespot[3750]: time="2024-09-04T17:16:02+03:00" level=debug msg="update volume to 60292/65535" Sep 04 17:16:02 volumio go-librespot[3750]: time="2024-09-04T17:16:02+03:00" level=debug msg="put connect state because VOLUME_CHANGED" Sep 04 17:16:02 volumio go-librespot[3750]: time="2024-09-04T17:16:02+03:00" level=trace msg="emitting websocket event: volume" Sep 04 17:16:02 volumio volumio[1063]: SPOTIFY: received: {"type":"volume","data":{"value":92,"max":100}} Sep 04 17:16:02 volumio volumio[1063]: SPOTIFY: RECEIVED SPOTIFY VOLUME 92 Sep 04 17:16:02 volumio volumio[1063]: SPOTIFY: received: {"type":"volume","data":{"value":92,"max":100}} Sep 04 17:16:02 volumio volumio[1063]: SPOTIFY: RECEIVED SPOTIFY VOLUME 92 Sep 04 17:16:04 volumio volumio[1063]: info: Getting Spotify volume Sep 04 17:16:04 volumio volumio[1063]: info: Spotify volume: 92 Sep 04 17:16:04 volumio volumio[1063]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Sep 04 17:16:04 volumio volumio[1063]: info: CoreCommandRouter::volumioGetState Sep 04 17:16:04 volumio volumio[1063]: SPOTIFY: RECEIVED VOLUMIO VOLUME 92 Sep 04 17:16:12 volumio volumio[1063]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 04 17:16:12 volumio volumio[1063]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Sep 04 17:16:12 volumio volumio[1063]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Sep 04 17:16:12 volumio volumio[1063]: info: Received Get System Version Sep 04 17:16:12 volumio volumio[1063]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 04 17:16:12 volumio volumio[1063]: info: Received Get System Info Sep 04 17:16:12 volumio volumio[1063]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 04 17:16:12 volumio volumio[1063]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 04 17:16:12 volumio volumio[1063]: info: Discovery: Getting this device information Sep 04 17:16:12 volumio volumio[1063]: info: CoreCommandRouter::volumioGetState Sep 04 17:16:12 volumio volumio[1063]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 04 17:16:40 volumio volumio[1063]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Sep 04 17:16:40 volumio volumio[1063]: info: PLUGIN onReboot : networkfs Sep 04 17:16:40 volumio volumio[1063]: info: PLUGIN onReboot : audiophonicsonoff Sep 04 17:16:40 volumio volumio[1063]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 04 17:16:40 volumio volumio[1063]: TypeError: Cannot read property 'writeSync' of undefined Sep 04 17:16:40 volumio volumio[1063]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_controller/audiophonicsonoff/index.js:35:25) Sep 04 17:16:40 volumio volumio[1063]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) Sep 04 17:16:40 volumio volumio[1063]: at HashMap. (/volumio/app/pluginmanager.js:668:31) Sep 04 17:16:40 volumio volumio[1063]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Sep 04 17:16:40 volumio volumio[1063]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Sep 04 17:16:40 volumio volumio[1063]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) Sep 04 17:16:40 volumio volumio[1063]: at CoreCommandRouter.reboot (/volumio/app/index.js:1331:22) Sep 04 17:16:40 volumio volumio[1063]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:864:33) Sep 04 17:16:40 volumio volumio[1063]: at Socket.emit (events.js:315:20) Sep 04 17:16:40 volumio volumio[1063]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Sep 04 17:16:40 volumio volumio[1063]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Sep 04 17:16:40 volumio volumio[1063]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 04 17:16:40 volumio sudo[3904]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-04 17:15 Sep 04 17:16:40 volumio sudo[3904]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"