Mar 24 11:30:01 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Mar 24 11:30:01 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Mar 24 11:30:02 volumio volumio[3072]: info: CALLMETHOD: system_hardware Bluetoothremote saveBT [object Object] Mar 24 11:30:02 volumio volumio[3072]: info: CoreCommandRouter::executeOnPlugin: Bluetoothremote , saveBT Mar 24 11:30:02 volumio volumio[3072]: info: Bluetoothremote--- Attempting to stop Bluetooth scan... Mar 24 11:30:02 volumio volumio[3072]: info: Bluetoothremote--- bluetoothctl scan off command sent. Mar 24 11:30:02 volumio volumio[3072]: info: Bluetoothremote--- Scan timeout cleared manually. Mar 24 11:30:02 volumio volumio[3072]: info: Bluetoothremote--- Starting pairing with device: A0:E9:DB:03:F6:45 Mar 24 11:30:02 volumio volumio[3072]: info: Bluetoothremote--- Sending command: power on Mar 24 11:30:02 volumio volumio[3072]: error: Failed callmethod call: TypeError: self.pairBtDevice(...).then(...).catch is not a function Mar 24 11:30:02 volumio volumio[3072]: info: Bluetoothremote--- Waiting to connect to bluetoothd... Mar 24 11:30:02 volumio volumio[3072]: [58B blob data] Mar 24 11:30:02 volumio volumio[3072]: [133B blob data] Mar 24 11:30:02 volumio volumio[3072]: [182B blob data] Mar 24 11:30:02 volumio volumio[3072]: [157B blob data] Mar 24 11:30:02 volumio volumio[3072]: [157B blob data] Mar 24 11:30:02 volumio volumio[3072]: [bluetoothctl]> Mar 24 11:30:02 volumio volumio[3072]: [122B blob data] Mar 24 11:30:02 volumio volumio[3072]: [bluetoothctl]> Mar 24 11:30:02 volumio volumio[3072]: info: Bluetoothremote--- power on Mar 24 11:30:02 volumio volumio[3072]: info: Bluetoothremote--- [bluetoothctl]> Mar 24 11:30:02 volumio bluetoothd[890]: Path / reserved for Adv Monitor app :1.110 Mar 24 11:30:02 volumio volumio[3072]: [142B blob data] Mar 24 11:30:02 volumio volumio[3072]: [124B blob data] Mar 24 11:30:02 volumio volumio[3072]: [bluetoothctl]> Mar 24 11:30:04 volumio volumio[3072]: info: Bluetoothremote--- Sending command: pair A0:E9:DB:03:F6:45 Mar 24 11:30:04 volumio volumio[3072]: info: Bluetoothremote--- pair A0:E9:DB:03:F6:45 Mar 24 11:30:04 volumio volumio[3072]: info: Bluetoothremote--- Mar 24 11:30:04 volumio volumio[3072]: Attempting to pair with A0:E9:DB:03:F6:45 Mar 24 11:30:04 volumio volumio[3072]: [bluetoothctl]> Mar 24 11:30:05 volumio volumio[3072]: [166B blob data] Mar 24 11:30:05 volumio volumio[3072]: [Amazon_BT1]> Mar 24 11:30:06 volumio volumio[3072]: [148B blob data] Mar 24 11:30:06 volumio volumio[3072]: [Amazon_BT1]> Mar 24 11:30:06 volumio volumio[3072]: [180B blob data] Mar 24 11:30:06 volumio volumio[3072]: [169B blob data] Mar 24 11:30:06 volumio volumio[3072]: [169B blob data] Mar 24 11:30:06 volumio volumio[3072]: [169B blob data] Mar 24 11:30:06 volumio volumio[3072]: [169B blob data] Mar 24 11:30:06 volumio volumio[3072]: [169B blob data] Mar 24 11:30:06 volumio volumio[3072]: [169B blob data] Mar 24 11:30:06 volumio volumio[3072]: [169B blob data] Mar 24 11:30:06 volumio volumio[3072]: [147B blob data] Mar 24 11:30:06 volumio volumio[3072]: [137B blob data] Mar 24 11:30:06 volumio volumio[3072]: [Amazon_BT1]> Mar 24 11:30:06 volumio volumio[3072]: [124B blob data] Mar 24 11:30:06 volumio volumio[3072]: [Amazon_BT1]> Mar 24 11:30:07 volumio volumio[3072]: info: Bluetoothremote--- Sending command: trust A0:E9:DB:03:F6:45 Mar 24 11:30:07 volumio volumio[3072]: info: Bluetoothremote--- trust A0:E9:DB:03:F6:45 Mar 24 11:30:07 volumio volumio[3072]: info: Bluetoothremote--- [Amazon_BT1]> Mar 24 11:30:07 volumio volumio[3072]: [149B blob data] Mar 24 11:30:07 volumio volumio[3072]: [137B blob data] Mar 24 11:30:07 volumio volumio[3072]: [Amazon_BT1]> Mar 24 11:30:09 volumio volumio[3072]: info: Bluetoothremote--- Sending command: connect A0:E9:DB:03:F6:45 Mar 24 11:30:09 volumio volumio[3072]: info: Bluetoothremote--- connect A0:E9:DB:03:F6:45 Mar 24 11:30:09 volumio volumio[3072]: info: Bluetoothremote--- Attempting to connect to A0:E9:DB:03:F6:45 Mar 24 11:30:09 volumio volumio[3072]: [Amazon_BT1]> Mar 24 11:30:09 volumio bluealsa[1099]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_A0_E9_DB_03_F6_45/sep1, ...) Mar 24 11:30:09 volumio volumio[3072]: [163B blob data] Mar 24 11:30:09 volumio volumio[3072]: [Amazon_BT1]> Mar 24 11:30:09 volumio bluealsa[1099]: bluez.c:1362: Adding new Stream End-Point: A0:E9:DB:03:F6:45: SNK: SBC Mar 24 11:30:09 volumio bluealsa[1099]: ../src/dbus.c:47: Called: org.bluez.MediaEndpoint1.SelectConfiguration() on /org/bluez/hci0/A2DP/SBC/source/1 Mar 24 11:30:09 volumio bluealsa[1099]: bluez.c:429: A2DP peer capabilities blob [len=4]: ffff0235 Mar 24 11:30:09 volumio bluealsa[1099]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_A0_E9_DB_03_F6_45/sep1/fd0, ...) Mar 24 11:30:09 volumio bluealsa[1099]: ../src/dbus.c:47: Called: org.bluez.MediaEndpoint1.SetConfiguration() on /org/bluez/hci0/A2DP/SBC/source/1 Mar 24 11:30:09 volumio bluealsa[1099]: ../src/a2dp-sbc.c:536: SBC: Selected bit-pool range: [2, 53] Mar 24 11:30:09 volumio bluealsa[1099]: ../src/storage.c:123: Loading storage: /var/lib/bluealsa/A0:E9:DB:03:F6:45 Mar 24 11:30:09 volumio volumio[3072]: [168B blob data] Mar 24 11:30:09 volumio volumio[3072]: [Amazon_BT1]> Mar 24 11:30:09 volumio bluealsa[1099]: bluez.c:572: A2DP Source (SBC) configured for device A0:E9:DB:03:F6:45 Mar 24 11:30:09 volumio bluealsa[1099]: bluez.c:575: A2DP selected configuration blob [len=4]: 11150235 Mar 24 11:30:09 volumio bluealsa[1099]: bluez.c:577: PCM configuration: channels: 2, sampling: 48000 Mar 24 11:30:09 volumio bluealsa[1099]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/source/3 Mar 24 11:30:09 volumio bluetoothd[890]: Endpoint registered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/source/3 Mar 24 11:30:09 volumio volumio[3072]: [127B blob data] Mar 24 11:30:09 volumio volumio[3072]: [Amazon_BT1]> Mar 24 11:30:09 volumio volumio[3072]: info: Bluetoothremote--- ✅ Connection confirmed: Amazon_BT1 (A0:E9:DB:03:F6:45) Mar 24 11:30:09 volumio kernel: input: Amazon_BT1 (AVRCP) as /devices/virtual/input/input10 Mar 24 11:30:10 volumio systemd[1]: Stopping triggerhappy.service - triggerhappy global hotkey daemon... Mar 24 11:30:10 volumio systemd[1]: triggerhappy.service: Deactivated successfully. Mar 24 11:30:10 volumio systemd[1]: Stopped triggerhappy.service - triggerhappy global hotkey daemon. Mar 24 11:30:10 volumio systemd[1]: Starting triggerhappy.service - triggerhappy global hotkey daemon... Mar 24 11:30:10 volumio systemd[1]: Started triggerhappy.service - triggerhappy global hotkey daemon. Mar 24 11:30:10 volumio systemd-logind[851]: Watching system buttons on /dev/input/event9 (Amazon_BT1 (AVRCP)) Mar 24 11:30:12 volumio volumio[3072]: info: Bluetoothremote--- Waiting for device to confirm connection... Mar 24 11:30:12 volumio volumio[3072]: info: Bluetoothremote--- quit Mar 24 11:30:12 volumio volumio[3072]: info: Bluetoothremote--- Mar 24 11:30:12 volumio volumio[3072]: [Amazon_BT1]> Mar 24 11:30:12 volumio volumio[3072]: info: Bluetoothremote--- Mar 24 11:30:12 volumio bluetoothd[890]: Adv Monitor app :1.110 disconnected from D-Bus Mar 24 11:30:12 volumio volumio[3072]: info: Bluetoothremote--- bluetoothctl exited with code 0 Mar 24 11:30:12 volumio volumio[3072]: info: Bluetoothremote--- Device list cleared and placeholder written. Mar 24 11:30:12 volumio volumio[3072]: info: Bluetoothremote--- Device paired successfully Mar 24 11:30:12 volumio volumio[3072]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 24 11:30:12 volumio volumio[3072]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 24 11:30:12 volumio volumio[3072]: info: Bluetoothremote--- Device found: Press scan to detect BT device - xx Mar 24 11:30:12 volumio volumio[3072]: info: Bluetoothremote--- Device found: Press scan to detect BT device - xx Mar 24 11:30:12 volumio bluetoothd[890]: Path / reserved for Adv Monitor app :1.111 Mar 24 11:30:12 volumio bluetoothd[890]: Path / reserved for Adv Monitor app :1.112 Mar 24 11:30:12 volumio bluetoothd[890]: Adv Monitor app :1.111 disconnected from D-Bus Mar 24 11:30:12 volumio bluetoothd[890]: Adv Monitor app :1.112 disconnected from D-Bus Mar 24 11:30:15 volumio bluealsa[1099]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_9C_3E_53_49_F2_D6, ...) Mar 24 11:30:15 volumio bluealsa[1099]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_67_13_A8_ED_B1_A2, ...) Mar 24 11:30:15 volumio bluealsa[1099]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_4D_3D_2C_44_9C_CD, ...) Mar 24 11:30:16 volumio bluealsa[1099]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_7F_9F_55_16_48_8E, ...) Mar 24 11:30:16 volumio bluealsa[1099]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_5F_8C_07_25_C2_15, ...) Mar 24 11:30:17 volumio volumio[3072]: info: CoreCommandRouter::Close All Modals sent Mar 24 11:30:17 volumio bluealsa[1099]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_40_47_F5_6B_51_76, ...) Mar 24 11:30:17 volumio bluealsa[1099]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_82_00_00_66_05_56, ...) Mar 24 11:30:17 volumio bluealsa[1099]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_82_00_00_66_16_44, ...) Mar 24 11:30:17 volumio bluealsa[1099]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_A8_51_AB_B5_39_32, ...) Mar 24 11:30:17 volumio bluealsa[1099]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_C6_35_33_33_48_08, ...) Mar 24 11:30:17 volumio bluealsa[1099]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_7F_B0_2A_4C_2B_C0, ...) Mar 24 11:30:17 volumio bluealsa[1099]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_60_72_60_40_7B_94, ...) Mar 24 11:30:17 volumio bluealsa[1099]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_5A_0E_94_B9_59_04, ...) Mar 24 11:30:17 volumio bluealsa[1099]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_04_21_4C_02_20_85, ...) Mar 24 11:30:18 volumio bluealsa[1099]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_CF_32_34_38_2D_73, ...) Mar 24 11:30:18 volumio bluealsa[1099]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_E4_8F_85_06_5D_89, ...) Mar 24 11:30:18 volumio bluealsa[1099]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_40_AE_5D_E7_4A_2E, ...) Mar 24 11:30:18 volumio bluealsa[1099]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_75_33_64_C6_81_E9, ...) Mar 24 11:30:18 volumio bluealsa[1099]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_49_D5_E9_BB_5F_8F, ...) Mar 24 11:30:18 volumio bluealsa[1099]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_53_00_6D_43_89_12, ...) Mar 24 11:30:18 volumio bluealsa[1099]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_56_53_E9_85_D8_5D, ...) Mar 24 11:30:19 volumio bluealsa[1099]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_50_32_37_D4_1D_76, ...) Mar 24 11:30:19 volumio bluealsa[1099]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_7D_78_3E_82_E7_60, ...) Mar 24 11:30:19 volumio bluealsa[1099]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_C7_09_BE_1E_3C_4F, ...) Mar 24 11:30:19 volumio bluealsa[1099]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_4A_25_12_E8_C4_47, ...) Mar 24 11:30:20 volumio volumio[3072]: info: CoreCommandRouter::volumioGetState Mar 24 11:30:20 volumio volumio[3072]: info: CorePlayQueue::getTrack 0 Mar 24 11:30:20 volumio bluealsa[1099]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_50_32_37_B4_CF_66, ...) Mar 24 11:30:20 volumio bluealsa[1099]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_00_71_47_15_87_09, ...) Mar 24 11:30:22 volumio volumio[3072]: info: CoreCommandRouter::volumioPlay Mar 24 11:30:22 volumio volumio[3072]: info: CoreStateMachine::play index undefined Mar 24 11:30:22 volumio volumio[3072]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 11:30:22 volumio volumio[3072]: info: CorePlayQueue::getTrack 0 Mar 24 11:30:22 volumio volumio[3072]: info: CoreStateMachine::startPlaybackTimer Mar 24 11:30:22 volumio volumio[3072]: info: CorePlayQueue::getTrack 0 Mar 24 11:30:22 volumio volumio[3072]: info: [1774366222870] ControllerWebradio::clearAddPlayTrack Mar 24 11:30:22 volumio volumio[3072]: verbose: ControllerMpd::sendMpdCommand stop Mar 24 11:30:22 volumio volumio[3072]: info: sendMpdCommand stop took 2 milliseconds Mar 24 11:30:22 volumio volumio[3072]: verbose: ControllerMpd::sendMpdCommand clear Mar 24 11:30:22 volumio volumio[3072]: info: Mar 24 11:30:22 volumio volumio[3072]: ---------------------------- MPD announces system playlist update Mar 24 11:30:22 volumio volumio[3072]: info: Ignoring MPD Status Update Mar 24 11:30:22 volumio volumio[3072]: info: sendMpdCommand clear took 2 milliseconds Mar 24 11:30:22 volumio volumio[3072]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568323" Mar 24 11:30:22 volumio volumio[3072]: info: Mar 24 11:30:22 volumio volumio[3072]: ---------------------------- MPD announces system playlist update Mar 24 11:30:22 volumio volumio[3072]: info: Ignoring MPD Status Update Mar 24 11:30:22 volumio volumio[3072]: error: updateQueue error: null Mar 24 11:30:22 volumio volumio[3072]: info: ------------------------------ 5ms Mar 24 11:30:23 volumio volumio[3072]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568323" took 266 milliseconds Mar 24 11:30:23 volumio volumio[3072]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 24 11:30:23 volumio volumio[3072]: verbose: ControllerMpd::sendMpdCommand play Mar 24 11:30:23 volumio volumio[3072]: info: Mar 24 11:30:23 volumio volumio[3072]: ---------------------------- MPD announces system playlist update Mar 24 11:30:23 volumio volumio[3072]: info: Ignoring MPD Status Update Mar 24 11:30:23 volumio volumio[3072]: info: ------------------------------ 267ms Mar 24 11:30:23 volumio volumio[3072]: info: Mar 24 11:30:23 volumio volumio[3072]: ---------------------------- MPD announces system playlist update Mar 24 11:30:23 volumio volumio[3072]: info: Ignoring MPD Status Update Mar 24 11:30:23 volumio volumio[3072]: info: sendMpdCommand play took 4 milliseconds Mar 24 11:30:23 volumio volumio[3072]: info: ------------------------------ 4ms Mar 24 11:30:23 volumio volumio[3072]: info: ------------------------------ 2ms Mar 24 11:30:25 volumio volumio[3072]: info: Mar 24 11:30:25 volumio volumio[3072]: ---------------------------- MPD announces state update: player Mar 24 11:30:25 volumio volumio[3072]: info: ControllerMpd::getState Mar 24 11:30:25 volumio volumio[3072]: verbose: ControllerMpd::sendMpdCommand status Mar 24 11:30:25 volumio volumio[3072]: info: Mar 24 11:30:25 volumio volumio[3072]: ---------------------------- MPD announces state update: player Mar 24 11:30:25 volumio volumio[3072]: info: ControllerMpd::getState Mar 24 11:30:25 volumio volumio[3072]: verbose: ControllerMpd::sendMpdCommand status Mar 24 11:30:25 volumio volumio[3072]: info: Mar 24 11:30:25 volumio volumio[3072]: ---------------------------- MPD announces state update: player Mar 24 11:30:25 volumio volumio[3072]: info: sendMpdCommand status took 7 milliseconds Mar 24 11:30:25 volumio volumio[3072]: info: sendMpdCommand status took 6 milliseconds Mar 24 11:30:25 volumio volumio[3072]: info: ControllerMpd::getState Mar 24 11:30:25 volumio volumio[3072]: verbose: ControllerMpd::sendMpdCommand status Mar 24 11:30:25 volumio volumio[3072]: verbose: ControllerMpd::parseState Mar 24 11:30:25 volumio volumio[3072]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 11:30:25 volumio volumio[3072]: verbose: ControllerMpd::parseState Mar 24 11:30:25 volumio volumio[3072]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 11:30:25 volumio volumio[3072]: info: Mar 24 11:30:25 volumio volumio[3072]: ---------------------------- MPD announces state update: player Mar 24 11:30:25 volumio volumio[3072]: info: ControllerMpd::getState Mar 24 11:30:25 volumio volumio[3072]: verbose: ControllerMpd::sendMpdCommand status Mar 24 11:30:25 volumio volumio[3072]: info: sendMpdCommand status took 4 milliseconds Mar 24 11:30:25 volumio volumio[3072]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 24 11:30:25 volumio volumio[3072]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 24 11:30:25 volumio volumio[3072]: info: sendMpdCommand status took 3 milliseconds Mar 24 11:30:25 volumio volumio[3072]: verbose: ControllerMpd::parseState Mar 24 11:30:25 volumio volumio[3072]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 11:30:25 volumio volumio[3072]: verbose: ControllerMpd::parseTrackInfo Mar 24 11:30:25 volumio volumio[3072]: verbose: ControllerMpd::parseTrackInfo Mar 24 11:30:25 volumio volumio[3072]: verbose: ControllerMpd::parseState Mar 24 11:30:25 volumio volumio[3072]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 11:30:25 volumio volumio[3072]: info: ControllerMpd::pushState Mar 24 11:30:25 volumio volumio[3072]: info: CoreCommandRouter::servicePushState Mar 24 11:30:25 volumio volumio[3072]: info: CorePlayQueue::getTrack 0 Mar 24 11:30:25 volumio volumio[3072]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"retrodance.mp3","artist":"(#1 - 3860/32000) Dance Wave Retro!","album":null,"uri":"https://retro.dancewave.online:443/retrodance.mp3","trackType":"mp3"} Mar 24 11:30:25 volumio volumio[3072]: verbose: CURRENT POSITION 0 Mar 24 11:30:25 volumio volumio[3072]: info: CoreStateMachine::syncState stateService play Mar 24 11:30:25 volumio volumio[3072]: info: CoreStateMachine::syncState currentStatus stop Mar 24 11:30:25 volumio volumio[3072]: info: ControllerMpd::pushState Mar 24 11:30:25 volumio volumio[3072]: info: CoreCommandRouter::servicePushState Mar 24 11:30:25 volumio volumio[3072]: info: CorePlayQueue::getTrack 0 Mar 24 11:30:25 volumio volumio[3072]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"retrodance.mp3","artist":"(#1 - 3860/32000) Dance Wave Retro!","album":null,"uri":"https://retro.dancewave.online:443/retrodance.mp3","trackType":"mp3"} Mar 24 11:30:25 volumio volumio[3072]: verbose: CURRENT POSITION 0 Mar 24 11:30:25 volumio volumio[3072]: info: CoreStateMachine::syncState stateService play Mar 24 11:30:25 volumio volumio[3072]: info: CoreStateMachine::syncState currentStatus play Mar 24 11:30:25 volumio volumio[3072]: info: Received an update from plugin. extracting info from payload Mar 24 11:30:25 volumio volumio[3072]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 24 11:30:25 volumio volumio[3072]: info: CoreStateMachine::pushState Mar 24 11:30:25 volumio volumio[3072]: info: CorePlayQueue::getTrack 0 Mar 24 11:30:25 volumio volumio[3072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 11:30:25 volumio volumio[3072]: info: CoreCommandRouter::volumioPushState Mar 24 11:30:25 volumio volumio[3072]: info: CoreStateMachine::pushState Mar 24 11:30:25 volumio volumio[3072]: info: CoreCommandRouter::volumioPushState Mar 24 11:30:25 volumio volumio[3072]: info: ------------------------------ 22ms Mar 24 11:30:25 volumio volumio[3072]: info: ------------------------------ 26ms Mar 24 11:30:25 volumio volumio[3072]: info: sendMpdCommand playlistinfo took 16 milliseconds Mar 24 11:30:25 volumio volumio[3072]: info: sendMpdCommand playlistinfo took 15 milliseconds Mar 24 11:30:25 volumio volumio[3072]: verbose: ControllerMpd::parseTrackInfo Mar 24 11:30:25 volumio volumio[3072]: verbose: ControllerMpd::parseTrackInfo Mar 24 11:30:25 volumio volumio[3072]: info: ControllerMpd::pushState Mar 24 11:30:25 volumio volumio[3072]: info: CoreCommandRouter::servicePushState Mar 24 11:30:25 volumio volumio[3072]: info: CorePlayQueue::getTrack 0 Mar 24 11:30:25 volumio volumio[3072]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"retrodance.mp3","artist":"(#1 - 3860/32000) Dance Wave Retro!","album":null,"uri":"https://retro.dancewave.online:443/retrodance.mp3","trackType":"mp3"} Mar 24 11:30:25 volumio volumio[3072]: verbose: CURRENT POSITION 0 Mar 24 11:30:25 volumio volumio[3072]: info: CoreStateMachine::syncState stateService play Mar 24 11:30:25 volumio volumio[3072]: info: CoreStateMachine::syncState currentStatus play Mar 24 11:30:25 volumio volumio[3072]: info: Received an update from plugin. extracting info from payload Mar 24 11:30:25 volumio volumio[3072]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 24 11:30:25 volumio volumio[3072]: info: CoreStateMachine::pushState Mar 24 11:30:25 volumio volumio[3072]: info: CorePlayQueue::getTrack 0 Mar 24 11:30:25 volumio volumio[3072]: info: CoreCommandRouter::volumioPushState Mar 24 11:30:25 volumio volumio[3072]: info: CoreStateMachine::pushState Mar 24 11:30:25 volumio volumio[3072]: info: CoreCommandRouter::volumioPushState Mar 24 11:30:25 volumio volumio[3072]: info: ControllerMpd::pushState Mar 24 11:30:25 volumio volumio[3072]: info: CoreCommandRouter::servicePushState Mar 24 11:30:25 volumio volumio[3072]: info: CorePlayQueue::getTrack 0 Mar 24 11:30:25 volumio volumio[3072]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"retrodance.mp3","artist":"(#1 - 3860/32000) Dance Wave Retro!","album":null,"uri":"https://retro.dancewave.online:443/retrodance.mp3","trackType":"mp3"} Mar 24 11:30:25 volumio volumio[3072]: verbose: CURRENT POSITION 0 Mar 24 11:30:25 volumio volumio[3072]: info: CoreStateMachine::syncState stateService play Mar 24 11:30:25 volumio volumio[3072]: info: CoreStateMachine::syncState currentStatus play Mar 24 11:30:25 volumio volumio[3072]: info: Received an update from plugin. extracting info from payload Mar 24 11:30:25 volumio volumio[3072]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 24 11:30:25 volumio volumio[3072]: info: CoreStateMachine::pushState Mar 24 11:30:25 volumio volumio[3072]: info: CorePlayQueue::getTrack 0 Mar 24 11:30:25 volumio volumio[3072]: info: CoreCommandRouter::volumioPushState Mar 24 11:30:25 volumio volumio[3072]: info: CoreStateMachine::pushState Mar 24 11:30:25 volumio volumio[3072]: info: CoreCommandRouter::volumioPushState Mar 24 11:30:25 volumio volumio[3072]: info: ------------------------------ 53ms Mar 24 11:30:25 volumio volumio[3072]: info: ------------------------------ 51ms Mar 24 11:30:25 volumio volumio[3072]: info: FusionDsp - Volumio is playing Mar 24 11:30:25 volumio volumio[3072]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Mar 24 11:30:25 volumio volumio[3072]: info: FusionDsp - Volumio is playing Mar 24 11:30:25 volumio volumio[3072]: info: FusionDsp - Volumio is playing Mar 24 11:30:25 volumio volumio[3072]: info: FusionDsp - Volumio is playing Mar 24 11:30:25 volumio volumio[3072]: info: FusionDsp - Volumio is playing Mar 24 11:30:25 volumio volumio[3072]: info: FusionDsp - Volumio is playing Mar 24 11:30:25 volumio volumio[3072]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 24 11:30:25 volumio volumio[3072]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 24 11:30:25 volumio volumio[3072]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 24 11:30:25 volumio sudo[9095]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 24 11:30:25 volumio volumio[3072]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 24 11:30:25 volumio sudo[9095]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 11:30:25 volumio sudo[9097]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 24 11:30:25 volumio sudo[9097]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 11:30:25 volumio volumio[3072]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 24 11:30:25 volumio volumio[3072]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 24 11:30:25 volumio sudo[9101]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 24 11:30:25 volumio sudo[9105]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 24 11:30:25 volumio sudo[9105]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 11:30:25 volumio volumio[3072]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 24 11:30:25 volumio sudo[9101]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 11:30:25 volumio volumio[3072]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 24 11:30:25 volumio sudo[9104]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 24 11:30:25 volumio sudo[9111]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 24 11:30:25 volumio sudo[9104]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 11:30:25 volumio sudo[9111]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 11:30:25 volumio sudo[9107]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 24 11:30:25 volumio sudo[9107]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 11:30:25 volumio volumio[3072]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 24 11:30:25 volumio sudo[9117]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 24 11:30:25 volumio volumio[3072]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 24 11:30:25 volumio sudo[9117]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 11:30:25 volumio systemd[1]: Started peppymeterbasic.service - peppymeterbasic Daemon. Mar 24 11:30:25 volumio sudo[9095]: pam_unix(sudo:session): session closed for user root Mar 24 11:30:25 volumio volumio[3072]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 24 11:30:25 volumio volumio[3072]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 24 11:30:25 volumio volumio[3072]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Mar 24 11:30:25 volumio volumio[3072]: info: FusionDsp - ---- read samplerate from file: 44100 Mar 24 11:30:25 volumio volumio[3072]: info: Mar 24 11:30:25 volumio volumio[3072]: ---------------------------- MPD announces system playlist update Mar 24 11:30:25 volumio volumio[3072]: info: Ignoring MPD Status Update Mar 24 11:30:25 volumio volumio[3072]: info: Mar 24 11:30:25 volumio volumio[3072]: ---------------------------- MPD announces state update: player Mar 24 11:30:25 volumio volumio[3072]: info: ControllerMpd::getState Mar 24 11:30:25 volumio volumio[3072]: verbose: ControllerMpd::sendMpdCommand status Mar 24 11:30:25 volumio volumio[3072]: info: Mar 24 11:30:25 volumio volumio[3072]: ---------------------------- MPD announces system playlist update Mar 24 11:30:25 volumio volumio[3072]: info: Ignoring MPD Status Update Mar 24 11:30:25 volumio volumio[3072]: info: Mar 24 11:30:25 volumio volumio[3072]: ---------------------------- MPD announces state update: player Mar 24 11:30:25 volumio sudo[9124]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 24 11:30:25 volumio volumio[3072]: info: ControllerMpd::getState Mar 24 11:30:25 volumio volumio[3072]: verbose: ControllerMpd::sendMpdCommand status Mar 24 11:30:25 volumio volumio[3072]: info: peppymeterbasic Daemon Started Mar 24 11:30:25 volumio volumio[3072]: info: ------------------------------ 24ms Mar 24 11:30:25 volumio volumio[3072]: info: sendMpdCommand status took 13 milliseconds Mar 24 11:30:25 volumio volumio[3072]: info: ------------------------------ 9ms Mar 24 11:30:25 volumio volumio[3072]: info: sendMpdCommand status took 4 milliseconds Mar 24 11:30:25 volumio volumio[3072]: verbose: ControllerMpd::parseState Mar 24 11:30:25 volumio volumio[3072]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 11:30:25 volumio volumio[3072]: verbose: ControllerMpd::parseState Mar 24 11:30:25 volumio volumio[3072]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 11:30:25 volumio sudo[9124]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 11:30:25 volumio volumio[3072]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 24 11:30:25 volumio volumio[3072]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Mar 24 11:30:25 volumio volumio[3072]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 24 11:30:25 volumio volumio[3072]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Mar 24 11:30:25 volumio volumio[3072]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 24 11:30:25 volumio volumio[3072]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Mar 24 11:30:25 volumio sudo[9126]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 24 11:30:25 volumio sudo[9119]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 24 11:30:25 volumio sudo[9126]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 11:30:25 volumio sudo[9119]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 11:30:25 volumio volumio[3072]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 24 11:30:25 volumio volumio[3072]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Mar 24 11:30:25 volumio volumio[3072]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 24 11:30:25 volumio volumio[3072]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Mar 24 11:30:25 volumio volumio[3072]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 24 11:30:25 volumio volumio[3072]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Mar 24 11:30:25 volumio volumio[3072]: info: sendMpdCommand playlistinfo took 105 milliseconds Mar 24 11:30:25 volumio volumio[3072]: info: sendMpdCommand playlistinfo took 106 milliseconds Mar 24 11:30:25 volumio volumio[3072]: verbose: ControllerMpd::parseTrackInfo Mar 24 11:30:25 volumio volumio[3072]: verbose: ControllerMpd::parseTrackInfo Mar 24 11:30:25 volumio volumio[3072]: info: ControllerMpd::pushState Mar 24 11:30:25 volumio volumio[3072]: info: CoreCommandRouter::servicePushState Mar 24 11:30:25 volumio volumio[3072]: info: CorePlayQueue::getTrack 0 Mar 24 11:30:25 volumio volumio[3072]: verbose: STATE SERVICE {"status":"play","position":0,"seek":975,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"All about Dance before 2000!","artist":"Dance Wave Retro!","album":null,"uri":"https://retro.dancewave.online:443/retrodance.mp3","trackType":"mp3"} Mar 24 11:30:25 volumio volumio[3072]: verbose: CURRENT POSITION 0 Mar 24 11:30:25 volumio volumio[3072]: info: CoreStateMachine::syncState stateService play Mar 24 11:30:25 volumio volumio[3072]: info: CoreStateMachine::syncState currentStatus play Mar 24 11:30:25 volumio volumio[3072]: info: Received an update from plugin. extracting info from payload Mar 24 11:30:25 volumio volumio[3072]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 24 11:30:25 volumio volumio[3072]: info: CoreStateMachine::pushState Mar 24 11:30:25 volumio volumio[3072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 11:30:25 volumio volumio[3072]: info: CoreCommandRouter::volumioPushState Mar 24 11:30:25 volumio volumio[3072]: info: CoreStateMachine::pushState Mar 24 11:30:25 volumio volumio[3072]: info: CoreCommandRouter::volumioPushState Mar 24 11:30:25 volumio volumio[3072]: info: ControllerMpd::pushState Mar 24 11:30:25 volumio volumio[3072]: info: CoreCommandRouter::servicePushState Mar 24 11:30:25 volumio volumio[3072]: info: CorePlayQueue::getTrack 0 Mar 24 11:30:25 volumio volumio[3072]: verbose: STATE SERVICE {"status":"play","position":0,"seek":975,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"All about Dance before 2000!","artist":"Dance Wave Retro!","album":null,"uri":"https://retro.dancewave.online:443/retrodance.mp3","trackType":"mp3"} Mar 24 11:30:25 volumio volumio[3072]: verbose: CURRENT POSITION 0 Mar 24 11:30:25 volumio volumio[3072]: info: CoreStateMachine::syncState stateService play Mar 24 11:30:25 volumio volumio[3072]: info: CoreStateMachine::syncState currentStatus play Mar 24 11:30:25 volumio volumio[3072]: info: Received an update from plugin. extracting info from payload Mar 24 11:30:25 volumio volumio[3072]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 24 11:30:25 volumio volumio[3072]: info: CoreStateMachine::pushState Mar 24 11:30:25 volumio volumio[3072]: info: CoreCommandRouter::volumioPushState Mar 24 11:30:25 volumio volumio[3072]: info: CoreStateMachine::pushState Mar 24 11:30:25 volumio volumio[3072]: info: CoreCommandRouter::volumioPushState Mar 24 11:30:25 volumio sudo[9131]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 24 11:30:25 volumio sudo[9131]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 11:30:25 volumio volumio[3072]: info: ------------------------------ 153ms Mar 24 11:30:25 volumio volumio[3072]: info: ------------------------------ 148ms Mar 24 11:30:25 volumio volumio[3072]: info: FusionDsp - Volumio is playing Mar 24 11:30:25 volumio volumio[3072]: info: FusionDsp - Volumio is playing Mar 24 11:30:25 volumio volumio[3072]: info: FusionDsp - Volumio is playing Mar 24 11:30:25 volumio volumio[3072]: info: FusionDsp - Volumio is playing Mar 24 11:30:25 volumio volumio[3072]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 24 11:30:25 volumio volumio[3072]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 24 11:30:25 volumio volumio[3072]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 24 11:30:25 volumio volumio[3072]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 24 11:30:25 volumio sudo[9119]: pam_unix(sudo:session): session closed for user root Mar 24 11:30:25 volumio sudo[9101]: pam_unix(sudo:session): session closed for user root Mar 24 11:30:25 volumio sudo[9105]: pam_unix(sudo:session): session closed for user root Mar 24 11:30:25 volumio sudo[9124]: pam_unix(sudo:session): session closed for user root Mar 24 11:30:25 volumio sudo[9107]: pam_unix(sudo:session): session closed for user root Mar 24 11:30:25 volumio sudo[9134]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 24 11:30:25 volumio sudo[9104]: pam_unix(sudo:session): session closed for user root Mar 24 11:30:25 volumio sudo[9117]: pam_unix(sudo:session): session closed for user root Mar 24 11:30:25 volumio sudo[9134]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 11:30:25 volumio volumio[3072]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 24 11:30:25 volumio sudo[9097]: pam_unix(sudo:session): session closed for user root Mar 24 11:30:25 volumio sudo[9111]: pam_unix(sudo:session): session closed for user root Mar 24 11:30:25 volumio sudo[9126]: pam_unix(sudo:session): session closed for user root Mar 24 11:30:25 volumio volumio[3072]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 24 11:30:25 volumio sudo[9141]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 24 11:30:25 volumio sudo[9141]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 11:30:25 volumio sudo[9138]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 24 11:30:25 volumio sudo[9138]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 11:30:26 volumio sudo[9136]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 24 11:30:26 volumio sudo[9136]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 11:30:26 volumio sudo[9131]: pam_unix(sudo:session): session closed for user root Mar 24 11:30:26 volumio sudo[9134]: pam_unix(sudo:session): session closed for user root Mar 24 11:30:26 volumio sudo[9143]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 24 11:30:26 volumio sudo[9143]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 11:30:26 volumio volumio[3072]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 24 11:30:26 volumio sudo[9141]: pam_unix(sudo:session): session closed for user root Mar 24 11:30:26 volumio sudo[9138]: pam_unix(sudo:session): session closed for user root Mar 24 11:30:26 volumio sudo[9149]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 24 11:30:26 volumio sudo[9149]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 11:30:26 volumio sudo[9136]: pam_unix(sudo:session): session closed for user root Mar 24 11:30:26 volumio volumio[3072]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 24 11:30:26 volumio sudo[9143]: pam_unix(sudo:session): session closed for user root Mar 24 11:30:26 volumio volumio[3072]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 24 11:30:26 volumio sudo[9154]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 24 11:30:26 volumio sudo[9149]: pam_unix(sudo:session): session closed for user root Mar 24 11:30:26 volumio sudo[9154]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 11:30:26 volumio sudo[9151]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 24 11:30:26 volumio sudo[9151]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 11:30:26 volumio volumio[3072]: info: peppymeterbasic Daemon Started Mar 24 11:30:26 volumio volumio[3072]: info: peppymeterbasic Daemon Started Mar 24 11:30:26 volumio volumio[3072]: info: peppymeterbasic Daemon Started Mar 24 11:30:26 volumio volumio[3072]: info: peppymeterbasic Daemon Started Mar 24 11:30:26 volumio volumio[3072]: info: peppymeterbasic Daemon Started Mar 24 11:30:26 volumio volumio[3072]: info: peppymeterbasic Daemon Started Mar 24 11:30:26 volumio volumio[3072]: info: peppymeterbasic Daemon Started Mar 24 11:30:26 volumio volumio[3072]: info: peppymeterbasic Daemon Started Mar 24 11:30:26 volumio volumio[3072]: info: peppymeterbasic Daemon Started Mar 24 11:30:26 volumio volumio[3072]: info: peppymeterbasic Daemon Started Mar 24 11:30:26 volumio volumio[3072]: info: peppymeterbasic Daemon Started Mar 24 11:30:26 volumio volumio[3072]: info: peppymeterbasic Daemon Started Mar 24 11:30:26 volumio volumio[3072]: info: peppymeterbasic Daemon Started Mar 24 11:30:26 volumio volumio[3072]: info: peppymeterbasic Daemon Started Mar 24 11:30:26 volumio volumio[3072]: info: peppymeterbasic Daemon Started Mar 24 11:30:26 volumio volumio[3072]: info: peppymeterbasic Daemon Started Mar 24 11:30:26 volumio volumio[3072]: info: peppymeterbasic Daemon Started Mar 24 11:30:26 volumio volumio[3072]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 24 11:30:26 volumio volumio[3072]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Mar 24 11:30:26 volumio volumio[3072]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 24 11:30:26 volumio volumio[3072]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Mar 24 11:30:26 volumio volumio[3072]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 24 11:30:26 volumio volumio[3072]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Mar 24 11:30:26 volumio volumio[3072]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 24 11:30:26 volumio volumio[3072]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Mar 24 11:30:26 volumio volumio[3072]: error: FusionDsp - Reload WebSocket error: [object Object] Mar 24 11:30:26 volumio sudo[9151]: pam_unix(sudo:session): session closed for user root Mar 24 11:30:26 volumio volumio[3072]: info: peppymeterbasic Daemon Started Mar 24 11:30:26 volumio sudo[9154]: pam_unix(sudo:session): session closed for user root Mar 24 11:30:26 volumio volumio[3072]: info: peppymeterbasic Daemon Started Mar 24 11:30:40 volumio volumio[3072]: info: CoreCommandRouter::volumioGetQueue Mar 24 11:30:40 volumio volumio[3072]: info: CoreStateMachine::getQueue Mar 24 11:30:40 volumio volumio[3072]: info: CorePlayQueue::getQueue Mar 24 11:30:45 volumio volumio[3072]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Mar 24 11:30:49 volumio volumio[9122]: libpng warning: iCCP: known incorrect sRGB profile Mar 24 11:30:51 volumio volumio[3072]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 24 11:30:52 volumio volumio[3072]: info: Preload queue cleared Mar 24 11:30:55 volumio volumio[3072]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 24 11:30:55 volumio volumio[3072]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Mar 24 11:30:55 volumio volumio[3072]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 24 11:30:55 volumio volumio[3072]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Mar 24 11:30:55 volumio volumio[3072]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 24 11:30:55 volumio volumio[3072]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Mar 24 11:30:55 volumio volumio[3072]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 24 11:30:55 volumio volumio[3072]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Mar 24 11:30:55 volumio volumio[3072]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 24 11:30:55 volumio volumio[3072]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Mar 24 11:30:55 volumio volumio[3072]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 24 11:30:55 volumio volumio[3072]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Mar 24 11:30:56 volumio volumio[3072]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 24 11:30:56 volumio volumio[3072]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Mar 24 11:30:56 volumio volumio[3072]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 24 11:30:56 volumio volumio[3072]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Mar 24 11:30:56 volumio volumio[3072]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 24 11:30:56 volumio volumio[3072]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Mar 24 11:30:56 volumio volumio[3072]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 24 11:30:56 volumio volumio[3072]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Mar 24 11:30:59 volumio volumio[3072]: info: Preload queue cleared Mar 24 11:30:59 volumio volumio[3072]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 24 11:30:59 volumio volumio[3072]: info: CoreStateMachine::ClearQueue Mar 24 11:30:59 volumio volumio[3072]: info: CoreStateMachine::stop Mar 24 11:30:59 volumio volumio[3072]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 11:30:59 volumio volumio[3072]: info: CoreStateMachine::stPlaybackTimer Mar 24 11:30:59 volumio volumio[3072]: info: CoreStateMachine::updateTrackBlock Mar 24 11:30:59 volumio volumio[3072]: info: CorePlayQueue::getTrackBlock Mar 24 11:30:59 volumio volumio[3072]: info: CoreStateMachine::pushState Mar 24 11:30:59 volumio volumio[3072]: info: CorePlayQueue::getTrack 0 Mar 24 11:30:59 volumio volumio[3072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 11:30:59 volumio volumio[3072]: info: CoreCommandRouter::volumioPushState Mar 24 11:30:59 volumio volumio[3072]: info: CoreStateMachine::serviceStop Mar 24 11:30:59 volumio volumio[3072]: info: CorePlayQueue::getTrack 0 Mar 24 11:30:59 volumio volumio[3072]: info: CoreCommandRouter::serviceStop Mar 24 11:30:59 volumio volumio[3072]: info: [1774366259172] ControllerWebradio::stop Mar 24 11:30:59 volumio volumio[3072]: verbose: ControllerMpd::sendMpdCommand stop Mar 24 11:30:59 volumio volumio[3072]: info: CorePlayQueue::clearPlayQueue Mar 24 11:30:59 volumio volumio[3072]: info: CorePlayQueue::saveQueue Mar 24 11:30:59 volumio volumio[3072]: info: CoreCommandRouter::volumioPushQueue Mar 24 11:30:59 volumio volumio[3072]: info: CoreStateMachine::addQueueItems Mar 24 11:30:59 volumio volumio[3072]: info: CorePlayQueue::addQueueItems Mar 24 11:30:59 volumio volumio[3072]: info: Preload queue cleared Mar 24 11:30:59 volumio volumio[3072]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320 Mar 24 11:30:59 volumio volumio[3072]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 24 11:30:59 volumio volumio[3072]: info: CoreCommandRouter::volumioPushQueue Mar 24 11:30:59 volumio volumio[3072]: info: CorePlayQueue::saveQueue Mar 24 11:30:59 volumio volumio[3072]: info: CoreStateMachine::updateTrackBlock Mar 24 11:30:59 volumio volumio[3072]: info: CorePlayQueue::getTrackBlock Mar 24 11:30:59 volumio volumio[3072]: info: CoreCommandRouter::volumioPlay Mar 24 11:30:59 volumio volumio[3072]: info: CoreStateMachine::play index 0 Mar 24 11:30:59 volumio volumio[3072]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 11:30:59 volumio volumio[3072]: info: CoreStateMachine::stop Mar 24 11:30:59 volumio volumio[3072]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 11:30:59 volumio volumio[3072]: info: CoreStateMachine::play index undefined Mar 24 11:30:59 volumio volumio[3072]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 11:30:59 volumio volumio[3072]: info: CorePlayQueue::getTrack 0 Mar 24 11:30:59 volumio volumio[3072]: info: CoreStateMachine::startPlaybackTimer Mar 24 11:30:59 volumio volumio[3072]: info: CorePlayQueue::getTrack 0 Mar 24 11:30:59 volumio volumio[3072]: info: [1774366259196] ControllerWebradio::clearAddPlayTrack Mar 24 11:30:59 volumio volumio[3072]: verbose: ControllerMpd::sendMpdCommand stop Mar 24 11:30:59 volumio volumio[3072]: info: FusionDsp - Volumio is not playing Mar 24 11:30:59 volumio volumio[3072]: info: FusionDsp - Clipped samples monitor stopped Mar 24 11:30:59 volumio volumio[3072]: info: PeppyMeterBasic ---peppymeterbasic status stop Mar 24 11:30:59 volumio volumio[3072]: info: PeppyMeterBasic ---peppymeterbasic status stop Mar 24 11:30:59 volumio sudo[9214]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Mar 24 11:30:59 volumio sudo[9214]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 11:30:59 volumio volumio[3072]: info: Mar 24 11:30:59 volumio volumio[3072]: ---------------------------- MPD announces state update: player Mar 24 11:30:59 volumio volumio[3072]: info: sendMpdCommand stop took 118 milliseconds Mar 24 11:30:59 volumio volumio[3072]: info: ControllerMpd::getState Mar 24 11:30:59 volumio volumio[3072]: verbose: ControllerMpd::sendMpdCommand status Mar 24 11:30:59 volumio volumio[3072]: info: sendMpdCommand stop took 94 milliseconds Mar 24 11:30:59 volumio volumio[3072]: verbose: ControllerMpd::sendMpdCommand clear Mar 24 11:30:59 volumio volumio[3072]: info: Mar 24 11:30:59 volumio volumio[3072]: ---------------------------- MPD announces state update: player Mar 24 11:30:59 volumio volumio[3072]: info: ControllerMpd::getState Mar 24 11:30:59 volumio volumio[3072]: verbose: ControllerMpd::sendMpdCommand status Mar 24 11:30:59 volumio sudo[9216]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Mar 24 11:30:59 volumio sudo[9216]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 11:30:59 volumio volumio[3072]: info: Mar 24 11:30:59 volumio volumio[3072]: ---------------------------- MPD announces system playlist update Mar 24 11:30:59 volumio volumio[3072]: info: Ignoring MPD Status Update Mar 24 11:30:59 volumio volumio[3072]: info: sendMpdCommand status took 24 milliseconds Mar 24 11:30:59 volumio volumio[3072]: info: sendMpdCommand clear took 23 milliseconds Mar 24 11:30:59 volumio volumio[3072]: info: sendMpdCommand status took 22 milliseconds Mar 24 11:30:59 volumio volumio[3072]: verbose: ControllerMpd::parseState Mar 24 11:30:59 volumio volumio[3072]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 11:30:59 volumio volumio[3072]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" Mar 24 11:30:59 volumio volumio[3072]: verbose: ControllerMpd::parseState Mar 24 11:30:59 volumio volumio[3072]: info: ControllerMpd::pushState Mar 24 11:30:59 volumio volumio[3072]: info: CoreCommandRouter::servicePushState Mar 24 11:30:59 volumio volumio[3072]: info: CoreStateMachine::pushState Mar 24 11:30:59 volumio volumio[3072]: info: CorePlayQueue::getTrack 0 Mar 24 11:30:59 volumio volumio[3072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 11:30:59 volumio volumio[3072]: info: CoreCommandRouter::volumioPushState Mar 24 11:30:59 volumio volumio[3072]: info: CorePlayQueue::getTrack 0 Mar 24 11:30:59 volumio volumio[3072]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 24 11:30:59 volumio volumio[3072]: info: ------------------------------ 79ms Mar 24 11:30:59 volumio volumio[3072]: info: Mar 24 11:30:59 volumio volumio[3072]: ---------------------------- MPD announces system playlist update Mar 24 11:30:59 volumio volumio[3072]: info: Ignoring MPD Status Update Mar 24 11:30:59 volumio systemd[1]: Stopping peppymeterbasic.service - peppymeterbasic Daemon... Mar 24 11:30:59 volumio volumio[3072]: error: updateQueue error: null Mar 24 11:30:59 volumio volumio[3072]: info: ------------------------------ 112ms Mar 24 11:30:59 volumio volumio[3072]: info: sendMpdCommand playlistinfo took 111 milliseconds Mar 24 11:30:59 volumio volumio[3072]: verbose: ControllerMpd::parseTrackInfo Mar 24 11:30:59 volumio volumio[3072]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Mar 24 11:30:59 volumio volumio[3072]: info: ------------------------------ 140ms Mar 24 11:30:59 volumio volumio[3072]: info: FusionDsp - Volumio is not playing Mar 24 11:30:59 volumio volumio[3072]: info: FusionDsp - Clipped samples monitor stopped Mar 24 11:30:59 volumio volumio[3072]: info: PeppyMeterBasic ---peppymeterbasic status stop Mar 24 11:30:59 volumio volumio[3072]: info: PeppyMeterBasic ---peppymeterbasic status stop Mar 24 11:30:59 volumio volumio[3072]: info: Mar 24 11:30:59 volumio volumio[3072]: ---------------------------- MPD announces system playlist update Mar 24 11:30:59 volumio volumio[3072]: info: Ignoring MPD Status Update Mar 24 11:30:59 volumio volumio[3072]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" took 193 milliseconds Mar 24 11:30:59 volumio volumio[3072]: info: ------------------------------ 137ms Mar 24 11:30:59 volumio volumio[3072]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 24 11:30:59 volumio volumio[3072]: verbose: ControllerMpd::sendMpdCommand play Mar 24 11:30:59 volumio volumio[3072]: info: Mar 24 11:30:59 volumio volumio[3072]: ---------------------------- MPD announces system playlist update Mar 24 11:30:59 volumio volumio[3072]: info: Ignoring MPD Status Update Mar 24 11:30:59 volumio volumio[3072]: info: touch_display: Setting screensaver timeout to 120 seconds. Mar 24 11:30:59 volumio volumio[3072]: info: ------------------------------ 18ms Mar 24 11:30:59 volumio volumio[3072]: info: sendMpdCommand play took 15 milliseconds Mar 24 11:30:59 volumio volumio[3072]: info: ------------------------------ 12ms Mar 24 11:30:59 volumio sudo[9222]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Mar 24 11:30:59 volumio sudo[9222]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 11:30:59 volumio sudo[9224]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Mar 24 11:30:59 volumio sudo[9224]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 11:30:59 volumio systemd[1]: peppymeterbasic.service: Deactivated successfully. Mar 24 11:30:59 volumio systemd[1]: Stopped peppymeterbasic.service - peppymeterbasic Daemon. Mar 24 11:30:59 volumio systemd[1]: peppymeterbasic.service: Consumed 4.437s CPU time. Mar 24 11:30:59 volumio sudo[9214]: pam_unix(sudo:session): session closed for user root Mar 24 11:30:59 volumio sudo[9216]: pam_unix(sudo:session): session closed for user root Mar 24 11:30:59 volumio volumio[3072]: info: peppymeterbasic Daemon Stop Mar 24 11:30:59 volumio volumio[3072]: info: peppymeterbasic Daemon Stop Mar 24 11:30:59 volumio sudo[9224]: pam_unix(sudo:session): session closed for user root Mar 24 11:30:59 volumio volumio[3072]: info: peppymeterbasic Daemon Stop Mar 24 11:30:59 volumio sudo[9222]: pam_unix(sudo:session): session closed for user root Mar 24 11:30:59 volumio volumio[3072]: info: peppymeterbasic Daemon Stop Mar 24 11:31:00 volumio volumio[3072]: info: Mar 24 11:31:00 volumio volumio[3072]: ---------------------------- MPD announces state update: player Mar 24 11:31:00 volumio volumio[3072]: info: ControllerMpd::getState Mar 24 11:31:00 volumio volumio[3072]: verbose: ControllerMpd::sendMpdCommand status Mar 24 11:31:00 volumio volumio[3072]: info: Mar 24 11:31:00 volumio volumio[3072]: ---------------------------- MPD announces state update: player Mar 24 11:31:00 volumio volumio[3072]: info: ControllerMpd::getState Mar 24 11:31:00 volumio volumio[3072]: verbose: ControllerMpd::sendMpdCommand status Mar 24 11:31:00 volumio volumio[3072]: info: Mar 24 11:31:00 volumio volumio[3072]: ---------------------------- MPD announces state update: player Mar 24 11:31:00 volumio volumio[3072]: info: sendMpdCommand status took 7 milliseconds Mar 24 11:31:00 volumio volumio[3072]: info: sendMpdCommand status took 6 milliseconds Mar 24 11:31:00 volumio volumio[3072]: info: ControllerMpd::getState Mar 24 11:31:00 volumio volumio[3072]: verbose: ControllerMpd::sendMpdCommand status Mar 24 11:31:00 volumio volumio[3072]: verbose: ControllerMpd::parseState Mar 24 11:31:00 volumio volumio[3072]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 11:31:00 volumio volumio[3072]: verbose: ControllerMpd::parseState Mar 24 11:31:00 volumio volumio[3072]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 11:31:00 volumio volumio[3072]: info: Mar 24 11:31:00 volumio volumio[3072]: ---------------------------- MPD announces state update: player Mar 24 11:31:00 volumio volumio[3072]: info: ControllerMpd::getState Mar 24 11:31:00 volumio volumio[3072]: verbose: ControllerMpd::sendMpdCommand status Mar 24 11:31:00 volumio volumio[3072]: info: sendMpdCommand status took 11 milliseconds Mar 24 11:31:00 volumio volumio[3072]: info: sendMpdCommand playlistinfo took 11 milliseconds Mar 24 11:31:00 volumio volumio[3072]: info: sendMpdCommand playlistinfo took 11 milliseconds Mar 24 11:31:00 volumio volumio[3072]: verbose: ControllerMpd::parseState Mar 24 11:31:00 volumio volumio[3072]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 11:31:00 volumio volumio[3072]: verbose: ControllerMpd::parseTrackInfo Mar 24 11:31:00 volumio volumio[3072]: verbose: ControllerMpd::parseTrackInfo Mar 24 11:31:00 volumio volumio[3072]: info: ControllerMpd::pushState Mar 24 11:31:00 volumio volumio[3072]: info: CoreCommandRouter::servicePushState Mar 24 11:31:00 volumio volumio[3072]: info: CorePlayQueue::getTrack 0 Mar 24 11:31:00 volumio volumio[3072]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"(#1 - 5791/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 24 11:31:00 volumio volumio[3072]: verbose: CURRENT POSITION 0 Mar 24 11:31:00 volumio volumio[3072]: info: CoreStateMachine::syncState stateService play Mar 24 11:31:00 volumio volumio[3072]: info: CoreStateMachine::syncState currentStatus stop Mar 24 11:31:00 volumio volumio[3072]: info: ControllerMpd::pushState Mar 24 11:31:00 volumio volumio[3072]: info: CoreCommandRouter::servicePushState Mar 24 11:31:00 volumio volumio[3072]: info: CorePlayQueue::getTrack 0 Mar 24 11:31:00 volumio volumio[3072]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"(#1 - 5791/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 24 11:31:00 volumio volumio[3072]: verbose: CURRENT POSITION 0 Mar 24 11:31:00 volumio volumio[3072]: info: CoreStateMachine::syncState stateService play Mar 24 11:31:00 volumio volumio[3072]: info: CoreStateMachine::syncState currentStatus play Mar 24 11:31:00 volumio volumio[3072]: info: Received an update from plugin. extracting info from payload Mar 24 11:31:00 volumio volumio[3072]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 24 11:31:00 volumio volumio[3072]: info: CoreStateMachine::pushState Mar 24 11:31:00 volumio volumio[3072]: info: CorePlayQueue::getTrack 0 Mar 24 11:31:00 volumio volumio[3072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 11:31:00 volumio volumio[3072]: info: CoreCommandRouter::volumioPushState Mar 24 11:31:00 volumio volumio[3072]: info: CoreStateMachine::pushState Mar 24 11:31:00 volumio volumio[3072]: info: CoreCommandRouter::volumioPushState Mar 24 11:31:00 volumio volumio[3072]: info: ------------------------------ 30ms Mar 24 11:31:00 volumio volumio[3072]: info: ------------------------------ 36ms Mar 24 11:31:00 volumio volumio[3072]: info: sendMpdCommand status took 30 milliseconds Mar 24 11:31:00 volumio volumio[3072]: info: sendMpdCommand playlistinfo took 20 milliseconds Mar 24 11:31:00 volumio volumio[3072]: verbose: ControllerMpd::parseState Mar 24 11:31:00 volumio volumio[3072]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 11:31:00 volumio volumio[3072]: verbose: ControllerMpd::parseTrackInfo Mar 24 11:31:00 volumio volumio[3072]: info: ControllerMpd::pushState Mar 24 11:31:00 volumio volumio[3072]: info: CoreCommandRouter::servicePushState Mar 24 11:31:00 volumio volumio[3072]: info: CorePlayQueue::getTrack 0 Mar 24 11:31:00 volumio volumio[3072]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"(#1 - 5791/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 24 11:31:00 volumio volumio[3072]: verbose: CURRENT POSITION 0 Mar 24 11:31:00 volumio volumio[3072]: info: CoreStateMachine::syncState stateService play Mar 24 11:31:00 volumio volumio[3072]: info: CoreStateMachine::syncState currentStatus play Mar 24 11:31:00 volumio volumio[3072]: info: Received an update from plugin. extracting info from payload Mar 24 11:31:00 volumio volumio[3072]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 24 11:31:00 volumio volumio[3072]: info: CoreStateMachine::pushState Mar 24 11:31:00 volumio volumio[3072]: info: CorePlayQueue::getTrack 0 Mar 24 11:31:00 volumio volumio[3072]: info: CoreCommandRouter::volumioPushState Mar 24 11:31:00 volumio volumio[3072]: info: CoreStateMachine::pushState Mar 24 11:31:00 volumio volumio[3072]: info: CoreCommandRouter::volumioPushState Mar 24 11:31:00 volumio volumio[3072]: info: ------------------------------ 56ms Mar 24 11:31:01 volumio volumio[3072]: info: FusionDsp - Volumio is playing Mar 24 11:31:01 volumio volumio[3072]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Mar 24 11:31:01 volumio volumio[3072]: info: FusionDsp - Volumio is playing Mar 24 11:31:01 volumio volumio[3072]: info: FusionDsp - Volumio is playing Mar 24 11:31:01 volumio volumio[3072]: info: FusionDsp - Volumio is playing Mar 24 11:31:01 volumio volumio[3072]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 24 11:31:01 volumio volumio[3072]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 24 11:31:01 volumio volumio[3072]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 24 11:31:01 volumio sudo[9230]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 24 11:31:01 volumio volumio[3072]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 24 11:31:01 volumio sudo[9230]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 11:31:01 volumio sudo[9232]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 24 11:31:01 volumio volumio[3072]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 24 11:31:01 volumio sudo[9232]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 11:31:01 volumio sudo[9234]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 24 11:31:01 volumio sudo[9234]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 11:31:01 volumio sudo[9237]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 24 11:31:01 volumio sudo[9237]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 11:31:01 volumio volumio[3072]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 24 11:31:01 volumio volumio[3072]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 24 11:31:01 volumio sudo[9241]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 24 11:31:01 volumio sudo[9241]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 11:31:01 volumio volumio[3072]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 24 11:31:01 volumio sudo[9244]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 24 11:31:01 volumio sudo[9244]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 11:31:01 volumio sudo[9247]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 24 11:31:01 volumio sudo[9247]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 11:31:01 volumio volumio[3072]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Mar 24 11:31:01 volumio volumio[3072]: info: FusionDsp - ---- read samplerate from file: 44100 Mar 24 11:31:01 volumio volumio[3072]: info: Mar 24 11:31:01 volumio volumio[3072]: ---------------------------- MPD announces system playlist update Mar 24 11:31:01 volumio volumio[3072]: info: Ignoring MPD Status Update Mar 24 11:31:01 volumio volumio[3072]: info: Mar 24 11:31:01 volumio volumio[3072]: ---------------------------- MPD announces state update: player Mar 24 11:31:01 volumio volumio[3072]: info: sendMpdCommand playlistinfo took 273 milliseconds Mar 24 11:31:01 volumio volumio[3072]: info: ControllerMpd::getState Mar 24 11:31:01 volumio volumio[3072]: verbose: ControllerMpd::sendMpdCommand status Mar 24 11:31:01 volumio volumio[3072]: verbose: ControllerMpd::parseTrackInfo Mar 24 11:31:01 volumio volumio[3072]: info: ControllerMpd::pushState Mar 24 11:31:01 volumio volumio[3072]: info: CoreCommandRouter::servicePushState Mar 24 11:31:01 volumio volumio[3072]: info: CorePlayQueue::getTrack 0 Mar 24 11:31:01 volumio volumio[3072]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"(#1 - 5791/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 24 11:31:01 volumio volumio[3072]: verbose: CURRENT POSITION 0 Mar 24 11:31:01 volumio volumio[3072]: info: CoreStateMachine::syncState stateService play Mar 24 11:31:01 volumio volumio[3072]: info: CoreStateMachine::syncState currentStatus play Mar 24 11:31:01 volumio volumio[3072]: info: Received an update from plugin. extracting info from payload Mar 24 11:31:01 volumio volumio[3072]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 24 11:31:01 volumio volumio[3072]: info: CoreStateMachine::pushState Mar 24 11:31:01 volumio volumio[3072]: info: CorePlayQueue::getTrack 0 Mar 24 11:31:01 volumio volumio[3072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 11:31:01 volumio volumio[3072]: info: CoreCommandRouter::volumioPushState Mar 24 11:31:01 volumio volumio[3072]: info: CoreStateMachine::pushState Mar 24 11:31:01 volumio volumio[3072]: info: CoreCommandRouter::volumioPushState Mar 24 11:31:01 volumio sudo[9252]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 24 11:31:01 volumio sudo[9252]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 11:31:01 volumio systemd[1]: Started peppymeterbasic.service - peppymeterbasic Daemon. Mar 24 11:31:01 volumio volumio[3072]: info: ------------------------------ 334ms Mar 24 11:31:01 volumio volumio[3072]: info: Mar 24 11:31:01 volumio volumio[3072]: ---------------------------- MPD announces system playlist update Mar 24 11:31:01 volumio volumio[3072]: info: Ignoring MPD Status Update Mar 24 11:31:01 volumio volumio[3072]: info: Mar 24 11:31:01 volumio volumio[3072]: ---------------------------- MPD announces state update: player Mar 24 11:31:01 volumio volumio[3072]: info: ControllerMpd::getState Mar 24 11:31:01 volumio volumio[3072]: verbose: ControllerMpd::sendMpdCommand status Mar 24 11:31:01 volumio volumio[3072]: info: Mar 24 11:31:01 volumio volumio[3072]: ---------------------------- MPD announces system playlist update Mar 24 11:31:01 volumio volumio[3072]: info: Ignoring MPD Status Update Mar 24 11:31:01 volumio volumio[3072]: info: Mar 24 11:31:01 volumio volumio[3072]: ---------------------------- MPD announces state update: player Mar 24 11:31:01 volumio volumio[3072]: info: ControllerMpd::getState Mar 24 11:31:01 volumio volumio[3072]: verbose: ControllerMpd::sendMpdCommand status Mar 24 11:31:01 volumio volumio[3072]: info: ------------------------------ 40ms Mar 24 11:31:01 volumio volumio[3072]: info: sendMpdCommand status took 38 milliseconds Mar 24 11:31:01 volumio volumio[3072]: info: ------------------------------ 6ms Mar 24 11:31:01 volumio volumio[3072]: verbose: ControllerMpd::parseState Mar 24 11:31:01 volumio volumio[3072]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 11:31:01 volumio volumio[3072]: info: Mar 24 11:31:01 volumio volumio[3072]: ---------------------------- MPD announces system playlist update Mar 24 11:31:01 volumio volumio[3072]: info: Ignoring MPD Status Update Mar 24 11:31:01 volumio volumio[3072]: info: Mar 24 11:31:01 volumio volumio[3072]: ---------------------------- MPD announces state update: player Mar 24 11:31:01 volumio volumio[3072]: info: ControllerMpd::getState Mar 24 11:31:01 volumio volumio[3072]: verbose: ControllerMpd::sendMpdCommand status Mar 24 11:31:01 volumio sudo[9230]: pam_unix(sudo:session): session closed for user root Mar 24 11:31:01 volumio sudo[9232]: pam_unix(sudo:session): session closed for user root Mar 24 11:31:01 volumio volumio[3072]: info: FusionDsp - Volumio is playing Mar 24 11:31:01 volumio volumio[3072]: info: FusionDsp - Volumio is playing Mar 24 11:31:01 volumio volumio[3072]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 24 11:31:01 volumio sudo[9237]: pam_unix(sudo:session): session closed for user root Mar 24 11:31:01 volumio volumio[3072]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 24 11:31:01 volumio volumio[3072]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 24 11:31:01 volumio sudo[9257]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 24 11:31:01 volumio sudo[9257]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 11:31:01 volumio sudo[9259]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 24 11:31:01 volumio sudo[9259]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 11:31:01 volumio volumio[3072]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 24 11:31:01 volumio volumio[3072]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 24 11:31:01 volumio volumio[3072]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Mar 24 11:31:01 volumio volumio[3072]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 24 11:31:01 volumio volumio[3072]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Mar 24 11:31:01 volumio volumio[3072]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 24 11:31:01 volumio volumio[3072]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Mar 24 11:31:01 volumio volumio[3072]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 24 11:31:01 volumio volumio[3072]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Mar 24 11:31:01 volumio volumio[3072]: info: sendMpdCommand status took 117 milliseconds Mar 24 11:31:01 volumio volumio[3072]: info: ------------------------------ 114ms Mar 24 11:31:01 volumio volumio[3072]: info: sendMpdCommand status took 114 milliseconds Mar 24 11:31:01 volumio volumio[3072]: info: sendMpdCommand playlistinfo took 113 milliseconds Mar 24 11:31:01 volumio volumio[3072]: info: ------------------------------ 111ms Mar 24 11:31:01 volumio volumio[3072]: info: sendMpdCommand status took 109 milliseconds Mar 24 11:31:01 volumio volumio[3072]: verbose: ControllerMpd::parseState Mar 24 11:31:01 volumio volumio[3072]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 11:31:01 volumio volumio[3072]: verbose: ControllerMpd::parseState Mar 24 11:31:01 volumio volumio[3072]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 11:31:01 volumio volumio[3072]: verbose: ControllerMpd::parseTrackInfo Mar 24 11:31:01 volumio volumio[3072]: verbose: ControllerMpd::parseState Mar 24 11:31:01 volumio volumio[3072]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 11:31:01 volumio volumio[3072]: info: ControllerMpd::pushState Mar 24 11:31:01 volumio volumio[3072]: info: CoreCommandRouter::servicePushState Mar 24 11:31:01 volumio volumio[3072]: info: CorePlayQueue::getTrack 0 Mar 24 11:31:01 volumio volumio[3072]: verbose: STATE SERVICE {"status":"play","position":0,"seek":974,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"All about Dance from 2000 till today!","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 24 11:31:01 volumio volumio[3072]: verbose: CURRENT POSITION 0 Mar 24 11:31:01 volumio volumio[3072]: info: CoreStateMachine::syncState stateService play Mar 24 11:31:01 volumio volumio[3072]: info: CoreStateMachine::syncState currentStatus play Mar 24 11:31:01 volumio volumio[3072]: info: Received an update from plugin. extracting info from payload Mar 24 11:31:01 volumio volumio[3072]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 24 11:31:01 volumio volumio[3072]: info: CoreStateMachine::pushState Mar 24 11:31:01 volumio volumio[3072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 11:31:01 volumio volumio[3072]: info: CoreCommandRouter::volumioPushState Mar 24 11:31:01 volumio volumio[3072]: info: CoreStateMachine::pushState Mar 24 11:31:01 volumio volumio[3072]: info: CoreCommandRouter::volumioPushState Mar 24 11:31:01 volumio sudo[9263]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 24 11:31:01 volumio sudo[9263]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 11:31:01 volumio sudo[9259]: pam_unix(sudo:session): session closed for user root Mar 24 11:31:01 volumio sudo[9257]: pam_unix(sudo:session): session closed for user root Mar 24 11:31:01 volumio sudo[9247]: pam_unix(sudo:session): session closed for user root Mar 24 11:31:01 volumio sudo[9244]: pam_unix(sudo:session): session closed for user root Mar 24 11:31:01 volumio sudo[9252]: pam_unix(sudo:session): session closed for user root Mar 24 11:31:01 volumio sudo[9241]: pam_unix(sudo:session): session closed for user root Mar 24 11:31:01 volumio sudo[9234]: pam_unix(sudo:session): session closed for user root Mar 24 11:31:01 volumio volumio[3072]: info: ------------------------------ 206ms Mar 24 11:31:01 volumio sudo[9265]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 24 11:31:01 volumio sudo[9265]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 11:31:01 volumio volumio[3072]: info: peppymeterbasic Daemon Started Mar 24 11:31:01 volumio volumio[3072]: info: peppymeterbasic Daemon Started Mar 24 11:31:01 volumio volumio[3072]: info: peppymeterbasic Daemon Started Mar 24 11:31:01 volumio volumio[3072]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 24 11:31:01 volumio volumio[3072]: info: sendMpdCommand playlistinfo took 122 milliseconds Mar 24 11:31:01 volumio volumio[3072]: info: sendMpdCommand playlistinfo took 123 milliseconds Mar 24 11:31:01 volumio volumio[3072]: info: sendMpdCommand playlistinfo took 124 milliseconds Mar 24 11:31:01 volumio volumio[3072]: verbose: ControllerMpd::parseTrackInfo Mar 24 11:31:01 volumio volumio[3072]: verbose: ControllerMpd::parseTrackInfo Mar 24 11:31:01 volumio volumio[3072]: verbose: ControllerMpd::parseTrackInfo Mar 24 11:31:01 volumio volumio[3072]: info: ControllerMpd::pushState Mar 24 11:31:01 volumio volumio[3072]: info: CoreCommandRouter::servicePushState Mar 24 11:31:01 volumio volumio[3072]: info: CorePlayQueue::getTrack 0 Mar 24 11:31:01 volumio volumio[3072]: verbose: STATE SERVICE {"status":"play","position":0,"seek":974,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"All about Dance from 2000 till today!","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 24 11:31:01 volumio volumio[3072]: verbose: CURRENT POSITION 0 Mar 24 11:31:01 volumio volumio[3072]: info: CoreStateMachine::syncState stateService play Mar 24 11:31:01 volumio volumio[3072]: info: CoreStateMachine::syncState currentStatus play Mar 24 11:31:01 volumio volumio[3072]: info: Received an update from plugin. extracting info from payload Mar 24 11:31:01 volumio volumio[3072]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 24 11:31:01 volumio volumio[3072]: info: CoreStateMachine::pushState Mar 24 11:31:01 volumio volumio[3072]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 11:31:01 volumio volumio[3072]: info: CoreCommandRouter::volumioPushState Mar 24 11:31:01 volumio volumio[3072]: info: CoreStateMachine::pushState Mar 24 11:31:01 volumio volumio[3072]: info: CoreCommandRouter::volumioPushState Mar 24 11:31:01 volumio sudo[9263]: pam_unix(sudo:session): session closed for user root Mar 24 11:31:01 volumio volumio[3072]: info: ControllerMpd::pushState Mar 24 11:31:01 volumio volumio[3072]: info: CoreCommandRouter::servicePushState Mar 24 11:31:01 volumio volumio[3072]: info: CorePlayQueue::getTrack 0 Mar 24 11:31:01 volumio volumio[3072]: verbose: STATE SERVICE {"status":"play","position":0,"seek":974,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"All about Dance from 2000 till today!","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 24 11:31:01 volumio volumio[3072]: verbose: CURRENT POSITION 0 Mar 24 11:31:01 volumio volumio[3072]: info: CoreStateMachine::syncState stateService play Mar 24 11:31:01 volumio volumio[3072]: info: CoreStateMachine::syncState currentStatus play Mar 24 11:31:01 volumio volumio[3072]: info: Received an update from plugin. extracting info from payload Mar 24 11:31:01 volumio volumio[3072]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 24 11:31:01 volumio volumio[3072]: info: CoreStateMachine::pushState Mar 24 11:31:01 volumio volumio[3072]: info: CoreCommandRouter::volumioPushState Mar 24 11:31:01 volumio volumio[3072]: info: CoreStateMachine::pushState Mar 24 11:31:01 volumio volumio[3072]: info: CoreCommandRouter::volumioPushState Mar 24 11:31:01 volumio volumio[3072]: info: ControllerMpd::pushState Mar 24 11:31:01 volumio volumio[3072]: info: CoreCommandRouter::servicePushState Mar 24 11:31:01 volumio volumio[3072]: info: CorePlayQueue::getTrack 0 Mar 24 11:31:01 volumio volumio[3072]: verbose: STATE SERVICE {"status":"play","position":0,"seek":974,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"All about Dance from 2000 till today!","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 24 11:31:01 volumio volumio[3072]: verbose: CURRENT POSITION 0 Mar 24 11:31:01 volumio volumio[3072]: info: CoreStateMachine::syncState stateService play Mar 24 11:31:01 volumio volumio[3072]: info: CoreStateMachine::syncState currentStatus play Mar 24 11:31:01 volumio volumio[3072]: info: Received an update from plugin. extracting info from payload Mar 24 11:31:01 volumio volumio[3072]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 24 11:31:01 volumio volumio[3072]: info: CoreStateMachine::pushState Mar 24 11:31:01 volumio volumio[3072]: info: CoreCommandRouter::volumioPushState Mar 24 11:31:01 volumio volumio[3072]: info: CoreStateMachine::pushState Mar 24 11:31:01 volumio volumio[3072]: info: CoreCommandRouter::volumioPushState Mar 24 11:31:01 volumio sudo[9265]: pam_unix(sudo:session): session closed for user root Mar 24 11:31:01 volumio volumio[3072]: info: ------------------------------ 286ms Mar 24 11:31:01 volumio volumio[3072]: info: ------------------------------ 283ms Mar 24 11:31:01 volumio volumio[3072]: info: ------------------------------ 277ms Mar 24 11:31:01 volumio volumio[3072]: info: FusionDsp - Volumio is playing Mar 24 11:31:01 volumio volumio[3072]: info: FusionDsp - Volumio is playing Mar 24 11:31:01 volumio volumio[3072]: info: FusionDsp - Volumio is playing Mar 24 11:31:01 volumio volumio[3072]: info: FusionDsp - Volumio is playing Mar 24 11:31:01 volumio volumio[3072]: info: FusionDsp - Volumio is playing Mar 24 11:31:01 volumio volumio[3072]: info: FusionDsp - Volumio is playing Mar 24 11:31:01 volumio volumio[3072]: info: FusionDsp - Volumio is playing Mar 24 11:31:01 volumio volumio[3072]: info: FusionDsp - Volumio is playing Mar 24 11:31:01 volumio volumio[3072]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 24 11:31:01 volumio volumio[3072]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 24 11:31:01 volumio volumio[3072]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 24 11:31:01 volumio sudo[9269]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 24 11:31:01 volumio volumio[3072]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 24 11:31:01 volumio sudo[9269]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 11:31:01 volumio sudo[9271]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 24 11:31:01 volumio sudo[9271]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 11:31:01 volumio volumio[3072]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 24 11:31:01 volumio sudo[9273]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 24 11:31:01 volumio sudo[9273]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 11:31:01 volumio sudo[9269]: pam_unix(sudo:session): session closed for user root Mar 24 11:31:01 volumio volumio[3072]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 24 11:31:01 volumio sudo[9280]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 24 11:31:01 volumio sudo[9280]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 11:31:01 volumio volumio[3072]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 24 11:31:01 volumio sudo[9283]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 24 11:31:01 volumio sudo[9283]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 11:31:01 volumio volumio[3072]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 24 11:31:01 volumio sudo[9271]: pam_unix(sudo:session): session closed for user root Mar 24 11:31:01 volumio sudo[9273]: pam_unix(sudo:session): session closed for user root Mar 24 11:31:01 volumio sudo[9280]: pam_unix(sudo:session): session closed for user root Mar 24 11:31:01 volumio volumio[3072]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 24 11:31:01 volumio sudo[9277]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 24 11:31:01 volumio sudo[9277]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 11:31:01 volumio sudo[9283]: pam_unix(sudo:session): session closed for user root Mar 24 11:31:01 volumio volumio[3072]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 24 11:31:01 volumio sudo[9288]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 24 11:31:01 volumio sudo[9288]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 11:31:01 volumio sudo[9286]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 24 11:31:01 volumio sudo[9286]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 11:31:01 volumio volumio[3072]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 24 11:31:01 volumio volumio[3072]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 24 11:31:01 volumio sudo[9295]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 24 11:31:01 volumio sudo[9295]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 11:31:01 volumio sudo[9288]: pam_unix(sudo:session): session closed for user root Mar 24 11:31:01 volumio volumio[3072]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 24 11:31:01 volumio sudo[9277]: pam_unix(sudo:session): session closed for user root Mar 24 11:31:01 volumio sudo[9291]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 24 11:31:01 volumio sudo[9291]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 11:31:01 volumio volumio[3072]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 24 11:31:02 volumio sudo[9297]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 24 11:31:02 volumio sudo[9297]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 11:31:02 volumio sudo[9300]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 24 11:31:02 volumio sudo[9300]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 11:31:02 volumio sudo[9295]: pam_unix(sudo:session): session closed for user root Mar 24 11:31:02 volumio sudo[9286]: pam_unix(sudo:session): session closed for user root Mar 24 11:31:02 volumio volumio[3072]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 24 11:31:02 volumio sudo[9291]: pam_unix(sudo:session): session closed for user root Mar 24 11:31:02 volumio sudo[9303]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 24 11:31:02 volumio sudo[9303]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 11:31:02 volumio volumio[3072]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 24 11:31:02 volumio sudo[9307]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 24 11:31:02 volumio sudo[9307]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 11:31:02 volumio volumio[3072]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 24 11:31:02 volumio volumio[3072]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Mar 24 11:31:02 volumio volumio[3072]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 24 11:31:02 volumio volumio[3072]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Mar 24 11:31:02 volumio volumio[3072]: info: peppymeterbasic Daemon Started Mar 24 11:31:02 volumio volumio[3072]: info: peppymeterbasic Daemon Started Mar 24 11:31:02 volumio volumio[3072]: info: peppymeterbasic Daemon Started Mar 24 11:31:02 volumio volumio[3072]: info: peppymeterbasic Daemon Started Mar 24 11:31:02 volumio volumio[3072]: info: peppymeterbasic Daemon Started Mar 24 11:31:02 volumio volumio[3072]: info: peppymeterbasic Daemon Started Mar 24 11:31:02 volumio volumio[3072]: info: peppymeterbasic Daemon Started Mar 24 11:31:02 volumio sudo[9311]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 24 11:31:02 volumio sudo[9311]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 11:31:02 volumio sudo[9313]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 24 11:31:02 volumio sudo[9313]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 11:31:02 volumio sudo[9297]: pam_unix(sudo:session): session closed for user root Mar 24 11:31:02 volumio sudo[9307]: pam_unix(sudo:session): session closed for user root Mar 24 11:31:02 volumio sudo[9300]: pam_unix(sudo:session): session closed for user root Mar 24 11:31:02 volumio volumio[3072]: error: FusionDsp - Reload WebSocket error: [object Object] Mar 24 11:31:02 volumio sudo[9303]: pam_unix(sudo:session): session closed for user root Mar 24 11:31:02 volumio volumio[3072]: info: peppymeterbasic Daemon Started Mar 24 11:31:02 volumio volumio[3072]: info: peppymeterbasic Daemon Started Mar 24 11:31:02 volumio volumio[3072]: info: peppymeterbasic Daemon Started Mar 24 11:31:02 volumio volumio[3072]: info: peppymeterbasic Daemon Started Mar 24 11:31:02 volumio volumio[3072]: info: peppymeterbasic Daemon Started Mar 24 11:31:02 volumio volumio[3072]: info: peppymeterbasic Daemon Started Mar 24 11:31:02 volumio volumio[3072]: info: peppymeterbasic Daemon Started Mar 24 11:31:02 volumio volumio[3072]: info: peppymeterbasic Daemon Started Mar 24 11:31:02 volumio volumio[3072]: info: peppymeterbasic Daemon Started Mar 24 11:31:02 volumio volumio[3072]: info: peppymeterbasic Daemon Started Mar 24 11:31:02 volumio volumio[3072]: info: peppymeterbasic Daemon Started Mar 24 11:31:02 volumio volumio[3072]: info: peppymeterbasic Daemon Started Mar 24 11:31:02 volumio sudo[9313]: pam_unix(sudo:session): session closed for user root Mar 24 11:31:02 volumio sudo[9311]: pam_unix(sudo:session): session closed for user root Mar 24 11:31:02 volumio volumio[3072]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 24 11:31:02 volumio volumio[3072]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Mar 24 11:31:02 volumio volumio[3072]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 24 11:31:02 volumio volumio[3072]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Mar 24 11:31:02 volumio volumio[3072]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 24 11:31:02 volumio volumio[3072]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Mar 24 11:31:02 volumio volumio[3072]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 24 11:31:02 volumio volumio[3072]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Mar 24 11:31:02 volumio volumio[3072]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 24 11:31:02 volumio volumio[3072]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Mar 24 11:31:02 volumio volumio[3072]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 24 11:31:02 volumio volumio[3072]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Mar 24 11:31:02 volumio volumio[3072]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 24 11:31:02 volumio volumio[3072]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Mar 24 11:31:02 volumio volumio[3072]: error: FusionDsp - Monitor WebSocket error: [object Object] Mar 24 11:31:02 volumio volumio[3072]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms Mar 24 11:31:02 volumio volumio[3072]: info: peppymeterbasic Daemon Started Mar 24 11:31:02 volumio volumio[3072]: info: peppymeterbasic Daemon Started Mar 24 11:31:02 volumio volumio[3072]: info: peppymeterbasic Daemon Started Mar 24 11:31:02 volumio volumio[3072]: info: peppymeterbasic Daemon Started Mar 24 11:31:02 volumio volumio[3072]: info: peppymeterbasic Daemon Started Mar 24 11:31:02 volumio volumio[3072]: info: peppymeterbasic Daemon Started Mar 24 11:31:03 volumio volumio[9255]: libpng warning: iCCP: known incorrect sRGB profile Mar 24 11:31:03 volumio volumio[9255]: libpng warning: iCCP: known incorrect sRGB profile Mar 24 11:31:03 volumio volumio[3072]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 24 11:31:03 volumio volumio[3072]: TypeError: Cannot read properties of null (reading 'slice') Mar 24 11:31:03 volumio volumio[3072]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Mar 24 11:31:03 volumio volumio[3072]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Mar 24 11:31:03 volumio volumio[3072]: at Object.onceWrapper (node:events:629:26) Mar 24 11:31:03 volumio volumio[3072]: at ClientRequest.emit (node:events:514:28) Mar 24 11:31:03 volumio volumio[3072]: at HTTPParser.parserOnIncomingClient [as onIncoming] (node:_http_client:693:27) Mar 24 11:31:03 volumio volumio[3072]: at HTTPParser.parserOnHeadersComplete (node:_http_common:119:17) Mar 24 11:31:03 volumio volumio[3072]: at TLSSocket.socketOnData (node:_http_client:535:22) Mar 24 11:31:03 volumio volumio[3072]: at TLSSocket.emit (node:events:514:28) Mar 24 11:31:03 volumio volumio[3072]: at addChunk (node:internal/streams/readable:343:12) Mar 24 11:31:03 volumio volumio[3072]: at readableAddChunk (node:internal/streams/readable:316:9) Mar 24 11:31:03 volumio volumio[3072]: at Readable.push (node:internal/streams/readable:253:10) Mar 24 11:31:03 volumio volumio[3072]: at TLSWrap.onStreamRead (node:internal/stream_base_commons:190:23) Mar 24 11:31:03 volumio volumio[3072]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 24 11:31:04 volumio sudo[9335]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-24 11:30' Mar 24 11:31:04 volumio sudo[9335]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="dc61260dec5515dafd2b634881860b4c46c919ff" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Mar 6 16:46:58 UTC 2026" VOLUMIO_VERSION="4.103" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="aa741395b8bfc648ff5d04e312f53d2c"