-- Logs begin at Tue 2019-03-12 18:57:25 UTC, end at Tue 2019-03-12 19:16:47 UTC. -- Mar 12 19:15:38 volumio volumio[839]: info: CorePlayQueue::getTrack 368 Mar 12 19:15:38 volumio volumio[839]: info: CorePlayQueue::getTrack 369 Mar 12 19:15:38 volumio volumio[839]: info: Prefetching next song Mar 12 19:15:38 volumio volumio[839]: info: DOING PREFETCH IN MPD Mar 12 19:15:38 volumio volumio[839]: info: ControllerMpd::sendMpdCommand add "USB/KINGSTON/montiel medieval 14/The Beatles - Love/08 the beatles - gnik nus.mp3" Mar 12 19:15:38 volumio volumio[839]: info: sending command... Mar 12 19:15:38 volumio volumio[839]: info: Mar 12 19:15:38 volumio volumio[839]: ---------------------------- MPD announces system playlist update Mar 12 19:15:38 volumio volumio[839]: info: Ignoring MPD Status Update Mar 12 19:15:38 volumio volumio[839]: info: parsing response... Mar 12 19:15:38 volumio volumio[839]: info: ControllerMpd::sendMpdCommand consume 1 Mar 12 19:15:38 volumio volumio[839]: info: sending command... Mar 12 19:15:38 volumio volumio[839]: info: ------------------------------ 4ms Mar 12 19:15:38 volumio volumio[839]: info: parsing response... Mar 12 19:15:42 volumio volumio[839]: info: CoreStateMachine::startPlaybackTimer Mar 12 19:15:42 volumio volumio[839]: info: CorePlayQueue::getTrack 369 Mar 12 19:15:43 volumio volumio[839]: info: Mar 12 19:15:43 volumio volumio[839]: ---------------------------- MPD announces system playlist update Mar 12 19:15:43 volumio volumio[839]: info: Ignoring MPD Status Update Mar 12 19:15:43 volumio volumio[839]: info: Mar 12 19:15:43 volumio volumio[839]: ---------------------------- MPD announces state update: player Mar 12 19:15:43 volumio volumio[839]: info: ControllerMpd::getState Mar 12 19:15:43 volumio volumio[839]: info: ControllerMpd::sendMpdCommand status Mar 12 19:15:43 volumio volumio[839]: info: sending command... Mar 12 19:15:43 volumio volumio[839]: info: ------------------------------ 4ms Mar 12 19:15:43 volumio volumio[839]: info: parsing response... Mar 12 19:15:43 volumio volumio[839]: info: ControllerMpd::parseState Mar 12 19:15:43 volumio volumio[839]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 12 19:15:43 volumio volumio[839]: info: sending command... Mar 12 19:15:43 volumio volumio[839]: info: parsing response... Mar 12 19:15:43 volumio volumio[839]: info: ControllerMpd::parseTrackInfo Mar 12 19:15:43 volumio volumio[839]: info: ControllerMpd::pushState Mar 12 19:15:43 volumio volumio[839]: info: CoreCommandRouter::servicePushState Mar 12 19:15:43 volumio volumio[839]: info: CoreStateMachine::syncState Mar 12 19:15:43 volumio volumio[839]: info: CorePlayQueue::getTrack 369 Mar 12 19:15:43 volumio volumio[839]: info: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":55,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"Gnik Nus","artist":"The Beatles","album":"Love","uri":"USB/KINGSTON/montiel medieval 14/The Beatles - Love/08 the beatles - gnik nus.mp3","trackType":"mp3"} Mar 12 19:15:43 volumio volumio[839]: info: CURRENT POSITION 369 Mar 12 19:15:43 volumio volumio[839]: info: CoreStateMachine::syncState stateService play Mar 12 19:15:43 volumio volumio[839]: info: CoreStateMachine::syncState currentStatus play Mar 12 19:15:43 volumio volumio[839]: info: Received an update from plugin. extracting info from payload Mar 12 19:15:43 volumio volumio[839]: info: CoreStateMachine::pushState Mar 12 19:15:43 volumio volumio[839]: info: CoreStateMachine::getState Mar 12 19:15:43 volumio volumio[839]: info: CorePlayQueue::getTrack 369 Mar 12 19:15:43 volumio volumio[839]: info: CoreCommandRouter::volumioPushState Mar 12 19:15:43 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 12 19:15:43 volumio volumio[839]: info: CoreStateMachine::pushState Mar 12 19:15:43 volumio volumio[839]: info: CoreStateMachine::getState Mar 12 19:15:43 volumio volumio[839]: info: CorePlayQueue::getTrack 369 Mar 12 19:15:43 volumio volumio[839]: info: CoreCommandRouter::volumioPushState Mar 12 19:15:43 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 12 19:15:43 volumio sudo[8680]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 12 19:15:43 volumio sudo[8680]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 19:15:43 volumio sudo[8680]: pam_unix(sudo:session): session closed for user root Mar 12 19:15:43 volumio sudo[8685]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 12 19:15:43 volumio volumio[839]: info: ------------------------------ 70ms Mar 12 19:15:43 volumio sudo[8685]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 19:15:43 volumio sudo[8685]: pam_unix(sudo:session): session closed for user root Mar 12 19:16:00 volumio dhcpd[861]: DHCPDISCOVER from b8:27:eb:3a:f7:81 (volumio) via wlan0 Mar 12 19:16:01 volumio dhcpd[861]: DHCPOFFER on 192.168.211.3 to b8:27:eb:3a:f7:81 (volumio) via wlan0 Mar 12 19:16:33 volumio volumio[839]: info: CorePlayQueue::getTrack 369 Mar 12 19:16:33 volumio volumio[839]: info: CorePlayQueue::getTrack 370 Mar 12 19:16:33 volumio volumio[839]: info: Prefetching next song Mar 12 19:16:33 volumio volumio[839]: info: DOING PREFETCH IN MPD Mar 12 19:16:33 volumio volumio[839]: info: ControllerMpd::sendMpdCommand add "USB/KINGSTON/montiel medieval 14/The Beatles - Love/09 the beatles - something.mp3" Mar 12 19:16:33 volumio volumio[839]: info: sending command... Mar 12 19:16:33 volumio volumio[839]: info: Mar 12 19:16:33 volumio volumio[839]: ---------------------------- MPD announces system playlist update Mar 12 19:16:33 volumio volumio[839]: info: Ignoring MPD Status Update Mar 12 19:16:33 volumio volumio[839]: info: parsing response... Mar 12 19:16:33 volumio volumio[839]: info: ControllerMpd::sendMpdCommand consume 1 Mar 12 19:16:33 volumio volumio[839]: info: sending command... Mar 12 19:16:33 volumio volumio[839]: info: ------------------------------ 4ms Mar 12 19:16:33 volumio volumio[839]: info: parsing response... Mar 12 19:16:34 volumio dhcpcd[932]: eth0: carrier acquired Mar 12 19:16:34 volumio kernel: smsc95xx 1-1.1:1.0 eth0: link up, 100Mbps, full-duplex, lpa 0xC5E1 Mar 12 19:16:34 volumio dhcpcd[932]: eth0: soliciting an IPv6 router Mar 12 19:16:34 volumio dhcpcd[932]: eth0: using static address 192.168.2.93 Mar 12 19:16:34 volumio dhcpcd[932]: eth0: adding host route to 192.168.2.93 via 127.0.0.1 Mar 12 19:16:34 volumio dhcpcd[932]: eth0: adding route to 192.168.2.0/24 Mar 12 19:16:34 volumio dhcpcd[932]: eth0: adding default route via 192.168.2.9 Mar 12 19:16:34 volumio avahi-daemon[602]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.2.93. Mar 12 19:16:34 volumio avahi-daemon[602]: New relevant interface eth0.IPv4 for mDNS. Mar 12 19:16:34 volumio avahi-daemon[602]: Registering new address record for 192.168.2.93 on eth0.IPv4. Mar 12 19:16:35 volumio ifplugd(eth0)[635]: Link beat detected. Mar 12 19:16:35 volumio ifplugd(eth0)[635]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Mar 12 19:16:35 volumio ifplugd(eth0)[635]: client: /sbin/ifup: interface eth0 already configured Mar 12 19:16:35 volumio ifplugd(eth0)[635]: Program executed successfully. Mar 12 19:16:36 volumio ntpd[659]: Listen normally on 5 eth0 192.168.2.93 UDP 123 Mar 12 19:16:36 volumio ntpd[659]: peers refreshed Mar 12 19:16:37 volumio volumio[839]: info: CoreStateMachine::startPlaybackTimer Mar 12 19:16:37 volumio volumio[839]: info: CorePlayQueue::getTrack 370 Mar 12 19:16:38 volumio volumio[839]: info: Mar 12 19:16:38 volumio volumio[839]: ---------------------------- MPD announces system playlist update Mar 12 19:16:38 volumio volumio[839]: info: Ignoring MPD Status Update Mar 12 19:16:38 volumio volumio[839]: info: Mar 12 19:16:38 volumio volumio[839]: ---------------------------- MPD announces state update: player Mar 12 19:16:38 volumio volumio[839]: info: ControllerMpd::getState Mar 12 19:16:38 volumio volumio[839]: info: ControllerMpd::sendMpdCommand status Mar 12 19:16:38 volumio volumio[839]: info: sending command... Mar 12 19:16:38 volumio volumio[839]: info: ------------------------------ 4ms Mar 12 19:16:38 volumio volumio[839]: info: parsing response... Mar 12 19:16:38 volumio volumio[839]: info: ControllerMpd::parseState Mar 12 19:16:38 volumio volumio[839]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 12 19:16:38 volumio volumio[839]: info: sending command... Mar 12 19:16:38 volumio volumio[839]: info: parsing response... Mar 12 19:16:38 volumio volumio[839]: info: ControllerMpd::parseTrackInfo Mar 12 19:16:38 volumio volumio[839]: info: ControllerMpd::pushState Mar 12 19:16:38 volumio volumio[839]: info: CoreCommandRouter::servicePushState Mar 12 19:16:38 volumio volumio[839]: info: CoreStateMachine::syncState Mar 12 19:16:38 volumio volumio[839]: info: CorePlayQueue::getTrack 370 Mar 12 19:16:38 volumio volumio[839]: info: STATE SERVICE {"status":"play","position":0,"seek":81,"duration":210,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"Something","artist":"The Beatles","album":"Love","uri":"USB/KINGSTON/montiel medieval 14/The Beatles - Love/09 the beatles - something.mp3","trackType":"mp3"} Mar 12 19:16:38 volumio volumio[839]: info: CURRENT POSITION 370 Mar 12 19:16:38 volumio volumio[839]: info: CoreStateMachine::syncState stateService play Mar 12 19:16:38 volumio volumio[839]: info: CoreStateMachine::syncState currentStatus play Mar 12 19:16:38 volumio volumio[839]: info: Received an update from plugin. extracting info from payload Mar 12 19:16:38 volumio volumio[839]: info: CoreStateMachine::pushState Mar 12 19:16:38 volumio volumio[839]: info: CoreStateMachine::getState Mar 12 19:16:38 volumio volumio[839]: info: CorePlayQueue::getTrack 370 Mar 12 19:16:38 volumio volumio[839]: info: CoreCommandRouter::volumioPushState Mar 12 19:16:38 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 12 19:16:38 volumio sudo[9118]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 12 19:16:38 volumio volumio[839]: info: CoreStateMachine::pushState Mar 12 19:16:38 volumio volumio[839]: info: CoreStateMachine::getState Mar 12 19:16:38 volumio volumio[839]: info: CorePlayQueue::getTrack 370 Mar 12 19:16:38 volumio volumio[839]: info: CoreCommandRouter::volumioPushState Mar 12 19:16:38 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 12 19:16:38 volumio sudo[9118]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 19:16:38 volumio sudo[9118]: pam_unix(sudo:session): session closed for user root Mar 12 19:16:38 volumio volumio[839]: info: ------------------------------ 71ms Mar 12 19:16:38 volumio sudo[9126]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 12 19:16:38 volumio sudo[9126]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 19:16:38 volumio sudo[9126]: pam_unix(sudo:session): session closed for user root Mar 12 19:16:40 volumio volumio[839]: Discovery: this is already registered, ea265773-36ac-4a46-a7de-87b84549b0b2 Mar 12 19:16:40 volumio volumio[839]: info: mDNS: Found device Volumio Mar 12 19:16:40 volumio sudo[9150]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 12 19:16:40 volumio sudo[9150]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 19:16:40 volumio sudo[9150]: pam_unix(sudo:session): session closed for user root Mar 12 19:16:43 volumio ntpd_intres[747]: DNS 0.debian.pool.ntp.org -> 194.0.5.123 Mar 12 19:16:46 volumio volumio[839]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 12 19:16:46 volumio volumio[839]: TypeError: Cannot create property 'type' on string 'invalid server key' Mar 12 19:16:46 volumio volumio[839]: at WebSocket.onError (/volumio/node_modules/engine.io-client/node_modules/ws/lib/WebSocket.js:450:16) Mar 12 19:16:46 volumio volumio[839]: at emitOne (events.js:116:13) Mar 12 19:16:46 volumio volumio[839]: at WebSocket.emit (events.js:211:7) Mar 12 19:16:46 volumio volumio[839]: at ClientRequest.upgrade (/volumio/node_modules/engine.io-client/node_modules/ws/lib/WebSocket.js:738:12) Mar 12 19:16:46 volumio volumio[839]: at Object.onceWrapper (events.js:319:30) Mar 12 19:16:46 volumio volumio[839]: at emitThree (events.js:136:13) Mar 12 19:16:46 volumio volumio[839]: at ClientRequest.emit (events.js:217:7) Mar 12 19:16:46 volumio volumio[839]: at Socket.socketOnData (_http_client.js:472:11) Mar 12 19:16:46 volumio volumio[839]: at emitOne (events.js:116:13) Mar 12 19:16:46 volumio volumio[839]: at Socket.emit (events.js:211:7) Mar 12 19:16:46 volumio volumio[839]: at addChunk (_stream_readable.js:263:12) Mar 12 19:16:46 volumio volumio[839]: at readableAddChunk (_stream_readable.js:250:11) Mar 12 19:16:46 volumio volumio[839]: at Socket.Readable.push (_stream_readable.js:208:10) Mar 12 19:16:46 volumio volumio[839]: at TCP.onread (net.js:607:20) Mar 12 19:16:46 volumio volumio[839]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 12 19:16:47 volumio sudo[9209]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2019-03-12 19:15 Mar 12 19:16:47 volumio sudo[9209]: 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="f1b59d96a3893a42f8609371956ecba0438017d7" VOLUMIO_FE_VERSION="95791212f747b95a2cb050c16459a831bfe5c7e7" VOLUMIO_BE_VERSION="1c3c01aeeeb4cff8e6c9a4b6c34e1a558d1b237b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 12 19:57:25 CET 2019" VOLUMIO_VERSION="2.565" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="64b803aae2d00ba86d00d612c4abb38d"