-- Logs begin at Sat 2024-07-06 19:37:55 UTC, end at Sun 2024-07-07 02:34:26 UTC. -- Jul 07 02:33:03 muskokaroom root[1688]: [2024/07/07 02:33:03:1868] NOTICE: Sending : 2 Jul 07 02:33:03 muskokaroom root[1688]: [2024/07/07 02:33:03:1868] NOTICE: ping Jul 07 02:33:04 muskokaroom root[1688]: 2024-07-07 02:33:04.678453 AM [AirPlay] Teardown Jul 07 02:33:04 muskokaroom root[1688]: 2024-07-07 02:33:04.678580 AM [AirPlay] AirPlay session ended: Dur=46 seconds Reason=0/0x0 noErr Jul 07 02:33:04 muskokaroom root[1688]: Glitches: 0%, 0 total, 0 glitchy minute(s) Jul 07 02:33:04 muskokaroom root[1688]: Retransmits: 0 sent, 0 received, 0 futile, 0 not found, 0/0/0 ms min/max/avg, 0/0 ms retry min/max Jul 07 02:33:04 muskokaroom root[1688]: Packets: 13 lost, 0 unrecovered, 0 late, 0 max burst, 0 big losses, 0% compression Jul 07 02:33:04 muskokaroom root[1688]: Time Sync: 1000000000/0/0 ms min/max/avg RTT, 2147483647/-2147483648/0 ┬ÁS min/max/avg offset, 0 step(s) Jul 07 02:33:04 muskokaroom root[1688]: 2024-07-07 02:33:04.678749 AM [Airtunesd] Received Play Status Update Message Jul 07 02:33:04 muskokaroom root[1688]: [2024/07/07 02:33:04:6788] NOTICE: Sending : 42["callMethod", {"endpoint":"music_service/airplay2","method":"Playing","data":{"status":"0"}}] Jul 07 02:33:04 muskokaroom root[1688]: 2024-07-07 02:33:04.678893 AM [Airtunesd] Received Play Status Update Message Jul 07 02:33:04 muskokaroom root[1688]: [2024/07/07 02:33:04:6852] NOTICE: Sending : 42["callMethod", {"endpoint":"music_service/airplay2","method":"Playing","data":{"status":"0"}}] Jul 07 02:33:04 muskokaroom volumio[530]: info: CALLMETHOD: music_service airplay2 Playing [object Object] Jul 07 02:33:04 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: airplay2 , Playing Jul 07 02:33:04 muskokaroom volumio[530]: info: Airplay stopped playing. Jul 07 02:33:04 muskokaroom volumio[530]: info: CoreCommandRouter::servicePushState Jul 07 02:33:04 muskokaroom volumio[530]: info: CoreStateMachine::syncState Jul 07 02:33:04 muskokaroom volumio[530]: info: CoreStateMachine::pushState Jul 07 02:33:04 muskokaroom volumio[530]: info: CoreStateMachine::getState Jul 07 02:33:04 muskokaroom volumio[530]: info: CoreCommandRouter::volumioPushState Jul 07 02:33:04 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 02:33:04 muskokaroom volumio[530]: info: VOLUMIO PUSHED THIS STATE: status - play, service - airplay Jul 07 02:33:04 muskokaroom volumio[530]: info: interfaceApi::pushState Jul 07 02:33:04 muskokaroom root[1688]: 2024-07-07 02:33:04.686003 AM [AirPlay] AirPlay remote control session ended: Dur=48 seconds Reason=0/0x0 noErr Jul 07 02:33:04 muskokaroom root[1688]: 2024-07-07 02:33:04.686333 AM [AirPlay] AirPlay remote control session ended: Dur=44 seconds Reason=0/0x0 noErr Jul 07 02:33:04 muskokaroom root[1688]: 2024-07-07 02:33:04.686873 AM [AirTunesAvnuClock] Device at ip address 192.168.2.171 is removed from the clock group. Jul 07 02:33:04 muskokaroom root[1688]: 2024-07-07 02:33:04.687114 AM [AirTunesAvnuClock] Device at ip address 192.168.2.191 is removed from the clock group. Jul 07 02:33:04 muskokaroom root[1688]: 2024-07-07 02:33:04.687182 AM [AirTunesAvnuClock] AirTunesPTPClock_Finalize Jul 07 02:33:04 muskokaroom root[1688]: 2024-07-07 02:33:04.687290 AM [AirTunesAvnuClock] AirTunesPTPClock_Finalize: SIGTERM 8489 Jul 07 02:33:04 muskokaroom root[1688]: 2024-07-07 02:33:04.693276 AM [AirTunesAvnuClock] ptp process 8489 exited with no errors Jul 07 02:33:04 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Jul 07 02:33:04 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getDevices Jul 07 02:33:04 muskokaroom root[1688]: 2024-07-07 02:33:04.705438 AM [AirPlay] Updated Bonjour TXT for _airplay._tcp. Jul 07 02:33:04 muskokaroom root[1688]: 2024-07-07 02:33:04.708450 AM [AirPlay] Updated Bonjour TXT for _raop._tcp. Jul 07 02:33:04 muskokaroom root[1688]: 2024-07-07 02:33:04.708590 AM [AirPlaySessionManager] Releasing master session:0x6EF0DE80 Jul 07 02:33:04 muskokaroom root[1688]: 2024-07-07 02:33:04.709574 AM [AirPlay] AirPlay remote control session ended: Dur=48 seconds Reason=0/0x0 noErr Jul 07 02:33:04 muskokaroom root[1688]: 2024-07-07 02:33:04.709760 AM [AirPlaySessionManager] Removing remote session:0x6C935178 Jul 07 02:33:04 muskokaroom sudo[8588]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 07 02:33:04 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getDevices Jul 07 02:33:04 muskokaroom sudo[8588]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 02:33:04 muskokaroom sudo[8588]: pam_unix(sudo:session): session closed for user root Jul 07 02:33:04 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 02:33:04 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getDevices Jul 07 02:33:04 muskokaroom sudo[8596]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 07 02:33:04 muskokaroom sudo[8596]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 02:33:04 muskokaroom sudo[8596]: pam_unix(sudo:session): session closed for user root Jul 07 02:33:04 muskokaroom sudo[8600]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 07 02:33:04 muskokaroom sudo[8600]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 02:33:04 muskokaroom volumio[530]: info: CALLMETHOD: music_service airplay2 Playing [object Object] Jul 07 02:33:04 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: airplay2 , Playing Jul 07 02:33:04 muskokaroom volumio[530]: info: Airplay stopped playing. Jul 07 02:33:04 muskokaroom volumio[530]: info: CoreCommandRouter::servicePushState Jul 07 02:33:04 muskokaroom volumio[530]: info: CoreStateMachine::syncState Jul 07 02:33:04 muskokaroom volumio[530]: info: CoreStateMachine::pushState Jul 07 02:33:04 muskokaroom volumio[530]: info: CoreStateMachine::getState Jul 07 02:33:04 muskokaroom volumio[530]: info: CoreCommandRouter::volumioPushState Jul 07 02:33:04 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 02:33:04 muskokaroom volumio[530]: info: VOLUMIO PUSHED THIS STATE: status - play, service - airplay Jul 07 02:33:04 muskokaroom volumio[530]: info: interfaceApi::pushState Jul 07 02:33:04 muskokaroom sudo[8600]: pam_unix(sudo:session): session closed for user root Jul 07 02:33:04 muskokaroom root[1688]: 2024-07-07 02:33:04.788807 AM [AirPlay] Control pair-verify ([fe80::1043:3b4:c1f3:30e7%eth0]:63646) 1 Jul 07 02:33:04 muskokaroom root[1688]: 2024-07-07 02:33:04.789025 AM [AirPlay] pair-verify useHomeKitPairing 1 pairingType 6 Jul 07 02:33:04 muskokaroom sudo[8608]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 07 02:33:04 muskokaroom sudo[8608]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 02:33:04 muskokaroom sudo[8608]: pam_unix(sudo:session): session closed for user root Jul 07 02:33:04 muskokaroom root[1688]: 2024-07-07 02:33:04.808138 AM [AirPlay] Control pair-setup HK ([fe80::1043:3b4:c1f3:30e7%eth0]:63646), type 4 Jul 07 02:33:04 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Jul 07 02:33:04 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Jul 07 02:33:04 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getDevices Jul 07 02:33:04 muskokaroom sudo[8626]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 07 02:33:04 muskokaroom sudo[8626]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 02:33:04 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getDevices Jul 07 02:33:04 muskokaroom sudo[8626]: pam_unix(sudo:session): session closed for user root Jul 07 02:33:04 muskokaroom sudo[8632]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 07 02:33:04 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 02:33:04 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getDevices Jul 07 02:33:04 muskokaroom sudo[8632]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 02:33:04 muskokaroom sudo[8632]: pam_unix(sudo:session): session closed for user root Jul 07 02:33:04 muskokaroom sudo[8638]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 07 02:33:04 muskokaroom sudo[8645]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 07 02:33:04 muskokaroom sudo[8638]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 02:33:04 muskokaroom sudo[8645]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 02:33:04 muskokaroom sudo[8638]: pam_unix(sudo:session): session closed for user root Jul 07 02:33:04 muskokaroom volumio[530]: info: Pushing Favourites {"service":"airplay","uri":"","favourite":false} Jul 07 02:33:04 muskokaroom sudo[8645]: pam_unix(sudo:session): session closed for user root Jul 07 02:33:04 muskokaroom volumio[530]: info: Pushing Favourites {"service":"airplay","uri":"","favourite":false} Jul 07 02:33:05 muskokaroom root[1688]: 2024-07-07 02:33:05.028488 AM [AirPlay] Control pair-setup HK ([fe80::1043:3b4:c1f3:30e7%eth0]:63646), type 4 Jul 07 02:33:05 muskokaroom root[1688]: 2024-07-07 02:33:05.427233 AM [AirPlay] AirPlay;2.0.4(28.0) relay session started from [fe80::1043:3b4:c1f3:30e7%eth0]:63646, 0/0x0 noErr Jul 07 02:33:13 muskokaroom root[1688]: [2024/07/07 02:33:13:2935] NOTICE: Sending : 2 Jul 07 02:33:13 muskokaroom root[1688]: [2024/07/07 02:33:13:2935] NOTICE: ping Jul 07 02:33:23 muskokaroom root[1688]: [2024/07/07 02:33:23:3998] NOTICE: Sending : 2 Jul 07 02:33:23 muskokaroom root[1688]: [2024/07/07 02:33:23:3999] NOTICE: ping Jul 07 02:33:27 muskokaroom avahi-daemon[528]: Record [_raop._tcp.local IN PTR B827EB73FC2A\064muskokaroom-AP2._raop._tcp.local ; ttl=4500] not fitting in legacy unicast packet, dropping. Jul 07 02:33:27 muskokaroom avahi-daemon[528]: Record [B827EB73FC2A\064muskokaroom-AP2._raop._tcp.local IN TXT "cn=0,1" "da=true" "et=0,4" "ft=0x445F8A00,0x1C340" "md=0,1,2" "am=Axiom Air" "sf=0x4" "tp=UDP" "vn=65537" "vs=366.0" "pk=b10cbe2d5ba799194cd44e3591fd7852b6351f157d9f49ed919cb809f3903fd3" ; t Jul 07 02:33:27 muskokaroom avahi-daemon[528]: Record [B827EB73FC2A\064muskokaroom-AP2._raop._tcp.local IN SRV 0 0 7000 muskokaroom.local ; ttl=120] not fitting in legacy unicast packet, dropping. Jul 07 02:33:33 muskokaroom root[1688]: 2024-07-07 02:33:33.080592 AM [AirPlay] AirPlay remote control session ended: Dur=72 seconds Reason=0/0x0 noErr Jul 07 02:33:33 muskokaroom root[1688]: 2024-07-07 02:33:33.080832 AM [AirPlaySessionManager] Removing remote session:0x6F328E00 Jul 07 02:33:33 muskokaroom root[1688]: [2024/07/07 02:33:33:5061] NOTICE: Sending : 2 Jul 07 02:33:33 muskokaroom root[1688]: [2024/07/07 02:33:33:5061] NOTICE: ping Jul 07 02:33:36 muskokaroom root[1688]: 2024-07-07 02:33:36.863606 AM [AirPlay] Control pair-verify ([fe80::180b:266a:615:88fd%eth0]:49377) 1 Jul 07 02:33:36 muskokaroom root[1688]: 2024-07-07 02:33:36.863771 AM [AirPlay] pair-verify useHomeKitPairing 1 pairingType 6 Jul 07 02:33:36 muskokaroom root[1688]: 2024-07-07 02:33:36.986484 AM [AirPlay] Control pair-setup HK ([fe80::180b:266a:615:88fd%eth0]:49377), type 4 Jul 07 02:33:37 muskokaroom root[1688]: 2024-07-07 02:33:37.188135 AM [AirPlay] Control pair-setup HK ([fe80::180b:266a:615:88fd%eth0]:49377), type 4 Jul 07 02:33:37 muskokaroom root[1688]: 2024-07-07 02:33:37.605568 AM [AirPlay] AirPlay;2.0.4(28.0) relay session started from [fe80::180b:266a:615:88fd%eth0]:49377, 0/0x0 noErr Jul 07 02:33:43 muskokaroom root[1688]: [2024/07/07 02:33:43:6124] NOTICE: Sending : 2 Jul 07 02:33:43 muskokaroom root[1688]: [2024/07/07 02:33:43:6124] NOTICE: ping Jul 07 02:33:45 muskokaroom root[1688]: 2024-07-07 02:33:45.614950 AM [AirPlay] Control pair-verify ([fe80::1043:3b4:c1f3:30e7%eth0]:63648) 1 Jul 07 02:33:45 muskokaroom root[1688]: 2024-07-07 02:33:45.615156 AM [AirPlay] pair-verify useHomeKitPairing 1 pairingType 6 Jul 07 02:33:45 muskokaroom root[1688]: 2024-07-07 02:33:45.638210 AM [AirPlay] Control pair-setup HK ([fe80::1043:3b4:c1f3:30e7%eth0]:63648), type 4 Jul 07 02:33:45 muskokaroom root[1688]: 2024-07-07 02:33:45.782801 AM [AirPlay] Control pair-setup HK ([fe80::1043:3b4:c1f3:30e7%eth0]:63648), type 4 Jul 07 02:33:46 muskokaroom root[1688]: 2024-07-07 02:33:46.182385 AM [Airtunesd] Received Client IP Update Message Jul 07 02:33:46 muskokaroom root[1688]: 2024-07-07 02:33:46.182494 AM [Airtunesd] Unsupported session copy property request: rtpSkewPlatformAdjust Jul 07 02:33:46 muskokaroom root[1688]: 2024-07-07 02:33:46.185707 AM [AirPlay] Updated Bonjour TXT for _airplay._tcp. Jul 07 02:33:46 muskokaroom root[1688]: 2024-07-07 02:33:46.188256 AM [AirPlay] Updated Bonjour TXT for _raop._tcp. Jul 07 02:33:46 muskokaroom root[1688]: 2024-07-07 02:33:46.188787 AM [Airtunesd] Unsupported session copy property request: ptpPowerState Jul 07 02:33:46 muskokaroom root[1688]: 2024-07-07 02:33:46.188880 AM [Airtunesd] Received and Unknown Update Message Jul 07 02:33:46 muskokaroom root[1688]: 2024-07-07 02:33:46.189182 AM [AirPlay] _GeneralAudioAddIPAddrs: our ptp info: { Jul 07 02:33:46 muskokaroom root[1688]: "SupportsClockPortMatchingOverride" : false Jul 07 02:33:46 muskokaroom root[1688]: "Addresses" : Jul 07 02:33:46 muskokaroom root[1688]: [ Jul 07 02:33:46 muskokaroom root[1688]: "192.168.2.81" Jul 07 02:33:46 muskokaroom root[1688]: ] Jul 07 02:33:46 muskokaroom root[1688]: "ID" : "26bfe265-86c9-4e43-9f13-990fc100786e" Jul 07 02:33:46 muskokaroom root[1688]: } Jul 07 02:33:46 muskokaroom root[1688]: 2024-07-07 02:33:46.210352 AM [AirPlay] _requestProcessGetParameter:server volume set to 0.0 Jul 07 02:33:46 muskokaroom root[1688]: 2024-07-07 02:33:46.213943 AM [AirPlay] AirPlay;2.0.4(28.0) master session started from [fe80::1043:3b4:c1f3:30e7%eth0]:63648, 0/0x0 noErr Jul 07 02:33:46 muskokaroom root[1688]: 2024-07-07 02:33:46.217898 AM [AirPlay] AirPlayReceiverSessionSetPeers: [ Jul 07 02:33:46 muskokaroom root[1688]: "192.168.2.171", Jul 07 02:33:46 muskokaroom root[1688]: "fe80::1043:3b4:c1f3:30e7", Jul 07 02:33:46 muskokaroom root[1688]: "192.168.2.191" Jul 07 02:33:46 muskokaroom root[1688]: ] Jul 07 02:33:46 muskokaroom root[1688]: on eth0 Jul 07 02:33:46 muskokaroom root[1688]: 2024-07-07 02:33:46.245903 AM [AirTunesAvnuClock] PTPClockLaunch: launched PID 8763 Jul 07 02:33:46 muskokaroom root[1688]: 2024-07-07 02:33:46.446266 AM [AirTunesAvnuClock] PTPClockOpenMsgPort Jul 07 02:33:46 muskokaroom root[1688]: 2024-07-07 02:33:46.446412 AM [AirTunesAvnuClock] Device at ip address 192.168.2.171 is added into the clock group. Jul 07 02:33:46 muskokaroom root[1688]: 2024-07-07 02:33:46.446546 AM [AirTunesAvnuClock] Device at ip address 192.168.2.191 is added into the clock group. Jul 07 02:33:46 muskokaroom root[1688]: 2024-07-07 02:33:46.450625 AM [Airtunesd] Received and Unknown Update Message Jul 07 02:33:46 muskokaroom root[1688]: 2024-07-07 02:33:46.450716 AM [AirPlay] ### Command from Client Identifier 'updateMRSupportedCommands' failed: 400/0x190 Bad Request, -6714/0xFFFFE5C6 kNotHandledErr Jul 07 02:33:46 muskokaroom root[1688]: 2024-07-07 02:33:46.453970 AM [AirPlay] _requestProcessSetParameterText: dbVolume = -20.0 Jul 07 02:33:46 muskokaroom root[1688]: 2024-07-07 02:33:46.454051 AM [AirPlay] _requestProcessSetParameterText:server volume set to -20.0 Jul 07 02:33:46 muskokaroom root[1688]: 2024-07-07 02:33:46.458798 AM [Airtunesd] Received and Unknown Update Message Jul 07 02:33:46 muskokaroom root[1688]: 2024-07-07 02:33:46.458890 AM [AirPlay] ### Command from Client Identifier 'updateMRSupportedCommands' failed: 400/0x190 Bad Request, -6714/0xFFFFE5C6 kNotHandledErr Jul 07 02:33:47 muskokaroom root[1688]: 2024-07-07 02:33:47.623140 AM [AirPlay] RCS creation status: 0 Jul 07 02:33:47 muskokaroom root[1688]: 2024-07-07 02:33:47.623255 AM [AirPlay] RCS creation response: {} Jul 07 02:33:47 muskokaroom root[1688]: Jul 07 02:33:47 muskokaroom root[1688]: 2024-07-07 02:33:47.623298 AM [AirPlay] Signalling complete Jul 07 02:33:47 muskokaroom root[1688]: 2024-07-07 02:33:47.623366 AM [AirPlay] RCS creation status: 0 Jul 07 02:33:47 muskokaroom root[1688]: 2024-07-07 02:33:47.623421 AM [AirPlay] RCS setup successful Jul 07 02:33:53 muskokaroom root[1688]: [2024/07/07 02:33:53:7188] NOTICE: Sending : 2 Jul 07 02:33:53 muskokaroom root[1688]: [2024/07/07 02:33:53:7188] NOTICE: ping Jul 07 02:33:57 muskokaroom avahi-daemon[528]: Record [_raop._tcp.local IN PTR B827EB73FC2A\064muskokaroom-AP2._raop._tcp.local ; ttl=4500] not fitting in legacy unicast packet, dropping. Jul 07 02:33:57 muskokaroom avahi-daemon[528]: Record [B827EB73FC2A\064muskokaroom-AP2._raop._tcp.local IN TXT "cn=0,1" "da=true" "et=0,4" "ft=0x445F8A00,0x1C340" "md=0,1,2" "am=Axiom Air" "sf=0x804" "tp=UDP" "vn=65537" "vs=366.0" "pk=b10cbe2d5ba799194cd44e3591fd7852b6351f157d9f49ed919cb809f3903fd3" ; Jul 07 02:33:57 muskokaroom avahi-daemon[528]: Record [B827EB73FC2A\064muskokaroom-AP2._raop._tcp.local IN SRV 0 0 7000 muskokaroom.local ; ttl=120] not fitting in legacy unicast packet, dropping. Jul 07 02:34:03 muskokaroom root[1688]: [2024/07/07 02:34:03:8252] NOTICE: Sending : 2 Jul 07 02:34:03 muskokaroom root[1688]: [2024/07/07 02:34:03:8253] NOTICE: ping Jul 07 02:34:05 muskokaroom root[1688]: 2024-07-07 02:34:05.442303 AM [AirPlay] Teardown Jul 07 02:34:05 muskokaroom root[1688]: 2024-07-07 02:34:05.442431 AM [AirPlay] AirPlay remote control session ended: Dur=60 seconds Reason=0/0x0 noErr Jul 07 02:34:05 muskokaroom root[1688]: 2024-07-07 02:34:05.442710 AM [AirPlaySessionManager] Removing remote session:0x6EF0DE80 Jul 07 02:34:13 muskokaroom root[1688]: [2024/07/07 02:34:13:9317] NOTICE: Sending : 2 Jul 07 02:34:13 muskokaroom root[1688]: [2024/07/07 02:34:13:9317] NOTICE: ping Jul 07 02:34:22 muskokaroom volumio[1575]: [Vollibrespot] : Connecting to AP "ap-gue1.spotify.com:4070" Jul 07 02:34:22 muskokaroom volumio[1575]: [Vollibrespot] : Authenticated as "22vnl7z4gjuxbuen6hjqucbdy" ! Jul 07 02:34:22 muskokaroom volumio[1575]: [Vollibrespot] : Country: "CA" Jul 07 02:34:22 muskokaroom volumio[1575]: [Vollibrespot] : Using alsa sink Jul 07 02:34:22 muskokaroom volumio[1575]: [Vollibrespot] : Metadata pipe established Jul 07 02:34:22 muskokaroom volumio[530]: [SpotifyConnect] Volume: Sp:49.999237048905165 Volumio: 50 Jul 07 02:34:22 muskokaroom volumio[530]: info: CoreStateMachine::pushState Jul 07 02:34:22 muskokaroom volumio[530]: info: CoreStateMachine::getState Jul 07 02:34:22 muskokaroom volumio[530]: info: CoreCommandRouter::volumioPushState Jul 07 02:34:22 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 02:34:22 muskokaroom volumio[530]: info: VOLUMIO PUSHED THIS STATE: status - play, service - airplay Jul 07 02:34:22 muskokaroom volumio[530]: info: interfaceApi::pushState Jul 07 02:34:22 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Jul 07 02:34:22 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Jul 07 02:34:22 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getDevices Jul 07 02:34:22 muskokaroom sudo[8848]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 07 02:34:22 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getDevices Jul 07 02:34:22 muskokaroom sudo[8848]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 02:34:22 muskokaroom sudo[8848]: pam_unix(sudo:session): session closed for user root Jul 07 02:34:22 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 02:34:22 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getDevices Jul 07 02:34:22 muskokaroom sudo[8853]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 07 02:34:22 muskokaroom sudo[8858]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 07 02:34:22 muskokaroom sudo[8853]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 02:34:22 muskokaroom sudo[8853]: pam_unix(sudo:session): session closed for user root Jul 07 02:34:22 muskokaroom sudo[8858]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 02:34:22 muskokaroom volumio[530]: info: Pushing Favourites {"service":"airplay","uri":"","favourite":false} Jul 07 02:34:22 muskokaroom sudo[8858]: pam_unix(sudo:session): session closed for user root Jul 07 02:34:22 muskokaroom sudo[8864]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 07 02:34:22 muskokaroom sudo[8864]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 02:34:22 muskokaroom sudo[8864]: pam_unix(sudo:session): session closed for user root Jul 07 02:34:23 muskokaroom volumio[530]: info: VolumeController::SetAlsaVolume32767 Jul 07 02:34:23 muskokaroom volumio[530]: amixer -M set -c 0 "Digital" 49.999237048905165% Jul 07 02:34:23 muskokaroom volumio[530]: null Jul 07 02:34:23 muskokaroom volumio[530]: info: VolumeController::Volume 49.999237048905165 Jul 07 02:34:23 muskokaroom volumio[530]: info: CoreStateMachine::pushState Jul 07 02:34:23 muskokaroom volumio[530]: info: CoreStateMachine::getState Jul 07 02:34:23 muskokaroom volumio[530]: info: CoreCommandRouter::volumioPushState Jul 07 02:34:23 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 02:34:23 muskokaroom volumio[530]: info: VOLUMIO PUSHED THIS STATE: status - play, service - airplay Jul 07 02:34:23 muskokaroom volumio[530]: info: interfaceApi::pushState Jul 07 02:34:23 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Jul 07 02:34:23 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Jul 07 02:34:23 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getDevices Jul 07 02:34:23 muskokaroom sudo[8885]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 07 02:34:23 muskokaroom sudo[8885]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 02:34:23 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getDevices Jul 07 02:34:23 muskokaroom sudo[8885]: pam_unix(sudo:session): session closed for user root Jul 07 02:34:23 muskokaroom sudo[8890]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 07 02:34:23 muskokaroom sudo[8890]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 02:34:23 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 02:34:23 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getDevices Jul 07 02:34:23 muskokaroom sudo[8890]: pam_unix(sudo:session): session closed for user root Jul 07 02:34:23 muskokaroom sudo[8898]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 07 02:34:23 muskokaroom sudo[8898]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 02:34:23 muskokaroom volumio[530]: info: Pushing Favourites {"service":"airplay","uri":"","favourite":false} Jul 07 02:34:23 muskokaroom sudo[8898]: pam_unix(sudo:session): session closed for user root Jul 07 02:34:23 muskokaroom sudo[8909]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 07 02:34:23 muskokaroom sudo[8909]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 02:34:23 muskokaroom sudo[8909]: pam_unix(sudo:session): session closed for user root Jul 07 02:34:23 muskokaroom volumio[1575]: [Vollibrespot] : SessionActive! Jul 07 02:34:23 muskokaroom volumio[530]: [SpotifyConnect] Connect Session is active! Jul 07 02:34:23 muskokaroom volumio[1575]: [Vollibrespot] : Loading track "Cold Heart - PNAU Remix" with Spotify URI "spotify:track:6zSpb8dQRaw0M1dK8PBwQz" Jul 07 02:34:23 muskokaroom root[1688]: 2024-07-07 02:34:23.455308 AM [Airtunesd] Received and Unknown Update Message Jul 07 02:34:23 muskokaroom root[1688]: 2024-07-07 02:34:23.455453 AM [Airtunesd] Received and Unknown Update Message Jul 07 02:34:23 muskokaroom root[1688]: 2024-07-07 02:34:23.455649 AM [Airtunesd] Received and Unknown Update Message Jul 07 02:34:23 muskokaroom root[1688]: 2024-07-07 02:34:23.456757 AM [AirPlay] Main audio setting up ALAC/44100/16/2 for default, input no, loopback no, volume:-20.000000 Jul 07 02:34:23 muskokaroom root[1688]: Shutting down audtio thread Jul 07 02:34:23 muskokaroom root[1688]: Audio Stream shutdown complete Jul 07 02:34:23 muskokaroom root[1688]: Entering drain phase.... Jul 07 02:34:23 muskokaroom root[1688]: Entering drop phase.... Jul 07 02:34:23 muskokaroom root[1688]: Entering free phase.... Jul 07 02:34:23 muskokaroom root[1688]: PCM name: 'volume' Jul 07 02:34:23 muskokaroom root[1688]: PCM state: OPEN Jul 07 02:34:23 muskokaroom root[1688]: Closing PCM Handle. Jul 07 02:34:23 muskokaroom root[1688]: PCM Released. Jul 07 02:34:23 muskokaroom root[1688]: >>>>> In Audio Finalize Jul 07 02:34:23 muskokaroom root[1688]: Shutting down audtio thread Jul 07 02:34:23 muskokaroom root[1688]: Audio Stream shutdown complete Jul 07 02:34:23 muskokaroom root[1688]: Source Format ID : 616c6163 Jul 07 02:34:23 muskokaroom kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Jul 07 02:34:24 muskokaroom root[1688]: [2024/07/07 02:34:24:0384] NOTICE: Sending : 2 Jul 07 02:34:24 muskokaroom root[1688]: [2024/07/07 02:34:24:0384] NOTICE: ping Jul 07 02:34:24 muskokaroom root[1688]: Frames: 256, period time: 5804, buff_size: 524288 Jul 07 02:34:24 muskokaroom root[1688]: 2024-07-07 02:34:24.475830 AM [AudioStream] ALSA device is not in SND_PCM_STATE_RUNNING or SND_PCM_STATE_DRAINING state in AudioThreadFn loop. It may be okay. Jul 07 02:34:24 muskokaroom root[1688]: 2024-07-07 02:34:24.482799 AM [AirPlay] SetRateAndAnchorTime inRate 1 rtpTme 1721815621 netTime (timeline=b827ebfffe73fc2a secs=1720319664 flags=0) Jul 07 02:34:24 muskokaroom root[1688]: 2024-07-07 02:34:24.482941 AM [AirPlay] SetRateAndAnchorTime timeline match after #0 tries Jul 07 02:34:24 muskokaroom root[1688]: 2024-07-07 02:34:24.483015 AM [Airtunesd] Received Play Status Update Message Jul 07 02:34:24 muskokaroom root[1688]: [2024/07/07 02:34:24:4830] NOTICE: Sending : 42["callMethod", {"endpoint":"music_service/airplay2","method":"Playing","data":{"status":"1"}}] Jul 07 02:34:24 muskokaroom root[1688]: 2024-07-07 02:34:24.483433 AM [AirPlay] SetRateAndAnchorTime inRate 1 rtpTme 1721815655 netTime (timeline=b827ebfffe73fc2a secs=1720319664 flags=0) Jul 07 02:34:24 muskokaroom root[1688]: 2024-07-07 02:34:24.483510 AM [AirPlay] SetRateAndAnchorTime timeline match after #0 tries Jul 07 02:34:24 muskokaroom root[1688]: 2024-07-07 02:34:24.483549 AM [AirPlay] _requestProcessSetParameterText: dbVolume = -20.0 Jul 07 02:34:24 muskokaroom root[1688]: 2024-07-07 02:34:24.483619 AM [AirPlay] Setting volume to dB=-20.000000, linear=0.100000 Jul 07 02:34:24 muskokaroom root[1688]: 2024-07-07 02:34:24.483661 AM [AirPlay] _requestProcessSetParameterText:server volume set to -20.0 Jul 07 02:34:24 muskokaroom root[1688]: 2024-07-07 02:34:24.484127 AM [Airtunesd] Received Play Status Update Message Jul 07 02:34:24 muskokaroom root[1688]: [2024/07/07 02:34:24:4842] NOTICE: Sending : 42["callMethod", {"endpoint":"music_service/airplay2","method":"Playing","data":{"status":"1"}}] Jul 07 02:34:24 muskokaroom volumio[530]: info: CALLMETHOD: music_service airplay2 Playing [object Object] Jul 07 02:34:24 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: airplay2 , Playing Jul 07 02:34:24 muskokaroom volumio[530]: info: Airplay started streaming Jul 07 02:34:24 muskokaroom volumio[530]: info: AIRPLAY: Un-Docking Jul 07 02:34:24 muskokaroom volumio[530]: info: CoreCommandRouter::servicePushState Jul 07 02:34:24 muskokaroom volumio[530]: info: CoreStateMachine::syncState Jul 07 02:34:24 muskokaroom volumio[530]: info: CoreStateMachine::pushState Jul 07 02:34:24 muskokaroom volumio[530]: info: CoreStateMachine::getState Jul 07 02:34:24 muskokaroom volumio[530]: info: CoreCommandRouter::volumioPushState Jul 07 02:34:24 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 02:34:24 muskokaroom volumio[530]: info: VOLUMIO PUSHED THIS STATE: status - play, service - airplay Jul 07 02:34:24 muskokaroom volumio[530]: info: interfaceApi::pushState Jul 07 02:34:24 muskokaroom root[1688]: 2024-07-07 02:34:24.519456 AM [AirPlay] Flushing receiver complete: flushSeq 7596 flushTS 1721894972 count 0 Jul 07 02:34:24 muskokaroom root[1688]: 2024-07-07 02:34:24.519645 AM [AirPlay] ProcessPkt Flush Complete flushSeq 7596 flushTS 1721894972 count 0 Seq # 7596 TS 1721894972 Jul 07 02:34:24 muskokaroom root[1688]: 2024-07-07 02:34:24.519734 AM [Airtunesd] Received Play Status Update Message Jul 07 02:34:24 muskokaroom root[1688]: [2024/07/07 02:34:24:5198] NOTICE: Sending : 42["callMethod", {"endpoint":"music_service/airplay2","method":"Playing","data":{"status":"0"}}] Jul 07 02:34:24 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Jul 07 02:34:24 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getDevices Jul 07 02:34:24 muskokaroom sudo[8929]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 07 02:34:24 muskokaroom root[1688]: 2024-07-07 02:34:24.533043 AM [AirPlay] ### Lost packets 7597-7598 (+1, 1 total) Jul 07 02:34:24 muskokaroom root[1688]: [2024/07/07 02:34:24:5389] NOTICE: Sending : 42["volume", 33] Jul 07 02:34:24 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getDevices Jul 07 02:34:24 muskokaroom sudo[8929]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 02:34:24 muskokaroom sudo[8929]: pam_unix(sudo:session): session closed for user root Jul 07 02:34:24 muskokaroom sudo[8933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 07 02:34:24 muskokaroom sudo[8933]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 02:34:24 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 02:34:24 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getDevices Jul 07 02:34:24 muskokaroom sudo[8933]: pam_unix(sudo:session): session closed for user root Jul 07 02:34:24 muskokaroom sudo[8943]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 07 02:34:24 muskokaroom sudo[8943]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 02:34:24 muskokaroom volumio[530]: info: CALLMETHOD: music_service airplay2 Playing [object Object] Jul 07 02:34:24 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: airplay2 , Playing Jul 07 02:34:24 muskokaroom sudo[8949]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 07 02:34:24 muskokaroom volumio[530]: info: Airplay started streaming Jul 07 02:34:24 muskokaroom volumio[530]: info: AIRPLAY: Un-Docking Jul 07 02:34:24 muskokaroom sudo[8943]: pam_unix(sudo:session): session closed for user root Jul 07 02:34:24 muskokaroom sudo[8949]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 02:34:24 muskokaroom volumio[530]: info: CoreCommandRouter::servicePushState Jul 07 02:34:24 muskokaroom sudo[8949]: pam_unix(sudo:session): session closed for user root Jul 07 02:34:24 muskokaroom volumio[530]: info: CoreStateMachine::syncState Jul 07 02:34:24 muskokaroom volumio[530]: info: CoreStateMachine::pushState Jul 07 02:34:24 muskokaroom volumio[530]: info: CoreStateMachine::getState Jul 07 02:34:24 muskokaroom volumio[530]: info: CoreCommandRouter::volumioPushState Jul 07 02:34:24 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 02:34:24 muskokaroom volumio[530]: info: VOLUMIO PUSHED THIS STATE: status - play, service - airplay Jul 07 02:34:24 muskokaroom volumio[530]: info: interfaceApi::pushState Jul 07 02:34:24 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Jul 07 02:34:24 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Jul 07 02:34:24 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getDevices Jul 07 02:34:24 muskokaroom sudo[8968]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 07 02:34:24 muskokaroom sudo[8968]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 02:34:24 muskokaroom sudo[8968]: pam_unix(sudo:session): session closed for user root Jul 07 02:34:24 muskokaroom sudo[8974]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 07 02:34:24 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getDevices Jul 07 02:34:24 muskokaroom sudo[8974]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 02:34:24 muskokaroom sudo[8974]: pam_unix(sudo:session): session closed for user root Jul 07 02:34:24 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 02:34:24 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getDevices Jul 07 02:34:24 muskokaroom sudo[8983]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 07 02:34:24 muskokaroom volumio[530]: info: CALLMETHOD: music_service airplay2 Playing [object Object] Jul 07 02:34:24 muskokaroom sudo[8983]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 02:34:24 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: airplay2 , Playing Jul 07 02:34:24 muskokaroom volumio[530]: info: Airplay stopped playing. Jul 07 02:34:24 muskokaroom volumio[530]: info: CoreCommandRouter::servicePushState Jul 07 02:34:24 muskokaroom volumio[530]: info: CoreStateMachine::syncState Jul 07 02:34:24 muskokaroom volumio[530]: info: CoreStateMachine::pushState Jul 07 02:34:24 muskokaroom volumio[530]: info: CoreStateMachine::getState Jul 07 02:34:24 muskokaroom volumio[530]: info: CoreCommandRouter::volumioPushState Jul 07 02:34:24 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 02:34:24 muskokaroom volumio[530]: info: VOLUMIO PUSHED THIS STATE: status - play, service - airplay Jul 07 02:34:24 muskokaroom sudo[8983]: pam_unix(sudo:session): session closed for user root Jul 07 02:34:24 muskokaroom volumio[530]: info: interfaceApi::pushState Jul 07 02:34:24 muskokaroom sudo[8989]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 07 02:34:24 muskokaroom sudo[8989]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 02:34:24 muskokaroom sudo[8989]: pam_unix(sudo:session): session closed for user root Jul 07 02:34:24 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Jul 07 02:34:24 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Jul 07 02:34:24 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getDevices Jul 07 02:34:24 muskokaroom sudo[9001]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 07 02:34:24 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getDevices Jul 07 02:34:24 muskokaroom sudo[9001]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 02:34:24 muskokaroom sudo[9006]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 07 02:34:24 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 02:34:24 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getDevices Jul 07 02:34:24 muskokaroom sudo[9001]: pam_unix(sudo:session): session closed for user root Jul 07 02:34:24 muskokaroom sudo[9006]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 02:34:24 muskokaroom sudo[9006]: pam_unix(sudo:session): session closed for user root Jul 07 02:34:24 muskokaroom volumio[530]: info: VolumeController::SetAlsaVolume33 Jul 07 02:34:24 muskokaroom volumio[530]: amixer -M set -c 0 "Digital" 33% Jul 07 02:34:24 muskokaroom sudo[9014]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 07 02:34:24 muskokaroom sudo[9014]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 02:34:24 muskokaroom sudo[9022]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 07 02:34:24 muskokaroom sudo[9014]: pam_unix(sudo:session): session closed for user root Jul 07 02:34:24 muskokaroom sudo[9022]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 02:34:24 muskokaroom sudo[9022]: pam_unix(sudo:session): session closed for user root Jul 07 02:34:24 muskokaroom volumio[530]: info: Pushing Favourites {"service":"airplay","uri":"","favourite":false} Jul 07 02:34:24 muskokaroom volumio[530]: info: Pushing Favourites {"service":"airplay","uri":"","favourite":false} Jul 07 02:34:24 muskokaroom volumio[530]: info: Pushing Favourites {"service":"airplay","uri":"","favourite":false} Jul 07 02:34:24 muskokaroom sudo[9042]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 07 02:34:24 muskokaroom sudo[9042]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 02:34:24 muskokaroom sudo[9042]: pam_unix(sudo:session): session closed for user root Jul 07 02:34:24 muskokaroom sudo[9047]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 07 02:34:24 muskokaroom volumio[530]: [SpotifyConnect] Pushing metadata Vollibrespot: false Jul 07 02:34:24 muskokaroom volumio[530]: info: CoreCommandRouter::volumioGetState Jul 07 02:34:24 muskokaroom volumio[530]: info: CoreStateMachine::getState Jul 07 02:34:24 muskokaroom volumio[530]: [SpotifyConnect] Currently active: airplay Jul 07 02:34:24 muskokaroom volumio[530]: [SpotifyConnect] Pushing new state :: false Jul 07 02:34:24 muskokaroom volumio[530]: info: CoreCommandRouter::servicePushState Jul 07 02:34:24 muskokaroom volumio[530]: info: CoreStateMachine::syncState Jul 07 02:34:24 muskokaroom volumio[530]: info: CorePlayQueue::getTrack 0 Jul 07 02:34:24 muskokaroom volumio[530]: info: STATE SERVICE {"status":"stop","service":"volspotconnect2","title":"Cold Heart - PNAU Remix","artist":"Elton John, Dua Lipa, PNAU","album":"Cold Heart (PNAU Remix)","albumart":"https://i.scdn.co/image/ab67616d0000b2739f5cce8304c42d3a5463fd23","uri":"spotify:track:6zSpb8dQRaw0M1dK8PBwQz","trackType":"spotify","seek":0,"duration":203,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jul 07 02:34:24 muskokaroom sudo[9047]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 02:34:24 muskokaroom volumio[530]: info: CURRENT POSITION 0 Jul 07 02:34:24 muskokaroom volumio[530]: info: CoreStateMachine::syncState stateService stop Jul 07 02:34:24 muskokaroom volumio[530]: info: CoreStateMachine::syncState currentStatus play Jul 07 02:34:24 muskokaroom volumio[530]: info: CURRENT POSITION 0 Jul 07 02:34:24 muskokaroom volumio[530]: info: CoreStateMachine::play index undefined Jul 07 02:34:24 muskokaroom sudo[9047]: pam_unix(sudo:session): session closed for user root Jul 07 02:34:24 muskokaroom volumio[530]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 07 02:34:24 muskokaroom volumio[530]: info: CoreStateMachine::pushState Jul 07 02:34:24 muskokaroom volumio[530]: info: CoreStateMachine::getState Jul 07 02:34:24 muskokaroom volumio[530]: info: CoreCommandRouter::volumioPushState Jul 07 02:34:24 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 02:34:25 muskokaroom volumio[530]: info: VOLUMIO PUSHED THIS STATE: status - play, service - airplay Jul 07 02:34:25 muskokaroom volumio[530]: info: interfaceApi::pushState Jul 07 02:34:25 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Jul 07 02:34:25 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Jul 07 02:34:25 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getDevices Jul 07 02:34:25 muskokaroom sudo[9061]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 07 02:34:25 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getDevices Jul 07 02:34:25 muskokaroom sudo[9061]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 02:34:25 muskokaroom sudo[9063]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 07 02:34:25 muskokaroom sudo[9061]: pam_unix(sudo:session): session closed for user root Jul 07 02:34:25 muskokaroom sudo[9063]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 02:34:25 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 02:34:25 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getDevices Jul 07 02:34:25 muskokaroom sudo[9063]: pam_unix(sudo:session): session closed for user root Jul 07 02:34:25 muskokaroom sudo[9071]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 07 02:34:25 muskokaroom sudo[9071]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 02:34:25 muskokaroom volumio[530]: info: CorePlayQueue::getTrack 0 Jul 07 02:34:25 muskokaroom volumio[530]: info: CoreStateMachine::startPlaybackTimer Jul 07 02:34:25 muskokaroom volumio[530]: info: CorePlayQueue::getTrack 0 Jul 07 02:34:25 muskokaroom volumio[530]: info: [1720319665089] ControllerWebradio::clearAddPlayTrack Jul 07 02:34:25 muskokaroom volumio[530]: info: ControllerMpd::sendMpdCommand stop Jul 07 02:34:25 muskokaroom sudo[9071]: pam_unix(sudo:session): session closed for user root Jul 07 02:34:25 muskokaroom sudo[9084]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 07 02:34:25 muskokaroom volumio[530]: info: sending command... Jul 07 02:34:25 muskokaroom volumio[530]: info: CoreStateMachine::pushState Jul 07 02:34:25 muskokaroom volumio[530]: info: CoreStateMachine::getState Jul 07 02:34:25 muskokaroom volumio[530]: info: CoreCommandRouter::volumioPushState Jul 07 02:34:25 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 02:34:25 muskokaroom volumio[530]: info: VOLUMIO PUSHED THIS STATE: status - play, service - airplay Jul 07 02:34:25 muskokaroom volumio[530]: info: interfaceApi::pushState Jul 07 02:34:25 muskokaroom sudo[9084]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 02:34:25 muskokaroom sudo[9084]: pam_unix(sudo:session): session closed for user root Jul 07 02:34:25 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Jul 07 02:34:25 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage Jul 07 02:34:25 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getDevices Jul 07 02:34:25 muskokaroom sudo[9096]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 07 02:34:25 muskokaroom sudo[9096]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 02:34:25 muskokaroom sudo[9096]: pam_unix(sudo:session): session closed for user root Jul 07 02:34:25 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getDevices Jul 07 02:34:25 muskokaroom sudo[9108]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 07 02:34:25 muskokaroom sudo[9108]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 02:34:25 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 02:34:25 muskokaroom sudo[9108]: pam_unix(sudo:session): session closed for user root Jul 07 02:34:25 muskokaroom volumio[530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getDevices Jul 07 02:34:25 muskokaroom volumio[1575]: [Vollibrespot] : Track "Cold Heart - PNAU Remix" loaded Jul 07 02:34:25 muskokaroom sudo[9116]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 07 02:34:25 muskokaroom volumio[1575]: [Vollibrespot] : Alsa error PCM open ALSA function 'snd_pcm_open' failed with error 'EBUSY: Device or resource busy' Jul 07 02:34:25 muskokaroom volumio[1575]: [Vollibrespot] : Could not start audio: Alsa error: PCM open failed Jul 07 02:34:25 muskokaroom volumio[1575]: [Vollibrespot] : Alsa error PCM open ALSA function 'snd_pcm_open' failed with error 'EBUSY: Device or resource busy' Jul 07 02:34:25 muskokaroom volumio[1575]: [Vollibrespot] : Could not start audio: Alsa error: PCM open failed Jul 07 02:34:25 muskokaroom sudo[9116]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 02:34:25 muskokaroom volumio[530]: [SpotifyConnect] Token: Jul 07 02:34:25 muskokaroom sudo[9121]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 07 02:34:25 muskokaroom sudo[9116]: pam_unix(sudo:session): session closed for user root Jul 07 02:34:25 muskokaroom sudo[9121]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 02:34:25 muskokaroom volumio[530]: [SpotifyConnect] Device is active! Jul 07 02:34:25 muskokaroom volumio[530]: info: CoreCommandRouter::volumioGetState Jul 07 02:34:25 muskokaroom volumio[530]: info: CoreStateMachine::getState Jul 07 02:34:25 muskokaroom volumio[530]: [SpotifyConnect] Currently active: airplay Jul 07 02:34:25 muskokaroom volumio[530]: [SpotifyConnect] Stopping currently active service Jul 07 02:34:25 muskokaroom sudo[9121]: pam_unix(sudo:session): session closed for user root Jul 07 02:34:25 muskokaroom volumio[530]: info: CoreCommandRouter::volumioStop Jul 07 02:34:25 muskokaroom volumio[530]: info: CoreStateMachine::getState Jul 07 02:34:25 muskokaroom volumio[530]: info: VOLUMIOSTOP: service stopped - airplay Jul 07 02:34:25 muskokaroom volumio[530]: info: CoreStateMachine::stop Jul 07 02:34:25 muskokaroom volumio[530]: info: CoreStateMachine::serviceStop Jul 07 02:34:25 muskokaroom volumio[530]: info: Received STOP, but no service to execute it Jul 07 02:34:25 muskokaroom volumio[530]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 07 02:34:25 muskokaroom volumio[530]: TypeError: Cannot read property 'then' of undefined Jul 07 02:34:25 muskokaroom volumio[530]: at SpotConnEvents. (/volumio/app/plugins/music_service/volspotconnect2/index.js:148:23) Jul 07 02:34:25 muskokaroom volumio[530]: at emitOne (events.js:116:13) Jul 07 02:34:25 muskokaroom volumio[530]: at SpotConnEvents.emit (events.js:211:7) Jul 07 02:34:25 muskokaroom volumio[530]: at SpotConnEvents.parseData (/volumio/app/plugins/music_service/volspotconnect2/SpotConnController.js:48:16) Jul 07 02:34:25 muskokaroom volumio[530]: at Socket.SpotConnEvents._udpsource.on.msg (/volumio/app/plugins/music_service/volspotconnect2/SpotConnController.js:15:12) Jul 07 02:34:25 muskokaroom volumio[530]: at emitTwo (events.js:126:13) Jul 07 02:34:25 muskokaroom volumio[530]: at Socket.emit (events.js:214:7) Jul 07 02:34:25 muskokaroom volumio[530]: at UDP.onMessage [as onmessage] (dgram.js:659:8) Jul 07 02:34:25 muskokaroom volumio[530]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 07 02:34:26 muskokaroom sudo[9144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-07 02:33 Jul 07 02:34:26 muskokaroom sudo[9144]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 9 (stretch)" NAME="Raspbian GNU/Linux" VERSION_ID="9" VERSION="9 (stretch)" VERSION_CODENAME=stretch 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_FE_VERSION="187be2f9c830c4fe312adf5ee10d6ca76c1221c8" VOLUMIO_BE_VERSION="f0b88cdc7b76371e2de89b66317a3eb412b85d57" VOLUMIO_ARCH="arm" VERSION="V2.13.4" VOLUMIO_VERSION="V2.13.4 - Toasted Cherry" VOLUMIO_VARIANT="AxiomAir" VOLUMIO_HARDWARE="AxiomAir" VOLUMIO_BUILD_DATE="2021-10-15"