-- Logs begin at Fri 2025-05-16 22:31:57 CST, end at Fri 2025-05-16 22:41:06 CST. --
May 16 22:40:03 volumio volumio[976]: info: CoreCommandRouter::volumioGetState
May 16 22:40:13 volumio volumio[976]: info: CoreCommandRouter::volumioGetState
May 16 22:40:13 volumio volumio[976]: info: Listing playlists
May 16 22:40:13 volumio volumio[976]: info: Listing playlists
May 16 22:40:20 volumio squeezeboxserver[938]: Can't resize unknown type, magic: "RIFFX\17\0\0"
May 16 22:40:22 volumio squeezeboxserver[938]: Can't resize unknown type, magic: "RIFFX\17\0\0"
May 16 22:40:23 volumio volumio[976]: info: CoreCommandRouter::volumioGetState
May 16 22:40:24 volumio squeezeboxserver[938]: Can't resize unknown type, magic: "RIFFX\17\0\0"
May 16 22:40:26 volumio squeezeboxserver[938]: [22:40:26.955] output_flush:331 [0x5cdfa8]: flush output buffer (full)
May 16 22:40:26 volumio squeezeboxserver[938]: [22:40:26.955] PlayerThread:585 [0x628670]: flushing ...
May 16 22:40:27 volumio squeezeboxserver[938]: [22:40:27.001] PlayerThread:481 [0x628670]: Disconnecting 3649704825
May 16 22:40:27 volumio squeezeboxserver[938]: [22:40:27.002] PlayerThread:598 [0x628670]: processing volume device:49 request:-15.31
May 16 22:40:27 volumio squeezeboxserver[938]: [22:40:27.002] process_strm:230 [0x5cdfa8] strm command s
May 16 22:40:27 volumio squeezeboxserver[938]: [22:40:27.002] process_strm:306 [0x5cdfa8], strm s autostart: 1 transition period: 10 transition type: 0 codec: m
May 16 22:40:27 volumio squeezeboxserver[938]: [22:40:27.002] process_strm:339 [0x5cdfa8]: codec:m, ch:255, s:255, r:255
May 16 22:40:27 volumio squeezeboxserver[938]: [22:40:27.002] PlayerThread:575 [0x628670]: raop connecting ...
May 16 22:40:27 volumio squeezeboxserver[938]: Can't resize unknown type, magic: "RIFF ~\0\0"
May 16 22:40:27 volumio squeezeboxserver[938]: Can't resize unknown type, magic: "RIFF ~\0\0"
May 16 22:40:27 volumio squeezeboxserver[938]: Can't resize unknown type, magic: "RIFF ~\0\0"
May 16 22:40:27 volumio squeezeboxserver[938]: [22:40:27.103] raopcl_connect:1003 [0x7fd6d001e740]: local interface 192.168.100.49
May 16 22:40:27 volumio volumio[976]: info: Airplay started streaming, receiving metadatas
May 16 22:40:27 volumio squeezeboxserver[938]: [22:40:27.130] PlayerThread:578 [0x628670]: raop connected
May 16 22:40:27 volumio squeezeboxserver[938]: [22:40:27.130] PlayerThread:598 [0x628670]: processing volume device:49 request:-15.31
May 16 22:40:27 volumio volumio[976]: info: CoreCommandRouter::volumioStop
May 16 22:40:27 volumio volumio[976]: info: CoreStateMachine::stop
May 16 22:40:27 volumio volumio[976]: info: CoreStateMachine::setConsumeUpdateService undefined
May 16 22:40:27 volumio volumio[976]: info: CoreStateMachine::stPlaybackTimer
May 16 22:40:27 volumio volumio[976]: info: CoreStateMachine::updateTrackBlock
May 16 22:40:27 volumio volumio[976]: info: CorePlayQueue::getTrackBlock
May 16 22:40:27 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:27 volumio volumio[976]: info: CorePlayQueue::getTrack 0
May 16 22:40:27 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 16 22:40:27 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:27 volumio squeezeboxserver[938]: [22:40:27.134] raopcl_accept_frames:417 [0x7fd6d001e740]: begining to stream hts:0 n:1747406427.578227152
May 16 22:40:27 volumio squeezeboxserver[938]: [22:40:27.135] raopcl_accept_frames:425 [0x7fd6d001e740]: restarting w/o pause n:1747406427.578227152, hts:77060623436637
May 16 22:40:27 volumio volumio[976]: info: CoreStateMachine::serviceStop
May 16 22:40:27 volumio volumio[976]: info: CorePlayQueue::getTrack 0
May 16 22:40:27 volumio volumio[976]: info: CoreCommandRouter::serviceStop
May 16 22:40:27 volumio volumio[976]: info: [1747406427141] ControllerUPNPBrowser::stop
May 16 22:40:27 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand stop
May 16 22:40:27 volumio volumio[976]: info: CoreStateMachine::setConsumeUpdateService undefined
May 16 22:40:27 volumio volumio[976]: info: Airplay started streaming, receiving metadatas
May 16 22:40:27 volumio volumio[976]: info: CorePlayQueue::getTrack 0
May 16 22:40:27 volumio volumio[976]: info: CoreCommandRouter::volumioStop
May 16 22:40:27 volumio volumio[976]: info: CoreStateMachine::stop
May 16 22:40:27 volumio volumio[976]: info: CoreStateMachine::setConsumeUpdateService undefined
May 16 22:40:27 volumio volumio[976]: info: CoreStateMachine::setConsumeUpdateService undefined
May 16 22:40:27 volumio volumio[976]: info: Airplay started streaming, receiving metadatas
May 16 22:40:27 volumio volumio[976]: info: CorePlayQueue::getTrack 0
May 16 22:40:27 volumio volumio[976]: info: CoreCommandRouter::volumioStop
May 16 22:40:27 volumio volumio[976]: info: CoreStateMachine::stop
May 16 22:40:27 volumio volumio[976]: info: CoreStateMachine::setConsumeUpdateService undefined
May 16 22:40:27 volumio squeezeboxserver[938]: [22:40:27.145] _output_frames:53 [0x5cdfa8]: start buffer frames: 49583
May 16 22:40:27 volumio squeezeboxserver[938]: [22:40:27.145] _output_frames:100 [0x5cdfa8]: track start sample rate: 44100 replay_gain: 0
May 16 22:40:27 volumio volumio[976]: info: Airplay started streaming, receiving metadatas
May 16 22:40:27 volumio volumio[976]: info: CorePlayQueue::getTrack 0
May 16 22:40:27 volumio volumio[976]: info: CoreCommandRouter::volumioStop
May 16 22:40:27 volumio volumio[976]: info: CoreStateMachine::stop
May 16 22:40:27 volumio volumio[976]: info: CoreStateMachine::setConsumeUpdateService undefined
May 16 22:40:27 volumio volumio[976]: info: CoreStateMachine::setConsumeUpdateService undefined
May 16 22:40:27 volumio volumio[976]: info: Airplay started streaming, receiving metadatas
May 16 22:40:27 volumio volumio[976]: info: CorePlayQueue::getTrack 0
May 16 22:40:27 volumio volumio[976]: info: CoreCommandRouter::volumioStop
May 16 22:40:27 volumio volumio[976]: info: CoreStateMachine::stop
May 16 22:40:27 volumio volumio[976]: info: CoreStateMachine::setConsumeUpdateService undefined
May 16 22:40:27 volumio volumio[976]: info: CoreStateMachine::setConsumeUpdateService undefined
May 16 22:40:27 volumio volumio[976]: info: Airplay started streaming, receiving metadatas
May 16 22:40:27 volumio volumio[976]: info: CorePlayQueue::getTrack 0
May 16 22:40:27 volumio volumio[976]: info: CoreCommandRouter::volumioStop
May 16 22:40:27 volumio volumio[976]: info: CoreStateMachine::stop
May 16 22:40:27 volumio volumio[976]: info: CoreStateMachine::setConsumeUpdateService undefined
May 16 22:40:27 volumio volumio[976]: info: CoreStateMachine::setConsumeUpdateService undefined
May 16 22:40:27 volumio volumio[976]: info: Airplay started streaming, receiving metadatas
May 16 22:40:27 volumio volumio[976]: info: CorePlayQueue::getTrack 0
May 16 22:40:27 volumio volumio[976]: info: CoreCommandRouter::volumioStop
May 16 22:40:27 volumio volumio[976]: info: CoreStateMachine::stop
May 16 22:40:27 volumio volumio[976]: info: CoreStateMachine::setConsumeUpdateService undefined
May 16 22:40:27 volumio volumio[976]: info: Airplay started streaming, receiving metadatas
May 16 22:40:27 volumio squeezeboxserver[938]: [22:40:27.156] output_raop_thread:91 [0x5cdfa8]: track actual start time:3649706208 (gap:-645261088)
May 16 22:40:27 volumio volumio[976]: info: CorePlayQueue::getTrack 0
May 16 22:40:27 volumio volumio[976]: info: CoreCommandRouter::volumioStop
May 16 22:40:27 volumio volumio[976]: info: CoreStateMachine::stop
May 16 22:40:27 volumio volumio[976]: info: CoreStateMachine::setConsumeUpdateService undefined
May 16 22:40:27 volumio volumio[976]: info: Airplay started streaming, receiving metadatas
May 16 22:40:27 volumio volumio[976]: info: CorePlayQueue::getTrack 0
May 16 22:40:27 volumio volumio[976]: info: CoreCommandRouter::volumioStop
May 16 22:40:27 volumio volumio[976]: info: CoreStateMachine::stop
May 16 22:40:27 volumio volumio[976]: info: CoreStateMachine::setConsumeUpdateService undefined
May 16 22:40:27 volumio volumio[976]: info: Airplay started streaming, receiving metadatas
May 16 22:40:27 volumio volumio[976]: info: CorePlayQueue::getTrack 0
May 16 22:40:27 volumio volumio[976]: info: CoreCommandRouter::volumioStop
May 16 22:40:27 volumio volumio[976]: info: CoreStateMachine::stop
May 16 22:40:27 volumio volumio[976]: info: CoreStateMachine::setConsumeUpdateService undefined
May 16 22:40:27 volumio volumio[976]: info: CoreStateMachine::setConsumeUpdateService undefined
May 16 22:40:27 volumio volumio[976]: info: Airplay started streaming, receiving metadatas
May 16 22:40:27 volumio volumio[976]: info: CorePlayQueue::getTrack 0
May 16 22:40:27 volumio volumio[976]: info: CoreCommandRouter::volumioStop
May 16 22:40:27 volumio volumio[976]: info: CoreStateMachine::stop
May 16 22:40:27 volumio volumio[976]: info: CoreStateMachine::setConsumeUpdateService undefined
May 16 22:40:27 volumio volumio[976]: info: Airplay started streaming, receiving metadatas
May 16 22:40:27 volumio volumio[976]: info: CorePlayQueue::getTrack 0
May 16 22:40:27 volumio volumio[976]: info: CoreCommandRouter::volumioStop
May 16 22:40:27 volumio volumio[976]: info: CoreStateMachine::stop
May 16 22:40:27 volumio volumio[976]: info: CoreStateMachine::setConsumeUpdateService undefined
May 16 22:40:27 volumio shairport-sync[4523]: % Total % Received % Xferd Average Speed Time Time Time Current
May 16 22:40:27 volumio shairport-sync[4523]: Dload Upload Total Spent Left Speed
May 16 22:40:27 volumio volumio[976]: info: Airplay started streaming, receiving metadatas
May 16 22:40:27 volumio volumio[976]: info: CorePlayQueue::getTrack 0
May 16 22:40:27 volumio volumio[976]: info: CoreCommandRouter::volumioStop
May 16 22:40:27 volumio volumio[976]: info: CoreStateMachine::stop
May 16 22:40:27 volumio volumio[976]: info: CoreStateMachine::setConsumeUpdateService undefined
May 16 22:40:27 volumio volumio[976]: info: Airplay started streaming, receiving metadatas
May 16 22:40:27 volumio volumio[976]: info: CorePlayQueue::getTrack 0
May 16 22:40:27 volumio volumio[976]: info: CoreCommandRouter::volumioStop
May 16 22:40:27 volumio volumio[976]: info: CoreStateMachine::stop
May 16 22:40:27 volumio volumio[976]: info: CoreStateMachine::setConsumeUpdateService undefined
May 16 22:40:27 volumio volumio[976]: info: Airplay started streaming, receiving metadatas
May 16 22:40:27 volumio volumio[976]: info: CorePlayQueue::getTrack 0
May 16 22:40:27 volumio volumio[976]: info: CoreCommandRouter::volumioStop
May 16 22:40:27 volumio volumio[976]: info: CoreStateMachine::stop
May 16 22:40:27 volumio volumio[976]: info: CoreStateMachine::setConsumeUpdateService undefined
May 16 22:40:27 volumio volumio[976]: info:
May 16 22:40:27 volumio volumio[976]: ---------------------------- Client requests Start Airplay PlaybackRoutine
May 16 22:40:27 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback
May 16 22:40:27 volumio volumio[976]: info: Airplay playback start
May 16 22:40:27 volumio volumio[976]: info: CorePlayQueue::getTrack 0
May 16 22:40:27 volumio volumio[976]: info: CoreCommandRouter::volumioStop
May 16 22:40:27 volumio volumio[976]: info: CoreStateMachine::stop
May 16 22:40:27 volumio volumio[976]: info: CoreStateMachine::setConsumeUpdateService undefined
May 16 22:40:27 volumio volumio[976]: info: CoreStateMachine::setConsumeUpdateService undefined
May 16 22:40:27 volumio shairport-sync[4523]: [158B blob data]
May 16 22:40:27 volumio volumio[976]: info:
May 16 22:40:27 volumio volumio[976]: ---------------------------- MPD announces state update: player
May 16 22:40:27 volumio volumio[976]: info: sendMpdCommand stop took 52 milliseconds
May 16 22:40:27 volumio volumio[976]: info: ControllerMpd::getState
May 16 22:40:27 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand status
May 16 22:40:27 volumio volumio[976]: info: sendMpdCommand status took 3 milliseconds
May 16 22:40:27 volumio volumio[976]: verbose: ControllerMpd::parseState
May 16 22:40:27 volumio volumio[976]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 16 22:40:27 volumio volumio[976]: info: sendMpdCommand playlistinfo took 1 milliseconds
May 16 22:40:27 volumio volumio[976]: verbose: ControllerMpd::parseTrackInfo
May 16 22:40:27 volumio volumio[976]: info: ControllerMpd::pushState
May 16 22:40:27 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:27 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:27 volumio volumio[976]: info: CorePlayQueue::getTrack 0
May 16 22:40:27 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:27 volumio volumio[976]: info: CorePlayQueue::getTrack 0
May 16 22:40:27 volumio volumio[976]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1973","artist":"James Blunt","album":"2025年3月份采集","uri":"http://192.168.100.49:9000/music/2270/download.mp3?bitrate=320","trackType":""}
May 16 22:40:27 volumio volumio[976]: verbose: CURRENT POSITION 0
May 16 22:40:27 volumio volumio[976]: info: CoreStateMachine::syncState stateService stop
May 16 22:40:27 volumio volumio[976]: info: CoreStateMachine::syncState currentStatus stop
May 16 22:40:27 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:27 volumio volumio[976]: info: CorePlayQueue::getTrack 0
May 16 22:40:27 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:27 volumio volumio[976]: info: No code
May 16 22:40:27 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:27 volumio volumio[976]: info: CorePlayQueue::getTrack 0
May 16 22:40:27 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:27 volumio volumio[976]: info: ------------------------------ 27ms
May 16 22:40:28 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:28 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:28 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 16 22:40:28 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:28 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:28 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:28 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:28 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:28 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:28 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:28 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:28 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:28 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:28 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:28 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:28 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:28 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:28 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:28 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:28 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:28 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:28 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:28 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:28 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:28 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:28 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:28 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:28 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:28 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:28 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:28 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:28 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:28 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:28 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:28 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:28 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:28 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:28 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:28 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:28 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:28 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:28 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:28 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:28 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:28 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:28 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:28 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:28 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:28 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:29 volumio squeezeboxserver[938]: [22:40:29.134] cli_open_socket:190 [0x5cdfa8]: opened CLI socket 31
May 16 22:40:29 volumio squeezeboxserver[938]: [22:40:29.145] PlayerThread:555 [0x628670]: idx 0
May 16 22:40:29 volumio squeezeboxserver[938]: artist:å¶è’¨æ–‡
May 16 22:40:29 volumio squeezeboxserver[938]: album:2025年4月份
May 16 22:40:29 volumio squeezeboxserver[938]: title:春风秋雨
May 16 22:40:29 volumio squeezeboxserver[938]: genre:æ— éŸ³ä¹æ›²é£Ž
May 16 22:40:29 volumio squeezeboxserver[938]: duration:232.333
May 16 22:40:29 volumio squeezeboxserver[938]: size:0
May 16 22:40:29 volumio squeezeboxserver[938]: cover:http://192.168.100.49:9000/music/8883dfa1/cover_.jpg
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:29 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:29 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:29 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:29 volumio squeezeboxserver[938]: Can't resize unknown type, magic: "RIFF ~\0\0"
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:29 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:29 volumio squeezeboxserver[938]: [22:40:29.161] GetArtworkThread:416 [0x628670]: Can't get artwork or device not active http://192.168.100.49:9000/music/8883dfa1/cover_.jpg
May 16 22:40:29 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:29 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:29 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:29 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:29 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:29 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:29 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:29 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:29 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:29 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:29 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:29 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:29 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:29 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:29 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:29 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:29 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:29 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:29 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:29 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:29 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:29 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:29 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:29 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:29 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:29 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:29 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:30 volumio squeezeboxserver[938]: Can't resize unknown type, magic: "RIFF ~\0\0"
May 16 22:40:33 volumio volumio[976]: info: CoreCommandRouter::volumioGetState
May 16 22:40:33 volumio volumio[976]: info: Listing playlists
May 16 22:40:33 volumio volumio[976]: info: Listing playlists
May 16 22:40:33 volumio squeezeboxserver[938]: Can't resize unknown type, magic: "RIFF ~\0\0"
May 16 22:40:33 volumio squeezeboxserver[938]: Can't resize unknown type, magic: "RIFF ~\0\0"
May 16 22:40:40 volumio squeezeboxserver[938]: [22:40:40.625] process_strm:230 [0x5cdfa8] strm command p
May 16 22:40:40 volumio squeezeboxserver[938]: [22:40:40.625] raopcl_pause:360 [0x7fd6d001e740]: set pause 77060624031165
May 16 22:40:40 volumio squeezeboxserver[938]: [22:40:40.626] PlayerThread:585 [0x628670]: flushing ...
May 16 22:40:40 volumio squeezeboxserver[938]: [22:40:40.626] raopcl_flush:1118 [0x7fd6d001e740]: flushing up to s:45180 ts:77060624031165
May 16 22:40:41 volumio squeezeboxserver[938]: [22:40:41.626] PlayerThread:481 [0x628670]: Disconnecting 3649719450
May 16 22:40:41 volumio squeezeboxserver[938]: [22:40:41.942] process_strm:230 [0x5cdfa8] strm command u
May 16 22:40:41 volumio squeezeboxserver[938]: [22:40:41.942] process_strm:293 [0x5cdfa8]: unpause at: 0 now: 3649719766
May 16 22:40:43 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:43 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:43 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 16 22:40:43 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:43 volumio squeezeboxserver[938]: [22:40:43.145] PlayerThread:598 [0x628670]: processing volume device:49 request:-144.00
May 16 22:40:43 volumio squeezeboxserver[938]: [22:40:43.145] PlayerThread:575 [0x628670]: raop connecting ...
May 16 22:40:43 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:43 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:43 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:43 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:43 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:43 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:43 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:43 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:43 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:43 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:43 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:43 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:43 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:43 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:43 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:43 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:43 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:43 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:43 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:43 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:43 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:43 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:43 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:43 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:43 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:43 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:43 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:43 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:43 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:43 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:43 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:43 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:43 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:43 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:43 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:43 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:43 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:43 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:43 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:43 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:43 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:43 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:43 volumio shairport-sync[4523]: {"time":1747406427186,"response":"startAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current
May 16 22:40:43 volumio shairport-sync[4523]: Dload Upload Total Spent Left Speed
May 16 22:40:43 volumio squeezeboxserver[938]: [22:40:43.246] raopcl_connect:1003 [0x7fd6d001e740]: local interface 192.168.100.49
May 16 22:40:43 volumio volumio[976]: info:
May 16 22:40:43 volumio volumio[976]: ---------------------------- Client requests Stop Airplay Playback
May 16 22:40:43 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopAirplayPlayback
May 16 22:40:43 volumio volumio[976]: info: Airplay playback stop
May 16 22:40:43 volumio volumio[976]: info: Airplay Playback Stopped, clearing state
May 16 22:40:43 volumio volumio[976]: info: CoreStateMachine::resetVolumioState
May 16 22:40:43 volumio volumio[976]: info: CoreStateMachine::getcurrentVolume
May 16 22:40:43 volumio volumio[976]: info: CoreCommandRouter::volumioRetrievevolume
May 16 22:40:43 volumio squeezeboxserver[938]: [22:40:43.266] PlayerThread:578 [0x628670]: raop connected
May 16 22:40:43 volumio squeezeboxserver[938]: [22:40:43.266] PlayerThread:598 [0x628670]: processing volume device:49 request:-144.00
May 16 22:40:43 volumio squeezeboxserver[938]: [22:40:43.267] raopcl_accept_frames:417 [0x7fd6d001e740]: begining to stream hts:77060624031165 n:1747406443.1147576606
May 16 22:40:43 volumio squeezeboxserver[938]: [22:40:43.267] raopcl_accept_frames:438 [0x7fd6d001e740]: restarting w/ pause n:1747406443.1147576606, hts:77060624092605 (re-send: 156)
May 16 22:40:43 volumio squeezeboxserver[938]: [22:40:43.269] PlayerThread:598 [0x628670]: processing volume device:49 request:-28.16
May 16 22:40:43 volumio squeezeboxserver[938]: [22:40:43.270] PlayerThread:598 [0x628670]: processing volume device:49 request:-25.41
May 16 22:40:43 volumio squeezeboxserver[938]: [22:40:43.271] PlayerThread:598 [0x628670]: processing volume device:49 request:-22.96
May 16 22:40:43 volumio squeezeboxserver[938]: [22:40:43.272] PlayerThread:598 [0x628670]: processing volume device:49 request:-20.82
May 16 22:40:43 volumio squeezeboxserver[938]: [22:40:43.274] PlayerThread:598 [0x628670]: processing volume device:49 request:-18.37
May 16 22:40:43 volumio squeezeboxserver[938]: [22:40:43.278] PlayerThread:598 [0x628670]: processing volume device:49 request:-15.92
May 16 22:40:43 volumio squeezeboxserver[938]: [22:40:43.280] PlayerThread:598 [0x628670]: processing volume device:49 request:-15.31
May 16 22:40:43 volumio shairport-sync[4523]: [158B blob data]
May 16 22:40:43 volumio volumio[976]: info: Airplay started streaming, receiving metadatas
May 16 22:40:43 volumio volumio[976]: info: Airplay started streaming, receiving metadatas
May 16 22:40:43 volumio volumio[976]: info: Airplay started streaming, receiving metadatas
May 16 22:40:43 volumio volumio[976]: info: Airplay started streaming, receiving metadatas
May 16 22:40:43 volumio volumio[976]: info: Airplay started streaming, receiving metadatas
May 16 22:40:43 volumio volumio[976]: info: Airplay started streaming, receiving metadatas
May 16 22:40:43 volumio volumio[976]: info: Airplay started streaming, receiving metadatas
May 16 22:40:43 volumio volumio[976]: info: Airplay started streaming, receiving metadatas
May 16 22:40:43 volumio volumio[976]: info: Airplay started streaming, receiving metadatas
May 16 22:40:43 volumio volumio[976]: info: Airplay started streaming, receiving metadatas
May 16 22:40:43 volumio volumio[976]: info: Airplay started streaming, receiving metadatas
May 16 22:40:43 volumio volumio[976]: info: Airplay started streaming, receiving metadatas
May 16 22:40:43 volumio volumio[976]: info: Airplay started streaming, receiving metadatas
May 16 22:40:43 volumio shairport-sync[4523]: {"time":1747406443247,"response":"stopAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current
May 16 22:40:43 volumio shairport-sync[4523]: Dload Upload Total Spent Left Speed
May 16 22:40:43 volumio volumio[976]: info: CoreCommandRouter::volumioGetState
May 16 22:40:43 volumio volumio[976]: info:
May 16 22:40:43 volumio volumio[976]: ---------------------------- Client requests Start Airplay PlaybackRoutine
May 16 22:40:43 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback
May 16 22:40:43 volumio volumio[976]: info: Airplay playback start
May 16 22:40:43 volumio shairport-sync[4523]: [158B blob data]
May 16 22:40:43 volumio volumio[976]: info: VolumeController:: Volume=47 Mute =false
May 16 22:40:43 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:43 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:43 volumio volumio[976]: info: CoreStateMachine::updateTrackBlock
May 16 22:40:43 volumio volumio[976]: info: CorePlayQueue::getTrackBlock
May 16 22:40:43 volumio volumio[976]: info: CoreCommandRouter::volumioRetrievevolume
May 16 22:40:43 volumio volumio[976]: info: VolumeController:: Volume=47 Mute =false
May 16 22:40:43 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:43 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:44 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:44 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:44 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 16 22:40:44 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:44 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:44 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:44 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:44 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:44 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:44 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:44 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:44 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:44 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:44 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:44 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:44 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:44 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:44 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:44 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:44 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:44 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:44 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:44 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:44 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:44 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:44 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:44 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:44 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:44 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:44 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:44 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:44 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:44 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:44 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:44 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:44 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:44 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:44 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:44 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:44 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:44 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:44 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:44 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:44 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:44 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:44 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:44 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:44 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:44 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:53 volumio volumio[976]: info: CoreCommandRouter::volumioGetState
May 16 22:40:53 volumio volumio[976]: info: Listing playlists
May 16 22:40:53 volumio volumio[976]: info: Listing playlists
May 16 22:40:55 volumio squeezeboxserver[938]: [22:40:55.054] process_strm:230 [0x5cdfa8] strm command p
May 16 22:40:55 volumio squeezeboxserver[938]: [22:40:55.054] raopcl_pause:360 [0x7fd6d001e740]: set pause 77060624667421
May 16 22:40:55 volumio squeezeboxserver[938]: [22:40:55.054] PlayerThread:585 [0x628670]: flushing ...
May 16 22:40:55 volumio squeezeboxserver[938]: [22:40:55.054] raopcl_flush:1118 [0x7fd6d001e740]: flushing up to s:46813 ts:77060624667421
May 16 22:40:56 volumio squeezeboxserver[938]: [22:40:56.055] PlayerThread:481 [0x628670]: Disconnecting 3649733879
May 16 22:40:57 volumio squeezeboxserver[938]: [22:40:57.084] process_strm:230 [0x5cdfa8] strm command u
May 16 22:40:57 volumio squeezeboxserver[938]: [22:40:57.084] process_strm:293 [0x5cdfa8]: unpause at: 0 now: 3649734908
May 16 22:40:57 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:57 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:57 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 16 22:40:57 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:57 volumio squeezeboxserver[938]: [22:40:57.282] PlayerThread:598 [0x628670]: processing volume device:24 request:-144.00
May 16 22:40:57 volumio squeezeboxserver[938]: [22:40:57.282] PlayerThread:575 [0x628670]: raop connecting ...
May 16 22:40:57 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:57 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:57 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:57 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:57 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:57 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:57 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:57 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:57 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:57 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:57 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:57 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:57 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:57 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:57 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:57 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:57 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:57 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:57 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:57 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:57 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:57 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:57 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:57 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:57 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:57 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:57 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:57 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:57 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:57 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:57 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:57 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:57 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:57 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:57 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:57 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:57 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:57 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:57 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:57 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:57 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:57 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:57 volumio shairport-sync[4523]: {"time":1747406443316,"response":"startAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current
May 16 22:40:57 volumio shairport-sync[4523]: Dload Upload Total Spent Left Speed
May 16 22:40:57 volumio squeezeboxserver[938]: [22:40:57.382] raopcl_connect:1003 [0x7fd6d001e740]: local interface 192.168.100.49
May 16 22:40:57 volumio volumio[976]: info:
May 16 22:40:57 volumio volumio[976]: ---------------------------- Client requests Stop Airplay Playback
May 16 22:40:57 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopAirplayPlayback
May 16 22:40:57 volumio volumio[976]: info: Airplay playback stop
May 16 22:40:57 volumio volumio[976]: info: Airplay Playback Stopped, clearing state
May 16 22:40:57 volumio volumio[976]: info: CoreStateMachine::resetVolumioState
May 16 22:40:57 volumio volumio[976]: info: CoreStateMachine::getcurrentVolume
May 16 22:40:57 volumio volumio[976]: info: CoreCommandRouter::volumioRetrievevolume
May 16 22:40:57 volumio squeezeboxserver[938]: [22:40:57.400] PlayerThread:578 [0x628670]: raop connected
May 16 22:40:57 volumio squeezeboxserver[938]: [22:40:57.400] PlayerThread:598 [0x628670]: processing volume device:47 request:-144.00
May 16 22:40:57 volumio squeezeboxserver[938]: [22:40:57.401] PlayerThread:598 [0x628670]: processing volume device:47 request:-28.16
May 16 22:40:57 volumio squeezeboxserver[938]: [22:40:57.402] PlayerThread:598 [0x628670]: processing volume device:47 request:-25.41
May 16 22:40:57 volumio squeezeboxserver[938]: [22:40:57.403] PlayerThread:598 [0x628670]: processing volume device:47 request:-22.96
May 16 22:40:57 volumio squeezeboxserver[938]: [22:40:57.404] PlayerThread:598 [0x628670]: processing volume device:47 request:-20.82
May 16 22:40:57 volumio squeezeboxserver[938]: [22:40:57.405] PlayerThread:598 [0x628670]: processing volume device:47 request:-18.37
May 16 22:40:57 volumio squeezeboxserver[938]: [22:40:57.406] PlayerThread:598 [0x628670]: processing volume device:47 request:-15.92
May 16 22:40:57 volumio squeezeboxserver[938]: [22:40:57.412] raopcl_accept_frames:417 [0x7fd6d001e740]: begining to stream hts:77060624667421 n:1747406457.1770132116
May 16 22:40:57 volumio squeezeboxserver[938]: [22:40:57.412] raopcl_accept_frames:438 [0x7fd6d001e740]: restarting w/ pause n:1747406457.1770132116, hts:77060624716398 (re-send: 156)
May 16 22:40:57 volumio squeezeboxserver[938]: [22:40:57.439] PlayerThread:598 [0x628670]: processing volume device:49 request:-15.31
May 16 22:40:57 volumio volumio[976]: info: Airplay started streaming, receiving metadatas
May 16 22:40:57 volumio shairport-sync[4523]: [158B blob data]
May 16 22:40:57 volumio volumio[976]: info: Airplay started streaming, receiving metadatas
May 16 22:40:57 volumio volumio[976]: info: Airplay started streaming, receiving metadatas
May 16 22:40:57 volumio shairport-sync[4523]: {"time":1747406457389,"response":"stopAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current
May 16 22:40:57 volumio shairport-sync[4523]: Dload Upload Total Spent Left Speed
May 16 22:40:57 volumio volumio[976]: info:
May 16 22:40:57 volumio volumio[976]: ---------------------------- Client requests Start Airplay PlaybackRoutine
May 16 22:40:57 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback
May 16 22:40:57 volumio volumio[976]: info: Airplay playback start
May 16 22:40:57 volumio shairport-sync[4523]: [158B blob data]
May 16 22:40:57 volumio volumio[976]: info: VolumeController:: Volume=47 Mute =false
May 16 22:40:57 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:57 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:57 volumio volumio[976]: info: CoreStateMachine::updateTrackBlock
May 16 22:40:57 volumio volumio[976]: info: CorePlayQueue::getTrackBlock
May 16 22:40:57 volumio volumio[976]: info: CoreCommandRouter::volumioRetrievevolume
May 16 22:40:57 volumio volumio[976]: info: VolumeController:: Volume=47 Mute =false
May 16 22:40:57 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:57 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:58 volumio squeezeboxserver[938]: [22:40:58.253] output_flush:331 [0x5cdfa8]: flush output buffer (full)
May 16 22:40:58 volumio squeezeboxserver[938]: [22:40:58.254] PlayerThread:585 [0x628670]: flushing ...
May 16 22:40:58 volumio squeezeboxserver[938]: [22:40:58.254] raopcl_flush:1118 [0x7fd6d001e740]: flushing up to s:47075 ts:77060624808622
May 16 22:40:58 volumio squeezeboxserver[938]: [22:40:58.289] PlayerThread:481 [0x628670]: Disconnecting 3649736113
May 16 22:40:58 volumio squeezeboxserver[938]: [22:40:58.290] process_strm:230 [0x5cdfa8] strm command s
May 16 22:40:58 volumio squeezeboxserver[938]: [22:40:58.290] process_strm:306 [0x5cdfa8], strm s autostart: 1 transition period: 10 transition type: 0 codec: m
May 16 22:40:58 volumio squeezeboxserver[938]: [22:40:58.290] process_strm:339 [0x5cdfa8]: codec:m, ch:255, s:255, r:255
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:58 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:58 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:58 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:58 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:58 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:58 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:58 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:58 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:58 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:58 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:58 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:58 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:58 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:58 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:58 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:58 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:58 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:58 volumio squeezeboxserver[938]: [22:40:58.916] PlayerThread:598 [0x628670]: processing volume device:49 request:-15.31
May 16 22:40:58 volumio squeezeboxserver[938]: [22:40:58.917] PlayerThread:575 [0x628670]: raop connecting ...
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:58 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:58 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:58 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:58 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:58 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:58 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:58 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:58 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:58 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:58 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:58 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:58 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:58 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:40:58 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:58 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:58 volumio shairport-sync[4523]: {"time":1747406457493,"response":"startAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current
May 16 22:40:58 volumio shairport-sync[4523]: Dload Upload Total Spent Left Speed
May 16 22:40:59 volumio volumio[976]: info:
May 16 22:40:59 volumio volumio[976]: ---------------------------- Client requests Stop Airplay Playback
May 16 22:40:59 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopAirplayPlayback
May 16 22:40:59 volumio volumio[976]: info: Airplay playback stop
May 16 22:40:59 volumio volumio[976]: info: Airplay Playback Stopped, clearing state
May 16 22:40:59 volumio volumio[976]: info: CoreStateMachine::resetVolumioState
May 16 22:40:59 volumio volumio[976]: info: CoreStateMachine::getcurrentVolume
May 16 22:40:59 volumio volumio[976]: info: CoreCommandRouter::volumioRetrievevolume
May 16 22:40:59 volumio squeezeboxserver[938]: [22:40:59.017] raopcl_connect:1003 [0x7fd6d001e740]: local interface 192.168.100.49
May 16 22:40:59 volumio squeezeboxserver[938]: [22:40:59.034] PlayerThread:578 [0x628670]: raop connected
May 16 22:40:59 volumio shairport-sync[4523]: [237B blob data]
May 16 22:40:59 volumio volumio[976]: info: Airplay started streaming, receiving metadatas
May 16 22:40:59 volumio squeezeboxserver[938]: [22:40:59.039] PlayerThread:598 [0x628670]: processing volume device:49 request:-15.31
May 16 22:40:59 volumio squeezeboxserver[938]: [22:40:59.039] raopcl_accept_frames:417 [0x7fd6d001e740]: begining to stream hts:77060624808622 n:1747406459.169887431
May 16 22:40:59 volumio squeezeboxserver[938]: [22:40:59.039] raopcl_accept_frames:425 [0x7fd6d001e740]: restarting w/o pause n:1747406459.169887431, hts:77060624843644
May 16 22:40:59 volumio volumio[976]: info: Airplay started streaming, receiving metadatas
May 16 22:40:59 volumio volumio[976]: info: Airplay started streaming, receiving metadatas
May 16 22:40:59 volumio volumio[976]: info: Airplay started streaming, receiving metadatas
May 16 22:40:59 volumio volumio[976]: info: Airplay started streaming, receiving metadatas
May 16 22:40:59 volumio volumio[976]: info: Airplay started streaming, receiving metadatas
May 16 22:40:59 volumio volumio[976]: info: Airplay started streaming, receiving metadatas
May 16 22:40:59 volumio squeezeboxserver[938]: [22:40:59.049] _output_frames:53 [0x5cdfa8]: start buffer frames: 389423
May 16 22:40:59 volumio squeezeboxserver[938]: [22:40:59.049] _output_frames:100 [0x5cdfa8]: track start sample rate: 44100 replay_gain: 0
May 16 22:40:59 volumio squeezeboxserver[938]: [22:40:59.060] output_raop_thread:91 [0x5cdfa8]: track actual start time:3649738113 (gap:-645229183)
May 16 22:40:59 volumio shairport-sync[4523]: {"time":1747406459009,"response":"stopAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current
May 16 22:40:59 volumio shairport-sync[4523]: Dload Upload Total Spent Left Speed
May 16 22:40:59 volumio volumio[976]: info:
May 16 22:40:59 volumio volumio[976]: ---------------------------- Client requests Start Airplay PlaybackRoutine
May 16 22:40:59 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback
May 16 22:40:59 volumio volumio[976]: info: Airplay playback start
May 16 22:40:59 volumio shairport-sync[4523]: [158B blob data]
May 16 22:40:59 volumio volumio[976]: info: VolumeController:: Volume=47 Mute =false
May 16 22:40:59 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:59 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:40:59 volumio volumio[976]: info: CoreStateMachine::updateTrackBlock
May 16 22:40:59 volumio volumio[976]: info: CorePlayQueue::getTrackBlock
May 16 22:40:59 volumio volumio[976]: info: CoreCommandRouter::volumioRetrievevolume
May 16 22:40:59 volumio volumio[976]: info: VolumeController:: Volume=47 Mute =false
May 16 22:40:59 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:40:59 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:00 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:00 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:00 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 16 22:41:00 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:00 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:00 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:00 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:00 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:00 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:00 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:00 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:00 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:00 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:00 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:00 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:00 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:00 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:00 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:00 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:00 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:00 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:00 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:00 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:00 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:00 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:00 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:00 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:00 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:00 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:00 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:00 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:00 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:00 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:00 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:00 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:00 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:00 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:00 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:00 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:00 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:00 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:00 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:00 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:00 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:00 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:00 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:00 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:00 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:00 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:00 volumio squeezeboxserver[938]: [22:41:00.730] output_flush:331 [0x5cdfa8]: flush output buffer (full)
May 16 22:41:00 volumio squeezeboxserver[938]: [22:41:00.730] PlayerThread:585 [0x628670]: flushing ...
May 16 22:41:00 volumio squeezeboxserver[938]: [22:41:00.730] raopcl_flush:1118 [0x7fd6d001e740]: flushing up to s:47286 ts:77060624917916
May 16 22:41:00 volumio squeezeboxserver[938]: [22:41:00.756] PlayerThread:481 [0x628670]: Disconnecting 3649738580
May 16 22:41:00 volumio squeezeboxserver[938]: [22:41:00.757] process_strm:230 [0x5cdfa8] strm command s
May 16 22:41:00 volumio squeezeboxserver[938]: [22:41:00.757] process_strm:306 [0x5cdfa8], strm s autostart: 1 transition period: 10 transition type: 0 codec: m
May 16 22:41:00 volumio squeezeboxserver[938]: [22:41:00.757] process_strm:339 [0x5cdfa8]: codec:m, ch:255, s:255, r:255
May 16 22:41:01 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:01 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:01 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 16 22:41:01 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:01 volumio squeezeboxserver[938]: [22:41:01.044] PlayerThread:598 [0x628670]: processing volume device:49 request:-15.31
May 16 22:41:01 volumio squeezeboxserver[938]: [22:41:01.044] PlayerThread:575 [0x628670]: raop connecting ...
May 16 22:41:01 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:01 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:01 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:01 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:01 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:01 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:01 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:01 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:01 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:01 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:01 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:01 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:01 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:01 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:01 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:01 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:01 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:01 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:01 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:01 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:01 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:01 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:01 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:01 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:01 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:01 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:01 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:01 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:01 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:01 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:01 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:01 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:01 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:01 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:01 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:01 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:01 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:01 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:01 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:01 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:01 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:01 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:01 volumio shairport-sync[4523]: {"time":1747406459091,"response":"startAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current
May 16 22:41:01 volumio shairport-sync[4523]: Dload Upload Total Spent Left Speed
May 16 22:41:01 volumio volumio[976]: info:
May 16 22:41:01 volumio volumio[976]: ---------------------------- Client requests Stop Airplay Playback
May 16 22:41:01 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopAirplayPlayback
May 16 22:41:01 volumio volumio[976]: info: Airplay playback stop
May 16 22:41:01 volumio volumio[976]: info: Airplay Playback Stopped, clearing state
May 16 22:41:01 volumio volumio[976]: info: CoreStateMachine::resetVolumioState
May 16 22:41:01 volumio volumio[976]: info: CoreStateMachine::getcurrentVolume
May 16 22:41:01 volumio volumio[976]: info: CoreCommandRouter::volumioRetrievevolume
May 16 22:41:01 volumio shairport-sync[4523]: [158B blob data]
May 16 22:41:01 volumio squeezeboxserver[938]: [22:41:01.145] raopcl_connect:1003 [0x7fd6d001e740]: local interface 192.168.100.49
May 16 22:41:01 volumio squeezeboxserver[938]: [22:41:01.165] PlayerThread:578 [0x628670]: raop connected
May 16 22:41:01 volumio squeezeboxserver[938]: [22:41:01.165] PlayerThread:598 [0x628670]: processing volume device:49 request:-15.31
May 16 22:41:01 volumio squeezeboxserver[938]: [22:41:01.166] raopcl_accept_frames:417 [0x7fd6d001e740]: begining to stream hts:77060624917916 n:1747406461.716099897
May 16 22:41:01 volumio squeezeboxserver[938]: [22:41:01.166] raopcl_accept_frames:425 [0x7fd6d001e740]: restarting w/o pause n:1747406461.716099897, hts:77060624937452
May 16 22:41:01 volumio squeezeboxserver[938]: [22:41:01.176] _output_frames:53 [0x5cdfa8]: start buffer frames: 389423
May 16 22:41:01 volumio squeezeboxserver[938]: [22:41:01.177] _output_frames:100 [0x5cdfa8]: track start sample rate: 44100 replay_gain: 0
May 16 22:41:01 volumio volumio[976]: info: Airplay started streaming, receiving metadatas
May 16 22:41:01 volumio squeezeboxserver[938]: [22:41:01.187] output_raop_thread:91 [0x5cdfa8]: track actual start time:3649740240 (gap:-645227056)
May 16 22:41:01 volumio volumio[976]: info: VolumeController:: Volume=47 Mute =false
May 16 22:41:01 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:01 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:01 volumio volumio[976]: info: CoreStateMachine::updateTrackBlock
May 16 22:41:01 volumio volumio[976]: info: CorePlayQueue::getTrackBlock
May 16 22:41:01 volumio volumio[976]: info: CoreCommandRouter::volumioRetrievevolume
May 16 22:41:01 volumio shairport-sync[4523]: {"time":1747406461111,"response":"stopAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current
May 16 22:41:01 volumio shairport-sync[4523]: Dload Upload Total Spent Left Speed
May 16 22:41:01 volumio volumio[976]: info:
May 16 22:41:01 volumio volumio[976]: ---------------------------- Client requests Start Airplay PlaybackRoutine
May 16 22:41:01 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback
May 16 22:41:01 volumio volumio[976]: info: Airplay playback start
May 16 22:41:01 volumio shairport-sync[4523]: [158B blob data]
May 16 22:41:01 volumio volumio[976]: info: VolumeController:: Volume=47 Mute =false
May 16 22:41:01 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:01 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:02 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:02 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:02 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 16 22:41:02 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:02 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:02 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:02 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:02 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:02 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:02 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:02 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:02 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:02 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:02 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:02 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:02 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:02 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:02 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:02 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:02 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:02 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:02 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:02 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:02 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:02 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:02 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:02 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:02 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:02 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:02 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:02 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:02 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:02 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:02 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:02 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:02 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:02 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:02 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:02 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:02 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:02 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:02 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:02 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:02 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:02 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:02 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:02 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:02 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:02 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:02 volumio squeezeboxserver[938]: Can't resize unknown type, magic: "RIFF ~\0\0"
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:03 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:03 volumio squeezeboxserver[938]: [22:41:03.176] PlayerThread:555 [0x628670]: idx 0
May 16 22:41:03 volumio squeezeboxserver[938]: artist:å¶è’¨æ–‡
May 16 22:41:03 volumio squeezeboxserver[938]: album:2025年4月份
May 16 22:41:03 volumio squeezeboxserver[938]: title:春风秋雨
May 16 22:41:03 volumio squeezeboxserver[938]: genre:æ— éŸ³ä¹æ›²é£Ž
May 16 22:41:03 volumio squeezeboxserver[938]: duration:232.333
May 16 22:41:03 volumio squeezeboxserver[938]: size:0
May 16 22:41:03 volumio squeezeboxserver[938]: cover:http://192.168.100.49:9000/music/8883dfa1/cover_.jpg
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:03 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:03 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:03 volumio squeezeboxserver[938]: Can't resize unknown type, magic: "RIFF ~\0\0"
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:03 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:03 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:03 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:03 volumio squeezeboxserver[938]: [22:41:03.188] GetArtworkThread:416 [0x628670]: Can't get artwork or device not active http://192.168.100.49:9000/music/8883dfa1/cover_.jpg
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:03 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:03 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:03 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:03 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:03 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:03 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:03 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:03 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:03 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:03 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:03 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:03 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:03 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:03 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:03 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:03 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:03 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:03 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:03 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:03 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:03 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:03 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:03 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::servicePushState
May 16 22:41:03 volumio volumio[976]: info: CoreStateMachine::pushState
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::volumioPushState
May 16 22:41:03 volumio volumio[976]: info: CoreCommandRouter::volumioGetState
May 16 22:41:05 volumio squeezeboxserver[938]: [22:41:05.711] output_flush:331 [0x5cdfa8]: flush output buffer (full)
May 16 22:41:05 volumio squeezeboxserver[938]: [22:41:05.712] PlayerThread:585 [0x628670]: flushing ...
May 16 22:41:05 volumio squeezeboxserver[938]: [22:41:05.712] raopcl_flush:1118 [0x7fd6d001e740]: flushing up to s:47854 ts:77060625137388
May 16 22:41:05 volumio squeezeboxserver[938]: [22:41:05.721] output_flush:331 [0x5cdfa8]: flush output buffer (full)
May 16 22:41:05 volumio squeezeboxserver[938]: [22:41:05.769] PlayerThread:481 [0x628670]: Disconnecting 3649743593
May 16 22:41:05 volumio squeezeboxserver[938]: [22:41:05.770] process_strm:230 [0x5cdfa8] strm command s
May 16 22:41:05 volumio squeezeboxserver[938]: [22:41:05.770] process_strm:306 [0x5cdfa8], strm s autostart: 1 transition period: 10 transition type: 0 codec: m
May 16 22:41:05 volumio squeezeboxserver[938]: [22:41:05.770] process_strm:339 [0x5cdfa8]: codec:m, ch:255, s:255, r:255
May 16 22:41:06 volumio volumio[976]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
May 16 22:41:06 volumio volumio[976]: Error: connect ETIMEDOUT 128.121.146.101:443
May 16 22:41:06 volumio volumio[976]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) {
May 16 22:41:06 volumio volumio[976]: errno: -110,
May 16 22:41:06 volumio volumio[976]: code: 'ETIMEDOUT',
May 16 22:41:06 volumio volumio[976]: syscall: 'connect',
May 16 22:41:06 volumio volumio[976]: address: '128.121.146.101',
May 16 22:41:06 volumio volumio[976]: port: 443
May 16 22:41:06 volumio volumio[976]: }
May 16 22:41:06 volumio volumio[976]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
May 16 22:41:06 volumio sudo[5284]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-16 22:40
May 16 22:41:06 volumio sudo[5284]: pam_unix(sudo:session): session opened for user root by (uid=0)
PRETTY_NAME="Debian GNU/Linux 10 (buster)"
NAME="Debian GNU/Linux"
VERSION_ID="10"
VERSION="10 (buster)"
VERSION_CODENAME=buster
ID=debian
HOME_URL="https://www.debian.org/"
SUPPORT_URL="https://www.debian.org/support"
BUG_REPORT_URL="https://bugs.debian.org/"
VOLUMIO_BUILD_VERSION="570c5791513f5bac7da274aba6690c1a961705de"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d"
VOLUMIO_ARCH="x64"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:50:12 PM CEST"
VOLUMIO_VERSION="3.812"
VOLUMIO_HARDWARE="x86_amd64"
VOLUMIO_DEVICENAME="x86_64"
VOLUMIO_HASH="39b0f8c200b2dcadf117e189b4b3632d"