Forked DAAPD / HomePod Log

forked-daapd.log

[2019-04-29 22:10:04] [DEBUG]      web: JSON api request: '/api/player'
[2019-04-29 22:10:04] [DEBUG]    httpd: Checking web interface authentication
[2019-04-29 22:10:04] [DEBUG]    httpd: Authentication successful
[2019-04-29 22:10:04] [DEBUG]   player: Player status: playing
[2019-04-29 22:10:06] [DEBUG]     raop: Got retransmit request from 'myPod', seq_start 16757 len 1
[2019-04-29 22:10:06] [DEBUG]     raop: Got retransmit request from 'myPod', seq_start 16816 len 1
[2019-04-29 22:10:10] [DEBUG]     raop: Got retransmit request from 'myPod', seq_start 17336 len 1
.
.
.
[2019-04-29 22:12:13] [DEBUG]     raop: Got retransmit request from 'myPod', seq_start 32667 len 1
[2019-04-29 22:12:14] [  LOG]     raop: Device 'myPod' closed RTSP connection
[2019-04-29 22:12:14] [DEBUG]     raop: Cleaning up failed session (deferred) on device 'myPod'
[2019-04-29 22:12:14] [DEBUG]   player: Callback request received, id is 0
[2019-04-29 22:12:14] [DEBUG]   player: Unsubscription request for quality 44100/16/2 (now 0 subscribers)
[2019-04-29 22:12:14] [DEBUG]   player: Making deferred callback to device_streaming_cb, id was 0
[2019-04-29 22:12:14] [DEBUG]   player: Callback from AirPlay to device_streaming_cb (status -1)
[2019-04-29 22:12:14] [  LOG]   player: The AirPlay device 'myPod' FAILED
[2019-04-29 22:12:14] [DEBUG]      mpd: Asynchronous listener callback called with event type 1.
[2019-04-29 22:12:14] [DEBUG]      mpd: Notify clients waiting for idle results: 1
[2019-04-29 22:12:14] [DEBUG]       db: Running query 'BEGIN TRANSACTION;'
[2019-04-29 22:12:14] [DEBUG]       db: Running query 'SELECT value FROM admin a WHERE a.key = 'queue_version';'
[2019-04-29 22:12:14] [DEBUG]       db: Running query 'DELETE FROM queue where id <> 0;'
[2019-04-29 22:12:14] [DEBUG]       db: Running query 'INSERT OR REPLACE INTO admin (key, value) VALUES ('queue_version', '152');'
[2019-04-29 22:12:14] [DEBUG]       db: Running query 'END TRANSACTION;'
[2019-04-29 22:12:14] [DEBUG]      mpd: Asynchronous listener callback called with event type 2.
[2019-04-29 22:12:14] [DEBUG]      mpd: Notify clients waiting for idle results: 2
[2019-04-29 22:12:14] [DEBUG]      web: notify callback reason: 11
[2019-04-29 22:12:14] [DEBUG]      web: JSON api request: '/api/player'
[2019-04-29 22:12:14] [DEBUG]    httpd: Checking web interface authentication
[2019-04-29 22:12:14] [DEBUG]    httpd: No Authorization header
[2019-04-29 22:12:14] [  LOG]    httpd: Web interface request to '/api/player' denied: Incorrect password
[2019-04-29 22:12:14] [DEBUG]      web: JSON api request: '/api/queue'
[2019-04-29 22:12:14] [DEBUG]    httpd: Checking web interface authentication
[2019-04-29 22:12:14] [DEBUG]    httpd: No Authorization header
[2019-04-29 22:12:14] [  LOG]    httpd: Web interface request to '/api/queue' denied: Incorrect password
[2019-04-29 22:12:14] [DEBUG]      web: JSON api request: '/api/player'
[2019-04-29 22:12:14] [DEBUG]    httpd: Checking web interface authentication
[2019-04-29 22:12:14] [DEBUG]    httpd: Authentication successful
[2019-04-29 22:12:14] [DEBUG]   player: Player status: stopped
[2019-04-29 22:12:14] [DEBUG]       db: Running query 'BEGIN TRANSACTION;'
[2019-04-29 22:12:14] [DEBUG]       db: Starting enum 'SELECT * FROM queue f WHERE pos = 0 ORDER BY pos;'
[2019-04-29 22:12:14] [DEBUG]       db: End of queue enum results
[2019-04-29 22:12:14] [DEBUG]       db: Running query 'END TRANSACTION;'
[2019-04-29 22:12:14] [DEBUG]      web: JSON api request: '/api/queue'
[2019-04-29 22:12:14] [DEBUG]    httpd: Checking web interface authentication
[2019-04-29 22:12:14] [DEBUG]    httpd: Authentication successful
[2019-04-29 22:12:14] [DEBUG]       db: Running query 'SELECT value FROM admin a WHERE a.key = 'queue_version';'
[2019-04-29 22:12:14] [DEBUG]       db: Running query 'SELECT COUNT(*) FROM queue;'
[2019-04-29 22:12:14] [DEBUG]   player: Player status: stopped
[2019-04-29 22:12:14] [DEBUG]       db: Running query 'BEGIN TRANSACTION;'
[2019-04-29 22:12:14] [DEBUG]       db: Starting enum 'SELECT * FROM queue f WHERE 1=1 ORDER BY pos;'
[2019-04-29 22:12:14] [DEBUG]       db: End of queue enum results
[2019-04-29 22:12:14] [DEBUG]       db: Running query 'END TRANSACTION;'
[2019-04-29 22:12:16] [DEBUG]     mdns: Avahi Resolver: resolved service 'D4A33D79B5CE@myPod' type '_raop._tcp' proto 0, host myPod.local, address 192.168.178.11
[2019-04-29 22:12:16] [DEBUG]     mdns: Connection test to 192.168.178.11:7000 completed successfully
[2019-04-29 22:12:16] [DEBUG]     raop: Event for AirPlay device 'myPod' (port 7000, id d4a33d79b5ce)
[2019-04-29 22:12:16] [ INFO]     raop: Adding AirPlay device 'myPod': password: 0, verification: 0, encrypt: 0, authsetup: 0, metadata: 1, type Other, address 192.168.178.11:7000
[2019-04-29 22:12:16] [DEBUG]     mdns: Avahi Resolver: resolved service 'D4A33D79B5CE@myPod' type '_raop._tcp' proto 1, host myPod.local, address 192.168.178.11
[2019-04-29 22:12:16] [ WARN]     mdns: Connection test to [192.168.178.11]:7000 failed with getaddrinfo error: Address family for hostname not supported
[2019-04-29 22:12:16] [ WARN]     mdns: Ignoring announcement from myPod.local, address [192.168.178.11] is not connectable
[2019-04-29 22:12:16] [DEBUG]      mpd: Asynchronous listener callback called with event type 8.
[2019-04-29 22:12:16] [DEBUG]      mpd: Notify clients waiting for idle results: 8
[2019-04-29 22:12:16] [DEBUG]     mdns: Avahi Record Browser (myPod.local, proto 1): NEW record fe80::1c0e:1811:83b4:3f95 for service type '_raop._tcp'
[2019-04-29 22:12:16] [ WARN]     mdns: Ignoring announcement from myPod.local, address [fe80::1c0e:1811:83b4:3f95] is link-local
[2019-04-29 22:12:16] [DEBUG]     mdns: Avahi Record Browser (myPod.local, proto 1): no more results (CACHE_EXHAUSTED)
[2019-04-29 22:12:16] [DEBUG]      web: notify callback reason: 11
[2019-04-29 22:12:17] [DEBUG]      web: JSON api request: '/api/outputs'
[2019-04-29 22:12:17] [DEBUG]    httpd: Checking web interface authentication
[2019-04-29 22:12:17] [DEBUG]    httpd: Authentication successful