search

ebaauw / homebridge-zp

Homebridge plugin for Sonos ZonePlayer
Apache License 2.0
243 stars 20 forks source link

Starting a song in SONOS does not update the Homekit Status and vice-versa #180

Closed dablais closed 2 years ago

dablais commented 3 years ago

Issue

Starting a song in SONOS does not update the Homekit Status and vice-versa

Log Messages

7/9/2021, 2:06:30 PMHOOBSSonosWARNINGwarning: heartbeat 6, drift 1842 7/9/2021, 2:06:30 PMHOOBSSonosRINCON_B8E93747354001400 [Manu’s Room Speakers]: associated S2 zone player 7/9/2021, 2:06:30 PMHOOBSSonosWARNINGwarning: RINCON_542A1B446C0A01400 [Sonos Roam]: rebooted {"name":"Sonos Roam","bootSeq":39,"oldBootSeq":38} -> 38 7/9/2021, 2:06:30 PMHOOBSSonosWARNINGwarning: heartbeat 7, drift 1275 7/9/2021, 2:06:30 PMHOOBSSonosWARNINGwarning: heartbeat 8, drift 279 7/9/2021, 2:06:32 PMHOOBSSonosWARNINGwarning: heartbeat 9, drift 1244 7/9/2021, 2:06:33 PMHOOBSSonosWARNINGwarning: heartbeat 10, drift 690 7/9/2021, 2:06:33 PMHOOBSSonosfound 33 S2 zone players in 21 zones 7/9/2021, 2:06:33 PMHOOBSSonos├─ Bachelor TV (+LS+RS) 7/9/2021, 2:06:33 PMHOOBSSonos│ ├─ RINCON_48A6B833315F01400 [Bachelor TV]: Sonos Beam (S14) (master, airPlay, tvIn) 7/9/2021, 2:06:33 PMHOOBSSonos│ ├─ RINCON_48A6B8AEB68801400 [Bachelor TV (LS)]: Sonos One SL (S22) (satellite) 7/9/2021, 2:06:33 PMHOOBSSonos│ └─ RINCON_48A6B8AEB5CA01400 [Bachelor TV (RS)]: Sonos One SL (S22) (satellite) 7/9/2021, 2:06:33 PMHOOBSSonos├─ Bachelor (L+R) 7/9/2021, 2:06:33 PMHOOBSSonos│ ├─ RINCON_48A6B8826D6C01400 [Bachelor (L)]: Sonos One (S18) (master, airPlay) 7/9/2021, 2:06:33 PMHOOBSSonos│ └─ RINCON_48A6B8826E8C01400 [Bachelor (R)]: Sonos One (S18) (slave) 7/9/2021, 2:06:33 PMHOOBSSonos├─ Basement 7/9/2021, 2:06:33 PMHOOBSSonos│ └─ RINCON_B8E9379BE5FA01400 [Basement]: Sonos Connect (ZP90) (master, audioIn) 7/9/2021, 2:06:33 PMHOOBSSonos├─ Bathroom 2 7/9/2021, 2:06:33 PMHOOBSSonos│ └─ RINCON_5CAAFD6E59C401400 [Bathroom 2]: Sonos Play:1 (S1) (master) 7/9/2021, 2:06:33 PMHOOBSSonos├─ Boost (invisible) 7/9/2021, 2:06:33 PMHOOBSSonos│ └─ RINCON_7828CA74723601400 [Boost]: Sonos Boost (BR200) (master) 7/9/2021, 2:06:33 PMHOOBSSonos├─ Dining Room 7/9/2021, 2:06:33 PMHOOBSSonos│ └─ RINCON_B8E9373AAD4601400 [Dining Room]: Sonos Play:3 (S3) (master) 7/9/2021, 2:06:33 PMHOOBSSonos├─ Garage (Gym) (L+R) 7/9/2021, 2:06:33 PMHOOBSSonos│ ├─ RINCON_48A6B8FE5FA801400 [Garage (Gym) (L)]: Sonos One (S18) (master, airPlay) 7/9/2021, 2:06:33 PMHOOBSSonos│ └─ RINCON_48A6B8FE5FDA01400 [Garage (Gym) (R)]: Sonos One (S18) (slave) 7/9/2021, 2:06:33 PMHOOBSSonos├─ Garage (Workbench) 7/9/2021, 2:06:33 PMHOOBSSonos│ └─ RINCON_B8E9379BE4FE01400 [Garage (Workbench)]: Sonos Connect (ZP90) (master, audioIn) 7/9/2021, 2:06:33 PMHOOBSSonos├─ Guest Room 7/9/2021, 2:06:33 PMHOOBSSonos│ └─ RINCON_B8E937DDBC2A01400 [Guest Room]: Sonos Play:3 (S3) (master) 7/9/2021, 2:06:33 PMHOOBSSonos├─ Hallway 0.5 7/9/2021, 2:06:33 PMHOOBSSonos│ └─ RINCON_5CAAFD6E5B5E01400 [Hallway 0.5]: Sonos Play:1 (S1) (master) 7/9/2021, 2:06:33 PMHOOBSSonos├─ Kitchen Speakers (L+R) 7/9/2021, 2:06:33 PMHOOBSSonos│ ├─ RINCON_7828CAF131E601400 [Kitchen Speakers (L)]: Sonos One (S18) (master, airPlay) 7/9/2021, 2:06:33 PMHOOBSSonos│ └─ RINCON_7828CAF131DA01400 [Kitchen Speakers (R)]: Sonos One (S18) (slave) 7/9/2021, 2:06:33 PMHOOBSSonos├─ Living Room Speakers (L+R) 7/9/2021, 2:06:33 PMHOOBSSonos│ ├─ RINCON_5CAAFDFF2C6C01400 [Living Room Speakers (L)]: Sonos Play:5 (S6) (master, airPlay, audioIn) 7/9/2021, 2:06:33 PMHOOBSSonos│ └─ RINCON_5CAAFD08DE3C01400 [Living Room Speakers (R)]: Sonos Play:5 (S6) (slave, audioIn) 7/9/2021, 2:06:33 PMHOOBSSonos├─ Living Room TV (+Sub+RS+LS) 7/9/2021, 2:06:33 PMHOOBSSonos│ ├─ RINCON_5CAAFD1C788401400 [Living Room TV]: Sonos Playbar (S9) (master, tvIn) 7/9/2021, 2:06:33 PMHOOBSSonos│ ├─ RINCON_5CAAFD811B1201400 [Living Room TV (Sub)]: Sonos Sub (Sub) (satellite) 7/9/2021, 2:06:33 PMHOOBSSonos│ ├─ RINCON_5CAAFD6E65E201400 [Living Room TV (RS)]: Sonos Play:1 (S1) (satellite) 7/9/2021, 2:06:33 PMHOOBSSonos│ └─ RINCON_5CAAFD6E652801400 [Living Room TV (LS)]: Sonos Play:1 (S1) (satellite) 7/9/2021, 2:06:33 PMHOOBSSonos├─ Manu’s Room Speakers (+LS+RS) 7/9/2021, 2:06:33 PMHOOBSSonos│ ├─ RINCON_B8E93747354001400 [Manu’s Room Speakers]: Sonos Playbase (S11) (master, airPlay, tvIn) 7/9/2021, 2:06:33 PMHOOBSSonos│ ├─ RINCON_5CAAFD284C4C01400 [Manu’s Room Speakers (LS)]: Sonos Play:1 (S1) (satellite) 7/9/2021, 2:06:33 PMHOOBSSonos│ └─ RINCON_5CAAFD60569001400 [Manu’s Room Speakers (RS)]: Sonos Play:1 (S1) (satellite) 7/9/2021, 2:06:33 PMHOOBSSonos├─ Master Bathroom 7/9/2021, 2:06:33 PMHOOBSSonos│ └─ RINCON_5CAAFD96773601400 [Master Bathroom]: Sonos Play:1 (S1) (master) 7/9/2021, 2:06:33 PMHOOBSSonos├─ Master Bedroom (L+R) 7/9/2021, 2:06:33 PMHOOBSSonos│ ├─ RINCON_5CAAFD967AC201400 [Master Bedroom (L)]: Sonos Play:1 (S1) (master) 7/9/2021, 2:06:33 PMHOOBSSonos│ └─ RINCON_5CAAFD9436D201400 [Master Bedroom (R)]: Sonos Play:1 (S1) (slave) 7/9/2021, 2:06:33 PMHOOBSSonos├─ Office 7/9/2021, 2:06:33 PMHOOBSSonos│ └─ RINCON_B8E9379DA85401400 [Office]: Sonos Connect (ZP90) (master, audioIn) 7/9/2021, 2:06:33 PMHOOBSSonos├─ Playroom 7/9/2021, 2:06:33 PMHOOBSSonos│ └─ RINCON_5CAAFD60583001400 [Playroom]: Sonos Play:1 (S1) (master) 7/9/2021, 2:06:33 PMHOOBSSonos├─ Sonos Move 7/9/2021, 2:06:33 PMHOOBSSonos│ └─ RINCON_48A6B8E6C49601400 [Sonos Move]: Sonos Move (S17) (master, airPlay) 7/9/2021, 2:06:33 PMHOOBSSonos├─ Sonos Roam 7/9/2021, 2:06:33 PMHOOBSSonos│ └─ RINCON_542A1B446C0A01400 [Sonos Roam]: Sonos Roam (S27) (master, airPlay) 7/9/2021, 2:06:33 PMHOOBSSonos└─ Studio Port 7/9/2021, 2:06:33 PMHOOBSSonos └─ RINCON_48A6B820B88001400 [Studio Port]: Sonos Port (S23) (master, airPlay, audioIn) 7/9/2021, 2:06:33 PMHOOBSSonosBachelor TV Sonos: remove stale characteristic Volume 7/9/2021, 2:06:33 PMHOOBSSonosBachelor TV: set Serial Number to "TV_48A6B833315F01400" 7/9/2021, 2:06:33 PMHOOBSSonosBachelor TV: set Manufacturer to "Sonos" 7/9/2021, 2:06:33 PMHOOBSSonosBachelor TV: set Model to "Beam (S14)" 7/9/2021, 2:06:33 PMHOOBSSonosBachelor TV: set Firmware Revision to "13.1.4" 7/9/2021, 2:06:33 PMHOOBSSonosBachelor TV TV: set Active to 0 7/9/2021, 2:06:33 PMHOOBSSonosBachelor TV TV: set Configured Name to "Bachelor TV Sonos" 7/9/2021, 2:06:33 PMHOOBSSonosBachelor Sonos: remove stale characteristic Volume 7/9/2021, 2:06:33 PMHOOBSSonosBachelor L: set Serial Number to "TV_48A6B8826D6C01400" 7/9/2021, 2:06:33 PMHOOBSSonosBachelor L: set Manufacturer to "Sonos" 7/9/2021, 2:06:33 PMHOOBSSonosBachelor L: set Model to "One (S18)" 7/9/2021, 2:06:33 PMHOOBSSonosBachelor L: set Firmware Revision to "13.1.4" 7/9/2021, 2:06:34 PMHOOBSSonosBachelor TV: set Active to 0 7/9/2021, 2:06:34 PMHOOBSSonosBachelor TV: set Configured Name to "Bachelor Sonos" 7/9/2021, 2:06:34 PMHOOBSSonosBasement Sonos: remove stale characteristic Volume 7/9/2021, 2:06:34 PMHOOBSSonosBasement: set Serial Number to "TV_B8E9379BE5FA01400" 7/9/2021, 2:06:34 PMHOOBSSonosBasement: set Manufacturer to "Sonos" 7/9/2021, 2:06:34 PMHOOBSSonosBasement: set Model to "Connect (ZP90)" 7/9/2021, 2:06:34 PMHOOBSSonosBasement: set Firmware Revision to "13.1.4" 7/9/2021, 2:06:34 PMHOOBSSonosBasement TV: set Active to 0 7/9/2021, 2:06:34 PMHOOBSSonosBasement TV: set Configured Name to "Basement Sonos" 7/9/2021, 2:06:34 PMHOOBSSonosBathroom 2 Sonos: remove stale characteristic Volume 7/9/2021, 2:06:34 PMHOOBSSonosBathroom 2: set Serial Number to "TV_5CAAFD6E59C401400" 7/9/2021, 2:06:34 PMHOOBSSonosBathroom 2: set Manufacturer to "Sonos" 7/9/2021, 2:06:34 PMHOOBSSonosBathroom 2: set Model to "Play:1 (S1)" 7/9/2021, 2:06:34 PMHOOBSSonosBathroom 2: set Firmware Revision to "13.1.4" 7/9/2021, 2:06:34 PMHOOBSSonosBathroom 2 TV: set Active to 0 7/9/2021, 2:06:34 PMHOOBSSonosBathroom 2 TV: set Configured Name to "Bathroom 2 Sonos" 7/9/2021, 2:06:34 PMHOOBSSonosDining Room Sonos: remove stale characteristic Volume 7/9/2021, 2:06:34 PMHOOBSSonosDining Room: set Serial Number to "TV_B8E9373AAD4601400" 7/9/2021, 2:06:34 PMHOOBSSonosDining Room: set Manufacturer to "Sonos" 7/9/2021, 2:06:34 PMHOOBSSonosDining Room: set Model to "Play:3 (S3)" 7/9/2021, 2:06:34 PMHOOBSSonosDining Room: set Firmware Revision to "13.1.4" 7/9/2021, 2:06:35 PMHOOBSSonosDining Room TV: set Active to 0 7/9/2021, 2:06:35 PMHOOBSSonosDining Room TV: set Configured Name to "Dining Room Sonos" 7/9/2021, 2:06:35 PMHOOBSSonosGarage (Gym) Sonos: remove stale characteristic Volume 7/9/2021, 2:06:35 PMHOOBSSonosGarage (Gym) L: set Serial Number to "TV_48A6B8FE5FA801400" 7/9/2021, 2:06:35 PMHOOBSSonosGarage (Gym) L: set Manufacturer to "Sonos" 7/9/2021, 2:06:35 PMHOOBSSonosGarage (Gym) L: set Model to "One (S18)" 7/9/2021, 2:06:35 PMHOOBSSonosGarage (Gym) L: set Firmware Revision to "13.1.4" 7/9/2021, 2:06:35 PMHOOBSSonosGarage (Gym) TV: set Active to 0 7/9/2021, 2:06:35 PMHOOBSSonosGarage (Gym) TV: set Configured Name to "Garage (Gym) Sonos" 7/9/2021, 2:06:35 PMHOOBSSonosGarage (Workbench) Sonos: remove stale characteristic Volume 7/9/2021, 2:06:35 PMHOOBSSonosGarage (Workbench): set Serial Number to "TV_B8E9379BE4FE01400" 7/9/2021, 2:06:35 PMHOOBSSonosGarage (Workbench): set Manufacturer to "Sonos" 7/9/2021, 2:06:35 PMHOOBSSonosGarage (Workbench): set Model to "Connect (ZP90)" 7/9/2021, 2:06:35 PMHOOBSSonosGarage (Workbench): set Firmware Revision to "13.1.4" 7/9/2021, 2:06:35 PMHOOBSSonosGarage (Workbench) TV: set Active to 0 7/9/2021, 2:06:35 PMHOOBSSonosGarage (Workbench) TV: set Configured Name to "Garage (Workbench) Sonos" 7/9/2021, 2:06:35 PMHOOBSSonosGuest Room Sonos: remove stale characteristic Volume 7/9/2021, 2:06:35 PMHOOBSSonosGuest Room: set Serial Number to "TV_B8E937DDBC2A01400" 7/9/2021, 2:06:35 PMHOOBSSonosGuest Room: set Manufacturer to "Sonos" 7/9/2021, 2:06:35 PMHOOBSSonosGuest Room: set Model to "Play:3 (S3)" 7/9/2021, 2:06:35 PMHOOBSSonosGuest Room: set Firmware Revision to "13.1.4" 7/9/2021, 2:06:35 PMHOOBSSonosGuest Room TV: set Active to 0 7/9/2021, 2:06:35 PMHOOBSSonosGuest Room TV: set Configured Name to "Guest Room Sonos" 7/9/2021, 2:06:35 PMHOOBSSonosHallway 0.5 Sonos: remove stale characteristic Volume 7/9/2021, 2:06:35 PMHOOBSSonosHallway 0.5: set Serial Number to "TV_5CAAFD6E5B5E01400" 7/9/2021, 2:06:35 PMHOOBSSonosHallway 0.5: set Manufacturer to "Sonos" 7/9/2021, 2:06:35 PMHOOBSSonosHallway 0.5: set Model to "Play:1 (S1)" 7/9/2021, 2:06:35 PMHOOBSSonosHallway 0.5: set Firmware Revision to "13.1.4" 7/9/2021, 2:06:36 PMHOOBSSonosHallway 0.5 TV: set Active to 0 7/9/2021, 2:06:36 PMHOOBSSonosHallway 0.5 TV: set Configured Name to "Hallway 0.5 Sonos" 7/9/2021, 2:06:36 PMHOOBSSonosKitchen Speakers Sonos: remove stale characteristic Volume 7/9/2021, 2:06:36 PMHOOBSSonosKitchen Speakers L: set Serial Number to "TV_7828CAF131E601400" 7/9/2021, 2:06:36 PMHOOBSSonosKitchen Speakers L: set Manufacturer to "Sonos" 7/9/2021, 2:06:36 PMHOOBSSonosKitchen Speakers L: set Model to "One (S18)" 7/9/2021, 2:06:36 PMHOOBSSonosKitchen Speakers L: set Firmware Revision to "13.1.4" 7/9/2021, 2:06:36 PMHOOBSSonosKitchen Speakers TV: set Active to 0 7/9/2021, 2:06:36 PMHOOBSSonosKitchen Speakers TV: set Configured Name to "Kitchen Speakers Sonos" 7/9/2021, 2:06:36 PMHOOBSSonosLiving Room Speakers Sonos: remove stale characteristic Volume 7/9/2021, 2:06:36 PMHOOBSSonosLiving Room Speakers L: set Serial Number to "TV_5CAAFDFF2C6C01400" 7/9/2021, 2:06:36 PMHOOBSSonosLiving Room Speakers L: set Manufacturer to "Sonos" 7/9/2021, 2:06:36 PMHOOBSSonosLiving Room Speakers L: set Model to "Play:5 (S6)" 7/9/2021, 2:06:36 PMHOOBSSonosLiving Room Speakers L: set Firmware Revision to "13.1.4" 7/9/2021, 2:06:36 PMHOOBSSonosLiving Room Speakers TV: set Active to 0 7/9/2021, 2:06:36 PMHOOBSSonosLiving Room Speakers TV: set Configured Name to "Living Room Speakers Sonos" 7/9/2021, 2:06:36 PMHOOBSSonosLiving Room TV Sonos: remove stale characteristic Volume 7/9/2021, 2:06:36 PMHOOBSSonosLiving Room TV: set Serial Number to "TV_5CAAFD1C788401400" 7/9/2021, 2:06:36 PMHOOBSSonosLiving Room TV: set Manufacturer to "Sonos" 7/9/2021, 2:06:36 PMHOOBSSonosLiving Room TV: set Model to "Playbar (S9)" 7/9/2021, 2:06:36 PMHOOBSSonosLiving Room TV: set Firmware Revision to "13.1.4" 7/9/2021, 2:06:36 PMHOOBSSonosLiving Room TV TV: set Active to 0 7/9/2021, 2:06:36 PMHOOBSSonosLiving Room TV TV: set Configured Name to "Living Room TV Sonos" 7/9/2021, 2:06:36 PMHOOBSSonosManu’s Room Speakers Sonos: remove stale characteristic Volume 7/9/2021, 2:06:36 PMHOOBSSonosManu’s Room Speakers: set Serial Number to "TV_B8E93747354001400" 7/9/2021, 2:06:36 PMHOOBSSonosManu’s Room Speakers: set Manufacturer to "Sonos" 7/9/2021, 2:06:36 PMHOOBSSonosManu’s Room Speakers: set Model to "Playbase (S11)" 7/9/2021, 2:06:36 PMHOOBSSonosManu’s Room Speakers: set Firmware Revision to "13.1.4" 7/9/2021, 2:06:37 PMHOOBSSonosManu’s Room Speakers TV: set Active to 0 7/9/2021, 2:06:37 PMHOOBSSonosManu’s Room Speakers TV: set Configured Name to "Manu’s Room Speakers Sonos" 7/9/2021, 2:06:37 PMHOOBSSonosMaster Bathroom Sonos: remove stale characteristic Volume 7/9/2021, 2:06:37 PMHOOBSSonosMaster Bathroom: set Serial Number to "TV_5CAAFD96773601400" 7/9/2021, 2:06:37 PMHOOBSSonosMaster Bathroom: set Manufacturer to "Sonos" 7/9/2021, 2:06:37 PMHOOBSSonosMaster Bathroom: set Model to "Play:1 (S1)" 7/9/2021, 2:06:37 PMHOOBSSonosMaster Bathroom: set Firmware Revision to "13.1.4" 7/9/2021, 2:06:37 PMHOOBSSonosMaster Bathroom TV: set Active to 0 7/9/2021, 2:06:37 PMHOOBSSonosMaster Bathroom TV: set Configured Name to "Master Bathroom Sonos" 7/9/2021, 2:06:37 PMHOOBSSonosMaster Bedroom Sonos: remove stale characteristic Volume 7/9/2021, 2:06:37 PMHOOBSSonosMaster Bedroom L: set Serial Number to "TV_5CAAFD967AC201400" 7/9/2021, 2:06:37 PMHOOBSSonosMaster Bedroom L: set Manufacturer to "Sonos" 7/9/2021, 2:06:37 PMHOOBSSonosMaster Bedroom L: set Model to "Play:1 (S1)" 7/9/2021, 2:06:37 PMHOOBSSonosMaster Bedroom L: set Firmware Revision to "13.1.4" 7/9/2021, 2:06:37 PMHOOBSSonosMaster Bedroom TV: set Active to 0 7/9/2021, 2:06:37 PMHOOBSSonosMaster Bedroom TV: set Configured Name to "Master Bedroom Sonos" 7/9/2021, 2:06:37 PMHOOBSSonosOffice Sonos: remove stale characteristic Volume 7/9/2021, 2:06:37 PMHOOBSSonosOffice: set Serial Number to "TV_B8E9379DA85401400" 7/9/2021, 2:06:37 PMHOOBSSonosOffice: set Manufacturer to "Sonos" 7/9/2021, 2:06:37 PMHOOBSSonosOffice: set Model to "Connect (ZP90)" 7/9/2021, 2:06:37 PMHOOBSSonosOffice: set Firmware Revision to "13.1.4" 7/9/2021, 2:06:37 PMHOOBSSonosOffice TV: set Active to 0 7/9/2021, 2:06:37 PMHOOBSSonosOffice TV: set Configured Name to "Office Sonos" 7/9/2021, 2:06:37 PMHOOBSSonosPlayroom Sonos: remove stale characteristic Volume 7/9/2021, 2:06:37 PMHOOBSSonosPlayroom: set Serial Number to "TV_5CAAFD60583001400" 7/9/2021, 2:06:37 PMHOOBSSonosPlayroom: set Manufacturer to "Sonos" 7/9/2021, 2:06:37 PMHOOBSSonosPlayroom: set Model to "Play:1 (S1)" 7/9/2021, 2:06:37 PMHOOBSSonosPlayroom: set Firmware Revision to "13.1.4" 7/9/2021, 2:06:38 PMHOOBSSonosPlayroom TV: set Active to 0 7/9/2021, 2:06:38 PMHOOBSSonosPlayroom TV: set Configured Name to "Playroom Sonos" 7/9/2021, 2:06:38 PMHOOBSSonosSonos Move Sonos: remove stale characteristic Volume 7/9/2021, 2:06:38 PMHOOBSSonosSonos Move: set Serial Number to "TV_48A6B8E6C49601400" 7/9/2021, 2:06:38 PMHOOBSSonosSonos Move: set Manufacturer to "Sonos" 7/9/2021, 2:06:38 PMHOOBSSonosSonos Move: set Model to "Move (S17)" 7/9/2021, 2:06:38 PMHOOBSSonosSonos Move: set Firmware Revision to "13.1.4" 7/9/2021, 2:06:38 PMHOOBSSonosSonos Move TV: set Active to 0 7/9/2021, 2:06:38 PMHOOBSSonosSonos Move TV: set Configured Name to "Sonos Move Sonos" 7/9/2021, 2:06:38 PMHOOBSSonosSonos Roam Sonos: remove stale characteristic Volume 7/9/2021, 2:06:38 PMHOOBSSonosSonos Roam: set Serial Number to "TV_542A1B446C0A01400" 7/9/2021, 2:06:38 PMHOOBSSonosSonos Roam: set Manufacturer to "Sonos" 7/9/2021, 2:06:38 PMHOOBSSonosSonos Roam: set Model to "Roam (S27)" 7/9/2021, 2:06:38 PMHOOBSSonosSonos Roam: set Firmware Revision to "13.1.4" 7/9/2021, 2:06:38 PMHOOBSSonosSonos Roam TV: set Active to 0 7/9/2021, 2:06:38 PMHOOBSSonosSonos Roam TV: set Configured Name to "Sonos Roam Sonos" 7/9/2021, 2:06:38 PMHOOBSSonosStudio Port Sonos: remove stale characteristic Volume 7/9/2021, 2:06:38 PMHOOBSSonosStudio Port: set Serial Number to "TV_48A6B820B88001400" 7/9/2021, 2:06:38 PMHOOBSSonosStudio Port: set Manufacturer to "Sonos" 7/9/2021, 2:06:38 PMHOOBSSonosStudio Port: set Model to "Port (S23)" 7/9/2021, 2:06:38 PMHOOBSSonosStudio Port: set Firmware Revision to "13.1.4" 7/9/2021, 2:06:38 PMHOOBSSonosStudio Port TV: set Active to 0 7/9/2021, 2:06:38 PMHOOBSSonosStudio Port TV: set Configured Name to "Studio Port Sonos" 7/9/2021, 2:06:39 PMHOOBSSonosLiving Room Speakers Sonos: get Remaining Duration: return 0s 7/9/2021, 2:06:39 PMHOOBSSonosStudio Port Sonos: get Remaining Duration: return 0s 7/9/2021, 2:06:39 PMHOOBSSonosBathroom 2 Sonos: get Remaining Duration: return 0s 7/9/2021, 2:06:39 PMHOOBSSonosBasement Sonos: get Remaining Duration: return 0s 7/9/2021, 2:06:39 PMHOOBSSonosDining Room Sonos: get Remaining Duration: return 0s 7/9/2021, 2:06:39 PMHOOBSSonosGarage (Gym) Sonos: get Remaining Duration: return 0s 7/9/2021, 2:06:39 PMHOOBSSonosGarage (Workbench) Sonos: get Remaining Duration: return 0s 7/9/2021, 2:06:39 PMHOOBSSonosGuest Room Sonos: get Remaining Duration: return 0s 7/9/2021, 2:06:39 PMHOOBSSonosHallway 0.5 Sonos: get Remaining Duration: return 0s 7/9/2021, 2:06:39 PMHOOBSSonosKitchen Speakers Sonos: get Remaining Duration: return 0s 7/9/2021, 2:06:39 PMHOOBSSonosMaster Bathroom Sonos: get Remaining Duration: return 0s 7/9/2021, 2:06:39 PMHOOBSSonosOffice Sonos: get Remaining Duration: return 0s 7/9/2021, 2:06:39 PMHOOBSSonosPlayroom Sonos: get Remaining Duration: return 0s 7/9/2021, 2:06:39 PMHOOBSSonosBachelor TV Sonos: get Remaining Duration: return 0s 7/9/2021, 2:06:39 PMHOOBSSonosBachelor Sonos: get Remaining Duration: return 0s 7/9/2021, 2:06:39 PMHOOBSSonosLiving Room TV Sonos: get Remaining Duration: return 0s 7/9/2021, 2:06:39 PMHOOBSSonosManu’s Room Speakers Sonos: get Remaining Duration: return 0s 7/9/2021, 2:06:39 PMHOOBSSonosMaster Bedroom Sonos: get Remaining Duration: return 0s 7/9/2021, 2:06:39 PMHOOBSSonosSonos Move Sonos: get Remaining Duration: return 0s 7/9/2021, 2:06:39 PMHOOBSSonosSonos Roam Sonos: get Remaining Duration: return 0s 7/9/2021, 2:06:39 PMHOOBSBachelor TV 63C3 is running on port 33481. 7/9/2021, 2:06:39 PMHOOBSBachelor L D5A9 is running on port 43067. 7/9/2021, 2:06:39 PMHOOBSBasement 1630 is running on port 34613. 7/9/2021, 2:06:39 PMHOOBSBathroom 2 A15F is running on port 46203. 7/9/2021, 2:06:39 PMHOOBSDining Room 2DFE is running on port 46825. 7/9/2021, 2:06:39 PMHOOBSGarage (Gym) L 25F7 is running on port 37493. 7/9/2021, 2:06:39 PMHOOBSGarage (Workbench) 9B9B is running on port 40793. 7/9/2021, 2:06:39 PMHOOBSGuest Room 412F is running on port 39581. 7/9/2021, 2:06:39 PMHOOBSHallway 0.5 BDBB is running on port 34821. 7/9/2021, 2:06:39 PMHOOBSKitchen Speakers L 6514 is running on port 39083. 7/9/2021, 2:06:40 PMHOOBSLiving Room Speakers L 1C30 is running on port 34811. 7/9/2021, 2:06:40 PMHOOBSLiving Room TV 39B8 is running on port 44045. 7/9/2021, 2:06:40 PMHOOBSManu’s Room Speakers 37F0 is running on port 41515. 7/9/2021, 2:06:40 PMHOOBSMaster Bathroom 2574 is running on port 37797. 7/9/2021, 2:06:40 PMHOOBSMaster Bedroom L A39A is running on port 35771. 7/9/2021, 2:06:40 PMHOOBSOffice 2D75 is running on port 40903. 7/9/2021, 2:06:40 PMHOOBSPlayroom D00D is running on port 39463. 7/9/2021, 2:06:40 PMHOOBSSonos Move 541B is running on port 40305. 7/9/2021, 2:06:40 PMHOOBSSonos Roam 1736 is running on port 35157. 7/9/2021, 2:06:40 PMHOOBSStudio Port F59C is running on port 38685. 7/9/2021, 2:06:40 PMHOOBSSonosWARNINGwarning: heartbeat 11, drift 7162 7/9/2021, 2:06:41 PMHOOBSSonosWARNINGwarning: heartbeat 12, drift 6946 7/9/2021, 2:06:41 PMHOOBSSonosWARNINGwarning: heartbeat 13, drift 6252 7/9/2021, 2:06:41 PMHOOBSSonosWARNINGwarning: heartbeat 14, drift 5491 7/9/2021, 2:06:41 PMHOOBSSonosWARNINGwarning: heartbeat 15, drift 4496 7/9/2021, 2:06:41 PMHOOBSSonosWARNINGBachelor TV: warning: request 5: http status 412 Precondition Failed 7/9/2021, 2:06:41 PMHOOBSSonosERRORBachelor TV: error: http status 412 Precondition Failed 7/9/2021, 2:06:41 PMHOOBSSonosWARNINGBachelor L: warning: request 5: http status 412 Precondition Failed 7/9/2021, 2:06:41 PMHOOBSSonosERRORBachelor L: error: http status 412 Precondition Failed 7/9/2021, 2:06:41 PMHOOBSSonosWARNINGBasement: warning: request 5: http status 412 Precondition Failed 7/9/2021, 2:06:41 PMHOOBSSonosERRORBasement: error: http status 412 Precondition Failed 7/9/2021, 2:06:41 PMHOOBSSonosWARNINGBathroom 2: warning: request 5: http status 412 Precondition Failed 7/9/2021, 2:06:41 PMHOOBSSonosERRORBathroom 2: error: http status 412 Precondition Failed 7/9/2021, 2:06:41 PMHOOBSSonosWARNINGDining Room: warning: request 5: http status 412 Precondition Failed 7/9/2021, 2:06:41 PMHOOBSSonosERRORDining Room: error: http status 412 Precondition Failed 7/9/2021, 2:06:41 PMHOOBSSonosWARNINGGarage (Gym) L: warning: request 5: http status 412 Precondition Failed 7/9/2021, 2:06:41 PMHOOBSSonosERRORGarage (Gym) L: error: http status 412 Precondition Failed 7/9/2021, 2:06:41 PMHOOBSSonosWARNINGGarage (Workbench): warning: request 5: http status 412 Precondition Failed 7/9/2021, 2:06:41 PMHOOBSSonosERRORGarage (Workbench): error: http status 412 Precondition Failed 7/9/2021, 2:06:41 PMHOOBSSonosWARNINGGuest Room: warning: request 5: http status 412 Precondition Failed 7/9/2021, 2:06:41 PMHOOBSSonosERRORGuest Room: error: http status 412 Precondition Failed 7/9/2021, 2:06:41 PMHOOBSSonosWARNINGHallway 0.5: warning: request 5: http status 412 Precondition Failed 7/9/2021, 2:06:41 PMHOOBSSonosERRORHallway 0.5: error: http status 412 Precondition Failed 7/9/2021, 2:06:41 PMHOOBSSonosWARNINGKitchen Speakers L: warning: request 5: http status 412 Precondition Failed 7/9/2021, 2:06:41 PMHOOBSSonosERRORKitchen Speakers L: error: http status 412 Precondition Failed 7/9/2021, 2:06:41 PMHOOBSSonosWARNINGLiving Room Speakers L: warning: request 5: http status 412 Precondition Failed 7/9/2021, 2:06:41 PMHOOBSSonosERRORLiving Room Speakers L: error: http status 412 Precondition Failed 7/9/2021, 2:06:41 PMHOOBSSonosWARNINGLiving Room TV: warning: request 5: http status 412 Precondition Failed 7/9/2021, 2:06:41 PMHOOBSSonosERRORLiving Room TV: error: http status 412 Precondition Failed 7/9/2021, 2:06:41 PMHOOBSSonosWARNINGManu’s Room Speakers: warning: request 6: http status 412 Precondition Failed 7/9/2021, 2:06:41 PMHOOBSSonosERRORManu’s Room Speakers: error: http status 412 Precondition Failed 7/9/2021, 2:06:41 PMHOOBSSonosWARNINGMaster Bathroom: warning: request 5: http status 412 Precondition Failed 7/9/2021, 2:06:41 PMHOOBSSonosERRORMaster Bathroom: error: http status 412 Precondition Failed 7/9/2021, 2:06:41 PMHOOBSSonosWARNINGMaster Bedroom L: warning: request 5: http status 412 Precondition Failed 7/9/2021, 2:06:41 PMHOOBSSonosERRORMaster Bedroom L: error: http status 412 Precondition Failed 7/9/2021, 2:06:41 PMHOOBSSonosWARNINGOffice: warning: request 5: http status 412 Precondition Failed 7/9/2021, 2:06:41 PMHOOBSSonosERROROffice: error: http status 412 Precondition Failed 7/9/2021, 2:06:41 PMHOOBSSonosWARNINGPlayroom: warning: request 5: http status 412 Precondition Failed 7/9/2021, 2:06:41 PMHOOBSSonosERRORPlayroom: error: http status 412 Precondition Failed 7/9/2021, 2:06:41 PMHOOBSSonosWARNINGSonos Move: warning: request 5: http status 412 Precondition Failed 7/9/2021, 2:06:41 PMHOOBSSonosERRORSonos Move: error: http status 412 Precondition Failed 7/9/2021, 2:06:41 PMHOOBSSonosWARNINGSonos Roam: warning: request 5: http status 412 Precondition Failed 7/9/2021, 2:06:41 PMHOOBSSonosERRORSonos Roam: error: http status 412 Precondition Failed 7/9/2021, 2:06:41 PMHOOBSSonosWARNINGStudio Port: warning: request 5: http status 412 Precondition Failed 7/9/2021, 2:06:41 PMHOOBSSonosERRORStudio Port: error: http status 412 Precondition Failed 7/9/2021, 2:06:42 PMHOOBSSonosWARNINGwarning: heartbeat 16, drift 4056 7/9/2021, 2:06:42 PMHOOBSSonosWARNINGwarning: heartbeat 17, drift 3534 7/9/2021, 2:06:42 PMHOOBSSonosWARNINGwarning: heartbeat 18, drift 2617 7/9/2021, 2:06:43 PMHOOBSSonosWARNINGwarning: heartbeat 19, drift 1856 7/9/2021, 2:06:43 PMHOOBSSonosWARNINGSonos Roam: warning: request 6: http status 412 Precondition Failed 7/9/2021, 2:06:43 PMHOOBSSonosERRORSonos Roam: error: http status 412 Precondition Failed 7/9/2021, 2:06:43 PMHOOBSSonosWARNINGSonos Move: warning: request 6: http status 412 Precondition Failed 7/9/2021, 2:06:43 PMHOOBSSonosERRORSonos Move: error: http status 412 Precondition Failed 7/9/2021, 2:06:43 PMHOOBSSonosWARNINGwarning: heartbeat 20, drift 1141 7/9/2021, 2:06:43 PMHOOBSSonosWARNINGwarning: heartbeat 21, drift 299 7/9/2021, 2:06:47 PMHOOBSSonosManu’s Room Speakers TV: set Active Identifier to "n/a" 7/9/2021, 2:06:52 PMHOOBSSonosWARNINGwarning: heartbeat 29, drift 807 7/9/2021, 2:06:57 PMHOOBSSonosBachelor Sonos: On changed from false to true 7/9/2021, 2:06:58 PMHOOBSSonosBachelor TV: set Active from 0 to 1 7/9/2021, 2:06:58 PMHOOBSSonosBachelor Sonos: On changed from true to true 7/9/2021, 2:06:58 PMHOOBSSonosWARNINGwarning: heartbeat 36, drift 342 7/9/2021, 2:07:02 PMHOOBSSonosLiving Room Speakers Sonos: get Remaining Duration: return 0s 7/9/2021, 2:07:02 PMHOOBSSonosStudio Port Sonos: get Remaining Duration: return 0s 7/9/2021, 2:07:02 PMHOOBSSonosBathroom 2 Sonos: get Remaining Duration: return 0s 7/9/2021, 2:07:02 PMHOOBSSonosBasement Sonos: get Remaining Duration: return 0s 7/9/2021, 2:07:02 PMHOOBSSonosDining Room Sonos: get Remaining Duration: return 0s 7/9/2021, 2:07:02 PMHOOBSSonosGarage (Gym) Sonos: get Remaining Duration: return 0s 7/9/2021, 2:07:02 PMHOOBSSonosGarage (Workbench) Sonos: get Remaining Duration: return 0s 7/9/2021, 2:07:02 PMHOOBSSonosGuest Room Sonos: get Remaining Duration: return 0s 7/9/2021, 2:07:02 PMHOOBSSonosHallway 0.5 Sonos: get Remaining Duration: return 0s 7/9/2021, 2:07:02 PMHOOBSSonosKitchen Speakers Sonos: get Remaining Duration: return 0s 7/9/2021, 2:07:02 PMHOOBSSonosMaster Bathroom Sonos: get Remaining Duration: return 0s 7/9/2021, 2:07:02 PMHOOBSSonosOffice Sonos: get Remaining Duration: return 0s 7/9/2021, 2:07:02 PMHOOBSSonosPlayroom Sonos: get Remaining Duration: return 0s 7/9/2021, 2:07:02 PMHOOBSSonosBachelor TV Sonos: get Remaining Duration: return 0s 7/9/2021, 2:07:02 PMHOOBSSonosBachelor Sonos: get Remaining Duration: return 0s 7/9/2021, 2:07:02 PMHOOBSSonosLiving Room TV Sonos: get Remaining Duration: return 0s 7/9/2021, 2:07:02 PMHOOBSSonosManu’s Room Speakers Sonos: get Remaining Duration: return 0s 7/9/2021, 2:07:02 PMHOOBSSonosMaster Bedroom Sonos: get Remaining Duration: return 0s 7/9/2021, 2:07:02 PMHOOBSSonosSonos Move Sonos: get Remaining Duration: return 0s 7/9/2021, 2:07:02 PMHOOBSSonosSonos Roam Sonos: get Remaining Duration: return 0s 7/9/2021, 2:07:13 PMHOOBSSonosBachelor Sonos: On changed from true to false 7/9/2021, 2:07:13 PMHOOBSSonosBachelor TV: set Active from 1 to 0 7/9/2021, 2:07:13 PMHOOBSSonosBachelor Sonos: On changed from false to false

Debug Files

ebaauw commented 3 years ago

Please, next time, capture a log file and attach that, instead of pasting a lot of lines.

Damn, this is not logging the request that causes the precondition failed, but I suspect it's a SUBSCRIBE.

I need to see the log entries from when Homebridge starts. In particular, the line showing the Homebridge ZP version, and the line showing the port of the web server. Could you check what subscriptions that web server is reporting, see https://github.com/ebaauw/homebridge-zp#web-server?

It doesn't look like you're running the latest Homebridge ZP version? The issue looks suspiciously like https://github.com/ebaauw/homebridge-zp/issues/179.

dablais commented 3 years ago

Hi Eric,

You`re probably right, the listening IP does not make sense. log (3).txt

Sonoslistening on http://0.0.0.0:34037/notify

But if I look at my bridge address with the same port they are all there....

Listening to 34 clients

ZonePlayer | IP Address | Local IP Address | Subscriptions Bachelor (L) | 10.0.0.173 | 10.0.0.209 |   Bachelor (R) | 10.0.0.174 | 10.0.0.209 |   Bachelor TV | 10.0.0.172 | 10.0.0.209 |   Bachelor TV (LS) | 10.0.0.170 | 10.0.0.209 |   Bachelor TV (RS) | 10.0.0.171 | 10.0.0.209 |   Bathroom 2 | 10.0.0.186 | 10.0.0.209 |   Boost | 10.0.0.180 | 10.0.0.209 |   Dining Room | 10.0.0.176 | 10.0.0.209 |   Garage (Gym) (L) | 10.0.0.157 | 10.0.0.209 |   Garage (Gym) (R) | 10.0.0.158 | 10.0.0.209 |   Garage (Workbench) | 10.0.0.195 | 10.0.0.209 |   Guest Room | 10.0.0.184 | 10.0.0.209 |   Hallway 0.5 | 10.0.0.185 | 10.0.0.209 |   Hallway 2 | 10.0.0.187 | 10.0.0.209 |   Kitchen Speakers (L) | 10.0.0.178 | 10.0.0.209 |   Kitchen Speakers (R) | 10.0.0.177 | 10.0.0.209 |   Living Room Speakers (L) | 10.0.0.197 | 10.0.0.209 |   Living Room Speakers (R) | 10.0.0.198 | 10.0.0.209 |   Living Room TV | 10.0.0.199 | 10.0.0.209 |   Living Room TV (LS) | 10.0.0.190 | 10.0.0.209 |   Living Room TV (RS) | 10.0.0.191 | 10.0.0.209 |   Living Room TV (Sub) | 10.0.0.192 | 10.0.0.209 |   Manu’s Room Speakers | 10.0.0.179 | 10.0.0.209 |   Manu’s Room Speakers (LS) | 10.0.0.182 | 10.0.0.209 |   Manu’s Room Speakers (RS) | 10.0.0.181 | 10.0.0.209 |   Master Bathroom | 10.0.0.189 | 10.0.0.209 |   Master Bedroom (L) | 10.0.0.188 | 10.0.0.209 |   Master Bedroom (R) | 10.0.0.183 | 10.0.0.209 |   Office | 10.0.0.196 | 10.0.0.209 |   Playroom | 10.0.0.193 | 10.0.0.209 |   Sonos Move | 10.0.0.169 | 10.0.0.209 | /ZoneGroupTopology Sonos Roam | 10.0.0.159 | 10.0.0.209 |   Studio Port | 10.0.0.194 | 10.0.0.209

Danny

ebaauw commented 3 years ago

That actually does make sense: it simply means the web server is listening on all interfaces.

You don't seem to have Homebridge debug mode enabled. I need to see the debug messages by Homebridge ZP. The Internal Server Error messages are in response the second request to each zone player. The first request, a GET of device_description.xml has succeeded, so the zone players are reachable altrigh. The second request is a SOAP call to get the ZoneGroupTopology state. This call is known to fail when there's "too many" players in your household. See https://github.com/ebaauw/homebridge-zp/issues/177. Homebridge ZP should mitigate this by subscribing to ZoneGroupTopology events, but apparently none are received.

Can you please check that zp -H 10.0.0.188 -D info returns the info of the zone player? You might also use the IP address of another zone player. You should see debug messages similar to:

$ zp -D info
zp: sn-living: request 1: GET /xml/device_description.xml
zp: sn-living: request 1: 200 OK
zp: sn-living: request 2: POST /ZoneGroupTopology/Control GetZoneGroupStatex
zp: sn-living: request 2: POST /ZoneGroupTopology/Control GetZoneGroupStatex
zp: warning: sn-living: request 2: http status 500 Internal Server Error
zp: listening on http://0.0.0.0:53964/notify
zp: sn-living: request 3: SUBSCRIBE /ZoneGroupTopology/Event
zp: sn-living: request 3: 200 OK
zp: undefined: notify ZoneGroupTopology/Event
zp: sn-living: request 4: UNSUBSCRIBE /ZoneGroupTopology/Event
zp: sn-living: request 4: 200 OK
zp: sn-living: reached using local address 192.x.x.40
{
  "address": "192.x.x.71",
  "bootSeq": 174,
  "channel": "",
  "homeTheatre": true,
  "id": "RINCON_5CAAFD1FA0D701400",
  "lastSeen": 0,
  "modelName": "Sonos Playbar",
  "modelNumber": "S9",
  "name": "Living Room",
  "role": "master",
  "satellites": [
    "RINCON_5CAAFD7C941401400",
    "RINCON_5CAAFD7C940401400",
    "RINCON_949F3EAECB4801400"
  ],
  "sonosOs": "S2",
  "tvIn": true,
  "version": "13.1.4",
  "zone": "RINCON_5CAAFD1FA0D701400",
  "zoneDisplayName": "Living Room (+LS+RS+Sub)",
  "zoneName": "Living Room"
}
zp info: closed http://0.0.0.0:53964/notify

Note that I've deliberately broken the GetZoneGroupState, to simulate your situation.

If you don't see any output after the request 3: 200 OK, please capture the output of zp -H 10.0.0.188 -DDD info to a file, and attach that file. This ludicrous amount of logging should tell me what's going on.

ebaauw commented 3 years ago

But if I look at my bridge address with the same port they are all there....

Missed that edit, sorry. So the other zone players have registered with the listener, but not yet subscribed to any events. The Sonos Move has subscribed to ZoneGroupTopology events, and Homebridge ZP seems to be waiting indefinitely for a notification. My money's on a firewall blocking the incoming notifications. That, or this is why Sonos only supports 32 zone players in a topology. Does the Sonos app work as expected, listing 34 "rooms"?

dablais commented 3 years ago

Hi Eric,

I will get the Debug log. For your information as soon as I reboot a Sonos Speaker it does register in Homekit (but still the on/off is not reflected in Sonos or vice-versa). There is no firewall between the Speakers and Hoobs Server. Everything is in the same subnet.

Thanks,

Danny

ebaauw commented 3 years ago

Thanks. If you have access to the command line, it may be easier to experiment with zp.

dablais commented 3 years ago

Hi Eric, yes I do have access to the command line. I ssh to it but sorry maybe I missed something but I can run the zp command, maybe I`m not in the right mode or something.

ebaauw commented 3 years ago

Normally, npm installs zp to /usr/bin or /usr/local/bin (depending on how NodeJS was installed). If it cannot be found, try npx zp, instead of zp.

dablais commented 3 years ago

Unfortunately, it still does not work...

hoobs@hoobs:/usr/bin $ zp -bash: zp: command not found hoobs@hoobs:/usr/bin $ npx zp npx: installed 2 in 4.755s command not found: zp hoobs@hoobs:/usr/bin $ cd /usr/local/bin hoobs@hoobs:/usr/local/bin $ zp -bash: zp: command not found hoobs@hoobs:/usr/local/bin $ npx zp npx: installed 2 in 4.745s command not found: zp

:(

ebaauw commented 3 years ago

You might try and search for it. It's .../node_modules/homebridge-zp/cli/zp.js, but I don't know how Hoobs installs plugins. Normally npm creates a symlink to this file in /usr/bin or /usr/local/bin.

Normally, the current directory isn't in your PATH, so doing a cd /usr/bin won't make a difference, unless you try ./zp.

dablais commented 3 years ago

Unfortunately, I`m still having issue running the pluggin in CLI.... but I received this in the logging now....

7/14/2021, 7:43:18 PMHOOBSERROR(node:16295) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 error listeners added to [ZpClient]. Use emitter.setMaxListeners() to increase limit 7/14/2021, 7:43:18 PMHOOBSERROR(Use node --trace-warnings ... to show where the warning was created) 7/14/2021, 7:48:17 PMHOOBSERROR(node:16295) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 init listeners added to [ZpClient]. Use emitter.setMaxListeners() to increase limit

Thanks,

Danny

dablais commented 3 years ago

Logs attached from zp.js.

zpdebug169.log zpdebug188.log

dablais commented 3 years ago

Noticed you published 1.3.4 so I upgraded the plugin. Now I get "412 Precondition failed" error responses. See attached log. zp-postupdate-412.log

ebaauw commented 3 years ago

Logs attached from zp.js.

Brilliant, thanks!

In your first log from the Move at 10.0.0.169 log, the GetZoneGroupState actually succeeds, but reports "only" 19 zone players in the installation. In your second log from the Play:1 at 10.0.0.188, the GetZoneGroupState fails, but the fallback succeeds, reporting 34 zone players, including the Move from the other log. Did you capture these logs shortly after each other? That would mean the topology hasn't been synced between the zone players, which I haven't seen before.

Noticed you published 1.3.4 so I upgraded the plugin. Now I get "412 Precondition failed" error responses.

That's to be expected; on larger installations the call to GetZoneGroupState fails, see #131 and #177. The update improved the error logging; it didn't introduce the error. As a fallback, Homebridge ZP subscribes to events from the ZoneGroupTopology service to receive the layout of the installation. Afaik, these two methods are the only way to know whether a zone player should be exposed, or is part of a stereo pair or home theatre setup and should be hidden.

The issue you're facing is that the fallback fails as well. I'm trying to understand why, in particular if it's something going wrong with Homebridge ZP, or if it's simply because your installation is too large.

The post update log looks kinda good, in that Homebridge ZP finds some zone players, but not all. It looks like Homebridge ZP has found 33 zone players through UPnP, but only exposes half of them. I really need to see the full Homebridge log, from the start of Homebridge through it running for a couple of minutes, while Homebridge DEBUG mode is enabled.

I see some attention points:

 7/16/2021, 1:53:31 PMHOOBSSonosRINCON_48A6B8E6C49601400 [Sonos Move]: associated S2 zone player

Homebridge ZP picks a Move as associated zone player (it typically pick the first to respond). That's a bad idea, as the Move (and the Roam) are battery powered, and mobile, so they're likely to leave the network. Homebridge ZP should probably see if it can find another zone player.

7/16/2021, 1:53:51 PMHOOBSSonosWARNINGwarning: RINCON_5CAAFD9439F001400 [10.0.0.187]: no ZoneGroupTopology event received in 15s

The fallback fails for the Hallway 2 zone player, after which Homebridge ZP exposed the confirmed zone players. So for these, the fallback as succeeded. That would mean including the SUBSCRIBE to ZoneGroupTopology. However, the subsequent SUBSCRIBE to GroupManagement fails for all 13 zones.

Since, you've found a way to run zp, could you try zp -H 10.0.0.188 -D topology and zp -H 10.0.0.188 -D eventlog? I half expect/hope that both will succeed. Could you then try the same for .188 and for .187? If they fail, could you please capture the debug output when specifying -DDD?

dablais commented 3 years ago

Hi Eric,

There are the file you requested.

zp -H 10.0.0.187 -D eventlog.log zp -H 10.0.0.187 -D topology.log zp -H 10.0.0.187 -DDD topology.log zp -H 10.0.0.188 -D eventlog.log zp -H 10.0.0.188 -D topology.log zp -H 10.0.0.188 -DDD topology.log

Thanks a lot for your help,

Danny

ebaauw commented 3 years ago

Ok, I think I understand what's happening. Your zone players are running out of resources, and don't accept additional subscriptions. Each zone player only supports a limited number of subscriptions (I think per service), see #68. Zone players subscribe to each other's events to maintain their mesh network, notably DeviceProperties and GroupManagement, maybe also ZoneGroupTopology. Hence Homebridge ZP's attempts to subscribe to these services fail. I think the latest release of Homebridge ZP is more forgiving on startup, but that doesn't make up for the missing info because of the failed subscriptions.

There used to be a diagnostics page on the zone player (somewhere under http://10.0.0.187:1400/status), where you could monitor what subscriptions had been made on it (that's how I know the zone player subscribe with each other). Unfortunately, they disabled that. I know of no other way to verify this. See also https://bsteiner.info/articles/hidden-sonos-interface.

Do you use the Sonos app? I think you might see weird behaviour there as well. If you're up to it, you might sniff the network traffic between the Sonos app and the zone players (it's unencrypted); I'm fairly certain it'll show similar errors.

I'm afraid there's little I can do; this is a limit in the zone player firmware, maybe even in the hardware. I wouldn't be surprised if the limit on older zone players is lower than on newer zone players. The limit of 32 zone players per installation (household) is very real, I'm afraid. Granted, Homebridge ZP is a little more demanding than the Sonos app when it comes to the number subscriptions used. I might be able to optimise that, although that will be a non-trivial change. I don't think I can fully work around it, though.

Even then, the zone players themselves fail to maintain the mesh network properly. You might check http://10.0.0.187:1400/support/review. That should give an overview of all zone players this zone player knows. Each player should know all others, but from your earlier logs, they don't.

You might also try a zp -H 10.0.0.188 -D topology -v. This will try and get the topology from each zone player known to 10.0.0.188. With -DD it will log the responses converted to JSON, but it's still a bit tedious to analyse these.

dablais commented 3 years ago

Thanks Eric! I will see if I can go down to 32. I might remove my bridge and replace to Play1 with a SONOS Port to stay at 32 and see if this is solving the issue.

ebaauw commented 3 years ago

Beta v1.4.0-1 should be a bit more forgiving on startup, and should replace the associated zone player when a battery-powered one had been selected earlier.

ebaauw commented 3 years ago

In a bit of an experimental mood, I ran 33 instances of zp eventlog in parallel, stress testing the zone player subscription mechanism.

When running against my old Sonos Connect, the only zone player in my S1 household, the subscription for each service fails at the 33rd instance.

When running against my PlayBar, in my S2 household with 11 zone players in 6 zones, some subscriptions fail earlier:

Service Fails at
Instance		 Subscriptions
# zp + # HbZP + Rest
/AlarmClock 33 32 + 0 + 0
/DeviceProperties 23 22 + 0 + 10 zone players
/ZoneGroupTopology 33 32 + 0 + 0
/GroupManagement 22 21 + 1 + 10 zone players
/HTControl 33 32 + 0 + 0
/MediaServer/ContentDirectory 30 29 + 0 + 3 ??
/MediaRenderer/RenderingControl 31 30 + 1 + 1 ??
/MediaRenderer/AVTransport 31 30 + 1 + 1 ??
/MediaRenderer/GroupRenderingControl 32 31 + 1 + 0
/MediaRenderer/VirtualLineIn 31 30 + 0 + 2 ??

I think the following conclusions are valid:

dablais commented 3 years ago

Great test Eric! That might explain a lot. From what I`m understanding now, I might not have the choice to go under 32 devices. I will have to figure out a way to remove 2 speakers....

Thanks for your great work!

Danny

ebaauw commented 3 years ago

I’m working on ditching the use of GroupManagement by Homebridge ZP. My understanding is that a network of up to 33 zone players would then work. The v1.4.0-x betas already pick a non-battery-powered zone player as associated zone player, and support multiple households. So you don’t have to remove the two players, you just need to configure them in a different household. Of course, you cannot sync music across multiple households, but you should be able to control all households using a single Homebridge ZP instance. I would probably move more than two players to the new household, just to keep some slack.

dablais commented 2 years ago

Thanks Eric! Very curious to try version 1.4.0. :)

ebaauw commented 2 years ago

Could you please try beta v1.4.0-3?

dablais commented 2 years ago

Sure, is it available now ?

On Sat, Jul 24, 2021 at 10:12 AM Erik Baauw @.***> wrote:

Could you please try beta v1.4.0-3?

— You are receiving this because you authored the thread. Reply to this email directly, view it on GitHub https://github.com/ebaauw/homebridge-zp/issues/180#issuecomment-886059331, or unsubscribe https://github.com/notifications/unsubscribe-auth/AQEWERJMDZM45TZZKTL623TTZLC4LANCNFSM5ADHGSNQ .

ebaauw commented 2 years ago

Yes, just published it.

ebaauw commented 2 years ago

Should be working in v1.4.0.

dablais commented 2 years ago

Hi Eric!

I can confirm that it’s working now! :)

Danny

On Sat, Jul 31, 2021 at 09:08 Erik Baauw @.***> wrote:

Should be working in v1.4.0.

— You are receiving this because you authored the thread. Reply to this email directly, view it on GitHub https://github.com/ebaauw/homebridge-zp/issues/180#issuecomment-890346166, or unsubscribe https://github.com/notifications/unsubscribe-auth/AQEWERN4FAMQU7XCZ72PG5DT2PYTVANCNFSM5ADHGSNQ .

dablais commented 2 years ago

A huge thank you man!

On Sat, Jul 31, 2021 at 09:08 Erik Baauw @.***> wrote:

Should be working in v1.4.0.

— You are receiving this because you authored the thread. Reply to this email directly, view it on GitHub https://github.com/ebaauw/homebridge-zp/issues/180#issuecomment-890346166, or unsubscribe https://github.com/notifications/unsubscribe-auth/AQEWERN4FAMQU7XCZ72PG5DT2PYTVANCNFSM5ADHGSNQ .

ebaauw commented 2 years ago

How many zone players in a single household?

dablais commented 2 years ago

33 speakers and I try back the bridge. So 34 total. All working well :)

On Sat, Jul 31, 2021 at 15:28 Erik Baauw @.***> wrote:

How many zone players in a single household?

— You are receiving this because you authored the thread. Reply to this email directly, view it on GitHub https://github.com/ebaauw/homebridge-zp/issues/180#issuecomment-890394404, or unsubscribe https://github.com/notifications/unsubscribe-auth/AQEWERKTVBLBVRNBEU6APO3T2RFFZANCNFSM5ADHGSNQ .