Ankündigung

**marcel0509** · 20.02.2017, 14:50

Hallo Stefan,

ich bin auch gerade dabei deine neueste Version zu installieren, welches problemlos geklappt hat, leider bekomme ich aber deine neue Funktion "Volumen DPT3 Unterstützung" nicht zum laufen über den MDT Glasttaster 2.

Habe dort 2 Tasten als "Dimmer" eingestellt (hat nur 4-Bit) und die KNX Adresse vergeben. Beim gedrückt halten wird die Musik lauter bzw. leiser aber beim los lassen des Tasters geht dieser wieder in seinen Ausgangswert zurück.

Liegt dies am Taster oder am Plugin?

Dank dir

[[volume]]
type = num
sonos_recv = volume
sonos_send = volume

[[[group_command]]]
type = bool
value = 0

[[[volume_dpt3]]]
type = list
sonos_volume_dpt3 = foo
sonos_vol_step = 2
sonos_vol_time = 1
knx_dpt = 3
knx_listen = 8/3/4

[[[[helper]]]]
type = num
sonos_send = volume

Code:

2017-02-20 14:43:18 DEBUG    item         Main         Item Kueche.play = True via KNX 1.1.27 8/3/0 -- item.py:__update:374
2017-02-20 14:43:18 DEBUG    __init__     Main         Sending request: {'command': 'set_play', 'parameter': {'play': 1, 'uid': 'rincon_5caafd28746401400'}} -- __init__.py:_send_cmd:504
2017-02-20 14:43:18 INFO     __init__     Main         Sonos: Message http://192.168.0.145:12900 {'command': 'set_play', 'parameter': {'play': 1, 'uid': 'rincon_5caafd28746401400'}} successfully sent - 200 OK -- __init__.py:_send_cmd:514
2017-02-20 14:43:25 DEBUG    __init__     Main         KNX[default]: 1.1.27 set 8/3/4 to [1, 1] -- __init__.py:parse_telegram:207
2017-02-20 14:43:25 DEBUG    item         Main         Item Kueche.volume.volume_dpt3 = [1, 1] via KNX 1.1.27 8/3/4 -- item.py:__update:374
2017-02-20 14:43:25 DEBUG    __init__     Main         KNX -- __init__.py:_handle_volume_dpt3:210
2017-02-20 14:43:25 DEBUG    scheduler    Main         Triggering Kueche.volume.volume_dpt3.helper - by: Logic source: None dest: None value: {'delta': 1, 'step': 2, 'dest': 100.0, ' -- scheduler.py:trigger:165
2017-02-20 14:43:25 DEBUG    __init__     Kueche.volume.volume_dpt3.helper Sending request: {'command': 'set_volume', 'parameter': {'uid': 'rincon_5caafd28746401400', 'group_command': 0, 'volume': 10}} -- __init__.py:_send_cmd:504
2017-02-20 14:43:25 INFO     __init__     Kueche.volume.volume_dpt3.helper Sonos: Message http://192.168.0.145:12900 {'command': 'set_volume', 'parameter': {'uid': 'rincon_5caafd28746401400', 'group_command': 0, 'volume': 10}} successfully sent - 200 OK -- __init__.py:_send_cmd:514
2017-02-20 14:43:26 DEBUG    __init__     Kueche.volume.volume_dpt3.helper Sending request: {'command': 'set_volume', 'parameter': {'uid': 'rincon_5caafd28746401400', 'group_command': 0, 'volume': 12}} -- __init__.py:_send_cmd:504
2017-02-20 14:43:26 INFO     __init__     Kueche.volume.volume_dpt3.helper Sonos: Message http://192.168.0.145:12900 {'command': 'set_volume', 'parameter': {'uid': 'rincon_5caafd28746401400', 'group_command': 0, 'volume': 12}} successfully sent - 200 OK -- __init__.py:_send_cmd:514
2017-02-20 14:43:27 DEBUG    __init__     Kueche.volume.volume_dpt3.helper Sending request: {'command': 'set_volume', 'parameter': {'uid': 'rincon_5caafd28746401400', 'group_command': 0, 'volume': 14}} -- __init__.py:_send_cmd:504
2017-02-20 14:43:27 INFO     __init__     Kueche.volume.volume_dpt3.helper Sonos: Message http://192.168.0.145:12900 {'command': 'set_volume', 'parameter': {'uid': 'rincon_5caafd28746401400', 'group_command': 0, 'volume': 14}} successfully sent - 200 OK -- __init__.py:_send_cmd:514
2017-02-20 14:43:28 DEBUG    __init__     Main         KNX[default]: 1.1.27 set 8/3/4 to [0, 0] -- __init__.py:parse_telegram:207
2017-02-20 14:43:28 DEBUG    item         Main         Item Kueche.volume.volume_dpt3 = [0, 0] via KNX 1.1.27 8/3/4 -- item.py:__update:374
2017-02-20 14:43:28 DEBUG    __init__     Main         KNX -- __init__.py:_handle_volume_dpt3:210
2017-02-20 14:43:28 DEBUG    item         Main         Item Kueche.volume.volume_dpt3.helper = 8 via Logic None None -- item.py:__update:374
2017-02-20 14:43:28 DEBUG    __init__     Main         Sending request: {'command': 'set_volume', 'parameter': {'uid': 'rincon_5caafd28746401400', 'group_command': 0, 'volume': 8}} -- __init__.py:_send_cmd:504
2017-02-20 14:43:28 INFO     __init__     Main         Sonos: Message http://192.168.0.145:12900 {'command': 'set_volume', 'parameter': {'uid': 'rincon_5caafd28746401400', 'group_command': 0, 'volume': 8}} successfully sent - 200 OK -- __init__.py:_send_cmd:514
2017-02-20 14:43:28 DEBUG    item         Main         Item Kueche.volume.volume_dpt3.helper = 9 via Logic None None -- item.py:__update:374
2017-02-20 14:43:28 DEBUG    __init__     Main         Sending request: {'command': 'set_volume', 'parameter': {'uid': 'rincon_5caafd28746401400', 'group_command': 0, 'volume': 9}} -- __init__.py:_send_cmd:504
2017-02-20 14:43:28 INFO     __init__     Main         Sonos: Message http://192.168.0.145:12900 {'command': 'set_volume', 'parameter': {'uid': 'rincon_5caafd28746401400', 'group_command': 0, 'volume': 9}} successfully sent - 200 OK -- __init__.py:_send_cmd:514
2017-02-20 14:43:28 DEBUG    item         Main         Item Kueche.volume.volume_dpt3.helper = 8 via Logic None None -- item.py:__update:374
2017-02-20 14:43:28 DEBUG    __init__     Main         Sending request: {'command': 'set_volume', 'parameter': {'uid': 'rincon_5caafd28746401400', 'group_command': 0, 'volume': 8}} -- __init__.py:_send_cmd:504
2017-02-20 14:43:29 INFO     __init__     Main         Sonos: Message http://192.168.0.145:12900 {'command': 'set_volume', 'parameter': {'uid': 'rincon_5caafd28746401400', 'group_command': 0, 'volume': 8}} successfully sent - 200 OK -- __init__.py:_send_cmd:514
2017-02-20 14:43:55 DEBUG    __init__     sonos-update (re)registering to sonos broker server ... -- __init__.py:_subscribe:127

**pfischi** · 20.02.2017, 17:04

Hm, bei mir geht die Funktion. Glaube aber auch nicht das es am Taster liegt.Ich schaue mir die Stelle nochmal an.

Gruss,

Stefan

**pfischi** · 20.02.2017, 19:11

Zitat von marcel0509 Beitrag anzeigen

Hallo Stefan,

ich bin auch gerade dabei deine neueste Version zu installieren, welches problemlos geklappt hat, leider bekomme ich aber deine neue Funktion "Volumen DPT3 Unterstützung" nicht zum laufen über den MDT Glasttaster 2.

Ich habe es nochmal getestet, läuft bei mir wunderbar. Nutzt du auch das Plugin, was ich gestern eingecheckt habe?

Tritt bei anderen das Problem auch auf?

Gruss,

Stefan

**aschwith** · 20.02.2017, 19:48

Hallo Stefan,
danke für die neue Version V1.1. Damit sind sowohl play_tunein als auch das sonos_vol_max warning behoben.
Viele Grüße
Alex

**marcel0509** · 21.02.2017, 19:40

Hallo Stefan,

ja ich nutze deine aktuellste Version, hatte dieses Problem auch mit der 1.0 aber da kam die 1.1 schneller raus.

Das einzige was mich wundert ist dass der Taster bei der Länge 4bit erwartet aber die GA mit 3 bit akzeptiert. (s. Anhang).

Wie schaut dein Taster hier aus?

Dank dir

**aschwith** · 24.02.2017, 18:00

Hallo zusammen,

bekommt noch jemand folgendes Warning für alle seine Sonos Geräte? Das Warning habe ich nicht nur in aktueller V1.1 sondern schonmal bei älteren Revision beobachtet. Dabei habe ich keine Sonos-Weckfunktionen konfiguriert.

2017-02-24 01:13:13 WARNING Main Item Sonos.XYZ.alarms: value does not match type dict. Via Sonos

VG
Alex

**pfischi** · 24.02.2017, 18:27

Kannst du ignorieren. Mache ich bei der nächsten Version noch hübscher

Gruss,

Stefan

**marcel0509** · 24.02.2017, 21:26

Hallo Stefan,

habe den Fehler bzgl. des nicht funktionieren meiner Dimmertasten gefunden. Ich hatte scheinbar noch den alten Sonos-Broker drauf gehabt, Hinweis kam im Log von SHNG beim Starten.

Funktioniert jetzt alles super!! Danke für die super Arbeit.

Jetzt schaue ich mir mal das Widget an.

**pfischi** · 24.02.2017, 23:54

Zitat von marcel0509 Beitrag anzeigen

Hallo Stefan,

habe den Fehler bzgl. des nicht funktionieren meiner Dimmertasten gefunden. Ich hatte scheinbar noch den alten Sonos-Broker drauf gehabt, Hinweis kam im Log von SHNG beim Starten.

Funktioniert jetzt alles super!! Danke für die super Arbeit.

Jetzt schaue ich mir mal das Widget an.

Danke für die Rückmeldung.

Gruss,

Stefan

**Max2612** · 26.02.2017, 11:17

Hallo Stefan,

ich habe auf die aktuelle Version umgestellt. Installation lief ohne Fehler durch.
Aber beim Starten des Broker kommt dieser Fehler:

Code:

smarthome@raspberrypi:~$ sonos-broker start
2017-02-26 11:14:09 ERROR    Thread-2     Created data structures: [<DidlPlaylis                                                                         tContai (CUT) from Didl string "<DIDL-Lite xmlns:dc=" (CUT)
2017-02-26 11:14:09 ERROR    Thread-7     Created data structures: [<DidlMusicTr                                                                         ack 'b' (CUT) from Didl string "<DIDL-Lite xmlns:dc=" (CUT)
2017-02-26 11:14:09 ERROR    Thread-7     Created data structures: [<DidlMusicTr                                                                         ack 'b' (CUT) from Didl string "<DIDL-Lite xmlns:dc=" (CUT)
2017-02-26 11:14:09 ERROR    Thread-7     Created data structures: [<DidlMusicAl                                                                         bum 'b' (CUT) from Didl string "<DIDL-Lite xmlns:dc=" (CUT)
2017-02-26 11:14:09 ERROR    Thread-1     Created data structures: [<DidlPlaylis                                                                         tContai (CUT) from Didl string "<DIDL-Lite xmlns:dc=" (CUT)

Kannst du mir da helfen?

**pfischi** · 26.02.2017, 11:33

Zitat von Max2612 Beitrag anzeigen

Hallo Stefan,

ich habe auf die aktuelle Version umgestellt. Installation lief ohne Fehler durch.
Aber beim Starten des Broker kommt dieser Fehler:

Code:

smarthome@raspberrypi:~$ sonos-broker start
2017-02-26 11:14:09 ERROR Thread-2 Created data structures: [<DidlPlaylis tContai (CUT) from Didl string "<DIDL-Lite xmlns:dc=" (CUT)
2017-02-26 11:14:09 ERROR Thread-7 Created data structures: [<DidlMusicTr ack 'b' (CUT) from Didl string "<DIDL-Lite xmlns:dc=" (CUT)
2017-02-26 11:14:09 ERROR Thread-7 Created data structures: [<DidlMusicTr ack 'b' (CUT) from Didl string "<DIDL-Lite xmlns:dc=" (CUT)
2017-02-26 11:14:09 ERROR Thread-7 Created data structures: [<DidlMusicAl bum 'b' (CUT) from Didl string "<DIDL-Lite xmlns:dc=" (CUT)
2017-02-26 11:14:09 ERROR Thread-1 Created data structures: [<DidlPlaylis tContai (CUT) from Didl string "<DIDL-Lite xmlns:dc=" (CUT)

Kannst du mir da helfen?

Ja, aber vorher musst du das Loglevel des Brokers auf Debug erhöhen. Das kannst du in der Datei

Code:

/etc/default/sonos-broker

einstellen.

Gruss,

Stefan

**Max2612** · 26.02.2017, 11:47

OK, dann kommt

Code:

smarthome@raspberrypi:~$ sonos-broker start
2017-02-26 11:46:08,172 DEBUG    MainThread   Webservice path set to '/var/www/GoogleTTS'. -- sonos-broker:__init__:165
2017-02-26 11:46:08,180 DEBUG    MainThread   Webservice path '/var/www/GoogleTTS' permission ok. -- sonos-broker:__init__:170
2017-02-26 11:46:08,187 DEBUG    MainThread   Webservice IP not set, trying to detect the local ip automatically ... -- sonos-broker:__init__:188
2017-02-26 11:46:08,234 DEBUG    MainThread   Webservice IP set to 192.168.1.13 -- sonos-broker:__init__:196
2017-02-26 11:46:08,242 DEBUG    MainThread   Quota set to 200 mb -- sonos-broker:__init__:201
2017-02-26 11:46:08,249 DEBUG    MainThread   Write permissions ok for tts on path /var/www/GoogleTTS -- sonos-broker:__init__:218
2017-02-26 11:46:08,256 DEBUG    MainThread   Local Google TTS mode set to True. -- sonos-broker:__init__:221
2017-02-26 11:46:08,264 INFO     MainThread   Sonos Broker v1.1 -- sonos-broker:start:233
Traceback (most recent call last):
  File "/usr/local/bin/sonos-broker", line 293, in <module>
    SonosBroker(args.debug, config=config_path).start()
  File "/usr/local/bin/sonos-broker", line 236, in start
    self._quota, self._tts_local_mode)
  File "/usr/local/lib/python3.5/site-packages/lib_sonos/sonos_service.py", line 359, in __init__
    self.webservice = SimpleHttpServer(self.host, self.port, webservice_path)
  File "/usr/local/lib/python3.5/site-packages/lib_sonos/sonos_service.py", line 135, in __init__
    self.server = ThreadedHTTPServer((ip, port), WebserviceHttpHandler)
  File "/usr/local/lib/python3.5/socketserver.py", line 440, in __init__
    self.server_bind()
  File "/usr/local/lib/python3.5/http/server.py", line 138, in server_bind
    socketserver.TCPServer.server_bind(self)
  File "/usr/local/lib/python3.5/socketserver.py", line 454, in server_bind
    self.socket.bind(self.server_address)
OSError: [Errno 98] Address already in use

**pfischi** · 26.02.2017, 11:53

Da läuft bereits eine andere Instanz des Brokers. Am besten du beendest mal alle Broker-Prozesse. Mit

Code:

ps aux | grep sonos-broker

kannst du dir alle laufenden Instanzen einblenden lassen (inkl. die dazugehörige Process-ID, kurz PID). Die nimmst du dann um die jeweiligen Prozesse zu beenden.

Code:

sudo kill -9 pid1 pid2 pid3 .......

Danach starte den Broker nochmal.

Gruss,

Stefan

**Max2612** · 26.02.2017, 12:18

Stimmt, es waren 2 Instanzen.

Nach dem Neustart

Code:

smarthome@raspberrypi:~$ sonos-broker start
2017-02-26 12:12:09,756 DEBUG    MainThread   Webservice path set to '/var/www/GoogleTTS'. -- sonos-broker:__init__:165
2017-02-26 12:12:09,764 DEBUG    MainThread   Webservice path '/var/www/GoogleTTS' permission ok. -- sonos-broker:__init__:170
2017-02-26 12:12:09,771 DEBUG    MainThread   Webservice IP not set, trying to detect the local ip automatically ... -- sonos-broker:__init__:188
2017-02-26 12:12:09,816 DEBUG    MainThread   Webservice IP set to 192.168.1.13 -- sonos-broker:__init__:196
2017-02-26 12:12:09,824 DEBUG    MainThread   Quota set to 200 mb -- sonos-broker:__init__:201
2017-02-26 12:12:09,832 DEBUG    MainThread   Write permissions ok for tts on path /var/www/GoogleTTS -- sonos-broker:__init__:218
2017-02-26 12:12:09,838 DEBUG    MainThread   Local Google TTS mode set to True. -- sonos-broker:__init__:221
2017-02-26 12:12:09,846 INFO     MainThread   Sonos Broker v1.1 -- sonos-broker:start:233
2017-02-26 12:12:11,869 DEBUG    Thread-2     active threads: 4 -- sonos_service.py:get_speakers_periodically:163
2017-02-26 12:12:11,877 INFO     Thread-2     scan devices ... -- sonos_service.py:get_speakers_periodically:164
2017-02-26 12:12:11,891 INFO     Thread-2     Sending discovery packets on [<socket.socket fd=7, family=AddressFamily.AF_INET, type=SocketKind.SOCK_DGRAM, proto=17, laddr=('0.0.0.0', 0)>, <socket.socket fd=8, family=AddressFamily.AF_INET, type=SocketKind.SOCK_DGRAM, proto=17, laddr=('0.0.0.0', 0)>] -- discovery.py:discover:126
2017-02-26 12:12:11,970 DEBUG    Thread-2     Received discovery response from ('192.168.1.24', 33866): "b'HTTP/1.1 200 OK\r\nCACHE-CONTROL: max-age = 1800\r\nEXT:\r\nLOCATION: http://192.168.1.24:1400/xml/device_description.xml\r\nSERVER: Linux UPnP/1.0 Sonos/34.16-37101 (ZPS12)\r\nST: urn:schemas-upnp-org:device:ZonePlayer:1\r\nUSN: uuid:RINCON_949F3E7072DC01400::urn:schemas-upnp-org:device:ZonePlayer:1\r\nX-RINCON-HOUSEHOLD: Sonos_NK5GZK7PASLUh5Vei3SIuPaCKx\r\nX-RINCON-BOOTSEQ: 12\r\nX-RINCON-WIFIMODE: 1\r\nX-RINCON-VARIANT: 1\r\n\r\n'" -- discovery.py:discover:173
2017-02-26 12:12:11,983 DEBUG    Thread-2     Created SoCo instance for ip: 192.168.1.24 -- core.py:__init__:228
2017-02-26 12:12:12,001 INFO     Thread-2     Sending GetZoneGroupState None to 192.168.1.24 -- services.py:send_command:381
2017-02-26 12:12:12,164 DEBUG    Thread-2     Sending {'SOAPACTION': 'urn:schemas-upnp-org:service:ZoneGroupTopology:1#GetZoneGroupState', 'Content-Type': 'text/xml; charset="utf-8"'}, <?xml version="1.0" ?>
<s:Envelope s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/" xmlns:s="http://schemas.xmlsoap.org/soap/envelope/">
  <s:Body>
    <u:GetZoneGroupState xmlns:u="urn:schemas-upnp-org:service:ZoneGroupTopology:1"/>
  </s:Body>
</s:Envelope>
 -- services.py:send_command:382
2017-02-26 12:12:12,269 DEBUG    Thread-2     Starting new HTTP connection (1): 192.168.1.24 -- connectionpool.py:_new_conn:207
2017-02-26 12:12:12,298 DEBUG    Thread-2     http://192.168.1.24:1400 "POST /ZoneGroupTopology/Control HTTP/1.1" 200 1131 -- connectionpool.py:_make_request:395
2017-02-26 12:12:12,323 DEBUG    Thread-2     Received {'CONTENT-LENGTH': '1131', 'Server': 'Linux UPnP/1.0 Sonos/34.16-37101 (ZPS12)', 'EXT': '', 'CONTENT-TYPE': 'text/xml; charset="utf-8"', 'Connection': 'close'}, <s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><s:Body><u:GetZoneGroupStateResponse xmlns:u="urn:schemas-upnp-org:service:ZoneGroupTopology:1"><ZoneGroupState>&lt;ZoneGroups&gt;&lt;ZoneGroup Coordinator=&quot;RINCON_949F3E7072DC01400&quot; ID=&quot;RINCON_949F3E7072DC01400:0&quot;&gt;&lt;ZoneGroupMember UUID=&quot;RINCON_949F3E7072DC01400&quot; Location=&quot;http://192.168.1.24:1400/xml/device_description.xml&quot; ZoneName=&quot;Wohnzimmer&quot; Icon=&quot;x-rincon-roomicon:living&quot; Configuration=&quot;1&quot; SoftwareVersion=&quot;34.16-37101&quot; MinCompatibleVersion=&quot;33.0-00000&quot; LegacyCompatibleVersion=&quot;25.0-00000&quot; BootSeq=&quot;12&quot; WirelessMode=&quot;1&quot; WirelessLeafOnly=&quot;0&quot; HasConfiguredSSID=&quot;1&quot; ChannelFreq=&quot;2437&quot; BehindWifiExtender=&quot;0&quot; WifiEnabled=&quot;1&quot; Orientation=&quot;0&quot; RoomCalibrationState=&quot;4&quot; SecureRegState=&quot;3&quot;/&gt;&lt;/ZoneGroup&gt;&lt;/ZoneGroups&gt;</ZoneGroupState></u:GetZoneGroupStateResponse></s:Body></s:Envelope> -- services.py:send_command:389
2017-02-26 12:12:12,329 INFO     Thread-2     Received status 200 from 192.168.1.24 -- services.py:send_command:392
2017-02-26 12:12:12,391 DEBUG    Thread-2     Starting new HTTP connection (1): 192.168.1.24 -- connectionpool.py:_new_conn:207
2017-02-26 12:12:12,416 DEBUG    Thread-2     http://192.168.1.24:1400 "GET /xml/device_description.xml HTTP/1.1" 200 None -- connectionpool.py:_make_request:395
2017-02-26 12:12:12,518 DEBUG    Thread-2     Dispatching method GetVolume -- services.py:__getattr__:195
2017-02-26 12:12:12,525 INFO     Thread-2     Sending GetVolume [('InstanceID', 0), ('Channel', 'Master')] to 192.168.1.24 -- services.py:send_command:381
2017-02-26 12:12:12,541 DEBUG    Thread-2     Sending {'SOAPACTION': 'urn:schemas-upnp-org:service:RenderingControl:1#GetVolume', 'Content-Type': 'text/xml; charset="utf-8"'}, <?xml version="1.0" ?>
<s:Envelope s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/" xmlns:s="http://schemas.xmlsoap.org/soap/envelope/">
  <s:Body>
    <u:GetVolume xmlns:u="urn:schemas-upnp-org:service:RenderingControl:1">
      <InstanceID>0</InstanceID>
      <Channel>Master</Channel>
    </u:GetVolume>
  </s:Body>
</s:Envelope>
 -- services.py:send_command:382
2017-02-26 12:12:12,598 DEBUG    Thread-2     Starting new HTTP connection (1): 192.168.1.24 -- connectionpool.py:_new_conn:207
2017-02-26 12:12:12,623 DEBUG    Thread-2     http://192.168.1.24:1400 "POST /MediaRenderer/RenderingControl/Control HTTP/1.1" 200 288 -- connectionpool.py:_make_request:395
2017-02-26 12:12:12,651 DEBUG    Thread-2     Received {'CONTENT-LENGTH': '288', 'Server': 'Linux UPnP/1.0 Sonos/34.16-37101 (ZPS12)', 'EXT': '', 'CONTENT-TYPE': 'text/xml; charset="utf-8"', 'Connection': 'close'}, <s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><s:Body><u:GetVolumeResponse xmlns:u="urn:schemas-upnp-org:service:RenderingControl:1"><CurrentVolume>45</CurrentVolume></u:GetVolumeResponse></s:Body></s:Envelope> -- services.py:send_command:389
2017-02-26 12:12:12,657 INFO     Thread-2     Received status 200 from 192.168.1.24 -- services.py:send_command:392
2017-02-26 12:12:12,665 DEBUG    Thread-2     Dispatching method GetBass -- services.py:__getattr__:195
2017-02-26 12:12:12,672 INFO     Thread-2     Sending GetBass [('InstanceID', 0), ('Channel', 'Master')] to 192.168.1.24 -- services.py:send_command:381
2017-02-26 12:12:12,688 DEBUG    Thread-2     Sending {'SOAPACTION': 'urn:schemas-upnp-org:service:RenderingControl:1#GetBass', 'Content-Type': 'text/xml; charset="utf-8"'}, <?xml version="1.0" ?>
<s:Envelope s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/" xmlns:s="http://schemas.xmlsoap.org/soap/envelope/">
  <s:Body>
    <u:GetBass xmlns:u="urn:schemas-upnp-org:service:RenderingControl:1">
      <InstanceID>0</InstanceID>
      <Channel>Master</Channel>
    </u:GetBass>
  </s:Body>
</s:Envelope>
 -- services.py:send_command:382
2017-02-26 12:12:12,747 DEBUG    Thread-2     Starting new HTTP connection (1): 192.168.1.24 -- connectionpool.py:_new_conn:207
2017-02-26 12:12:12,775 DEBUG    Thread-2     http://192.168.1.24:1400 "POST /MediaRenderer/RenderingControl/Control HTTP/1.1" 200 279 -- connectionpool.py:_make_request:395
2017-02-26 12:12:12,800 DEBUG    Thread-2     Received {'CONTENT-LENGTH': '279', 'Server': 'Linux UPnP/1.0 Sonos/34.16-37101 (ZPS12)', 'EXT': '', 'CONTENT-TYPE': 'text/xml; charset="utf-8"', 'Connection': 'close'}, <s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><s:Body><u:GetBassResponse xmlns:u="urn:schemas-upnp-org:service:RenderingControl:1"><CurrentBass>0</CurrentBass></u:GetBassResponse></s:Body></s:Envelope> -- services.py:send_command:389
2017-02-26 12:12:12,806 INFO     Thread-2     Received status 200 from 192.168.1.24 -- services.py:send_command:392
2017-02-26 12:12:12,814 DEBUG    Thread-2     Dispatching method GetTreble -- services.py:__getattr__:195
2017-02-26 12:12:12,820 INFO     Thread-2     Sending GetTreble [('InstanceID', 0), ('Channel', 'Master')] to 192.168.1.24 -- services.py:send_command:381
2017-02-26 12:12:12,837 DEBUG    Thread-2     Sending {'SOAPACTION': 'urn:schemas-upnp-org:service:RenderingControl:1#GetTreble', 'Content-Type': 'text/xml; charset="utf-8"'}, <?xml version="1.0" ?>
<s:Envelope s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/" xmlns:s="http://schemas.xmlsoap.org/soap/envelope/">
  <s:Body>
    <u:GetTreble xmlns:u="urn:schemas-upnp-org:service:RenderingControl:1">
      <InstanceID>0</InstanceID>
      <Channel>Master</Channel>
    </u:GetTreble>
  </s:Body>
</s:Envelope>
 -- services.py:send_command:382
2017-02-26 12:12:12,896 DEBUG    Thread-2     Starting new HTTP connection (1): 192.168.1.24 -- connectionpool.py:_new_conn:207
2017-02-26 12:12:12,920 DEBUG    Thread-2     http://192.168.1.24:1400 "POST /MediaRenderer/RenderingControl/Control HTTP/1.1" 200 287 -- connectionpool.py:_make_request:395
2017-02-26 12:12:12,943 DEBUG    Thread-2     Received {'CONTENT-LENGTH': '287', 'Server': 'Linux UPnP/1.0 Sonos/34.16-37101 (ZPS12)', 'EXT': '', 'CONTENT-TYPE': 'text/xml; charset="utf-8"', 'Connection': 'close'}, <s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><s:Body><u:GetTrebleResponse xmlns:u="urn:schemas-upnp-org:service:RenderingControl:1"><CurrentTreble>0</CurrentTreble></u:GetTrebleResponse></s:Body></s:Envelope> -- services.py:send_command:389
2017-02-26 12:12:12,950 INFO     Thread-2     Received status 200 from 192.168.1.24 -- services.py:send_command:392
2017-02-26 12:12:12,957 DEBUG    Thread-2     Dispatching method GetLoudness -- services.py:__getattr__:195
2017-02-26 12:12:12,964 INFO     Thread-2     Sending GetLoudness [('InstanceID', 0), ('Channel', 'Master')] to 192.168.1.24 -- services.py:send_command:381
2017-02-26 12:12:12,980 DEBUG    Thread-2     Sending {'SOAPACTION': 'urn:schemas-upnp-org:service:RenderingControl:1#GetLoudness', 'Content-Type': 'text/xml; charset="utf-8"'}, <?xml version="1.0" ?>
<s:Envelope s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/" xmlns:s="http://schemas.xmlsoap.org/soap/envelope/">
  <s:Body>
    <u:GetLoudness xmlns:u="urn:schemas-upnp-org:service:RenderingControl:1">
      <InstanceID>0</InstanceID>
      <Channel>Master</Channel>
    </u:GetLoudness>
  </s:Body>
</s:Envelope>
 -- services.py:send_command:382
2017-02-26 12:12:13,039 DEBUG    Thread-2     Starting new HTTP connection (1): 192.168.1.24 -- connectionpool.py:_new_conn:207
2017-02-26 12:12:13,063 DEBUG    Thread-2     http://192.168.1.24:1400 "POST /MediaRenderer/RenderingControl/Control HTTP/1.1" 200 295 -- connectionpool.py:_make_request:395
2017-02-26 12:12:13,089 DEBUG    Thread-2     Received {'CONTENT-LENGTH': '295', 'Server': 'Linux UPnP/1.0 Sonos/34.16-37101 (ZPS12)', 'EXT': '', 'CONTENT-TYPE': 'text/xml; charset="utf-8"', 'Connection': 'close'}, <s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><s:Body><u:GetLoudnessResponse xmlns:u="urn:schemas-upnp-org:service:RenderingControl:1"><CurrentLoudness>1</CurrentLoudness></u:GetLoudnessResponse></s:Body></s:Envelope> -- services.py:send_command:389
2017-02-26 12:12:13,095 INFO     Thread-2     Received status 200 from 192.168.1.24 -- services.py:send_command:392
2017-02-26 12:12:13,104 DEBUG    Thread-2     Dispatching method GetTransportSettings -- services.py:__getattr__:195
2017-02-26 12:12:13,110 INFO     Thread-2     Sending GetTransportSettings [('InstanceID', 0)] to 192.168.1.24 -- services.py:send_command:381
2017-02-26 12:12:13,125 DEBUG    Thread-2     Sending {'SOAPACTION': 'urn:schemas-upnp-org:service:AVTransport:1#GetTransportSettings', 'Content-Type': 'text/xml; charset="utf-8"'}, <?xml version="1.0" ?>
<s:Envelope s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/" xmlns:s="http://schemas.xmlsoap.org/soap/envelope/">
  <s:Body>
    <u:GetTransportSettings xmlns:u="urn:schemas-upnp-org:service:AVTransport:1">
      <InstanceID>0</InstanceID>
    </u:GetTransportSettings>
  </s:Body>
</s:Envelope>
 -- services.py:send_command:382
2017-02-26 12:12:13,188 DEBUG    Thread-2     Starting new HTTP connection (1): 192.168.1.24 -- connectionpool.py:_new_conn:207
2017-02-26 12:12:13,214 DEBUG    Thread-2     http://192.168.1.24:1400 "POST /MediaRenderer/AVTransport/Control HTTP/1.1" 200 347 -- connectionpool.py:_make_request:395
2017-02-26 12:12:13,238 DEBUG    Thread-2     Received {'CONTENT-LENGTH': '347', 'Server': 'Linux UPnP/1.0 Sonos/34.16-37101 (ZPS12)', 'EXT': '', 'CONTENT-TYPE': 'text/xml; charset="utf-8"', 'Connection': 'close'}, <s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><s:Body><u:GetTransportSettingsResponse xmlns:u="urn:schemas-upnp-org:service:AVTransport:1"><PlayMode>NORMAL</PlayMode><RecQualityMode>NOT_IMPLEMENTED</RecQualityMode></u:GetTransportSettingsResponse></s:Body></s:Envelope> -- services.py:send_command:389
2017-02-26 12:12:13,245 INFO     Thread-2     Received status 200 from 192.168.1.24 -- services.py:send_command:392
2017-02-26 12:12:13,253 DEBUG    Thread-2     Dispatching method GetHouseholdID -- services.py:__getattr__:195
2017-02-26 12:12:13,259 INFO     Thread-2     Sending GetHouseholdID None to 192.168.1.24 -- services.py:send_command:381
2017-02-26 12:12:13,273 DEBUG    Thread-2     Sending {'SOAPACTION': 'urn:schemas-upnp-org:service:DeviceProperties:1#GetHouseholdID', 'Content-Type': 'text/xml; charset="utf-8"'}, <?xml version="1.0" ?>
<s:Envelope s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/" xmlns:s="http://schemas.xmlsoap.org/soap/envelope/">
  <s:Body>
    <u:GetHouseholdID xmlns:u="urn:schemas-upnp-org:service:DeviceProperties:1"/>
  </s:Body>
</s:Envelope>
 -- services.py:send_command:382
2017-02-26 12:12:13,330 DEBUG    Thread-2     Starting new HTTP connection (1): 192.168.1.24 -- connectionpool.py:_new_conn:207
2017-02-26 12:12:13,356 DEBUG    Thread-2     http://192.168.1.24:1400 "POST /DeviceProperties/Control HTTP/1.1" 200 338 -- connectionpool.py:_make_request:395
2017-02-26 12:12:13,379 DEBUG    Thread-2     Received {'CONTENT-LENGTH': '338', 'Server': 'Linux UPnP/1.0 Sonos/34.16-37101 (ZPS12)', 'EXT': '', 'CONTENT-TYPE': 'text/xml; charset="utf-8"', 'Connection': 'close'}, <s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><s:Body><u:GetHouseholdIDResponse xmlns:u="urn:schemas-upnp-org:service:DeviceProperties:1"><CurrentHouseholdID>Sonos_NK5GZK7PASLUh5Vei3SIuPaCKx</CurrentHouseholdID></u:GetHouseholdIDResponse></s:Body></s:Envelope> -- services.py:send_command:389
2017-02-26 12:12:13,386 INFO     Thread-2     Received status 200 from 192.168.1.24 -- services.py:send_command:392
2017-02-26 12:12:13,442 DEBUG    Thread-2     Starting new HTTP connection (1): 192.168.1.24 -- connectionpool.py:_new_conn:207
2017-02-26 12:12:13,480 DEBUG    Thread-2     http://192.168.1.24:1400 "GET /status/ifconfig HTTP/1.1" 200 None -- connectionpool.py:_make_request:395
2017-02-26 12:12:13,505 DEBUG    Thread-2     Dispatching method Browse -- services.py:__getattr__:195
2017-02-26 12:12:13,514 INFO     Thread-2     Sending Browse [('ObjectID', 'SQ:'), ('BrowseFlag', 'BrowseDirectChildren'), ('Filter', '*'), ('StartingIndex', 0), ('RequestedCount', 100), ('SortCriteria', '')] to 192.168.1.24 -- services.py:send_command:381
2017-02-26 12:12:13,535 DEBUG    Thread-2     Sending {'SOAPACTION': 'urn:schemas-upnp-org:service:ContentDirectory:1#Browse', 'Content-Type': 'text/xml; charset="utf-8"'}, <?xml version="1.0" ?>
<s:Envelope s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/" xmlns:s="http://schemas.xmlsoap.org/soap/envelope/">
  <s:Body>
    <u:Browse xmlns:u="urn:schemas-upnp-org:service:ContentDirectory:1">
      <ObjectID>SQ:</ObjectID>
      <BrowseFlag>BrowseDirectChildren</BrowseFlag>
      <Filter>*</Filter>
      <StartingIndex>0</StartingIndex>
      <RequestedCount>100</RequestedCount>
      <SortCriteria/>
    </u:Browse>
  </s:Body>
</s:Envelope>
 -- services.py:send_command:382
2017-02-26 12:12:13,595 DEBUG    Thread-2     Starting new HTTP connection (1): 192.168.1.24 -- connectionpool.py:_new_conn:207
2017-02-26 12:12:13,628 DEBUG    Thread-2     http://192.168.1.24:1400 "POST /MediaServer/ContentDirectory/Control HTTP/1.1" 200 None -- connectionpool.py:_make_request:395
2017-02-26 12:12:13,653 DEBUG    Thread-2     Received {'Server': 'Linux UPnP/1.0 Sonos/34.16-37101 (ZPS12)', 'EXT': '', 'CONTENT-TYPE': 'text/xml; charset="utf-8"', 'Connection': 'close', 'TRANSFER-ENCODING': 'chunked'}, <s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><s:Body><u:BrowseResponse xmlns:u="urn:schemas-upnp-org:service:ContentDirectory:1"><Result>&lt;DIDL-Lite xmlns:dc=&quot;http://purl.org/dc/elements/1.1/&quot; xmlns:upnp=&quot;urn:schemas-upnp-org:metadata-1-0/upnp/&quot; xmlns:r=&quot;urn:schemas-rinconnetworks-com:metadata-1-0/&quot; xmlns=&quot;urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/&quot;&gt;&lt;container id=&quot;SQ:2&quot; parentID=&quot;SQ:&quot; restricted=&quot;true&quot;&gt;&lt;dc:title&gt;Best of 2016&lt;/dc:title&gt;&lt;res protocolInfo=&quot;file:*:audio/mpegurl:*&quot;&gt;file:///jffs/settings/savedqueues.rsq#2&lt;/res&gt;&lt;upnp:class&gt;object.container.playlistContainer&lt;/upnp:class&gt;&lt;upnp:albumArtURI&gt;/getaa?s=1&amp;amp;u=x-sonosapi-hls-static%3acatalog%252fplaylists%252fB01NAGN8AO%252f%2523chunk%252b0%3fsid%3d201%26flags%3d8224%26sn%3d2&lt;/upnp:albumArtURI&gt;&lt;upnp:albumArtURI&gt;/getaa?s=1&amp;amp;u=x-sonosapi-hls-static%3acatalog%252fplaylists%252fB01NAGN8AO%252f%2523chunk%252b16%3fsid%3d201%26flags%3d8224%26sn%3d2&lt;/upnp:albumArtURI&gt;&lt;upnp:albumArtURI&gt;/getaa?s=1&amp;amp;u=x-sonosapi-hls-static%3acatalog%252fplaylists%252fB01NAGN8AO%252f%2523chunk%252b33%3fsid%3d201%26flags%3d8224%26sn%3d2&lt;/upnp:albumArtURI&gt;&lt;upnp:albumArtURI&gt;/getaa?s=1&amp;amp;u=x-sonosapi-hls-static%3acatalog%252fplaylists%252fB01NAGN8AO%252f%2523chunk%252b49%3fsid%3d201%26flags%3d8224%26sn%3d2&lt;/upnp:albumArtURI&gt;&lt;/container&gt;&lt;container id=&quot;SQ:3&quot; parentID=&quot;SQ:&quot; restricted=&quot;true&quot;&gt;&lt;dc:title&gt;Fit 2017&lt;/dc:title&gt;&lt;res protocolInfo=&quot;file:*:audio/mpegurl:*&quot;&gt;file:///jffs/settings/savedqueues.rsq#3&lt;/res&gt;&lt;upnp:class&gt;object.container.playlistContainer&lt;/upnp:class&gt;&lt;upnp:albumArtURI&gt;/getaa?s=1&amp;amp;u=x-sonosapi-hls-static%3acatalog%252fplaylists%252fB01MT0SR0E%252f%2523chunk%252b0%3fsid%3d201%26flags%3d8224%26sn%3d2&lt;/upnp:albumArtURI&gt;&lt;upnp:albumArtURI&gt;/getaa?s=1&amp;amp;u=x-sonosapi-hls-static%3acatalog%252fplaylists%252fB01MT0SR0E%252f%2523chunk%252b6%3fsid%3d201%26flags%3d8224%26sn%3d2&lt;/upnp:albumArtURI&gt;&lt;upnp:albumArtURI&gt;/getaa?s=1&amp;amp;u=x-sonosapi-hls-static%3acatalog%252fplaylists%252fB01MT0SR0E%252f%2523chunk%252b13%3fsid%3d201%26flags%3d8224%26sn%3d2&lt;/upnp:albumArtURI&gt;&lt;upnp:albumArtURI&gt;/getaa?s=1&amp;amp;u=x-sonosapi-hls-static%3acatalog%252fplaylists%252fB01MT0SR0E%252f%2523chunk%252b19%3fsid%3d201%26flags%3d8224%26sn%3d2&lt;/upnp:albumArtURI&gt;&lt;/container&gt;&lt;container id=&quot;SQ:5&quot; parentID=&quot;SQ:&quot; restricted=&quot;true&quot;&gt;&lt;dc:title&gt;Motivation&lt;/dc:title&gt;&lt;res protocolInfo=&quot;file:*:audio/mpegurl:*&quot;&gt;file:///jffs/settings/savedqueues.rsq#5&lt;/res&gt;&lt;upnp:class&gt;object.container.playlistContainer&lt;/upnp:class&gt;&lt;upnp:albumArtURI&gt;/getaa?s=1&amp;amp;u=x-sonosapi-hls-static%3acatalog%252fplaylists%252fB01N2Z4EBP%252f%2523chunk%252b0%3fsid%3d201%26flags%3d8224%26sn%3d2&lt;/upnp:albumArtURI&gt;&lt;upnp:albumArtURI&gt;/getaa?s=1&amp;amp;u=x-sonosapi-hls-static%3acatalog%252fplaylists%252fB01N2Z4EBP%252f%2523chunk%252b12%3fsid%3d201%26flags%3d8224%26sn%3d2&lt;/upnp:albumArtURI&gt;&lt;upnp:albumArtURI&gt;/getaa?s=1&amp;amp;u=x-sonosapi-hls-static%3acatalog%252fplaylists%252fB01N2Z4EBP%252f%2523chunk%252b24%3fsid%3d201%26flags%3d8224%26sn%3d2&lt;/upnp:albumArtURI&gt;&lt;upnp:albumArtURI&gt;/getaa?s=1&amp;amp;u=x-sonosapi-hls-static%3acatalog%252fplaylists%252fB01N2Z4EBP%252f%2523chunk%252b36%3fsid%3d201%26flags%3d8224%26sn%3d2&lt;/upnp:albumArtURI&gt;&lt;/container&gt;&lt;container id=&quot;SQ:4&quot; parentID=&quot;SQ:&quot; restricted=&quot;true&quot;&gt;&lt;dc:title&gt;Rock Party&lt;/dc:title&gt;&lt;res protocolInfo=&quot;file:*:audio/mpegurl:*&quot;&gt;file:///jffs/settings/savedqueues.rsq#4&lt;/res&gt;&lt;upnp:class&gt;object.container.playlistContainer&lt;/upnp:class&gt;&lt;upnp:albumArtURI&gt;/getaa?s=1&amp;amp;u=x-sonosapi-hls-static%3acatalog%252fplaylists%252fB01NH0TXB3%252f%2523chunk%252b0%3fsid%3d201%26flags%3d8224%26sn%3d2&lt;/upnp:albumArtURI&gt;&lt;upnp:albumArtURI&gt;/getaa?s=1&amp;amp;u=x-sonosapi-hls-static%3acatalog%252fplaylists%252fB01NH0TXB3%252f%2523chunk%252b14%3fsid%3d201%26flags%3d8224%26sn%3d2&lt;/upnp:albumArtURI&gt;&lt;upnp:albumArtURI&gt;/getaa?s=1&amp;amp;u=x-sonosapi-hls-static%3acatalog%252fplaylists%252fB01NH0TXB3%252f%2523chunk%252b27%3fsid%3d201%26flags%3d8224%26sn%3d2&lt;/upnp:albumArtURI&gt;&lt;upnp:albumArtURI&gt;/getaa?s=1&amp;amp;u=x-sonosapi-hls-static%3acatalog%252fplaylists%252fB01NH0TXB3%252f%2523chunk%252b41%3fsid%3d201%26flags%3d8224%26sn%3d2&lt;/upnp:albumArtURI&gt;&lt;/container&gt;&lt;/DIDL-Lite&gt;</Result><NumberReturned>4</NumberReturned><TotalMatches>4</TotalMatches><UpdateID>0</UpdateID></u:BrowseResponse></s:Body></s:Envelope> -- services.py:send_command:389
2017-02-26 12:12:13,668 INFO     Thread-2     Received status 200 from 192.168.1.24 -- services.py:send_command:392
2017-02-26 12:12:13,689 DEBUG    Thread-2     Upgrade not necessary -- data_structures_entry.py:attempt_datastructure_upgrade:136
2017-02-26 12:12:13,696 DEBUG    Thread-2     Upgrade not necessary -- data_structures_entry.py:attempt_datastructure_upgrade:136
2017-02-26 12:12:13,704 DEBUG    Thread-2     Upgrade not necessary -- data_structures_entry.py:attempt_datastructure_upgrade:136
2017-02-26 12:12:13,712 DEBUG    Thread-2     Upgrade not necessary -- data_structures_entry.py:attempt_datastructure_upgrade:136
2017-02-26 12:12:13,717 ERROR    Thread-2     Created data structures: [<DidlPlaylistContai (CUT) from Didl string "<DIDL-Lite xmlns:dc=" (CUT) -- data_structures_entry.py:from_didl_string:66
2017-02-26 12:12:13,725 DEBUG    Thread-2     Cache hit -- services.py:send_command:377
2017-02-26 12:12:13,731 DEBUG    Thread-2     Cache hit -- services.py:send_command:377
2017-02-26 12:12:13,736 DEBUG    Thread-2     Cache hit -- services.py:send_command:377
2017-02-26 12:12:13,744 DEBUG    Thread-2     Cache hit -- services.py:send_command:377
2017-02-26 12:12:13,749 DEBUG    Thread-2     Cache hit -- services.py:send_command:377
2017-02-26 12:12:13,754 DEBUG    Thread-2     renewing topology event for rincon_949f3e7072dc01400 -- sonos_speaker.py:event_subscription:1519
2017-02-26 12:12:13,767 INFO     Thread-2     Event listener started -- events.py:start:338
2017-02-26 12:12:13,787 INFO     Thread-4     Event listener running on ('192.168.1.13', 1400) -- events.py:run:275
2017-02-26 12:12:13,863 DEBUG    Thread-2     Starting new HTTP connection (1): 192.168.1.24 -- connectionpool.py:_new_conn:207
2017-02-26 12:12:13,892 DEBUG    Thread-2     http://192.168.1.24:1400 "SUBSCRIBE /ZoneGroupTopology/Event HTTP/1.1" 200 None -- connectionpool.py:_make_request:395
2017-02-26 12:12:13,943 INFO     Thread-2     Subscribed to http://192.168.1.24:1400/ZoneGroupTopology/Event, sid: uuid:RINCON_949F3E7072DC01400_sub0000005041 -- events.py:subscribe:477
2017-02-26 12:12:13,948 INFO     Thread-5     No service registered for uuid:RINCON_949F3E7072DC01400_sub0000005041 -- events.py:do_NOTIFY:243
2017-02-26 12:12:13,958 DEBUG    Thread-5     "NOTIFY / HTTP/1.1" 200 - -- events.py:log_message:249
2017-02-26 12:12:13,964 DEBUG    Thread-2     renewing av-transport event for rincon_949f3e7072dc01400 -- sonos_speaker.py:event_subscription:1536
2017-02-26 12:12:14,029 DEBUG    Thread-2     Starting new HTTP connection (1): 192.168.1.24 -- connectionpool.py:_new_conn:207
2017-02-26 12:12:14,060 DEBUG    Thread-2     http://192.168.1.24:1400 "SUBSCRIBE /MediaRenderer/AVTransport/Event HTTP/1.1" 200 None -- connectionpool.py:_make_request:395
2017-02-26 12:12:14,104 INFO     Thread-2     Subscribed to http://192.168.1.24:1400/MediaRenderer/AVTransport/Event, sid: uuid:RINCON_949F3E7072DC01400_sub0000005042 -- events.py:subscribe:477
2017-02-26 12:12:14,095 INFO     Thread-7     No service registered for uuid:RINCON_949F3E7072DC01400_sub0000005042 -- events.py:do_NOTIFY:243
2017-02-26 12:12:14,117 DEBUG    Thread-7     "NOTIFY / HTTP/1.1" 200 - -- events.py:log_message:249
2017-02-26 12:12:14,128 DEBUG    Thread-2     renewing rendering event for rincon_949f3e7072dc01400 -- sonos_speaker.py:event_subscription:1552
2017-02-26 12:12:14,191 DEBUG    Thread-2     Starting new HTTP connection (1): 192.168.1.24 -- connectionpool.py:_new_conn:207
2017-02-26 12:12:14,220 DEBUG    Thread-2     http://192.168.1.24:1400 "SUBSCRIBE /MediaRenderer/RenderingControl/Event HTTP/1.1" 200 None -- connectionpool.py:_make_request:395
2017-02-26 12:12:14,257 INFO     Thread-2     Subscribed to http://192.168.1.24:1400/MediaRenderer/RenderingControl/Event, sid: uuid:RINCON_949F3E7072DC01400_sub0000005043 -- events.py:subscribe:477
2017-02-26 12:12:14,263 INFO     Thread-9     No service registered for uuid:RINCON_949F3E7072DC01400_sub0000005043 -- events.py:do_NOTIFY:243
2017-02-26 12:12:14,278 DEBUG    Thread-9     "NOTIFY / HTTP/1.1" 200 - -- events.py:log_message:249
2017-02-26 12:12:14,275 DEBUG    Thread-2     renewing alarm event for rincon_949f3e7072dc01400 -- sonos_speaker.py:event_subscription:1568
2017-02-26 12:12:14,343 DEBUG    Thread-2     Starting new HTTP connection (1): 192.168.1.24 -- connectionpool.py:_new_conn:207
2017-02-26 12:12:14,368 DEBUG    Thread-2     http://192.168.1.24:1400 "SUBSCRIBE /AlarmClock/Event HTTP/1.1" 200 None -- connectionpool.py:_make_request:395
2017-02-26 12:12:14,407 INFO     Thread-2     Subscribed to http://192.168.1.24:1400/AlarmClock/Event, sid: uuid:RINCON_949F3E7072DC01400_sub0000005044 -- events.py:subscribe:477
2017-02-26 12:12:14,415 INFO     Thread-11    No service registered for uuid:RINCON_949F3E7072DC01400_sub0000005044 -- events.py:do_NOTIFY:243
2017-02-26 12:12:14,425 DEBUG    Thread-11    "NOTIFY / HTTP/1.1" 200 - -- events.py:log_message:249
2017-02-26 12:12:14,434 DEBUG    Thread-2     Start wait -- sonos_service.py:get_speakers_periodically:167
2017-02-26 12:12:50,276 DEBUG    Thread-13    COMMAND ClientSubscribe -- attributes: port: 9999, ip: 192.168.1.13 -- sonos_commands.py:run:69
2017-02-26 12:12:50,283 INFO     Thread-13    register client for udp messages: 192.168.1.13:9999 -- udp_broker.py:subscribe_client:13
2017-02-26 12:12:50,290 INFO     Thread-13    registered clients: 192.168.1.13:[9999] -- udp_broker.py:subscribe_client:23
192.168.1.13 - - [26/Feb/2017 12:12:50] "POST / HTTP/1.1" 200 -
2017-02-26 12:12:50,313 DEBUG    Thread-13    Server response -- status: True -- response:  -- sonos_service.py:do_POST:109
2017-02-26 12:12:50,422 DEBUG    Thread-14    COMMAND CurrentState -- attributes: group_command: 0, uid: rincon_949f3e7072dc01400 -- sonos_commands.py:run:129
2017-02-26 12:12:50,434 INFO     Thread-14    Sending Browse [('ObjectID', 'SQ:'), ('BrowseFlag', 'BrowseDirectChildren'), ('Filter', '*'), ('StartingIndex', 0), ('RequestedCount', 100), ('SortCriteria', '')] to 192.168.1.24 -- services.py:send_command:381
2017-02-26 12:12:50,454 DEBUG    Thread-14    Sending {'SOAPACTION': 'urn:schemas-upnp-org:service:ContentDirectory:1#Browse', 'Content-Type': 'text/xml; charset="utf-8"'}, <?xml version="1.0" ?>
<s:Envelope s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/" xmlns:s="http://schemas.xmlsoap.org/soap/envelope/">
  <s:Body>
    <u:Browse xmlns:u="urn:schemas-upnp-org:service:ContentDirectory:1">
      <ObjectID>SQ:</ObjectID>
      <BrowseFlag>BrowseDirectChildren</BrowseFlag>
      <Filter>*</Filter>
      <StartingIndex>0</StartingIndex>
      <RequestedCount>100</RequestedCount>
      <SortCriteria/>
    </u:Browse>
  </s:Body>
</s:Envelope>
 -- services.py:send_command:382
2017-02-26 12:12:50,516 DEBUG    Thread-14    Starting new HTTP connection (1): 192.168.1.24 -- connectionpool.py:_new_conn:207
2017-02-26 12:12:50,545 DEBUG    Thread-14    http://192.168.1.24:1400 "POST /MediaServer/ContentDirectory/Control HTTP/1.1" 200 None -- connectionpool.py:_make_request:395
2017-02-26 12:12:50,570 DEBUG    Thread-14    Received {'Server': 'Linux UPnP/1.0 Sonos/34.16-37101 (ZPS12)', 'EXT': '', 'CONTENT-TYPE': 'text/xml; charset="utf-8"', 'Connection': 'close', 'TRANSFER-ENCODING': 'chunked'}, <s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><s:Body><u:BrowseResponse xmlns:u="urn:schemas-upnp-org:service:ContentDirectory:1"><Result>&lt;DIDL-Lite xmlns:dc=&quot;http://purl.org/dc/elements/1.1/&quot; xmlns:upnp=&quot;urn:schemas-upnp-org:metadata-1-0/upnp/&quot; xmlns:r=&quot;urn:schemas-rinconnetworks-com:metadata-1-0/&quot; xmlns=&quot;urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/&quot;&gt;&lt;container id=&quot;SQ:2&quot; parentID=&quot;SQ:&quot; restricted=&quot;true&quot;&gt;&lt;dc:title&gt;Best of 2016&lt;/dc:title&gt;&lt;res protocolInfo=&quot;file:*:audio/mpegurl:*&quot;&gt;file:///jffs/settings/savedqueues.rsq#2&lt;/res&gt;&lt;upnp:class&gt;object.container.playlistContainer&lt;/upnp:class&gt;&lt;upnp:albumArtURI&gt;/getaa?s=1&amp;amp;u=x-sonosapi-hls-static%3acatalog%252fplaylists%252fB01NAGN8AO%252f%2523chunk%252b0%3fsid%3d201%26flags%3d8224%26sn%3d2&lt;/upnp:albumArtURI&gt;&lt;upnp:albumArtURI&gt;/getaa?s=1&amp;amp;u=x-sonosapi-hls-static%3acatalog%252fplaylists%252fB01NAGN8AO%252f%2523chunk%252b16%3fsid%3d201%26flags%3d8224%26sn%3d2&lt;/upnp:albumArtURI&gt;&lt;upnp:albumArtURI&gt;/getaa?s=1&amp;amp;u=x-sonosapi-hls-static%3acatalog%252fplaylists%252fB01NAGN8AO%252f%2523chunk%252b33%3fsid%3d201%26flags%3d8224%26sn%3d2&lt;/upnp:albumArtURI&gt;&lt;upnp:albumArtURI&gt;/getaa?s=1&amp;amp;u=x-sonosapi-hls-static%3acatalog%252fplaylists%252fB01NAGN8AO%252f%2523chunk%252b49%3fsid%3d201%26flags%3d8224%26sn%3d2&lt;/upnp:albumArtURI&gt;&lt;/container&gt;&lt;container id=&quot;SQ:3&quot; parentID=&quot;SQ:&quot; restricted=&quot;true&quot;&gt;&lt;dc:title&gt;Fit 2017&lt;/dc:title&gt;&lt;res protocolInfo=&quot;file:*:audio/mpegurl:*&quot;&gt;file:///jffs/settings/savedqueues.rsq#3&lt;/res&gt;&lt;upnp:class&gt;object.container.playlistContainer&lt;/upnp:class&gt;&lt;upnp:albumArtURI&gt;/getaa?s=1&amp;amp;u=x-sonosapi-hls-static%3acatalog%252fplaylists%252fB01MT0SR0E%252f%2523chunk%252b0%3fsid%3d201%26flags%3d8224%26sn%3d2&lt;/upnp:albumArtURI&gt;&lt;upnp:albumArtURI&gt;/getaa?s=1&amp;amp;u=x-sonosapi-hls-static%3acatalog%252fplaylists%252fB01MT0SR0E%252f%2523chunk%252b6%3fsid%3d201%26flags%3d8224%26sn%3d2&lt;/upnp:albumArtURI&gt;&lt;upnp:albumArtURI&gt;/getaa?s=1&amp;amp;u=x-sonosapi-hls-static%3acatalog%252fplaylists%252fB01MT0SR0E%252f%2523chunk%252b13%3fsid%3d201%26flags%3d8224%26sn%3d2&lt;/upnp:albumArtURI&gt;&lt;upnp:albumArtURI&gt;/getaa?s=1&amp;amp;u=x-sonosapi-hls-static%3acatalog%252fplaylists%252fB01MT0SR0E%252f%2523chunk%252b19%3fsid%3d201%26flags%3d8224%26sn%3d2&lt;/upnp:albumArtURI&gt;&lt;/container&gt;&lt;container id=&quot;SQ:5&quot; parentID=&quot;SQ:&quot; restricted=&quot;true&quot;&gt;&lt;dc:title&gt;Motivation&lt;/dc:title&gt;&lt;res protocolInfo=&quot;file:*:audio/mpegurl:*&quot;&gt;file:///jffs/settings/savedqueues.rsq#5&lt;/res&gt;&lt;upnp:class&gt;object.container.playlistContainer&lt;/upnp:class&gt;&lt;upnp:albumArtURI&gt;/getaa?s=1&amp;amp;u=x-sonosapi-hls-static%3acatalog%252fplaylists%252fB01N2Z4EBP%252f%2523chunk%252b0%3fsid%3d201%26flags%3d8224%26sn%3d2&lt;/upnp:albumArtURI&gt;&lt;upnp:albumArtURI&gt;/getaa?s=1&amp;amp;u=x-sonosapi-hls-static%3acatalog%252fplaylists%252fB01N2Z4EBP%252f%2523chunk%252b12%3fsid%3d201%26flags%3d8224%26sn%3d2&lt;/upnp:albumArtURI&gt;&lt;upnp:albumArtURI&gt;/getaa?s=1&amp;amp;u=x-sonosapi-hls-static%3acatalog%252fplaylists%252fB01N2Z4EBP%252f%2523chunk%252b24%3fsid%3d201%26flags%3d8224%26sn%3d2&lt;/upnp:albumArtURI&gt;&lt;upnp:albumArtURI&gt;/getaa?s=1&amp;amp;u=x-sonosapi-hls-static%3acatalog%252fplaylists%252fB01N2Z4EBP%252f%2523chunk%252b36%3fsid%3d201%26flags%3d8224%26sn%3d2&lt;/upnp:albumArtURI&gt;&lt;/container&gt;&lt;container id=&quot;SQ:4&quot; parentID=&quot;SQ:&quot; restricted=&quot;true&quot;&gt;&lt;dc:title&gt;Rock Party&lt;/dc:title&gt;&lt;res protocolInfo=&quot;file:*:audio/mpegurl:*&quot;&gt;file:///jffs/settings/savedqueues.rsq#4&lt;/res&gt;&lt;upnp:class&gt;object.container.playlistContainer&lt;/upnp:class&gt;&lt;upnp:albumArtURI&gt;/getaa?s=1&amp;amp;u=x-sonosapi-hls-static%3acatalog%252fplaylists%252fB01NH0TXB3%252f%2523chunk%252b0%3fsid%3d201%26flags%3d8224%26sn%3d2&lt;/upnp:albumArtURI&gt;&lt;upnp:albumArtURI&gt;/getaa?s=1&amp;amp;u=x-sonosapi-hls-static%3acatalog%252fplaylists%252fB01NH0TXB3%252f%2523chunk%252b14%3fsid%3d201%26flags%3d8224%26sn%3d2&lt;/upnp:albumArtURI&gt;&lt;upnp:albumArtURI&gt;/getaa?s=1&amp;amp;u=x-sonosapi-hls-static%3acatalog%252fplaylists%252fB01NH0TXB3%252f%2523chunk%252b27%3fsid%3d201%26flags%3d8224%26sn%3d2&lt;/upnp:albumArtURI&gt;&lt;upnp:albumArtURI&gt;/getaa?s=1&amp;amp;u=x-sonosapi-hls-static%3acatalog%252fplaylists%252fB01NH0TXB3%252f%2523chunk%252b41%3fsid%3d201%26flags%3d8224%26sn%3d2&lt;/upnp:albumArtURI&gt;&lt;/container&gt;&lt;/DIDL-Lite&gt;</Result><NumberReturned>4</NumberReturned><TotalMatches>4</TotalMatches><UpdateID>0</UpdateID></u:BrowseResponse></s:Body></s:Envelope> -- services.py:send_command:389
2017-02-26 12:12:50,581 INFO     Thread-14    Received status 200 from 192.168.1.24 -- services.py:send_command:392
2017-02-26 12:12:50,600 DEBUG    Thread-14    Upgrade not necessary -- data_structures_entry.py:attempt_datastructure_upgrade:136
2017-02-26 12:12:50,607 DEBUG    Thread-14    Upgrade not necessary -- data_structures_entry.py:attempt_datastructure_upgrade:136
2017-02-26 12:12:50,615 DEBUG    Thread-14    Upgrade not necessary -- data_structures_entry.py:attempt_datastructure_upgrade:136
2017-02-26 12:12:50,623 DEBUG    Thread-14    Upgrade not necessary -- data_structures_entry.py:attempt_datastructure_upgrade:136
2017-02-26 12:12:50,628 ERROR    Thread-14    Created data structures: [<DidlPlaylistContai (CUT) from Didl string "<DIDL-Lite xmlns:dc=" (CUT) -- data_structures_entry.py:from_didl_string:66
2017-02-26 12:12:50,649 INFO     Thread-14    registered clients: 192.168.1.13:[9999] -- udp_broker.py:udp_send:43
2017-02-26 12:12:50,655 INFO     Thread-14    sending sonos speaker data: {
    "additional_zone_members": "",
    "alarms": "",
    "balance": 0,
    "bass": 0,
    "display_version": "7.1",
    "hardware_version": "1.20.1.6-2",
    "household_id": "Sonos_NK5GZK7PASLUh5Vei3SIuPaCKx",
    "ip": "192.168.1.24",
    "is_coordinator": true,
    "led": 1,
    "loudness": 1,
    "mac_address": "94-9F-3E-70-72-DC",
    "max_volume": -1,
    "model": "Sonos PLAY:1",
    "model_number": "S12",
    "mute": 0,
    "nightmode": 0,
    "pause": 0,
    "play": 0,
    "playlist_position": 0,
    "playlist_total_tracks": 0,
    "playmode": "normal",
    "radio_show": "",
    "radio_station": "",
    "serial_number": "94-9F-3E-70-72-DC:F",
    "software_version": "34.16-37101",
    "sonos_playlists": "Best of 2016,Fit 2017,Motivation,Rock Party",
    "status": true,
    "stop": 0,
    "streamtype": "",
    "track_album": "",
    "track_album_art": "",
    "track_artist": "",
    "track_duration": "00:00:00",
    "track_position": "00:00:00",
    "track_title": "",
    "track_uri": "",
    "transport_actions": "",
    "treble": 0,
    "uid": "rincon_949f3e7072dc01400",
    "volume": 45,
    "wifi_state": 1,
    "zone_icon": "/img/icon-S12.png",
    "zone_name": "Wohnzimmer"
} -- udp_broker.py:udp_send:44
192.168.1.13 - - [26/Feb/2017 12:12:50] "POST / HTTP/1.1" 200 -
2017-02-26 12:12:50,689 DEBUG    Thread-14    Server response -- status: True -- response:  -- sonos_service.py:do_POST:109
2017-02-26 12:13:14,516 INFO     Thread-15    Event 1 received for AVTransport service on thread <Thread(Thread-15, started -1282411424)> at 1488107594.5121553 -- events.py:do_NOTIFY:227
2017-02-26 12:13:14,521 DEBUG    Thread-15    Event content: b'<e:propertyset xmlns:e="urn:schemas-upnp-org:event-1-0"><e:property><LastChange>&lt;Event xmlns=&quot;urn:schemas-upnp-org:metadata-1-0/AVT/&quot; xmlns:r=&quot;urn:schemas-rinconnetworks-com:metadata-1-0/&quot;&gt;&lt;InstanceID val=&quot;0&quot;&gt;&lt;TransportState val=&quot;PLAYING&quot;/&gt;&lt;CurrentPlayMode val=&quot;NORMAL&quot;/&gt;&lt;CurrentCrossfadeMode val=&quot;0&quot;/&gt;&lt;NumberOfTracks val=&quot;20&quot;/&gt;&lt;CurrentTrack val=&quot;2&quot;/&gt;&lt;CurrentSection val=&quot;0&quot;/&gt;&lt;CurrentTrackURI val=&quot;x-sonosapi-hls-static:catalog%2fplaylists%2fB01MT0SR0E%2f%23chunk%2b1?sid=201&amp;amp;flags=8224&amp;amp;sn=2&quot;/&gt;&lt;CurrentTrackDuration val=&quot;0:03:23&quot;/&gt;&lt;CurrentTrackMetaData val=&quot;&amp;lt;DIDL-Lite xmlns:dc=&amp;quot;http://purl.org/dc/elements/1.1/&amp;quot; xmlns:upnp=&amp;quot;urn:schemas-upnp-org:metadata-1-0/upnp/&amp;quot; xmlns:r=&amp;quot;urn:schemas-rinconnetworks-com:metadata-1-0/&amp;quot; xmlns=&amp;quot;urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/&amp;quot;&amp;gt;&amp;lt;item id=&amp;quot;-1&amp;quot; parentID=&amp;quot;-1&amp;quot; restricted=&amp;quot;true&amp;quot;&amp;gt;&amp;lt;res protocolInfo=&amp;quot;sonos.com-http:*:application/x-mpegURL:*&amp;quot; duration=&amp;quot;0:03:23&amp;quot;&amp;gt;x-sonosapi-hls-static:catalog%2fplaylists%2fB01MT0SR0E%2f%23chunk%2b1?sid=201&amp;amp;amp;flags=8224&amp;amp;amp;sn=2&amp;lt;/res&amp;gt;&amp;lt;r:streamContent&amp;gt;&amp;lt;/r:streamContent&amp;gt;&amp;lt;r:radioShowMd&amp;gt;&amp;lt;/r:radioShowMd&amp;gt;&amp;lt;upnp:albumArtURI&amp;gt;/getaa?s=1&amp;amp;amp;u=x-sonosapi-hls-static%3acatalog%252fplaylists%252fB01MT0SR0E%252f%2523chunk%252b1%3fsid%3d201%26flags%3d8224%26sn%3d2&amp;lt;/upnp:albumArtURI&amp;gt;&amp;lt;dc:title&amp;gt;Break The Rules [Explicit]&amp;lt;/dc:title&amp;gt;&amp;lt;upnp:class&amp;gt;object.item.audioItem.musicTrack&amp;lt;/upnp:class&amp;gt;&amp;lt;dc:creator&amp;gt;Charli XCX&amp;lt;/dc:creator&amp;gt;&amp;lt;/item&amp;gt;&amp;lt;/DIDL-Lite&amp;gt;&quot;/&gt;&lt;r:NextTrackURI val=&quot;x-sonosapi-hls-static:catalog%2fplaylists%2fB01MT0SR0E%2f%23chunk%2b2?sid=201&amp;amp;flags=8224&amp;amp;sn=2&quot;/&gt;&lt;r:NextTrackMetaData val=&quot;&amp;lt;DIDL-Lite xmlns:dc=&amp;quot;http://purl.org/dc/elements/1.1/&amp;quot; xmlns:upnp=&amp;quot;urn:schemas-upnp-org:metadata-1-0/upnp/&amp;quot; xmlns:r=&amp;quot;urn:schemas-rinconnetworks-com:metadata-1-0/&amp;quot; xmlns=&amp;quot;urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/&amp;quot;&amp;gt;&amp;lt;item id=&amp;quot;-1&amp;quot; parentID=&amp;quot;-1&amp;quot; restricted=&amp;quot;true&amp;quot;&amp;gt;&amp;lt;res protocolInfo=&amp;quot;sonos.com-http:*:application/x-mpegURL:*&amp;quot; duration=&amp;quot;0:03:49&amp;quot;&amp;gt;x-sonosapi-hls-static:catalog%2fplaylists%2fB01MT0SR0E%2f%23chunk%2b2?sid=201&amp;amp;amp;flags=8224&amp;amp;amp;sn=2&amp;lt;/res&amp;gt;&amp;lt;upnp:albumArtURI&amp;gt;/getaa?s=1&amp;amp;amp;u=x-sonosapi-hls-static%3acatalog%252fplaylists%252fB01MT0SR0E%252f%2523chunk%252b2%3fsid%3d201%26flags%3d8224%26sn%3d2&amp;lt;/upnp:albumArtURI&amp;gt;&amp;lt;dc:title&amp;gt;Give It 2 U [feat. Kendrick Lamar] [Explicit]&amp;lt;/dc:title&amp;gt;&amp;lt;upnp:class&amp;gt;object.item.audioItem.musicTrack&amp;lt;/upnp:class&amp;gt;&amp;lt;dc:creator&amp;gt;Robin Thicke&amp;lt;/dc:creator&amp;gt;&amp;lt;/item&amp;gt;&amp;lt;/DIDL-Lite&amp;gt;&quot;/&gt;&lt;r:EnqueuedTransportURI val=&quot;file:///jffs/settings/savedqueues.rsq#3&quot;/&gt;&lt;r:EnqueuedTransportURIMetaData val=&quot;&amp;lt;DIDL-Lite xmlns=&amp;quot;urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/&amp;quot; xmlns:dc=&amp;quot;http://purl.org/dc/elements/1.1/&amp;quot; xmlns:upnp=&amp;quot;urn:schemas-upnp-org:metadata-1-0/upnp/&amp;quot;&amp;gt;&amp;lt;item id=&amp;quot;SQ:3&amp;quot; parentID=&amp;quot;SQ:&amp;quot; restricted=&amp;quot;true&amp;quot;&amp;gt;&amp;lt;dc:title&amp;gt;Fit 2017&amp;lt;/dc:title&amp;gt;&amp;lt;res protocolInfo=&amp;quot;file:*:audio/mpegurl:*&amp;quot;&amp;gt;file:///jffs/settings/savedqueues.rsq#3&amp;lt;/res&amp;gt;&amp;lt;upnp:class&amp;gt;object.container.playlistContainer&amp;lt;/upnp:class&amp;gt;&amp;lt;desc id=&amp;quot;cdudn&amp;quot; nameSpace=&amp;quot;urn:schemas-rinconnetworks-com:metadata-1-0/&amp;quot; /&amp;gt;&amp;lt;/item&amp;gt;&amp;lt;/DIDL-Lite&amp;gt;&quot;/&gt;&lt;CurrentTransportActions val=&quot;Set, Stop, Pause, Play, X_DLNA_SeekTime, Next, Previous, X_DLNA_SeekTrackNr&quot;/&gt;&lt;/InstanceID&gt;&lt;/Event&gt;</LastChange></e:property></e:propertyset>' -- events.py:do_NOTIFY:228
2017-02-26 12:13:14,563 DEBUG    Thread-15    Upgrade not necessary -- data_structures_entry.py:attempt_datastructure_upgrade:136
2017-02-26 12:13:14,567 ERROR    Thread-15    Created data structures: [<DidlMusicTrack 'b' (CUT) from Didl string "<DIDL-Lite xmlns:dc=" (CUT) -- data_structures_entry.py:from_didl_string:66
2017-02-26 12:13:14,583 DEBUG    Thread-15    Upgrade not necessary -- data_structures_entry.py:attempt_datastructure_upgrade:136
2017-02-26 12:13:14,587 ERROR    Thread-15    Created data structures: [<DidlMusicTrack 'b' (CUT) from Didl string "<DIDL-Lite xmlns:dc=" (CUT) -- data_structures_entry.py:from_didl_string:66
2017-02-26 12:13:14,599 DEBUG    Thread-15    Upgrade not necessary -- data_structures_entry.py:attempt_datastructure_upgrade:136
2017-02-26 12:13:14,604 ERROR    Thread-15    Created data structures: [<DidlPlaylistContai (CUT) from Didl string "<DIDL-Lite xmlns="ur" (CUT) -- data_structures_entry.py:from_didl_string:66
2017-02-26 12:13:14,612 DEBUG    Thread-15    "NOTIFY / HTTP/1.1" 200 - -- events.py:log_message:249
2017-02-26 12:13:14,617 DEBUG    Thread-1     Dispatching method GetPositionInfo -- services.py:__getattr__:195
2017-02-26 12:13:14,630 INFO     Thread-1     Sending GetPositionInfo [('InstanceID', 0), ('Channel', 'Master')] to 192.168.1.24 -- services.py:send_command:381
2017-02-26 12:13:14,648 DEBUG    Thread-1     Sending {'SOAPACTION': 'urn:schemas-upnp-org:service:AVTransport:1#GetPositionInfo', 'Content-Type': 'text/xml; charset="utf-8"'}, <?xml version="1.0" ?>
<s:Envelope s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/" xmlns:s="http://schemas.xmlsoap.org/soap/envelope/">
  <s:Body>
    <u:GetPositionInfo xmlns:u="urn:schemas-upnp-org:service:AVTransport:1">
      <InstanceID>0</InstanceID>
      <Channel>Master</Channel>
    </u:GetPositionInfo>
  </s:Body>
</s:Envelope>
 -- services.py:send_command:382
2017-02-26 12:13:14,707 DEBUG    Thread-1     Starting new HTTP connection (1): 192.168.1.24 -- connectionpool.py:_new_conn:207
2017-02-26 12:13:14,736 DEBUG    Thread-1     http://192.168.1.24:1400 "POST /MediaRenderer/AVTransport/Control HTTP/1.1" 200 1602 -- connectionpool.py:_make_request:395
2017-02-26 12:13:14,760 DEBUG    Thread-1     Received {'CONTENT-LENGTH': '1602', 'Server': 'Linux UPnP/1.0 Sonos/34.16-37101 (ZPS12)', 'EXT': '', 'CONTENT-TYPE': 'text/xml; charset="utf-8"', 'Connection': 'close'}, <s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><s:Body><u:GetPositionInfoResponse xmlns:u="urn:schemas-upnp-org:service:AVTransport:1"><Track>2</Track><TrackDuration>0:03:23</TrackDuration><TrackMetaData>&lt;DIDL-Lite xmlns:dc=&quot;http://purl.org/dc/elements/1.1/&quot; xmlns:upnp=&quot;urn:schemas-upnp-org:metadata-1-0/upnp/&quot; xmlns:r=&quot;urn:schemas-rinconnetworks-com:metadata-1-0/&quot; xmlns=&quot;urn:schemas-upnp-org:metadata-1-0/DIDL-Lite/&quot;&gt;&lt;item id=&quot;-1&quot; parentID=&quot;-1&quot; restricted=&quot;true&quot;&gt;&lt;res protocolInfo=&quot;sonos.com-http:*:application/x-mpegURL:*&quot; duration=&quot;0:03:23&quot;&gt;x-sonosapi-hls-static:catalog%2fplaylists%2fB01MT0SR0E%2f%23chunk%2b1?sid=201&amp;amp;flags=8224&amp;amp;sn=2&lt;/res&gt;&lt;r:streamContent&gt;&lt;/r:streamContent&gt;&lt;upnp:albumArtURI&gt;/getaa?s=1&amp;amp;u=x-sonosapi-hls-static%3acatalog%252fplaylists%252fB01MT0SR0E%252f%2523chunk%252b1%3fsid%3d201%26flags%3d8224%26sn%3d2&lt;/upnp:albumArtURI&gt;&lt;dc:title&gt;Break The Rules [Explicit]&lt;/dc:title&gt;&lt;upnp:class&gt;object.item.audioItem.musicTrack&lt;/upnp:class&gt;&lt;dc:creator&gt;Charli XCX&lt;/dc:creator&gt;&lt;/item&gt;&lt;/DIDL-Lite&gt;</TrackMetaData><TrackURI>x-sonosapi-hls-static:catalog%2fplaylists%2fB01MT0SR0E%2f%23chunk%2b1?sid=201&amp;flags=8224&amp;sn=2</TrackURI><RelTime>0:00:00</RelTime><AbsTime>NOT_IMPLEMENTED</AbsTime><RelCount>2147483647</RelCount><AbsCount>2147483647</AbsCount></u:GetPositionInfoResponse></s:Body></s:Envelope> -- services.py:send_command:389
2017-02-26 12:13:14,767 INFO     Thread-1     Received status 200 from 192.168.1.24 -- services.py:send_command:392
2017-02-26 12:13:14,787 INFO     Thread-1     registered clients: 192.168.1.13:[9999] -- udp_broker.py:udp_send:43
2017-02-26 12:13:14,793 INFO     Thread-1     sending sonos speaker data: {
    "pause": 0,
    "play": 1,
    "playlist_position": "2",
    "playlist_total_tracks": "20",
    "stop": 0,
    "streamtype": "music",
    "track_album_art": "http://192.168.1.24:1400/getaa?s=1&u=x-sonosapi-hls-static%3acatalog%252fplaylists%252fB01MT0SR0E%252f%2523chunk%252b1%3fsid%3d201%26flags%3d8224%26sn%3d2",
    "track_artist": "Charli XCX",
    "track_duration": "0:03:23",
    "track_position": "0:00:00",
    "track_title": "Break The Rules [Explicit]",
    "track_uri": "x-sonosapi-hls-static:catalog%2fplaylists%2fB01MT0SR0E%2f%23chunk%2b1?sid=201&flags=8224&sn=2",
    "transport_actions": "Set, Stop, Pause, Play, X_DLNA_SeekTime, Next, Previous, X_DLNA_SeekTrackNr",
    "uid": "rincon_949f3e7072dc01400"
} -- udp_broker.py:udp_send:44

**pfischi** · 26.02.2017, 12:34

Hast du irgendwelche Ausfälle wenn der Fehler kommt? Sonst würde ich den einfach ignorieren. Meistens sind es Metadaten, die ausserhalb der Spezifikation des zugrundeliegenden Frameworks liegen. Behalte das mal im Auge, ich mach das gleiche

Gruss,

Stefan

Ankündigung

Sonos Anbindung

Kommentar

Kommentar

Kommentar

Kommentar

Kommentar

Kommentar

Kommentar

Kommentar

Kommentar

Kommentar

Kommentar

Kommentar

Kommentar

Kommentar

Kommentar