-- Logs begin at Sat 2021-02-27 05:55:35 UTC, end at Sun 2024-08-18 00:39:15 UTC. -- Aug 18 00:38:57 opera systemd[1]: Time has been changed Aug 18 00:38:58 opera volumio[1116]: info: Loading plugin "networkfs"... Aug 18 00:38:58 opera volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 18 00:38:58 opera volumio[1116]: info: Plugin alarm-clock is not enabled Aug 18 00:38:58 opera volumio[1116]: info: Loading plugin "airplay_emulation"... Aug 18 00:38:58 opera volumio[1116]: info: [1723941538129] Starting Shairport Sync Aug 18 00:38:58 opera volumio[1116]: info: Loading plugin "cd_controller"... Aug 18 00:38:58 opera volumio[1116]: error: ATTENTION!!!: Plugin cd_controller does not return adequate promise from onVolumioStart: please update! Aug 18 00:38:58 opera volumio[1116]: info: Loading plugin "webradio"... Aug 18 00:38:59 opera volumio[1116]: info: Loading plugin "i2s_dacs"... Aug 18 00:38:59 opera volumio[1116]: info: I2S DAC not set, start Auto-detection Aug 18 00:38:59 opera volumio[1116]: info: Loading plugin "volumiodiscovery"... Aug 18 00:38:59 opera volumio[1116]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 18 00:38:59 opera node[1116]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 18 00:38:59 opera node[1116]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 18 00:38:59 opera node[1116]: *** WARNING *** For more information see Aug 18 00:38:59 opera node[1116]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 18 00:38:59 opera node[1116]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 18 00:38:59 opera node[1116]: *** WARNING *** For more information see Aug 18 00:38:59 opera volumio[1116]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 18 00:38:59 opera volumio[1116]: *** WARNING *** For more information see Aug 18 00:38:59 opera volumio[1116]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 18 00:38:59 opera volumio[1116]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 18 00:38:59 opera volumio[1116]: *** WARNING *** For more information see Aug 18 00:38:59 opera volumio[1116]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 18 00:38:59 opera volumio[1116]: Discovery: StartAdv! undefined Aug 18 00:38:59 opera volumio[1116]: Discovery: Started advertising... Opera - undefined Aug 18 00:38:59 opera volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 18 00:38:59 opera volumio[1116]: info: Loading plugin "albumart"... Aug 18 00:38:59 opera volumio[1116]: info: Plugin example_plugin is not enabled Aug 18 00:38:59 opera volumio[1116]: info: Loading plugin "updater_comm"... Aug 18 00:39:00 opera dhcpcd[575]: timed out Aug 18 00:39:00 opera dhcpcd[575]: allowing 8 seconds for IPv4LL timeout Aug 18 00:39:00 opera ifup[553]: dhcpcd[575]: timed out Aug 18 00:39:00 opera ifup[553]: dhcpcd[575]: allowing 8 seconds for IPv4LL timeout Aug 18 00:39:00 opera volumio[1116]: info: Plugin mpdemulation is not enabled Aug 18 00:39:00 opera volumio[1116]: info: Loading plugin "websocket"... Aug 18 00:39:00 opera volumio[1116]: info: ___________ START PLUGINS ___________ Aug 18 00:39:00 opera volumio[1116]: info: PLUGIN START: appearance Aug 18 00:39:00 opera volumio[1116]: info: PLUGIN START: webradio Aug 18 00:39:00 opera volumio[1116]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 18 00:39:00 opera volumio[1116]: info: [1723941540404] CoreMusicLibrary::Adding element Webradio Aug 18 00:39:00 opera volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 18 00:39:00 opera volumio[1116]: info: Loading i18n strings for locale zh Aug 18 00:39:00 opera volumio[1116]: Updating browse sources language Aug 18 00:39:00 opera volumio[1116]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 00:39:00 opera volumio[1116]: info: CoreCommandRouter::initPlayerControls Aug 18 00:39:00 opera volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 00:39:00 opera volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Aug 18 00:39:00 opera volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 00:39:00 opera volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 00:39:00 opera volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 00:39:00 opera volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 00:39:00 opera volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 00:39:00 opera volumio[1116]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 00:39:00 opera volumio[1116]: info: BOOT COMPLETED Aug 18 00:39:00 opera volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 00:39:00 opera volumio[1116]: info: CoreCommandRouter::Close All Modals sent Aug 18 00:39:00 opera volumio[1116]: Express server listening on port 3000 Aug 18 00:39:00 opera volumio[1116]: Volumio Calling Home Aug 18 00:39:01 opera volumio[1116]: info: CoreStateMachine::resetVolumioState Aug 18 00:39:01 opera volumio[1116]: info: CoreStateMachine::getcurrentVolume Aug 18 00:39:01 opera volumio[1116]: info: CoreCommandRouter::volumioRetrievevolume Aug 18 00:39:01 opera volumio[1116]: info: CoreStateMachine::updateTrackBlock Aug 18 00:39:01 opera volumio[1116]: info: CorePlayQueue::getTrackBlock Aug 18 00:39:01 opera volumio[1116]: info: Setting Device type: Raspberry PI Aug 18 00:39:01 opera volumio[1116]: info: MPD running with PID645 ,establishing connection Aug 18 00:39:01 opera sudo[1178]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Aug 18 00:39:01 opera sudo[1178]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:39:01 opera sudo[1178]: pam_unix(sudo:session): session closed for user root Aug 18 00:39:02 opera volumio[1116]: info: VolumeController:: Volume=undefined Mute =undefined Aug 18 00:39:02 opera volumio[1116]: info: CoreStateMachine::pushState Aug 18 00:39:02 opera volumio[1116]: info: CoreStateMachine::getState Aug 18 00:39:02 opera volumio[1116]: info: CorePlayQueue::getTrack 0 Aug 18 00:39:02 opera volumio[1116]: info: CoreCommandRouter::volumioPushState Aug 18 00:39:02 opera volumio[1116]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 00:39:02 opera volumio[1116]: info: Cannot read I2C interface or I2C interface not presentError: Command failed: /usr/bin/sudo /usr/sbin/i2cdetect -y 1 Aug 18 00:39:02 opera volumio[1116]: Error: Could not open file `/dev/i2c-1' or `/dev/i2c/1': No such file or directory Aug 18 00:39:02 opera volumio[1116]: info: Reloading queue from file Aug 18 00:39:02 opera volumio[1116]: info: CoreStateMachine::setRepeat true single undefined Aug 18 00:39:02 opera volumio[1116]: info: CoreStateMachine::pushState Aug 18 00:39:02 opera volumio[1116]: info: CoreStateMachine::getState Aug 18 00:39:02 opera volumio[1116]: info: CorePlayQueue::getTrack 0 Aug 18 00:39:02 opera volumio[1116]: info: CoreCommandRouter::volumioPushState Aug 18 00:39:02 opera volumio[1116]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 00:39:02 opera volumio[1116]: info: CoreStateMachine::setRandom false Aug 18 00:39:02 opera volumio[1116]: info: CoreStateMachine::pushState Aug 18 00:39:02 opera volumio[1116]: info: CoreStateMachine::getState Aug 18 00:39:02 opera volumio[1116]: info: CorePlayQueue::getTrack 0 Aug 18 00:39:02 opera volumio[1116]: info: CoreCommandRouter::volumioPushState Aug 18 00:39:02 opera volumio[1116]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 00:39:03 opera sudo[1189]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay Aug 18 00:39:03 opera volumio[1116]: info: CALLMETHOD: music_service cd_controller detectCD [object Object] Aug 18 00:39:03 opera volumio[1116]: info: CoreCommandRouter::executeOnPlugin: cd_controller , detectCD Aug 18 00:39:03 opera volumio[1116]: info: Writing CDDB file Aug 18 00:39:03 opera sudo[1189]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:39:03 opera systemd[1]: Starting ShairportSync AirTunes receiver... Aug 18 00:39:03 opera systemd[1]: Started ShairportSync AirTunes receiver. Aug 18 00:39:03 opera sudo[1189]: pam_unix(sudo:session): session closed for user root Aug 18 00:39:03 opera shairport-sync[1195]: Successful Startup Aug 18 00:39:03 opera volumio[1116]: info: Pushing Favourites {"service":"mpd","uri":"","favourite":false} Aug 18 00:39:03 opera volumio[1116]: Discovery: adding 3269fcff-0cdd-4c69-abcc-78820a9a6f21 Aug 18 00:39:03 opera volumio[1116]: info: mDNS: Found device Opera Aug 18 00:39:03 opera volumio[1116]: info: [1723941543705] Shairport-Sync Started Aug 18 00:39:03 opera volumio[1116]: Error adding Membership: Error: addMembership EINVAL Aug 18 00:39:03 opera volumio[1116]: info: CDDB Entry Already exists Aug 18 00:39:03 opera volumio[1116]: info: Pushing Favourites {"service":"mpd","uri":"cdda:///1","favourite":false} Aug 18 00:39:03 opera volumio[1116]: info: Pushing Favourites {"service":"mpd","uri":"cdda:///1","favourite":false} Aug 18 00:39:04 opera volumio[1116]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 18 00:39:04 opera volumio[1116]: info: [1723941544023] CoreMusicLibrary::Adding element Audio CD Aug 18 00:39:06 opera volumio[1116]: info: CoreCommandRouter::Close All Modals sent Aug 18 00:39:07 opera volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 18 00:39:07 opera volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 18 00:39:07 opera sudo[1212]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 18 00:39:07 opera sudo[1212]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:39:07 opera systemd[1]: Starting UPnP Renderer front-end to MPD... Aug 18 00:39:07 opera systemd[1]: Started UPnP Renderer front-end to MPD. Aug 18 00:39:07 opera sudo[1212]: pam_unix(sudo:session): session closed for user root Aug 18 00:39:07 opera volumio[1116]: info: Upmpdcli Daemon Started Aug 18 00:39:08 opera dhcpcd[575]: timed out Aug 18 00:39:08 opera dhcpcd[575]: exited Aug 18 00:39:08 opera ifup[553]: dhcpcd[575]: timed out Aug 18 00:39:08 opera ifup[553]: dhcpcd[575]: exited Aug 18 00:39:08 opera ifup[553]: Failed to bring up eth0. Aug 18 00:39:09 opera volumio[1116]: info: CoreCommandRouter::volumioGetState Aug 18 00:39:09 opera volumio[1116]: info: CoreStateMachine::getState Aug 18 00:39:09 opera volumio[1116]: info: CorePlayQueue::getTrack 0 Aug 18 00:39:09 opera volumio[1116]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 18 00:39:09 opera volumio[1116]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 18 00:39:09 opera volumio[1116]: Plugin multiroom or method getMultiroom not found Aug 18 00:39:09 opera volumio[1116]: info: Listing playlists Aug 18 00:39:09 opera volumio[1116]: info: No input data Aug 18 00:39:10 opera volumio[1116]: info: VolumeController::SetAlsaVolume100 Aug 18 00:39:10 opera volumio[1116]: Error: Alsa Mixer Error: Aug 18 00:39:10 opera volumio[1116]: at Socket. (/volumio/app/volumecontrol.js:72:10) Aug 18 00:39:10 opera volumio[1116]: at emitOne (events.js:96:13) Aug 18 00:39:10 opera volumio[1116]: at Socket.emit (events.js:188:7) Aug 18 00:39:10 opera volumio[1116]: at readableAddChunk (_stream_readable.js:176:18) Aug 18 00:39:10 opera volumio[1116]: at Socket.Readable.push (_stream_readable.js:134:10) Aug 18 00:39:10 opera volumio[1116]: at Pipe.onread (net.js:547:20) Aug 18 00:39:10 opera volumio[1116]: info: VolumeController::Volume 100 Aug 18 00:39:10 opera volumio[1116]: info: CoreStateMachine::pushState Aug 18 00:39:10 opera volumio[1116]: info: CoreStateMachine::getState Aug 18 00:39:10 opera volumio[1116]: info: CorePlayQueue::getTrack 0 Aug 18 00:39:10 opera volumio[1116]: info: CoreCommandRouter::volumioPushState Aug 18 00:39:10 opera volumio[1116]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 00:39:10 opera volumio[1116]: info: Pushing Favourites {"service":"mpd","uri":"cdda:///1","favourite":false} Aug 18 00:39:11 opera volumio-remote-updater[621]: [2024-08-18 00:39:11] [connect] Successful connection Aug 18 00:39:11 opera volumio-remote-updater[621]: [2024-08-18 00:39:11] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1723941551 101 Aug 18 00:39:12 opera volumio[1116]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=opera&version=2.114&uuid=290cbe8acdb25b6750a7699b4e406957" http://updates.volumio.org:7070/downloader-v1/track-device Aug 18 00:39:12 opera volumio[1116]: % Total % Received % Xferd Average Speed Time Time Time Current Aug 18 00:39:12 opera volumio[1116]: Dload Upload Total Spent Left Speed Aug 18 00:39:12 opera volumio[1116]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Aug 18 00:39:12 opera volumio[1116]: retrying in 5 seconds, trial 0 Aug 18 00:39:12 opera volumio[1116]: Volumio Calling Home Aug 18 00:39:13 opera volumio[1116]: info: CoreCommandRouter::volumioSeek Aug 18 00:39:13 opera volumio[1116]: info: CoreStateMachine::seek Aug 18 00:39:13 opera volumio[1116]: info: CorePlayQueue::getTrack 0 Aug 18 00:39:13 opera volumio[1116]: info: TRACKBLOCK {"albumart":"/albumart","bitdepth":"","channels":2,"title":"Track 1","album":"CD Audio","artist":"","samplerate":"","service":"mpd","uri":"cdda:///1","name":"Track 1"} Aug 18 00:39:13 opera volumio[1116]: info: CoreStateMachine::startPlaybackTimer Aug 18 00:39:13 opera volumio[1116]: info: CorePlayQueue::getTrack 0 Aug 18 00:39:13 opera volumio[1116]: info: ControllerMpd::seek Aug 18 00:39:13 opera volumio[1116]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 18 00:39:13 opera volumio[1116]: { AssertionError: false == true Aug 18 00:39:13 opera volumio[1116]: at MpdClient.sendCommand (/volumio/app/plugins/music_service/mpd/lib/mpd.js:92:9) Aug 18 00:39:13 opera volumio[1116]: at ControllerMpd.seek (/volumio/app/plugins/music_service/mpd/index.js:2722:24) Aug 18 00:39:13 opera volumio[1116]: at CoreStateMachine.seek (/volumio/app/statemachine.js:1179:15) Aug 18 00:39:13 opera volumio[1116]: at CoreCommandRouter.volumioSeek (/volumio/app/index.js:1303:27) Aug 18 00:39:13 opera volumio[1116]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:151:31) Aug 18 00:39:13 opera volumio[1116]: at emitTwo (events.js:106:13) Aug 18 00:39:13 opera volumio[1116]: at Socket.emit (events.js:191:7) Aug 18 00:39:13 opera volumio[1116]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Aug 18 00:39:13 opera volumio[1116]: at _combinedTickCallback (internal/process/next_tick.js:73:7) Aug 18 00:39:13 opera volumio[1116]: at process._tickCallback (internal/process/next_tick.js:104:9) Aug 18 00:39:13 opera volumio[1116]: name: 'AssertionError', Aug 18 00:39:13 opera volumio[1116]: actual: false, Aug 18 00:39:13 opera volumio[1116]: expected: true, Aug 18 00:39:13 opera volumio[1116]: operator: '==', Aug 18 00:39:13 opera volumio[1116]: message: 'false == true', Aug 18 00:39:13 opera volumio[1116]: generatedMessage: true } Aug 18 00:39:13 opera volumio[1116]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 18 00:39:15 opera sudo[1246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-18 00:38 Aug 18 00:39:15 opera sudo[1246]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="7bac1c7b678b8c4c610bba4d70a5c16ee62b7e19" VOLUMIO_FE_VERSION="2d80816ad99ca4dfec2998adad5179fd25e874d5" VOLUMIO_BE_VERSION="9ac13199e84bd2c5cf8082565cccc6b80b02df97" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="opera" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon Apr 9 12:10:35 CEST 2018" VOLUMIO_VERSION="2.114" VOLUMIO_HARDWARE="pi"