-- Logs begin at Wed 2026-05-06 23:58:03 PDT, end at Mon 2026-05-25 22:49:47 PDT. -- May 25 22:48:05 allo-signature kernel: hwmon hwmon1: Voltage normalised May 25 22:48:06 allo-signature volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 25 22:48:06 allo-signature volumio[1048]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 25 22:48:06 allo-signature volumio[1048]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 25 22:48:06 allo-signature volumio[1048]: info: Received Get System Version May 25 22:48:06 allo-signature volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 22:48:06 allo-signature volumio[1048]: info: Received Get System Info May 25 22:48:06 allo-signature volumio[1048]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 22:48:06 allo-signature volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 22:48:06 allo-signature volumio[1048]: info: Discovery: Getting this device information May 25 22:48:06 allo-signature volumio[1048]: info: CoreCommandRouter::volumioGetState May 25 22:48:06 allo-signature volumio[1048]: info: CorePlayQueue::getTrack 0 May 25 22:48:06 allo-signature volumio[1048]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 22:48:08 allo-signature volumio[1048]: Searching plugin music_service/qobuz May 25 22:48:08 allo-signature volumio[1048]: info: CoreCommandRouter::executeOnPlugin: qobuz , search May 25 22:48:09 allo-signature kernel: hwmon hwmon1: Undervoltage detected! May 25 22:48:09 allo-signature volumio[1048]: info: searchQOBUZUri took 912 milliseconds May 25 22:48:09 allo-signature volumio[1048]: info: All search sources collected, pushing search results May 25 22:48:09 allo-signature volumio[1048]: Searching plugin music_service/qobuz May 25 22:48:09 allo-signature volumio[1048]: info: CoreCommandRouter::executeOnPlugin: qobuz , search May 25 22:48:10 allo-signature volumio[1048]: info: searchQOBUZUri took 925 milliseconds May 25 22:48:10 allo-signature volumio[1048]: info: All search sources collected, pushing search results May 25 22:48:17 allo-signature kernel: hwmon hwmon1: Voltage normalised May 25 22:48:19 allo-signature kernel: hwmon hwmon1: Undervoltage detected! May 25 22:48:26 allo-signature kernel: hwmon hwmon1: Voltage normalised May 25 22:48:30 allo-signature kernel: hwmon hwmon1: Undervoltage detected! May 25 22:48:36 allo-signature kernel: hwmon hwmon1: Voltage normalised May 25 22:48:40 allo-signature kernel: hwmon hwmon1: Undervoltage detected! May 25 22:48:41 allo-signature volumio[1048]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri May 25 22:48:41 allo-signature volumio[1048]: info: handleBrowseUri took 477 milliseconds May 25 22:48:42 allo-signature volumio[1048]: info: Preload queue cleared May 25 22:48:42 allo-signature volumio[1048]: info: Preloading song: qobuz://song/326763782 May 25 22:48:42 allo-signature volumio[1048]: info: Preloading song: qobuz://song/326763783 May 25 22:48:42 allo-signature volumio[1048]: info: Preloading song: qobuz://song/326763784 May 25 22:48:42 allo-signature volumio[1048]: info: Preloading song: qobuz://song/326763785 May 25 22:48:42 allo-signature volumio[1048]: info: Preloading song: qobuz://song/326763786 May 25 22:48:42 allo-signature volumio[1048]: info: Preloading song: qobuz://song/326763787 May 25 22:48:42 allo-signature volumio[1048]: info: Preloading song: qobuz://song/326763788 May 25 22:48:42 allo-signature volumio[1048]: info: Preloading song: qobuz://song/326763789 May 25 22:48:42 allo-signature volumio[1048]: info: Preloading song: qobuz://song/326763790 May 25 22:48:42 allo-signature volumio[1048]: info: Preloading song: qobuz://song/326763791 May 25 22:48:42 allo-signature volumio[1048]: info: Preloading song: qobuz://song/326763792 May 25 22:48:42 allo-signature volumio[1048]: info: Preloading song: qobuz://song/122407632 May 25 22:48:42 allo-signature volumio[1048]: info: Preloading song: qobuz://song/122407633 May 25 22:48:42 allo-signature volumio[1048]: info: Preloading song: qobuz://song/122407634 May 25 22:48:42 allo-signature volumio[1048]: info: Preloading song: qobuz://song/122407635 May 25 22:48:42 allo-signature volumio[1048]: info: Preloading song: qobuz://song/122407636 May 25 22:48:42 allo-signature volumio[1048]: info: Preloading song: qobuz://song/122407637 May 25 22:48:42 allo-signature volumio[1048]: info: Preloading song: qobuz://song/122407638 May 25 22:48:42 allo-signature volumio[1048]: info: Preloading song: qobuz://song/122407639 May 25 22:48:42 allo-signature volumio[1048]: info: Preloading song: qobuz://song/122407640 May 25 22:48:42 allo-signature volumio[1048]: info: Preloading song: qobuz://song/122407641 May 25 22:48:42 allo-signature volumio[1048]: info: Preloading song: qobuz://song/122407642 May 25 22:48:42 allo-signature volumio[1048]: info: Preloading song: qobuz://song/122407643 May 25 22:48:42 allo-signature volumio[1048]: info: Preloading song: qobuz://song/122407644 May 25 22:48:42 allo-signature volumio[1048]: info: Preloading song: qobuz://song/122407645 May 25 22:48:42 allo-signature volumio[1048]: info: Preloading song: qobuz://song/122407646 May 25 22:48:42 allo-signature volumio[1048]: info: Preloading song: qobuz://song/122528839 May 25 22:48:42 allo-signature volumio[1048]: info: Preloading song: qobuz://song/326763623 May 25 22:48:42 allo-signature volumio[1048]: info: Preloading song: qobuz://song/360049040 May 25 22:48:42 allo-signature volumio[1048]: info: Exploding uri qobuz://song/326763782 in service qobuz May 25 22:48:42 allo-signature volumio[1048]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 25 22:48:42 allo-signature volumio[1048]: info: Exploding uri qobuz://song/326763783 in service qobuz May 25 22:48:42 allo-signature volumio[1048]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 25 22:48:42 allo-signature volumio[1048]: info: Exploding uri qobuz://song/326763784 in service qobuz May 25 22:48:42 allo-signature volumio[1048]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 25 22:48:42 allo-signature volumio[1048]: info: Exploding uri qobuz://song/326763785 in service qobuz May 25 22:48:42 allo-signature volumio[1048]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 25 22:48:42 allo-signature volumio[1048]: info: Exploding uri qobuz://song/326763786 in service qobuz May 25 22:48:42 allo-signature volumio[1048]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 25 22:48:42 allo-signature volumio[1048]: info: Exploding uri qobuz://song/326763787 in service qobuz May 25 22:48:42 allo-signature volumio[1048]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 25 22:48:42 allo-signature volumio[1048]: info: Exploding uri qobuz://song/326763788 in service qobuz May 25 22:48:42 allo-signature volumio[1048]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 25 22:48:42 allo-signature volumio[1048]: info: Exploding uri qobuz://song/326763789 in service qobuz May 25 22:48:42 allo-signature volumio[1048]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 25 22:48:42 allo-signature volumio[1048]: info: explodeUri took 369 milliseconds May 25 22:48:42 allo-signature volumio[1048]: info: Exploding uri qobuz://song/326763790 in service qobuz May 25 22:48:42 allo-signature volumio[1048]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 25 22:48:42 allo-signature volumio[1048]: info: Exploding uri qobuz://song/326763791 in service qobuz May 25 22:48:42 allo-signature volumio[1048]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 25 22:48:42 allo-signature volumio[1048]: info: explodeUri took 446 milliseconds May 25 22:48:42 allo-signature volumio[1048]: info: Exploding uri qobuz://song/326763792 in service qobuz May 25 22:48:42 allo-signature volumio[1048]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 25 22:48:42 allo-signature volumio[1048]: info: explodeUri took 451 milliseconds May 25 22:48:42 allo-signature volumio[1048]: info: Exploding uri qobuz://song/122407632 in service qobuz May 25 22:48:42 allo-signature volumio[1048]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 25 22:48:42 allo-signature volumio[1048]: info: Exploding uri qobuz://song/122407633 in service qobuz May 25 22:48:42 allo-signature volumio[1048]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 25 22:48:42 allo-signature volumio[1048]: info: explodeUri took 459 milliseconds May 25 22:48:42 allo-signature volumio[1048]: info: explodeUri took 422 milliseconds May 25 22:48:42 allo-signature volumio[1048]: info: Exploding uri qobuz://song/122407634 in service qobuz May 25 22:48:42 allo-signature volumio[1048]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 25 22:48:42 allo-signature volumio[1048]: info: explodeUri took 417 milliseconds May 25 22:48:42 allo-signature volumio[1048]: info: Exploding uri qobuz://song/122407635 in service qobuz May 25 22:48:42 allo-signature volumio[1048]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 25 22:48:42 allo-signature volumio[1048]: info: explodeUri took 374 milliseconds May 25 22:48:42 allo-signature volumio[1048]: info: explodeUri took 434 milliseconds May 25 22:48:42 allo-signature volumio[1048]: info: Exploding uri qobuz://song/122407636 in service qobuz May 25 22:48:42 allo-signature volumio[1048]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 25 22:48:42 allo-signature volumio[1048]: info: Exploding uri qobuz://song/122407637 in service qobuz May 25 22:48:42 allo-signature volumio[1048]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 25 22:48:42 allo-signature volumio[1048]: info: explodeUri took 426 milliseconds May 25 22:48:42 allo-signature volumio[1048]: info: Exploding uri qobuz://song/122407638 in service qobuz May 25 22:48:42 allo-signature volumio[1048]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 25 22:48:42 allo-signature volumio[1048]: info: explodeUri took 426 milliseconds May 25 22:48:42 allo-signature volumio[1048]: info: Exploding uri qobuz://song/122407639 in service qobuz May 25 22:48:42 allo-signature volumio[1048]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 25 22:48:42 allo-signature volumio[1048]: info: explodeUri took 417 milliseconds May 25 22:48:43 allo-signature volumio[1048]: info: explodeUri took 389 milliseconds May 25 22:48:43 allo-signature volumio[1048]: info: Exploding uri qobuz://song/122407640 in service qobuz May 25 22:48:43 allo-signature volumio[1048]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 25 22:48:43 allo-signature volumio[1048]: info: explodeUri took 379 milliseconds May 25 22:48:43 allo-signature volumio[1048]: info: Exploding uri qobuz://song/122407641 in service qobuz May 25 22:48:43 allo-signature volumio[1048]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 25 22:48:43 allo-signature volumio[1048]: info: explodeUri took 395 milliseconds May 25 22:48:43 allo-signature volumio[1048]: info: Exploding uri qobuz://song/122407642 in service qobuz May 25 22:48:43 allo-signature volumio[1048]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 25 22:48:43 allo-signature volumio[1048]: info: Exploding uri qobuz://song/122407643 in service qobuz May 25 22:48:43 allo-signature volumio[1048]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 25 22:48:43 allo-signature volumio[1048]: info: Exploding uri qobuz://song/122407644 in service qobuz May 25 22:48:43 allo-signature volumio[1048]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 25 22:48:43 allo-signature volumio[1048]: info: explodeUri took 458 milliseconds May 25 22:48:43 allo-signature volumio[1048]: info: explodeUri took 413 milliseconds May 25 22:48:43 allo-signature volumio[1048]: info: Exploding uri qobuz://song/122407645 in service qobuz May 25 22:48:43 allo-signature volumio[1048]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 25 22:48:43 allo-signature volumio[1048]: info: explodeUri took 446 milliseconds May 25 22:48:43 allo-signature volumio[1048]: info: Exploding uri qobuz://song/122407646 in service qobuz May 25 22:48:43 allo-signature volumio[1048]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 25 22:48:43 allo-signature volumio[1048]: info: explodeUri took 442 milliseconds May 25 22:48:43 allo-signature volumio[1048]: info: Exploding uri qobuz://song/122528839 in service qobuz May 25 22:48:43 allo-signature volumio[1048]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 25 22:48:43 allo-signature volumio[1048]: info: Exploding uri qobuz://song/326763623 in service qobuz May 25 22:48:43 allo-signature volumio[1048]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 25 22:48:43 allo-signature volumio[1048]: info: explodeUri took 458 milliseconds May 25 22:48:43 allo-signature volumio[1048]: info: Exploding uri qobuz://song/360049040 in service qobuz May 25 22:48:43 allo-signature volumio[1048]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 25 22:48:43 allo-signature volumio[1048]: info: explodeUri took 454 milliseconds May 25 22:48:43 allo-signature volumio[1048]: info: explodeUri took 438 milliseconds May 25 22:48:43 allo-signature volumio[1048]: info: explodeUri took 397 milliseconds May 25 22:48:43 allo-signature volumio[1048]: info: explodeUri took 430 milliseconds May 25 22:48:43 allo-signature volumio[1048]: info: explodeUri took 414 milliseconds May 25 22:48:43 allo-signature volumio[1048]: info: explodeUri took 424 milliseconds May 25 22:48:43 allo-signature volumio[1048]: info: explodeUri took 411 milliseconds May 25 22:48:43 allo-signature volumio[1048]: info: explodeUri took 433 milliseconds May 25 22:48:43 allo-signature volumio[1048]: info: explodeUri took 486 milliseconds May 25 22:48:43 allo-signature volumio[1048]: info: explodeUri took 407 milliseconds May 25 22:48:48 allo-signature kernel: hwmon hwmon1: Voltage normalised May 25 22:48:51 allo-signature kernel: hwmon hwmon1: Undervoltage detected! May 25 22:48:56 allo-signature volumiologrotate[680]: ls: cannot access '/var/log/samba/log.wb-ALLO': No such file or directory May 25 22:48:56 allo-signature volumiologrotate[680]: ls: cannot access 'SIGNATURE': No such file or directory May 25 22:49:03 allo-signature kernel: hwmon hwmon1: Voltage normalised May 25 22:49:07 allo-signature kernel: hwmon hwmon1: Undervoltage detected! May 25 22:49:13 allo-signature kernel: hwmon hwmon1: Voltage normalised May 25 22:49:16 allo-signature volumio[1048]: info: Preload queue cleared May 25 22:49:16 allo-signature volumio[1048]: info: CoreCommandRouter::volumioReplaceandPlayItems May 25 22:49:16 allo-signature volumio[1048]: info: CoreStateMachine::ClearQueue May 25 22:49:16 allo-signature volumio[1048]: info: CoreStateMachine::stop May 25 22:49:16 allo-signature volumio[1048]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 22:49:16 allo-signature volumio[1048]: info: CorePlayQueue::clearPlayQueue May 25 22:49:16 allo-signature volumio[1048]: info: CorePlayQueue::saveQueue May 25 22:49:16 allo-signature volumio[1048]: info: CoreCommandRouter::volumioPushQueue May 25 22:49:16 allo-signature volumio[1048]: info: CoreStateMachine::addQueueItems May 25 22:49:16 allo-signature volumio[1048]: info: CorePlayQueue::addQueueItems May 25 22:49:16 allo-signature volumio[1048]: info: Preload queue cleared May 25 22:49:16 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763782 May 25 22:49:16 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763782 May 25 22:49:16 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763783 May 25 22:49:16 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763783 May 25 22:49:16 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763784 May 25 22:49:16 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763784 May 25 22:49:16 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763785 May 25 22:49:16 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763785 May 25 22:49:16 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763786 May 25 22:49:16 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763786 May 25 22:49:16 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763787 May 25 22:49:16 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763787 May 25 22:49:16 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763788 May 25 22:49:16 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763788 May 25 22:49:16 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763789 May 25 22:49:16 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763789 May 25 22:49:16 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763790 May 25 22:49:16 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763790 May 25 22:49:16 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763791 May 25 22:49:16 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763791 May 25 22:49:16 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763792 May 25 22:49:16 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763792 May 25 22:49:16 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407632 May 25 22:49:16 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407632 May 25 22:49:16 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407633 May 25 22:49:16 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407633 May 25 22:49:16 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407634 May 25 22:49:16 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407634 May 25 22:49:16 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407635 May 25 22:49:16 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407635 May 25 22:49:16 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407636 May 25 22:49:16 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407636 May 25 22:49:16 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407637 May 25 22:49:16 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407637 May 25 22:49:16 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407638 May 25 22:49:16 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407638 May 25 22:49:16 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407639 May 25 22:49:16 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407639 May 25 22:49:16 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407640 May 25 22:49:16 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407640 May 25 22:49:16 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407641 May 25 22:49:16 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407641 May 25 22:49:16 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407642 May 25 22:49:16 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407642 May 25 22:49:16 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407643 May 25 22:49:16 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407643 May 25 22:49:16 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407644 May 25 22:49:16 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407644 May 25 22:49:16 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407645 May 25 22:49:16 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407645 May 25 22:49:16 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407646 May 25 22:49:16 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407646 May 25 22:49:16 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122528839 May 25 22:49:16 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122528839 May 25 22:49:16 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763623 May 25 22:49:16 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763623 May 25 22:49:16 allo-signature volumio[1048]: info: CoreCommandRouter::volumioPushQueue May 25 22:49:16 allo-signature volumio[1048]: info: CorePlayQueue::saveQueue May 25 22:49:16 allo-signature volumio[1048]: info: CoreStateMachine::updateTrackBlock May 25 22:49:16 allo-signature volumio[1048]: info: CorePlayQueue::getTrackBlock May 25 22:49:16 allo-signature volumio[1048]: info: CoreCommandRouter::volumioPlay May 25 22:49:16 allo-signature volumio[1048]: info: CoreStateMachine::play index 27 May 25 22:49:16 allo-signature volumio[1048]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 22:49:16 allo-signature volumio[1048]: info: CoreStateMachine::addQueueItems May 25 22:49:16 allo-signature volumio[1048]: info: CorePlayQueue::addQueueItems May 25 22:49:16 allo-signature volumio[1048]: info: Preload queue cleared May 25 22:49:16 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/360049040 May 25 22:49:16 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/360049040 May 25 22:49:16 allo-signature volumio[1048]: info: CoreStateMachine::stop May 25 22:49:16 allo-signature volumio[1048]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 22:49:16 allo-signature volumio[1048]: info: CoreCommandRouter::volumioPushQueue May 25 22:49:16 allo-signature volumio[1048]: info: CorePlayQueue::saveQueue May 25 22:49:16 allo-signature volumio[1048]: info: CoreStateMachine::play index undefined May 25 22:49:16 allo-signature volumio[1048]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 22:49:16 allo-signature volumio[1048]: info: CoreStateMachine::updateTrackBlock May 25 22:49:16 allo-signature volumio[1048]: info: CorePlayQueue::getTrackBlock May 25 22:49:16 allo-signature volumio[1048]: info: CorePlayQueue::getTrack 27 May 25 22:49:16 allo-signature volumio[1048]: info: CoreStateMachine::startPlaybackTimer May 25 22:49:16 allo-signature volumio[1048]: info: CorePlayQueue::getTrack 27 May 25 22:49:16 allo-signature volumio[1048]: info: [1779774556305] ControllerQobuz::clearAddPlayTrack May 25 22:49:16 allo-signature volumio[1048]: verbose: ControllerMpd::sendMpdCommand stop May 25 22:49:16 allo-signature volumio[1048]: info: sendMpdCommand stop took 7 milliseconds May 25 22:49:16 allo-signature volumio[1048]: verbose: ControllerMpd::sendMpdCommand clear May 25 22:49:16 allo-signature volumio[1048]: info: May 25 22:49:16 allo-signature volumio[1048]: ---------------------------- MPD announces system playlist update May 25 22:49:16 allo-signature volumio[1048]: info: Ignoring MPD Status Update May 25 22:49:16 allo-signature volumio[1048]: info: sendMpdCommand clear took 2 milliseconds May 25 22:49:16 allo-signature volumio[1048]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/326763623" May 25 22:49:16 allo-signature volumio[1048]: error: updateQueue error: null May 25 22:49:16 allo-signature volumio[1048]: info: ------------------------------ 4ms May 25 22:49:16 allo-signature volumio[1048]: STREAMING PROXY: Handling url /?data=qobuz://song/326763623 May 25 22:49:16 allo-signature volumio[1048]: info: Executing endpoint getStreamUrlqobuz May 25 22:49:16 allo-signature volumio[1048]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI May 25 22:49:16 allo-signature volumio[1048]: info: getStreamUrl took 344 milliseconds May 25 22:49:16 allo-signature volumio[1048]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=3484260&eid=326763623&fmt=6&profile=raw&app_id=539451548&cid=2490321&etsp=1779778156&hmac=-O4BYkWuA_8c7uTT-02DqNvjARI May 25 22:49:16 allo-signature volumio[1048]: STREAMING PROXY: Requesting upstream with range: none May 25 22:49:17 allo-signature volumio[1048]: STREAMING PROXY: Upstream status code: 200 May 25 22:49:17 allo-signature volumio[1048]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/326763623" May 25 22:49:17 allo-signature volumio[1048]: STREAMING PROXY: Client closed connection May 25 22:49:17 allo-signature volumio[1048]: info: May 25 22:49:17 allo-signature volumio[1048]: ---------------------------- MPD announces system playlist update May 25 22:49:17 allo-signature volumio[1048]: info: Ignoring MPD Status Update May 25 22:49:17 allo-signature volumio[1048]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/326763623" took 3 milliseconds May 25 22:49:17 allo-signature volumio[1048]: info: CoreStateMachine::setConsumeUpdateService mpd May 25 22:49:17 allo-signature volumio[1048]: verbose: ControllerMpd::sendMpdCommand play May 25 22:49:17 allo-signature volumio[1048]: STREAMING PROXY: Upstream closed. Received 16384 bytes May 25 22:49:17 allo-signature volumio[1048]: STREAMING PROXY: Incomplete transfer. Expected 21665038, got 16384 May 25 22:49:17 allo-signature volumio[1048]: STREAMING PROXY: Retrying with range: bytes=16384- (attempt 1) May 25 22:49:17 allo-signature volumio[1048]: info: ------------------------------ 7ms May 25 22:49:17 allo-signature volumio[1048]: info: sendMpdCommand play took 5 milliseconds May 25 22:49:17 allo-signature volumio[1048]: STREAMING PROXY: Handling url /?data=qobuz://song/326763623 May 25 22:49:17 allo-signature volumio[1048]: info: Executing endpoint getStreamUrlqobuz May 25 22:49:17 allo-signature volumio[1048]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI May 25 22:49:18 allo-signature kernel: hwmon hwmon1: Undervoltage detected! May 25 22:49:18 allo-signature volumio[1048]: info: Preload queue cleared May 25 22:49:18 allo-signature volumio[1048]: info: CoreCommandRouter::volumioReplaceandPlayItems May 25 22:49:18 allo-signature volumio[1048]: info: CoreStateMachine::ClearQueue May 25 22:49:18 allo-signature volumio[1048]: info: CoreStateMachine::stop May 25 22:49:18 allo-signature volumio[1048]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 22:49:18 allo-signature volumio[1048]: info: CorePlayQueue::clearPlayQueue May 25 22:49:18 allo-signature volumio[1048]: info: CorePlayQueue::saveQueue May 25 22:49:18 allo-signature volumio[1048]: info: CoreCommandRouter::volumioPushQueue May 25 22:49:18 allo-signature volumio[1048]: info: CoreStateMachine::addQueueItems May 25 22:49:18 allo-signature volumio[1048]: info: CorePlayQueue::addQueueItems May 25 22:49:18 allo-signature volumio[1048]: info: Preload queue cleared May 25 22:49:18 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763782 May 25 22:49:18 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763782 May 25 22:49:18 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763783 May 25 22:49:18 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763783 May 25 22:49:18 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763784 May 25 22:49:18 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763784 May 25 22:49:18 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763785 May 25 22:49:18 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763785 May 25 22:49:18 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763786 May 25 22:49:18 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763786 May 25 22:49:18 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763787 May 25 22:49:18 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763787 May 25 22:49:18 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763788 May 25 22:49:18 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763788 May 25 22:49:18 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763789 May 25 22:49:18 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763789 May 25 22:49:18 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763790 May 25 22:49:18 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763790 May 25 22:49:18 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763791 May 25 22:49:18 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763791 May 25 22:49:18 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763792 May 25 22:49:18 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763792 May 25 22:49:18 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407632 May 25 22:49:18 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407632 May 25 22:49:18 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407633 May 25 22:49:18 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407633 May 25 22:49:18 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407634 May 25 22:49:18 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407634 May 25 22:49:18 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407635 May 25 22:49:18 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407635 May 25 22:49:18 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407636 May 25 22:49:18 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407636 May 25 22:49:18 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407637 May 25 22:49:18 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407637 May 25 22:49:18 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407638 May 25 22:49:18 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407638 May 25 22:49:18 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407639 May 25 22:49:18 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407639 May 25 22:49:18 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407640 May 25 22:49:18 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407640 May 25 22:49:18 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407641 May 25 22:49:18 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407641 May 25 22:49:18 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407642 May 25 22:49:18 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407642 May 25 22:49:18 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407643 May 25 22:49:18 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407643 May 25 22:49:18 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407644 May 25 22:49:18 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407644 May 25 22:49:18 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407645 May 25 22:49:18 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407645 May 25 22:49:18 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407646 May 25 22:49:18 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407646 May 25 22:49:18 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122528839 May 25 22:49:18 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122528839 May 25 22:49:18 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763623 May 25 22:49:18 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763623 May 25 22:49:18 allo-signature volumio[1048]: info: CoreCommandRouter::volumioPushQueue May 25 22:49:18 allo-signature volumio[1048]: info: CorePlayQueue::saveQueue May 25 22:49:18 allo-signature volumio[1048]: info: CoreStateMachine::updateTrackBlock May 25 22:49:18 allo-signature volumio[1048]: info: CorePlayQueue::getTrackBlock May 25 22:49:18 allo-signature volumio[1048]: info: CoreCommandRouter::volumioPlay May 25 22:49:18 allo-signature volumio[1048]: info: CoreStateMachine::play index 27 May 25 22:49:18 allo-signature volumio[1048]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 22:49:18 allo-signature volumio[1048]: info: CoreStateMachine::addQueueItems May 25 22:49:18 allo-signature volumio[1048]: info: CorePlayQueue::addQueueItems May 25 22:49:18 allo-signature volumio[1048]: info: Preload queue cleared May 25 22:49:18 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/360049040 May 25 22:49:18 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/360049040 May 25 22:49:18 allo-signature volumio[1048]: info: CoreStateMachine::stop May 25 22:49:18 allo-signature volumio[1048]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 22:49:18 allo-signature volumio[1048]: info: CoreCommandRouter::volumioPushQueue May 25 22:49:18 allo-signature volumio[1048]: info: CorePlayQueue::saveQueue May 25 22:49:18 allo-signature volumio[1048]: info: CoreStateMachine::play index undefined May 25 22:49:18 allo-signature volumio[1048]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 22:49:18 allo-signature volumio[1048]: info: CoreStateMachine::updateTrackBlock May 25 22:49:18 allo-signature volumio[1048]: info: CorePlayQueue::getTrackBlock May 25 22:49:18 allo-signature volumio[1048]: info: CorePlayQueue::getTrack 27 May 25 22:49:18 allo-signature volumio[1048]: info: CoreStateMachine::startPlaybackTimer May 25 22:49:18 allo-signature volumio[1048]: info: CorePlayQueue::getTrack 27 May 25 22:49:18 allo-signature volumio[1048]: info: [1779774558333] ControllerQobuz::clearAddPlayTrack May 25 22:49:18 allo-signature volumio[1048]: verbose: ControllerMpd::sendMpdCommand stop May 25 22:49:18 allo-signature volumio[1048]: info: May 25 22:49:18 allo-signature volumio[1048]: ---------------------------- MPD announces state update: player May 25 22:49:18 allo-signature volumio[1048]: info: sendMpdCommand stop took 23 milliseconds May 25 22:49:18 allo-signature volumio[1048]: info: ControllerMpd::getState May 25 22:49:18 allo-signature volumio[1048]: verbose: ControllerMpd::sendMpdCommand status May 25 22:49:18 allo-signature volumio[1048]: verbose: ControllerMpd::sendMpdCommand clear May 25 22:49:18 allo-signature volumio[1048]: info: getStreamUrl took 412 milliseconds May 25 22:49:18 allo-signature volumio[1048]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=3484260&eid=326763623&fmt=6&profile=raw&app_id=539451548&cid=2490321&etsp=1779778158&hmac=SuHYj5Z_JkZS4wkHWxKn3PsMyoI May 25 22:49:18 allo-signature volumio[1048]: STREAMING PROXY: Requesting upstream with range: none May 25 22:49:18 allo-signature volumio[1048]: info: May 25 22:49:18 allo-signature volumio[1048]: ---------------------------- MPD announces system playlist update May 25 22:49:18 allo-signature volumio[1048]: info: Ignoring MPD Status Update May 25 22:49:18 allo-signature volumio[1048]: info: sendMpdCommand status took 24 milliseconds May 25 22:49:18 allo-signature volumio[1048]: info: sendMpdCommand clear took 23 milliseconds May 25 22:49:18 allo-signature volumio[1048]: verbose: ControllerMpd::parseState May 25 22:49:18 allo-signature volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 22:49:18 allo-signature volumio[1048]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/326763623" May 25 22:49:18 allo-signature volumio[1048]: error: updateQueue error: null May 25 22:49:18 allo-signature volumio[1048]: info: ------------------------------ 11ms May 25 22:49:18 allo-signature volumio[1048]: info: sendMpdCommand playlistinfo took 4 milliseconds May 25 22:49:18 allo-signature volumio[1048]: STREAMING PROXY: Handling url /?data=qobuz://song/326763623 May 25 22:49:18 allo-signature volumio[1048]: verbose: ControllerMpd::parseTrackInfo May 25 22:49:18 allo-signature volumio[1048]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 25 22:49:18 allo-signature volumio[1048]: info: ------------------------------ 40ms May 25 22:49:18 allo-signature volumio[1048]: info: Executing endpoint getStreamUrlqobuz May 25 22:49:18 allo-signature volumio[1048]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI May 25 22:49:18 allo-signature volumio[1048]: STREAMING PROXY: Upstream status code: 200 May 25 22:49:18 allo-signature volumio[1048]: info: getStreamUrl took 349 milliseconds May 25 22:49:18 allo-signature volumio[1048]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=3484260&eid=326763623&fmt=6&profile=raw&app_id=539451548&cid=2490321&etsp=1779778158&hmac=SuHYj5Z_JkZS4wkHWxKn3PsMyoI May 25 22:49:18 allo-signature volumio[1048]: STREAMING PROXY: Requesting upstream with range: none May 25 22:49:18 allo-signature volumio[1048]: STREAMING PROXY: Resuming with range: bytes=16384- May 25 22:49:19 allo-signature volumio[1048]: STREAMING PROXY: Upstream status code: 200 May 25 22:49:19 allo-signature volumio[1048]: STREAMING PROXY: Client closed connection May 25 22:49:19 allo-signature volumio[1048]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/326763623" May 25 22:49:19 allo-signature volumio[1048]: STREAMING PROXY: Upstream closed. Received 15454 bytes May 25 22:49:19 allo-signature volumio[1048]: STREAMING PROXY: Incomplete transfer. Expected 21665038, got 15454 May 25 22:49:19 allo-signature volumio[1048]: STREAMING PROXY: Retrying with range: bytes=15454- (attempt 1) May 25 22:49:19 allo-signature volumio[1048]: info: May 25 22:49:19 allo-signature volumio[1048]: ---------------------------- MPD announces system playlist update May 25 22:49:19 allo-signature volumio[1048]: info: Ignoring MPD Status Update May 25 22:49:19 allo-signature volumio[1048]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/326763623" took 4 milliseconds May 25 22:49:19 allo-signature volumio[1048]: info: CoreStateMachine::setConsumeUpdateService mpd May 25 22:49:19 allo-signature volumio[1048]: verbose: ControllerMpd::sendMpdCommand play May 25 22:49:19 allo-signature volumio[1048]: info: ------------------------------ 6ms May 25 22:49:19 allo-signature volumio[1048]: info: sendMpdCommand play took 3 milliseconds May 25 22:49:19 allo-signature volumio[1048]: STREAMING PROXY: Handling url /?data=qobuz://song/326763623 May 25 22:49:19 allo-signature volumio[1048]: info: Executing endpoint getStreamUrlqobuz May 25 22:49:19 allo-signature volumio[1048]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI May 25 22:49:19 allo-signature volumio[1048]: STREAMING PROXY: Resume upstream status code: 206 May 25 22:49:19 allo-signature volumio[1048]: info: Preload queue cleared May 25 22:49:19 allo-signature volumio[1048]: info: CoreCommandRouter::volumioReplaceandPlayItems May 25 22:49:19 allo-signature volumio[1048]: info: CoreStateMachine::ClearQueue May 25 22:49:19 allo-signature volumio[1048]: info: CoreStateMachine::stop May 25 22:49:19 allo-signature volumio[1048]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 22:49:19 allo-signature volumio[1048]: info: CorePlayQueue::clearPlayQueue May 25 22:49:19 allo-signature volumio[1048]: info: CorePlayQueue::saveQueue May 25 22:49:19 allo-signature volumio[1048]: info: CoreCommandRouter::volumioPushQueue May 25 22:49:19 allo-signature volumio[1048]: info: CoreStateMachine::addQueueItems May 25 22:49:19 allo-signature volumio[1048]: info: CorePlayQueue::addQueueItems May 25 22:49:19 allo-signature volumio[1048]: info: Preload queue cleared May 25 22:49:19 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763782 May 25 22:49:19 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763782 May 25 22:49:19 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763783 May 25 22:49:19 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763783 May 25 22:49:19 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763784 May 25 22:49:19 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763784 May 25 22:49:19 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763785 May 25 22:49:19 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763785 May 25 22:49:19 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763786 May 25 22:49:19 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763786 May 25 22:49:19 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763787 May 25 22:49:19 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763787 May 25 22:49:19 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763788 May 25 22:49:19 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763788 May 25 22:49:19 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763789 May 25 22:49:19 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763789 May 25 22:49:19 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763790 May 25 22:49:19 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763790 May 25 22:49:19 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763791 May 25 22:49:19 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763791 May 25 22:49:19 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763792 May 25 22:49:19 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763792 May 25 22:49:19 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407632 May 25 22:49:19 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407632 May 25 22:49:19 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407633 May 25 22:49:19 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407633 May 25 22:49:19 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407634 May 25 22:49:19 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407634 May 25 22:49:19 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407635 May 25 22:49:19 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407635 May 25 22:49:19 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407636 May 25 22:49:19 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407636 May 25 22:49:19 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407637 May 25 22:49:19 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407637 May 25 22:49:19 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407638 May 25 22:49:19 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407638 May 25 22:49:19 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407639 May 25 22:49:19 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407639 May 25 22:49:19 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407640 May 25 22:49:19 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407640 May 25 22:49:19 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407641 May 25 22:49:19 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407641 May 25 22:49:19 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407642 May 25 22:49:19 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407642 May 25 22:49:19 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407643 May 25 22:49:19 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407643 May 25 22:49:19 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407644 May 25 22:49:19 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407644 May 25 22:49:19 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407645 May 25 22:49:19 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407645 May 25 22:49:19 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407646 May 25 22:49:19 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407646 May 25 22:49:19 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122528839 May 25 22:49:19 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122528839 May 25 22:49:19 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763623 May 25 22:49:19 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763623 May 25 22:49:19 allo-signature volumio[1048]: info: CoreCommandRouter::volumioPushQueue May 25 22:49:19 allo-signature volumio[1048]: info: CorePlayQueue::saveQueue May 25 22:49:19 allo-signature volumio[1048]: info: CoreStateMachine::updateTrackBlock May 25 22:49:19 allo-signature volumio[1048]: info: CorePlayQueue::getTrackBlock May 25 22:49:19 allo-signature volumio[1048]: info: CoreCommandRouter::volumioPlay May 25 22:49:19 allo-signature volumio[1048]: info: CoreStateMachine::play index 27 May 25 22:49:19 allo-signature volumio[1048]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 22:49:19 allo-signature volumio[1048]: info: CoreStateMachine::addQueueItems May 25 22:49:19 allo-signature volumio[1048]: info: CorePlayQueue::addQueueItems May 25 22:49:19 allo-signature volumio[1048]: info: Preload queue cleared May 25 22:49:19 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/360049040 May 25 22:49:19 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/360049040 May 25 22:49:19 allo-signature volumio[1048]: info: CoreStateMachine::stop May 25 22:49:19 allo-signature volumio[1048]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 22:49:19 allo-signature volumio[1048]: info: CoreCommandRouter::volumioPushQueue May 25 22:49:19 allo-signature volumio[1048]: info: CorePlayQueue::saveQueue May 25 22:49:19 allo-signature volumio[1048]: info: CoreStateMachine::play index undefined May 25 22:49:19 allo-signature volumio[1048]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 22:49:19 allo-signature volumio[1048]: info: CoreStateMachine::updateTrackBlock May 25 22:49:19 allo-signature volumio[1048]: info: CorePlayQueue::getTrackBlock May 25 22:49:19 allo-signature volumio[1048]: info: CorePlayQueue::getTrack 27 May 25 22:49:19 allo-signature volumio[1048]: info: CoreStateMachine::startPlaybackTimer May 25 22:49:19 allo-signature volumio[1048]: info: CorePlayQueue::getTrack 27 May 25 22:49:19 allo-signature volumio[1048]: info: [1779774559384] ControllerQobuz::clearAddPlayTrack May 25 22:49:19 allo-signature volumio[1048]: verbose: ControllerMpd::sendMpdCommand stop May 25 22:49:19 allo-signature volumio[1048]: info: May 25 22:49:19 allo-signature volumio[1048]: ---------------------------- MPD announces state update: player May 25 22:49:19 allo-signature volumio[1048]: info: sendMpdCommand stop took 60 milliseconds May 25 22:49:19 allo-signature volumio[1048]: info: ControllerMpd::getState May 25 22:49:19 allo-signature volumio[1048]: verbose: ControllerMpd::sendMpdCommand status May 25 22:49:19 allo-signature volumio[1048]: verbose: ControllerMpd::sendMpdCommand clear May 25 22:49:19 allo-signature volumio[1048]: info: May 25 22:49:19 allo-signature volumio[1048]: ---------------------------- MPD announces system playlist update May 25 22:49:19 allo-signature volumio[1048]: info: Ignoring MPD Status Update May 25 22:49:19 allo-signature volumio[1048]: info: sendMpdCommand status took 8 milliseconds May 25 22:49:19 allo-signature volumio[1048]: info: sendMpdCommand clear took 8 milliseconds May 25 22:49:19 allo-signature volumio[1048]: verbose: ControllerMpd::parseState May 25 22:49:19 allo-signature volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 22:49:19 allo-signature volumio[1048]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/326763623" May 25 22:49:19 allo-signature volumio[1048]: error: updateQueue error: null May 25 22:49:19 allo-signature volumio[1048]: info: ------------------------------ 9ms May 25 22:49:19 allo-signature volumio[1048]: info: sendMpdCommand playlistinfo took 5 milliseconds May 25 22:49:19 allo-signature volumio[1048]: verbose: ControllerMpd::parseTrackInfo May 25 22:49:19 allo-signature volumio[1048]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 25 22:49:19 allo-signature volumio[1048]: info: ------------------------------ 22ms May 25 22:49:19 allo-signature volumio[1048]: STREAMING PROXY: Handling url /?data=qobuz://song/326763623 May 25 22:49:19 allo-signature volumio[1048]: info: Executing endpoint getStreamUrlqobuz May 25 22:49:19 allo-signature volumio[1048]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI May 25 22:49:19 allo-signature volumio[1048]: info: getStreamUrl took 746 milliseconds May 25 22:49:19 allo-signature volumio[1048]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=3484260&eid=326763623&fmt=6&profile=raw&app_id=539451548&cid=2490321&etsp=1779778159&hmac=HyMgWQzdR_jwgUc5aCH8LAKWEAY May 25 22:49:19 allo-signature volumio[1048]: STREAMING PROXY: Requesting upstream with range: none May 25 22:49:20 allo-signature volumio[1048]: STREAMING PROXY: Resuming with range: bytes=15454- May 25 22:49:20 allo-signature volumio[1048]: STREAMING PROXY: Upstream status code: 200 May 25 22:49:20 allo-signature volumio[1048]: info: getStreamUrl took 902 milliseconds May 25 22:49:20 allo-signature volumio[1048]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=3484260&eid=326763623&fmt=6&profile=raw&app_id=539451548&cid=2490321&etsp=1779778160&hmac=YgHyBrXbJ9RE1uuSyGpGQSkMeak May 25 22:49:20 allo-signature volumio[1048]: STREAMING PROXY: Requesting upstream with range: none May 25 22:49:20 allo-signature volumio[1048]: STREAMING PROXY: Resume upstream status code: 206 May 25 22:49:21 allo-signature volumio[1048]: STREAMING PROXY: Upstream status code: 200 May 25 22:49:21 allo-signature volumio[1048]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/326763623" May 25 22:49:21 allo-signature volumio[1048]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/326763623" took 2 milliseconds May 25 22:49:21 allo-signature volumio[1048]: info: CoreStateMachine::setConsumeUpdateService mpd May 25 22:49:21 allo-signature volumio[1048]: verbose: ControllerMpd::sendMpdCommand play May 25 22:49:21 allo-signature volumio[1048]: STREAMING PROXY: Client closed connection May 25 22:49:21 allo-signature volumio[1048]: info: May 25 22:49:21 allo-signature volumio[1048]: ---------------------------- MPD announces system playlist update May 25 22:49:21 allo-signature volumio[1048]: info: Ignoring MPD Status Update May 25 22:49:21 allo-signature volumio[1048]: info: sendMpdCommand play took 5 milliseconds May 25 22:49:21 allo-signature volumio[1048]: info: ------------------------------ 3ms May 25 22:49:21 allo-signature volumio[1048]: STREAMING PROXY: Upstream closed. Received 16384 bytes May 25 22:49:21 allo-signature volumio[1048]: STREAMING PROXY: Incomplete transfer. Expected 21665038, got 16384 May 25 22:49:21 allo-signature volumio[1048]: STREAMING PROXY: Retrying with range: bytes=16384- (attempt 1) May 25 22:49:21 allo-signature volumio[1048]: STREAMING PROXY: Handling url /?data=qobuz://song/326763623 May 25 22:49:21 allo-signature volumio[1048]: info: Executing endpoint getStreamUrlqobuz May 25 22:49:21 allo-signature volumio[1048]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI May 25 22:49:22 allo-signature volumio[1048]: STREAMING PROXY: Resuming with range: bytes=16384- May 25 22:49:22 allo-signature volumio[1048]: info: getStreamUrl took 1044 milliseconds May 25 22:49:22 allo-signature volumio[1048]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=3484260&eid=326763623&fmt=6&profile=raw&app_id=539451548&cid=2490321&etsp=1779778161&hmac=oovUg80UNfU6LDZ3unCAQY1SM3k May 25 22:49:22 allo-signature volumio[1048]: STREAMING PROXY: Requesting upstream with range: none May 25 22:49:22 allo-signature volumio[1048]: STREAMING PROXY: Upstream status code: 200 May 25 22:49:22 allo-signature volumio[1048]: info: May 25 22:49:22 allo-signature volumio[1048]: ---------------------------- MPD announces state update: player May 25 22:49:22 allo-signature volumio[1048]: info: ControllerMpd::getState May 25 22:49:22 allo-signature volumio[1048]: verbose: ControllerMpd::sendMpdCommand status May 25 22:49:22 allo-signature volumio[1048]: STREAMING PROXY: Resume upstream status code: 206 May 25 22:49:22 allo-signature kernel: bcm2835-i2s fe203000.i2s: I2S SYNC error! May 25 22:49:22 allo-signature volumio[1048]: info: May 25 22:49:22 allo-signature volumio[1048]: ---------------------------- MPD announces state update: player May 25 22:49:22 allo-signature volumio[1048]: info: sendMpdCommand status took 22 milliseconds May 25 22:49:22 allo-signature volumio[1048]: info: ControllerMpd::getState May 25 22:49:22 allo-signature volumio[1048]: verbose: ControllerMpd::sendMpdCommand status May 25 22:49:22 allo-signature volumio[1048]: verbose: ControllerMpd::parseState May 25 22:49:22 allo-signature volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 22:49:22 allo-signature volumio[1048]: info: sendMpdCommand status took 4 milliseconds May 25 22:49:22 allo-signature volumio[1048]: info: sendMpdCommand playlistinfo took 3 milliseconds May 25 22:49:22 allo-signature volumio[1048]: verbose: ControllerMpd::parseState May 25 22:49:22 allo-signature volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 22:49:22 allo-signature volumio[1048]: verbose: ControllerMpd::parseTrackInfo May 25 22:49:22 allo-signature volumio[1048]: info: ControllerMpd::pushState May 25 22:49:22 allo-signature volumio[1048]: info: CoreCommandRouter::servicePushState May 25 22:49:22 allo-signature volumio[1048]: info: CorePlayQueue::getTrack 27 May 25 22:49:22 allo-signature volumio[1048]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":196,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"326763623","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/326763623","trackType":"qobuz"} May 25 22:49:22 allo-signature volumio[1048]: verbose: CURRENT POSITION 27 May 25 22:49:22 allo-signature volumio[1048]: info: CoreStateMachine::syncState stateService play May 25 22:49:22 allo-signature volumio[1048]: info: CoreStateMachine::syncState currentStatus stop May 25 22:49:22 allo-signature volumio[1048]: info: ------------------------------ 39ms May 25 22:49:22 allo-signature volumio[1048]: info: sendMpdCommand playlistinfo took 9 milliseconds May 25 22:49:22 allo-signature volumio[1048]: verbose: ControllerMpd::parseTrackInfo May 25 22:49:22 allo-signature volumio[1048]: info: ControllerMpd::pushState May 25 22:49:22 allo-signature volumio[1048]: info: CoreCommandRouter::servicePushState May 25 22:49:22 allo-signature volumio[1048]: info: CorePlayQueue::getTrack 27 May 25 22:49:22 allo-signature volumio[1048]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":196,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"326763623","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/326763623","trackType":"qobuz"} May 25 22:49:22 allo-signature volumio[1048]: verbose: CURRENT POSITION 27 May 25 22:49:22 allo-signature volumio[1048]: info: CoreStateMachine::syncState stateService play May 25 22:49:22 allo-signature volumio[1048]: info: CoreStateMachine::syncState currentStatus play May 25 22:49:22 allo-signature volumio[1048]: info: Received an update from plugin. extracting info from payload May 25 22:49:22 allo-signature volumio[1048]: info: CoreStateMachine::pushState May 25 22:49:22 allo-signature volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 22:49:22 allo-signature volumio[1048]: info: CoreCommandRouter::volumioPushState May 25 22:49:22 allo-signature volumio[1048]: info: MRS: Pushing multiroomSync output update for this device May 25 22:49:22 allo-signature volumio[1048]: info: MRS: Pushing multiroomSync output May 25 22:49:22 allo-signature volumio[1048]: info: CoreCommandRouter::volumioGetState May 25 22:49:22 allo-signature volumio[1048]: info: CoreStateMachine::pushState May 25 22:49:22 allo-signature volumio[1048]: info: CoreCommandRouter::volumioPushState May 25 22:49:22 allo-signature volumio[1048]: info: MRS: Pushing multiroomSync output update for this device May 25 22:49:22 allo-signature volumio[1048]: info: MRS: Pushing multiroomSync output May 25 22:49:22 allo-signature volumio[1048]: info: CoreCommandRouter::volumioGetState May 25 22:49:22 allo-signature volumio5-onboarding[1301]: time=2026-05-25T22:49:22.855-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.253:60554 @ 0x1d44b10" state=STATUS_PLAYING positionMs=0 volume=100 May 25 22:49:22 allo-signature volumio5-onboarding[1301]: time=2026-05-25T22:49:22.855-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.253:60554 @ 0x1d44b10" id=qobuz://song/326763623 title="網中人 (無綫電視劇《網中人》主題曲)" May 25 22:49:22 allo-signature volumio5-onboarding[1301]: time=2026-05-25T22:49:22.859-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.253:60554 @ 0x1d44b10" state=STATUS_PLAYING positionMs=0 volume=100 May 25 22:49:22 allo-signature volumio5-onboarding[1301]: time=2026-05-25T22:49:22.859-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.253:60554 @ 0x1d44b10" id=qobuz://song/326763623 title="網中人 (無綫電視劇《網中人》主題曲)" May 25 22:49:22 allo-signature volumio[1048]: info: ------------------------------ 92ms May 25 22:49:23 allo-signature volumio[1048]: info: Preload queue cleared May 25 22:49:23 allo-signature volumio[1048]: info: CoreCommandRouter::volumioReplaceandPlayItems May 25 22:49:23 allo-signature volumio[1048]: info: CoreStateMachine::ClearQueue May 25 22:49:23 allo-signature volumio[1048]: info: CoreStateMachine::stop May 25 22:49:23 allo-signature volumio[1048]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 22:49:23 allo-signature volumio[1048]: info: CoreStateMachine::stPlaybackTimer May 25 22:49:23 allo-signature volumio[1048]: info: CoreStateMachine::updateTrackBlock May 25 22:49:23 allo-signature volumio[1048]: info: CorePlayQueue::getTrackBlock May 25 22:49:23 allo-signature volumio[1048]: info: CoreStateMachine::pushState May 25 22:49:23 allo-signature volumio[1048]: info: CorePlayQueue::getTrack 27 May 25 22:49:23 allo-signature volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 22:49:23 allo-signature volumio[1048]: info: CoreCommandRouter::volumioPushState May 25 22:49:23 allo-signature volumio[1048]: info: MRS: Pushing multiroomSync output update for this device May 25 22:49:23 allo-signature volumio[1048]: info: MRS: Pushing multiroomSync output May 25 22:49:23 allo-signature volumio[1048]: info: CoreCommandRouter::volumioGetState May 25 22:49:23 allo-signature volumio[1048]: info: CorePlayQueue::getTrack 27 May 25 22:49:23 allo-signature volumio[1048]: info: CoreStateMachine::serviceStop May 25 22:49:23 allo-signature volumio[1048]: info: CorePlayQueue::getTrack 27 May 25 22:49:23 allo-signature volumio[1048]: info: CoreCommandRouter::serviceStop May 25 22:49:23 allo-signature volumio[1048]: info: [1779774563529] ControllerQobuz::stop May 25 22:49:23 allo-signature volumio[1048]: info: CoreStateMachine::setConsumeUpdateService mpd May 25 22:49:23 allo-signature volumio[1048]: info: ControllerMpd::stop May 25 22:49:23 allo-signature volumio[1048]: verbose: ControllerMpd::sendMpdCommand stop May 25 22:49:23 allo-signature volumio[1048]: info: CorePlayQueue::clearPlayQueue May 25 22:49:23 allo-signature volumio[1048]: info: CorePlayQueue::saveQueue May 25 22:49:23 allo-signature volumio[1048]: info: CoreCommandRouter::volumioPushQueue May 25 22:49:23 allo-signature volumio[1048]: info: CoreStateMachine::addQueueItems May 25 22:49:23 allo-signature volumio[1048]: info: CorePlayQueue::addQueueItems May 25 22:49:23 allo-signature volumio[1048]: info: Preload queue cleared May 25 22:49:23 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763782 May 25 22:49:23 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763782 May 25 22:49:23 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763783 May 25 22:49:23 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763783 May 25 22:49:23 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763784 May 25 22:49:23 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763784 May 25 22:49:23 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763785 May 25 22:49:23 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763785 May 25 22:49:23 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763786 May 25 22:49:23 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763786 May 25 22:49:23 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763787 May 25 22:49:23 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763787 May 25 22:49:23 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763788 May 25 22:49:23 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763788 May 25 22:49:23 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763789 May 25 22:49:23 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763789 May 25 22:49:23 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763790 May 25 22:49:23 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763790 May 25 22:49:23 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763791 May 25 22:49:23 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763791 May 25 22:49:23 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763792 May 25 22:49:23 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763792 May 25 22:49:23 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407632 May 25 22:49:23 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407632 May 25 22:49:23 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407633 May 25 22:49:23 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407633 May 25 22:49:23 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407634 May 25 22:49:23 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407634 May 25 22:49:23 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407635 May 25 22:49:23 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407635 May 25 22:49:23 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407636 May 25 22:49:23 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407636 May 25 22:49:23 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407637 May 25 22:49:23 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407637 May 25 22:49:23 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407638 May 25 22:49:23 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407638 May 25 22:49:23 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407639 May 25 22:49:23 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407639 May 25 22:49:23 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407640 May 25 22:49:23 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407640 May 25 22:49:23 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407641 May 25 22:49:23 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407641 May 25 22:49:23 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407642 May 25 22:49:23 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407642 May 25 22:49:23 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407643 May 25 22:49:23 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407643 May 25 22:49:23 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407644 May 25 22:49:23 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407644 May 25 22:49:23 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407645 May 25 22:49:23 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407645 May 25 22:49:23 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407646 May 25 22:49:23 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407646 May 25 22:49:23 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122528839 May 25 22:49:23 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122528839 May 25 22:49:23 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763623 May 25 22:49:23 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763623 May 25 22:49:23 allo-signature volumio5-onboarding[1301]: time=2026-05-25T22:49:23.569-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.253:60554 @ 0x1d44b10" state=STATUS_STOPPED positionMs=0 volume=100 May 25 22:49:23 allo-signature volumio5-onboarding[1301]: time=2026-05-25T22:49:23.570-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.253:60554 @ 0x1d44b10" id=qobuz://song/326763623 title="網中人 (無綫電視劇《網中人》主題曲)" May 25 22:49:23 allo-signature volumio[1048]: info: CoreCommandRouter::volumioPushQueue May 25 22:49:23 allo-signature volumio[1048]: info: CorePlayQueue::saveQueue May 25 22:49:23 allo-signature volumio[1048]: STREAMING PROXY: Client closed connection May 25 22:49:23 allo-signature volumio[1048]: STREAMING PROXY: Upstream closed. Received 384766 bytes May 25 22:49:23 allo-signature volumio[1048]: STREAMING PROXY: Incomplete transfer. Expected 21665038, got 384766 May 25 22:49:23 allo-signature volumio[1048]: STREAMING PROXY: Retrying with range: bytes=384766- (attempt 1) May 25 22:49:23 allo-signature volumio[1048]: info: CoreStateMachine::updateTrackBlock May 25 22:49:23 allo-signature volumio[1048]: info: CorePlayQueue::getTrackBlock May 25 22:49:23 allo-signature volumio[1048]: info: CoreCommandRouter::volumioPlay May 25 22:49:23 allo-signature volumio[1048]: info: CoreStateMachine::play index 27 May 25 22:49:23 allo-signature volumio[1048]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 22:49:23 allo-signature volumio[1048]: info: CoreStateMachine::addQueueItems May 25 22:49:23 allo-signature volumio[1048]: info: CorePlayQueue::addQueueItems May 25 22:49:23 allo-signature volumio[1048]: info: Preload queue cleared May 25 22:49:23 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/360049040 May 25 22:49:23 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/360049040 May 25 22:49:23 allo-signature volumio[1048]: info: CoreStateMachine::stop May 25 22:49:23 allo-signature volumio[1048]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 22:49:23 allo-signature volumio[1048]: info: CoreCommandRouter::volumioPushQueue May 25 22:49:23 allo-signature volumio[1048]: info: CorePlayQueue::saveQueue May 25 22:49:23 allo-signature volumio[1048]: info: CoreStateMachine::play index undefined May 25 22:49:23 allo-signature volumio[1048]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 22:49:23 allo-signature volumio[1048]: info: CoreStateMachine::updateTrackBlock May 25 22:49:23 allo-signature volumio[1048]: info: CorePlayQueue::getTrackBlock May 25 22:49:23 allo-signature volumio[1048]: info: CorePlayQueue::getTrack 27 May 25 22:49:23 allo-signature volumio[1048]: info: CoreStateMachine::startPlaybackTimer May 25 22:49:23 allo-signature volumio[1048]: info: CorePlayQueue::getTrack 27 May 25 22:49:23 allo-signature volumio[1048]: info: [1779774563625] ControllerQobuz::clearAddPlayTrack May 25 22:49:23 allo-signature volumio[1048]: verbose: ControllerMpd::sendMpdCommand stop May 25 22:49:23 allo-signature volumio[1048]: info: May 25 22:49:23 allo-signature volumio[1048]: ---------------------------- MPD announces state update: player May 25 22:49:23 allo-signature volumio[1048]: info: sendMpdCommand stop took 123 milliseconds May 25 22:49:23 allo-signature volumio[1048]: info: ControllerMpd::getState May 25 22:49:23 allo-signature volumio[1048]: verbose: ControllerMpd::sendMpdCommand status May 25 22:49:23 allo-signature volumio[1048]: info: sendMpdCommand stop took 29 milliseconds May 25 22:49:23 allo-signature volumio[1048]: verbose: ControllerMpd::sendMpdCommand clear May 25 22:49:23 allo-signature volumio[1048]: info: May 25 22:49:23 allo-signature volumio[1048]: ---------------------------- MPD announces system playlist update May 25 22:49:23 allo-signature volumio[1048]: info: Ignoring MPD Status Update May 25 22:49:23 allo-signature volumio[1048]: info: sendMpdCommand status took 6 milliseconds May 25 22:49:23 allo-signature volumio[1048]: info: sendMpdCommand clear took 5 milliseconds May 25 22:49:23 allo-signature volumio[1048]: verbose: ControllerMpd::parseState May 25 22:49:23 allo-signature volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 22:49:23 allo-signature volumio[1048]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/326763623" May 25 22:49:23 allo-signature volumio[1048]: error: updateQueue error: null May 25 22:49:23 allo-signature volumio[1048]: info: ------------------------------ 7ms May 25 22:49:23 allo-signature volumio[1048]: info: sendMpdCommand playlistinfo took 4 milliseconds May 25 22:49:23 allo-signature volumio[1048]: verbose: ControllerMpd::parseTrackInfo May 25 22:49:23 allo-signature volumio[1048]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 25 22:49:23 allo-signature volumio[1048]: info: ------------------------------ 16ms May 25 22:49:23 allo-signature volumio[1048]: STREAMING PROXY: Handling url /?data=qobuz://song/326763623 May 25 22:49:23 allo-signature volumio[1048]: info: Executing endpoint getStreamUrlqobuz May 25 22:49:23 allo-signature volumio[1048]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI May 25 22:49:24 allo-signature volumio[1048]: STREAMING PROXY: Resuming with range: bytes=384766- May 25 22:49:24 allo-signature volumio[1048]: info: Preload queue cleared May 25 22:49:24 allo-signature volumio[1048]: info: CoreCommandRouter::volumioReplaceandPlayItems May 25 22:49:24 allo-signature volumio[1048]: info: CoreStateMachine::ClearQueue May 25 22:49:24 allo-signature volumio[1048]: info: CoreStateMachine::stop May 25 22:49:24 allo-signature volumio[1048]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 22:49:24 allo-signature volumio[1048]: info: CorePlayQueue::clearPlayQueue May 25 22:49:24 allo-signature volumio[1048]: info: CorePlayQueue::saveQueue May 25 22:49:24 allo-signature volumio[1048]: info: CoreCommandRouter::volumioPushQueue May 25 22:49:24 allo-signature volumio[1048]: info: CoreStateMachine::addQueueItems May 25 22:49:24 allo-signature volumio[1048]: info: CorePlayQueue::addQueueItems May 25 22:49:24 allo-signature volumio[1048]: info: Preload queue cleared May 25 22:49:24 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763782 May 25 22:49:24 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763782 May 25 22:49:24 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763783 May 25 22:49:24 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763783 May 25 22:49:24 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763784 May 25 22:49:24 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763784 May 25 22:49:24 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763785 May 25 22:49:24 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763785 May 25 22:49:24 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763786 May 25 22:49:24 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763786 May 25 22:49:24 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763787 May 25 22:49:24 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763787 May 25 22:49:24 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763788 May 25 22:49:24 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763788 May 25 22:49:24 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763789 May 25 22:49:24 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763789 May 25 22:49:24 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763790 May 25 22:49:24 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763790 May 25 22:49:24 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763791 May 25 22:49:24 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763791 May 25 22:49:24 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763792 May 25 22:49:24 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763792 May 25 22:49:24 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407632 May 25 22:49:24 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407632 May 25 22:49:24 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407633 May 25 22:49:24 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407633 May 25 22:49:24 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407634 May 25 22:49:24 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407634 May 25 22:49:24 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407635 May 25 22:49:24 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407635 May 25 22:49:24 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407636 May 25 22:49:24 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407636 May 25 22:49:24 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407637 May 25 22:49:24 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407637 May 25 22:49:24 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407638 May 25 22:49:24 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407638 May 25 22:49:24 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407639 May 25 22:49:24 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407639 May 25 22:49:24 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407640 May 25 22:49:24 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407640 May 25 22:49:24 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407641 May 25 22:49:24 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407641 May 25 22:49:24 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407642 May 25 22:49:24 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407642 May 25 22:49:24 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407643 May 25 22:49:24 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407643 May 25 22:49:24 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407644 May 25 22:49:24 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407644 May 25 22:49:24 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407645 May 25 22:49:24 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407645 May 25 22:49:24 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122407646 May 25 22:49:24 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122407646 May 25 22:49:24 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/122528839 May 25 22:49:24 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/122528839 May 25 22:49:24 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/326763623 May 25 22:49:24 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/326763623 May 25 22:49:24 allo-signature volumio[1048]: info: CoreCommandRouter::volumioPushQueue May 25 22:49:24 allo-signature volumio[1048]: info: CorePlayQueue::saveQueue May 25 22:49:24 allo-signature volumio[1048]: info: CoreStateMachine::updateTrackBlock May 25 22:49:24 allo-signature volumio[1048]: info: CorePlayQueue::getTrackBlock May 25 22:49:24 allo-signature volumio[1048]: info: CoreCommandRouter::volumioPlay May 25 22:49:24 allo-signature volumio[1048]: info: CoreStateMachine::play index 27 May 25 22:49:24 allo-signature volumio[1048]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 22:49:24 allo-signature volumio[1048]: info: CoreStateMachine::addQueueItems May 25 22:49:24 allo-signature volumio[1048]: info: CorePlayQueue::addQueueItems May 25 22:49:24 allo-signature volumio[1048]: info: Preload queue cleared May 25 22:49:24 allo-signature volumio[1048]: info: Adding Item to queue: qobuz://song/360049040 May 25 22:49:24 allo-signature volumio[1048]: info: Using cached record of: qobuz://song/360049040 May 25 22:49:24 allo-signature volumio[1048]: info: CoreStateMachine::stop May 25 22:49:24 allo-signature volumio[1048]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 22:49:24 allo-signature volumio[1048]: info: CoreCommandRouter::volumioPushQueue May 25 22:49:24 allo-signature volumio[1048]: info: CorePlayQueue::saveQueue May 25 22:49:24 allo-signature volumio[1048]: info: CoreStateMachine::play index undefined May 25 22:49:24 allo-signature volumio[1048]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 22:49:24 allo-signature volumio[1048]: info: CoreStateMachine::updateTrackBlock May 25 22:49:24 allo-signature volumio[1048]: info: CorePlayQueue::getTrackBlock May 25 22:49:24 allo-signature volumio[1048]: info: CorePlayQueue::getTrack 27 May 25 22:49:24 allo-signature volumio[1048]: info: CoreStateMachine::startPlaybackTimer May 25 22:49:24 allo-signature volumio[1048]: info: CorePlayQueue::getTrack 27 May 25 22:49:24 allo-signature volumio[1048]: info: [1779774564874] ControllerQobuz::clearAddPlayTrack May 25 22:49:24 allo-signature volumio[1048]: verbose: ControllerMpd::sendMpdCommand stop May 25 22:49:24 allo-signature volumio[1048]: info: getStreamUrl took 1221 milliseconds May 25 22:49:24 allo-signature volumio[1048]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=3484260&eid=326763623&fmt=6&profile=raw&app_id=539451548&cid=2490321&etsp=1779778164&hmac=qj5igFQ-IwOOCFEZwyUURRgX9f4 May 25 22:49:24 allo-signature volumio[1048]: STREAMING PROXY: Requesting upstream with range: none May 25 22:49:25 allo-signature volumio[1048]: STREAMING PROXY: Resume upstream status code: 206 May 25 22:49:25 allo-signature volumio[1048]: STREAMING PROXY: Upstream status code: 200 May 25 22:49:25 allo-signature volumio[1048]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/326763623" May 25 22:49:25 allo-signature volumio[1048]: info: sendMpdCommand stop took 447 milliseconds May 25 22:49:25 allo-signature volumio[1048]: STREAMING PROXY: Client closed connection May 25 22:49:25 allo-signature volumio[1048]: verbose: ControllerMpd::sendMpdCommand clear May 25 22:49:25 allo-signature volumio[1048]: info: May 25 22:49:25 allo-signature volumio[1048]: ---------------------------- MPD announces system playlist update May 25 22:49:25 allo-signature volumio[1048]: info: Ignoring MPD Status Update May 25 22:49:25 allo-signature volumio[1048]: info: May 25 22:49:25 allo-signature volumio[1048]: ---------------------------- MPD announces system playlist update May 25 22:49:25 allo-signature volumio[1048]: STREAMING PROXY: Upstream closed. Received 16384 bytes May 25 22:49:25 allo-signature volumio[1048]: STREAMING PROXY: Incomplete transfer. Expected 21665038, got 16384 May 25 22:49:25 allo-signature volumio[1048]: STREAMING PROXY: Retrying with range: bytes=16384- (attempt 1) May 25 22:49:25 allo-signature volumio[1048]: info: Ignoring MPD Status Update May 25 22:49:25 allo-signature volumio[1048]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/326763623" took 5 milliseconds May 25 22:49:25 allo-signature volumio[1048]: info: sendMpdCommand clear took 4 milliseconds May 25 22:49:25 allo-signature volumio[1048]: info: CoreStateMachine::setConsumeUpdateService mpd May 25 22:49:25 allo-signature volumio[1048]: verbose: ControllerMpd::sendMpdCommand play May 25 22:49:25 allo-signature volumio[1048]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/326763623" May 25 22:49:25 allo-signature volumio[1048]: error: updateQueue error: null May 25 22:49:25 allo-signature volumio[1048]: error: updateQueue error: null May 25 22:49:25 allo-signature volumio[1048]: info: ------------------------------ 8ms May 25 22:49:25 allo-signature volumio[1048]: STREAMING PROXY: Handling url /?data=qobuz://song/326763623 May 25 22:49:25 allo-signature volumio[1048]: info: ------------------------------ 7ms May 25 22:49:25 allo-signature volumio[1048]: info: sendMpdCommand play took 5 milliseconds May 25 22:49:25 allo-signature volumio[1048]: info: Executing endpoint getStreamUrlqobuz May 25 22:49:25 allo-signature volumio[1048]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI May 25 22:49:26 allo-signature volumio[1048]: info: getStreamUrl took 809 milliseconds May 25 22:49:26 allo-signature volumio[1048]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=3484260&eid=326763623&fmt=6&profile=raw&app_id=539451548&cid=2490321&etsp=1779778165&hmac=_RB4KWpZ3J7VVguWccEpAJ8qZDk May 25 22:49:26 allo-signature volumio[1048]: STREAMING PROXY: Requesting upstream with range: none May 25 22:49:26 allo-signature volumio[1048]: STREAMING PROXY: Resuming with range: bytes=16384- May 25 22:49:26 allo-signature volumio[1048]: STREAMING PROXY: Upstream status code: 200 May 25 22:49:26 allo-signature volumio[1048]: STREAMING PROXY: Client closed connection May 25 22:49:26 allo-signature volumio[1048]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/326763623" May 25 22:49:26 allo-signature volumio[1048]: STREAMING PROXY: Upstream closed. Received 16384 bytes May 25 22:49:26 allo-signature volumio[1048]: STREAMING PROXY: Incomplete transfer. Expected 21665038, got 16384 May 25 22:49:26 allo-signature volumio[1048]: STREAMING PROXY: Retrying with range: bytes=16384- (attempt 1) May 25 22:49:26 allo-signature volumio[1048]: info: May 25 22:49:26 allo-signature volumio[1048]: ---------------------------- MPD announces system playlist update May 25 22:49:26 allo-signature volumio[1048]: info: Ignoring MPD Status Update May 25 22:49:26 allo-signature volumio[1048]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/326763623" took 3 milliseconds May 25 22:49:26 allo-signature volumio[1048]: info: CoreStateMachine::setConsumeUpdateService mpd May 25 22:49:26 allo-signature volumio[1048]: verbose: ControllerMpd::sendMpdCommand play May 25 22:49:26 allo-signature volumio[1048]: info: ------------------------------ 5ms May 25 22:49:26 allo-signature volumio[1048]: info: sendMpdCommand play took 3 milliseconds May 25 22:49:26 allo-signature volumio[1048]: STREAMING PROXY: Handling url /?data=qobuz://song/326763623 May 25 22:49:26 allo-signature volumio[1048]: info: Executing endpoint getStreamUrlqobuz May 25 22:49:26 allo-signature volumio[1048]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI May 25 22:49:26 allo-signature volumio[1048]: STREAMING PROXY: Resume upstream status code: 206 May 25 22:49:27 allo-signature volumio[1048]: info: getStreamUrl took 963 milliseconds May 25 22:49:27 allo-signature volumio[1048]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=3484260&eid=326763623&fmt=6&profile=raw&app_id=539451548&cid=2490321&etsp=1779778167&hmac=nssmCBkvx2sTLoqpR7NTOhzPHEQ May 25 22:49:27 allo-signature volumio[1048]: STREAMING PROXY: Requesting upstream with range: none May 25 22:49:27 allo-signature volumio[1048]: STREAMING PROXY: Resuming with range: bytes=16384- May 25 22:49:28 allo-signature volumio[1048]: STREAMING PROXY: Upstream status code: 200 May 25 22:49:28 allo-signature volumio[1048]: info: May 25 22:49:28 allo-signature volumio[1048]: ---------------------------- MPD announces state update: player May 25 22:49:28 allo-signature volumio[1048]: info: ControllerMpd::getState May 25 22:49:28 allo-signature volumio[1048]: verbose: ControllerMpd::sendMpdCommand status May 25 22:49:28 allo-signature volumio[1048]: info: May 25 22:49:28 allo-signature volumio[1048]: ---------------------------- MPD announces state update: player May 25 22:49:28 allo-signature volumio[1048]: info: ControllerMpd::getState May 25 22:49:28 allo-signature volumio[1048]: verbose: ControllerMpd::sendMpdCommand status May 25 22:49:28 allo-signature volumio[1048]: info: sendMpdCommand status took 4 milliseconds May 25 22:49:28 allo-signature volumio[1048]: verbose: ControllerMpd::parseState May 25 22:49:28 allo-signature volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 22:49:28 allo-signature volumio[1048]: info: sendMpdCommand status took 4 milliseconds May 25 22:49:28 allo-signature volumio[1048]: info: sendMpdCommand playlistinfo took 3 milliseconds May 25 22:49:28 allo-signature volumio[1048]: verbose: ControllerMpd::parseState May 25 22:49:28 allo-signature volumio[1048]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 22:49:28 allo-signature volumio[1048]: verbose: ControllerMpd::parseTrackInfo May 25 22:49:28 allo-signature volumio[1048]: info: ControllerMpd::pushState May 25 22:49:28 allo-signature volumio[1048]: info: CoreCommandRouter::servicePushState May 25 22:49:28 allo-signature volumio[1048]: info: CorePlayQueue::getTrack 27 May 25 22:49:28 allo-signature volumio[1048]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":196,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"326763623","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/326763623","trackType":"qobuz"} May 25 22:49:28 allo-signature volumio[1048]: verbose: CURRENT POSITION 27 May 25 22:49:28 allo-signature volumio[1048]: info: CoreStateMachine::syncState stateService play May 25 22:49:28 allo-signature volumio[1048]: info: CoreStateMachine::syncState currentStatus stop May 25 22:49:28 allo-signature volumio[1048]: info: ------------------------------ 23ms May 25 22:49:28 allo-signature volumio[1048]: info: sendMpdCommand playlistinfo took 6 milliseconds May 25 22:49:28 allo-signature volumio[1048]: verbose: ControllerMpd::parseTrackInfo May 25 22:49:28 allo-signature volumio[1048]: info: ControllerMpd::pushState May 25 22:49:28 allo-signature volumio[1048]: info: CoreCommandRouter::servicePushState May 25 22:49:28 allo-signature volumio[1048]: info: CorePlayQueue::getTrack 27 May 25 22:49:28 allo-signature volumio[1048]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":196,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"326763623","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/326763623","trackType":"qobuz"} May 25 22:49:28 allo-signature volumio[1048]: verbose: CURRENT POSITION 27 May 25 22:49:28 allo-signature volumio[1048]: info: CoreStateMachine::syncState stateService play May 25 22:49:28 allo-signature volumio[1048]: info: CoreStateMachine::syncState currentStatus play May 25 22:49:28 allo-signature volumio[1048]: info: Received an update from plugin. extracting info from payload May 25 22:49:28 allo-signature volumio[1048]: info: CoreStateMachine::pushState May 25 22:49:28 allo-signature volumio[1048]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 22:49:28 allo-signature volumio[1048]: info: CoreCommandRouter::volumioPushState May 25 22:49:28 allo-signature volumio[1048]: STREAMING PROXY: Resume upstream status code: 206 May 25 22:49:28 allo-signature volumio[1048]: info: MRS: Pushing multiroomSync output update for this device May 25 22:49:28 allo-signature volumio[1048]: info: MRS: Pushing multiroomSync output May 25 22:49:28 allo-signature volumio[1048]: info: CoreCommandRouter::volumioGetState May 25 22:49:28 allo-signature volumio[1048]: info: CoreStateMachine::pushState May 25 22:49:28 allo-signature volumio[1048]: info: CoreCommandRouter::volumioPushState May 25 22:49:28 allo-signature volumio[1048]: info: MRS: Pushing multiroomSync output update for this device May 25 22:49:28 allo-signature volumio[1048]: info: MRS: Pushing multiroomSync output May 25 22:49:28 allo-signature volumio[1048]: info: CoreCommandRouter::volumioGetState May 25 22:49:28 allo-signature volumio5-onboarding[1301]: time=2026-05-25T22:49:28.356-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.253:60554 @ 0x1d44b10" state=STATUS_PLAYING positionMs=0 volume=100 May 25 22:49:28 allo-signature volumio5-onboarding[1301]: time=2026-05-25T22:49:28.358-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.253:60554 @ 0x1d44b10" state=STATUS_PLAYING positionMs=0 volume=100 May 25 22:49:28 allo-signature volumio5-onboarding[1301]: time=2026-05-25T22:49:28.359-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.253:60554 @ 0x1d44b10" id=qobuz://song/326763623 title="網中人 (無綫電視劇《網中人》主題曲)" May 25 22:49:28 allo-signature volumio5-onboarding[1301]: time=2026-05-25T22:49:28.360-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.253:60554 @ 0x1d44b10" id=qobuz://song/326763623 title="網中人 (無綫電視劇《網中人》主題曲)" May 25 22:49:28 allo-signature volumio[1048]: info: ------------------------------ 81ms May 25 22:49:36 allo-signature volumio[1048]: info: CorePlayQueue::getTrack 27 May 25 22:49:36 allo-signature volumio[1048]: info: CoreCommandRouter::executeOnPlugin: qobuz , goto May 25 22:49:36 allo-signature volumio[1048]: https://prod.vlmapi.io/v2/qobuz/explodeUri May 25 22:49:37 allo-signature volumio[1048]: info: explodeUri took 923 milliseconds May 25 22:49:38 allo-signature volumio[1048]: info: handleBrowseUri took 1208 milliseconds May 25 22:49:39 allo-signature volumio[1048]: info: Executing endpoint getSimilarArtists May 25 22:49:39 allo-signature volumio[1048]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists May 25 22:49:39 allo-signature volumio[1048]: info: Executing endpoint getSimilarArtists May 25 22:49:39 allo-signature volumio[1048]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists May 25 22:49:39 allo-signature volumio[1048]: info: Executing endpoint metavolumio May 25 22:49:39 allo-signature volumio[1048]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 25 22:49:39 allo-signature volumio[1048]: info: Executing endpoint metavolumio May 25 22:49:39 allo-signature volumio[1048]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 25 22:49:40 allo-signature volumio[1048]: STREAMING PROXY: Upstream closed. Received 21665038 bytes May 25 22:49:46 allo-signature volumio[1048]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 22:49:46 allo-signature volumio[1048]: TypeError: Cannot read property 'slice' of null May 25 22:49:46 allo-signature volumio[1048]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) May 25 22:49:46 allo-signature volumio[1048]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) May 25 22:49:46 allo-signature volumio[1048]: at Object.onceWrapper (events.js:422:26) May 25 22:49:46 allo-signature volumio[1048]: at ClientRequest.emit (events.js:315:20) May 25 22:49:46 allo-signature volumio[1048]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:641:27) May 25 22:49:46 allo-signature volumio[1048]: at HTTPParser.parserOnHeadersComplete (_http_common.js:126:17) May 25 22:49:46 allo-signature volumio[1048]: at TLSSocket.socketOnData (_http_client.js:509:22) May 25 22:49:46 allo-signature volumio[1048]: at TLSSocket.emit (events.js:315:20) May 25 22:49:46 allo-signature volumio[1048]: at addChunk (internal/streams/readable.js:309:12) May 25 22:49:46 allo-signature volumio[1048]: at readableAddChunk (internal/streams/readable.js:284:9) May 25 22:49:46 allo-signature volumio[1048]: at TLSSocket.Readable.push (internal/streams/readable.js:223:10) May 25 22:49:46 allo-signature volumio[1048]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) May 25 22:49:46 allo-signature volumio[1048]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 22:49:47 allo-signature sudo[2128]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-05-25 22:48 May 25 22:49:47 allo-signature sudo[2128]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"