06 12/29/18 22:20:37.488 Device_Variable::m_szValue_set device: 40 service: urn:futzle-com:serviceId:CurrentCostEnviR1 variable: Time was: 16:04:10 now: 16:04:11 #hooks: 0 upnp: 0 skip: 0 v:0x119d4a0/NONE duplicate:0 <0x70bcd520> 06 12/29/18 22:20:37.509 Device_Variable::m_szValue_set device: 41 service: urn:micasaverde-com:serviceId:EnergyMetering1 variable: Watts was: 2591 now: 2642 #hooks: 1 upnp: 0 skip: 0 v:0x1199238/NONE duplicate:0 <0x70bcd520> 08 12/29/18 22:20:37.512 JobHandler_LuaUPnP::HandleActionRequest device: 491 service: urn:upnp-org:serviceId:altsonos1 action: AudioClip <0x70dcd520> 08 12/29/18 22:20:37.513 JobHandler_LuaUPnP::HandleActionRequest argument DeviceNum=491 <0x70dcd520> 08 12/29/18 22:20:37.513 JobHandler_LuaUPnP::HandleActionRequest argument serviceId=urn:upnp-org:serviceId:altsonos1 <0x70dcd520> 08 12/29/18 22:20:37.513 JobHandler_LuaUPnP::HandleActionRequest argument action=AudioClip <0x70dcd520> 08 12/29/18 22:20:37.513 JobHandler_LuaUPnP::HandleActionRequest argument groupID_playerID=RINCON_5CAAFDF5D49C01400 <0x70dcd520> 08 12/29/18 22:20:37.514 JobHandler_LuaUPnP::HandleActionRequest argument urlClip=http://192.168.1.27:8085/Poubelle recyclable.mp3 <0x70dcd520> 08 12/29/18 22:20:37.514 JobHandler_LuaUPnP::HandleActionRequest argument Volume=25 <0x70dcd520> 50 12/29/18 22:20:37.517 luup_log:491: ALTSonos: debug: loadStreamUrl(491,RINCON_5CAAFDF5D49C01400,http://192.168.1.27:8085/Poubelle recyclable.mp3,,25) <0x770b9320> 50 12/29/18 22:20:37.521 luup_log:491: ALTSonos: debug: loadStreamUrlGid(491,RINCON_5CAAFDF5D49C01400,http://192.168.1.27:8085/Poubelle recyclable.mp3,,25) <0x770b9320> 50 12/29/18 22:20:37.522 luup_log:491: ALTSonos: debug: warning -- duration is ignored now. ( corrected duration:7 ) <0x770b9320> 50 12/29/18 22:20:37.528 luup_log:491: ALTSonos: debug: resolveGroup( RINCON_5CAAFDF5D49C01400 ): is a player, the group is RINCON_5CAAFDF5D49C01400:3291522576 <0x770b9320> 50 12/29/18 22:20:37.533 luup_log:491: ALTSonos: debug: corrected groupID:RINCON_5CAAFDF5D49C01400:3291522576 <0x770b9320> 50 12/29/18 22:20:37.533 luup_log:491: ALTSonos: debug: createSession(491,RINCON_5CAAFDF5D49C01400:3291522576) <0x770b9320> 50 12/29/18 22:20:37.535 luup_log:491: ALTSonos: debug: SonosHTTP(491,api.ws.sonos.com/control/api/v1/groups/RINCON_5CAAFDF5D49C01400:3291522576/playbackSession,POST,{ "appId": "com.getvera.amg0.altsonos", "appContext": "altsonos_audioClip" },Bearer ab8f688a-15c0-44ba-8b81-c8ff2683373f,application/json,{ "householdId": "Sonos_wFPVFKCnR9Fyxq0Gwy0xgxTz3Q.-KgfWIJj0GLlXDBN3Nyu", "groupId": "RINCON_5CAAFDF5D49C01400:3291522576" }) <0x770b9320> 50 12/29/18 22:20:37.538 luup_log:491: ALTSonos: debug: request headers:{ "Authorization": "Bearer ab8f688a-15c0-44ba-8b81-c8ff2683373f", "householdId": "Sonos_wFPVFKCnR9Fyxq0Gwy0xgxTz3Q.-KgfWIJj0GLlXDBN3Nyu", "groupId": "RINCON_5CAAFDF5D49C01400:3291522576", "Content-Type": "application/json", "Cache-Control": "no-cache", "Content-Length": 76 } <0x770b9320> 06 12/29/18 22:20:37.572 Device_Variable::m_szValue_set device: 41 service: urn:micasaverde-com:serviceId:EnergyMetering1 variable: Log was: 2591,1228,3126,1546118431,3032 now: 2642,1230,3126,1546118437,3038 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x70bcd520> 06 12/29/18 22:20:37.573 Device_Variable::m_szValue_set device: 41 service: urn:futzle-com:serviceId:CurrentCostEnviR1 variable: Time was: 16:04:04 now: 16:04:11 #hooks: 0 upnp: 0 skip: 0 v:0x119d4a0/NONE duplicate:0 <0x70bcd520> 06 12/29/18 22:20:37.594 Device_Variable::m_szValue_set device: 41 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: CurrentTemperature was: 29.3 now: 29.4 #hooks: 0 upnp: 0 skip: 0 v:0x11dcd10/NONE duplicate:0 <0x70bcd520> 06 12/29/18 22:20:37.594 Device_Variable::m_szValue_set device: 40 service: urn:micasaverde-com:serviceId:EnergyMetering1 variable: Watts was: 2591 now: 2642 #hooks: 0 upnp: 0 skip: 0 v:0x1199238/NONE duplicate:0 <0x70bcd520> 06 12/29/18 22:20:37.595 Device_Variable::m_szValue_set device: 40 service: urn:micasaverde-com:serviceId:EnergyMetering1 variable: Log was: 2591,1226,3126,1546118431,3032 now: 2642,1228,3126,1546118437,3038 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x70bcd520> 06 12/29/18 22:20:37.596 Device_Variable::m_szValue_set device: 40 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: CurrentTemperature was: 29.3 now: 29.4 #hooks: 0 upnp: 0 skip: 0 v:0x11dcd10/NONE duplicate:0 <0x70bcd520> 06 12/29/18 22:20:37.596 Device_Variable::m_szValue_set device: 44 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: CurrentTemperature was: 29.3 now: 29.4 #hooks: 0 upnp: 0 skip: 0 v:0x11dcd10/NONE duplicate:0 <0x70bcd520> 50 12/29/18 22:20:38.269 luup_log:491: ALTSonos: debug: response request:1 <0x770b9320> 50 12/29/18 22:20:38.270 luup_log:491: ALTSonos: debug: code:200 <0x770b9320> 50 12/29/18 22:20:38.272 luup_log:491: ALTSonos: debug: headers:{ "connection": "close", "date": "Sat, 29 Dec 2018 21:20:38 GMT", "pragma": "no-cache", "x-sonos-user-id": "117838608", "cache-control": "max-age=0, no-cache, no-store", "access-control-allow-origin": "", "expires": "Sat, 29 Dec 2018 21:20:38 GMT", "x-sonos-session": "RINCON_5CAAFDF5D49C01400:3291522576@3781054720", "x-sonos-household-id": "Sonos_wFPVFKCnR9Fyxq0Gwy0xgxTz3Q.-KgfWIJj0GLlXDBN3Nyu", "content-length": "125", "content-type": "application/json", "x-sonos-type": "sessionStatus", "x-sonos-group-id": "RINCON_5CAAFDF5D49C01400:3291522576", "x-sonos-mac": "5caafdf5d49c", "server": "Apache-Coyote/1.1" } <0x770b9320> 50 12/29/18 22:20:38.272 luup_log:491: ALTSonos: debug: data:"{\"sessionId\":\"RINCON_5CAAFDF5D49C01400:3291522576@3950131392\",\"sessionState\":\"SESSION_STATE_CONNECTED\",\"sessionCreated\":true}" <0x770b9320> 50 12/29/18 22:20:38.273 luup_log:491: ALTSonos: debug: setVariableIfChanged(urn:upnp-org:serviceId:altsonos1,IconCode,100,491) <0x770b9320> 50 12/29/18 22:20:38.273 luup_log:491: ALTSonos: debug: getVolume(491,RINCON_5CAAFDF5D49C01400:3291522576) <0x770b9320> 50 12/29/18 22:20:38.276 luup_log:491: ALTSonos: debug: corrected groupID:RINCON_5CAAFDF5D49C01400:3291522576 <0x770b9320> 50 12/29/18 22:20:38.277 luup_log:491: ALTSonos: debug: SonosHTTP(491,api.ws.sonos.com/control/api/v1/groups/RINCON_5CAAFDF5D49C01400:3291522576/groupVolume,GET,,Bearer ab8f688a-15c0-44ba-8b81-c8ff2683373f,application/x-www-form-urlencoded,[ ]) <0x770b9320> 50 12/29/18 22:20:38.277 luup_log:491: ALTSonos: debug: request headers:{ "Content-Type": "application/x-www-form-urlencoded", "Authorization": "Bearer ab8f688a-15c0-44ba-8b81-c8ff2683373f", "Cache-Control": "no-cache", "Content-Length": 0 } <0x770b9320> 50 12/29/18 22:20:38.683 luup_log:491: ALTSonos: debug: response request:1 <0x770b9320> 50 12/29/18 22:20:38.683 luup_log:491: ALTSonos: debug: code:200 <0x770b9320> 50 12/29/18 22:20:38.687 luup_log:491: ALTSonos: debug: headers:{ "connection": "close", "date": "Sat, 29 Dec 2018 21:20:38 GMT", "pragma": "no-cache", "x-sonos-user-id": "117838608", "cache-control": "max-age=0, no-cache, no-store", "access-control-allow-origin": "", "expires": "Sat, 29 Dec 2018 21:20:38 GMT", "x-sonos-session": "RINCON_5CAAFDF5D49C01400:3291522576@3950131392", "x-sonos-household-id": "Sonos_wFPVFKCnR9Fyxq0Gwy0xgxTz3Q.-KgfWIJj0GLlXDBN3Nyu", "content-length": "41", "content-type": "application/json", "x-sonos-type": "groupVolume", "x-sonos-group-id": "RINCON_5CAAFDF5D49C01400:3291522576", "x-sonos-mac": "5caafdf5d49c", "server": "Apache-Coyote/1.1" } <0x770b9320> 50 12/29/18 22:20:38.688 luup_log:491: ALTSonos: debug: data:"{\"volume\":20,\"muted\":false,\"fixed\":false}" <0x770b9320> 50 12/29/18 22:20:38.689 luup_log:491: ALTSonos: debug: setVariableIfChanged(urn:upnp-org:serviceId:altsonos1,IconCode,100,491) <0x770b9320> 50 12/29/18 22:20:38.712 luup_log:491: ALTSonos: debug: updated DB { "Sonos_wFPVFKCnR9Fyxq0Gwy0xgxTz3Q.-KgfWIJj0GLlXDBN3Nyu": { "favorites": [ { "favorite": { "seq_id": 0, "description": "De Liam Gallagher", "id": "11", "imageUrl": "https://i.scdn.co/image/7ddb06ff671088a16198d113b2f19bdbcd5e34f4", "service": { "name": "Spotify" }, "name": "Bold" } }, { "favorite": { "seq_id": 0, "description": "De Macklemore", "id": "10", "imageUrl": "https://i.scdn.co/image/3d717a0265dad4f53a25f48826e9d8ec676a5fc8", "service": { "name": "Spotify" }, "name": "Glorious (feat. Skylar Grey)" } }, { "favorite": { "seq_id": 0, "description": "De Kokab", "id": "9", "imageUrl": "https://i.scdn.co/image/6caab0e25e9100a34a283b0ff0895fe81fadbfb5", "service": { "name": "Spotify" }, "name": "Got U (Ready or Not)" } }, { "favorite": { "seq_id": 0, "description": "De Calogero", "id": "7", "imageUrl": "https://i.scdn.co/image/e27bee3c6a1119102d00108942302a6928e393b8", "service": { "name": "Spotify" }, "name": "Je joue de la musique" } }, { "favorite": { "seq_id": 0, "description": "De Hoshi", "id": "6", "imageUrl": "https://i.scdn.co/image/a5331d97356497ccb4a0d3d1b22a6223fb3efa94", "service": { "name": "Spotify" }, "name": "mariniere" } }, { "favorite": { "seq_id": 0, "description": "Liste de lecture Spotify", "id": "15", "imageUrl": "https://i.scdn.co/image/0406391f1afd5a3ec6105cfc4462dc54abcf25e2", "service": { "name": "Spotify" }, "name": "New Music Friday France" } }, { "favorite": { "seq_id": 0, "description": "De Bag Raiders", "id": "12", "imageUrl": "https://i.scdn.co/image/0436452ae40573e12391eb5fe805997395b9f587", "service": { "name": "Spotify" }, "name": "Shooting Stars - Original Mix" } }, { "favorite": { "seq_id": 0, "description": "De Foster The People", "id": "1", "imageUrl": "https://i.scdn.co/image/6af5ce94627ad636d5d41b5206d527a13347dac7", "service": { "name": "Spotify" }, "name": "Sit Next to Me" } }, { "favorite": { "seq_id": 0, "description": "De Metronomy", "id": "3", "imageUrl": "https://i.scdn.co/image/04af8a8c03eb7ac8c0c9fdf8054cce07ce3bfc98", "service": { "name": "Spotify" }, "name": "The Look" } }, { "favorite": { "seq_id": 0, "description": "De Miley Cyrus", "id": "0", "imageUrl": "https://i.scdn.co/image/a1fa7f590996868ebd1248aab3233e793db22fd7", "service": { "name": "Spotify" }, "name": "Thinkin'" } } ], "householdId": [ ], "groupId": { "RINCON_5CAAFDF5D49C01400:3291522576": { "core": { "seq_id": 1546116460920, "name": "Veranda", "coordinatorId": "RINCON_5CAAFDF5D49C01400", "id": "RINCON_5CAAFDF5D49C01400:3291522576", "playerIds": [ "RINCON_5CAAFDF5D49C01400" ] }, "groupVolume": { "seq_id": 1546117277880, "fixed": false, "muted": false, "volume": 64 }, "metadataStatus": { "seq_id": 1546117278286, "container": { "type": "station" } }, "playbackStatus": { "playbackState": "PLAYBACK_STATE_IDLE", "availablePlaybackActions": { "canSeek": false, "canSkip": false, "canShuffle": false, "canStop": true, "canSkipBack": false, "canPause": false, "canCrossfade": false, "canRepeat": false, "canRepeatOne": false }, "seq_id": 1546117283328, "positionMillis": 0, "playModes": { "repeatOne": false, "repeat": false, "shuffle": false, "crossfade": false }, "previousPositionMillis": 4935 }, "altsonos": { "trigger": false, "seq_id": 0, "action": "stopAfterPlay", "params": "{ \"gid\": \"RINCON_5CAAFDF5D49C01400:3291522576\", \"delta\": 10, \"lul_device\": 491 }" } }, "RINCON_949F3E85D3D001400:3235904393": { "metadataStatus": { "seq_id": 1546116476597, "currentItem": { "track": { "album": { "name": "Younger Now" }, "type": "track", "name": "Thinkin'", "durationMillis": 245000, "id": { "serviceId": "9", "objectId": "spotify:track:742YV8T9ZyczCNdrTIAhaw", "accountId": "sn_1" }, "imageUrl": "http://192.168.1.32:1400/getaa?s=1&u=x-sonos-spotify%3aspotify%253atrack%253a742YV8T9ZyczCNdrTIAhaw%3fsid%3d9%26flags%3d8224%26sn%3d1", "artist": { "name": "Miley Cyrus" }, "service": { "name": "Spotify" } } }, "container": { "id": { "serviceId": "9", "objectId": "spotify:track:742YV8T9ZyczCNdrTIAhaw", "accountId": "sn_1" }, "type": "track", "name": "Thinkin'", "service": { "name": "Spotify" } } }, "groupVolume": { "seq_id": 1546116476931, "fixed": false, "muted": false, "volume": 40 }, "playbackStatus": { "playbackState": "PLAYBACK_STATE_IDLE", "availablePlaybackActions": { "canSeek": true, "canSkip": false, "canShuffle": true, "canStop": true, "canSkipBack": false, "canPause": true, "canCrossfade": true, "canRepeat": true, "canRepeatOne": true }, "seq_id": 1546116477278, "positionMillis": 0, "playModes": { "repeatOne": false, "repeat": false, "shuffle": false, "crossfade": false }, "previousPositionMillis": 43974 }, "core": { "seq_id": 1546116460920, "name": "Cuisine", "coordinatorId": "RINCON_949F3E85D3D001400", "id": "RINCON_949F3E85D3D001400:3235904393", "playerIds": [ "RINCON_949F3E85D3D001400" ] } } } } } <0x770b9320> 06 12/29/18 22:20:38.713 Device_Variable::m_szValue_set device: 491 service: urn:upnp-org:serviceId:altsonos1 variable: LastVolume was: 30 now: 20 #hooks: 0 upnp: 0 skip: 0 v:0x131cca8/NONE duplicate:0 <0x770b9320> 50 12/29/18 22:20:38.724 luup_log:491: ALTSonos: debug: setVolumeRelative(491,RINCON_5CAAFDF5D49C01400:3291522576,5) <0x770b9320> 50 12/29/18 22:20:38.727 luup_log:491: ALTSonos: debug: corrected groupID:RINCON_5CAAFDF5D49C01400:3291522576 <0x770b9320> 50 12/29/18 22:20:38.728 luup_log:491: ALTSonos: debug: setDBValue(491,0,Sonos_wFPVFKCnR9Fyxq0Gwy0xgxTz3Q.-KgfWIJj0GLlXDBN3Nyu,groupId,RINCON_5CAAFDF5D49C01400:3291522576,groupVolume) <0x770b9320> 50 12/29/18 22:20:38.728 luup_log:491: ALTSonos: debug: target:RINCON_5CAAFDF5D49C01400:3291522576 type:groupVolume body is { "seq_id": 1546117277880, "fixed": false, "muted": false, "volume": 69 } <0x770b9320> 50 12/29/18 22:20:38.729 luup_log:491: ALTSonos: debug: onDefaultNotification(491,0,Sonos_wFPVFKCnR9Fyxq0Gwy0xgxTz3Q.-KgfWIJj0GLlXDBN3Nyu,groupId,RINCON_5CAAFDF5D49C01400:3291522576,groupVolume,{ "seq_id": 1546117277880, "fixed": false, "muted": false, "volume": 69 }) <0x770b9320> 50 12/29/18 22:20:38.729 luup_log:491: ALTSonos: debug: ignoring out of sequence seq_id 0 , DB contains 1546117277880 <0x770b9320> 50 12/29/18 22:20:38.730 luup_log:491: ALTSonos: debug: SonosHTTP(491,api.ws.sonos.com/control/api/v1/groups/RINCON_5CAAFDF5D49C01400:3291522576/groupVolume/relative,POST,{ "volumeDelta": 5 },Bearer ab8f688a-15c0-44ba-8b81-c8ff2683373f,application/json,[ ]) <0x770b9320> 50 12/29/18 22:20:38.730 luup_log:491: ALTSonos: debug: request headers:{ "Content-Type": "application/json", "Authorization": "Bearer ab8f688a-15c0-44ba-8b81-c8ff2683373f", "Cache-Control": "no-cache", "Content-Length": 20 } <0x770b9320> 50 12/29/18 22:20:39.101 luup_log:491: ALTSonos: debug: response request:1 <0x770b9320> 50 12/29/18 22:20:39.102 luup_log:491: ALTSonos: debug: code:200 <0x770b9320> 50 12/29/18 22:20:39.103 luup_log:491: ALTSonos: debug: headers:{ "connection": "close", "date": "Sat, 29 Dec 2018 21:20:39 GMT", "pragma": "no-cache", "x-sonos-user-id": "117838608", "cache-control": "max-age=0, no-cache, no-store", "access-control-allow-origin": "", "expires": "Sat, 29 Dec 2018 21:20:39 GMT", "x-sonos-session": "RINCON_5CAAFDF5D49C01400:3291522576@3950131392", "x-sonos-household-id": "Sonos_wFPVFKCnR9Fyxq0Gwy0xgxTz3Q.-KgfWIJj0GLlXDBN3Nyu", "content-length": "2", "content-type": "application/json", "x-sonos-type": "none", "x-sonos-group-id": "RINCON_5CAAFDF5D49C01400:3291522576", "x-sonos-mac": "5caafdf5d49c", "server": "Apache-Coyote/1.1" } <0x770b9320> 50 12/29/18 22:20:39.104 luup_log:491: ALTSonos: debug: data:"{}" <0x770b9320> 50 12/29/18 22:20:39.104 luup_log:491: ALTSonos: debug: setVariableIfChanged(urn:upnp-org:serviceId:altsonos1,IconCode,100,491) <0x770b9320> 50 12/29/18 22:20:39.116 luup_log:491: ALTSonos: debug: updated DB { "Sonos_wFPVFKCnR9Fyxq0Gwy0xgxTz3Q.-KgfWIJj0GLlXDBN3Nyu": { "favorites": [ { "favorite": { "seq_id": 0, "description": "De Liam Gallagher", "id": "11", "imageUrl": "https://i.scdn.co/image/7ddb06ff671088a16198d113b2f19bdbcd5e34f4", "service": { "name": "Spotify" }, "name": "Bold" } }, { "favorite": { "seq_id": 0, "description": "De Macklemore", "id": "10", "imageUrl": "https://i.scdn.co/image/3d717a0265dad4f53a25f48826e9d8ec676a5fc8", "service": { "name": "Spotify" }, "name": "Glorious (feat. Skylar Grey)" } }, { "favorite": { "seq_id": 0, "description": "De Kokab", "id": "9", "imageUrl": "https://i.scdn.co/image/6caab0e25e9100a34a283b0ff0895fe81fadbfb5", "service": { "name": "Spotify" }, "name": "Got U (Ready or Not)" } }, { "favorite": { "seq_id": 0, "description": "De Calogero", "id": "7", "imageUrl": "https://i.scdn.co/image/e27bee3c6a1119102d00108942302a6928e393b8", "service": { "name": "Spotify" }, "name": "Je joue de la musique" } }, { "favorite": { "seq_id": 0, "description": "De Hoshi", "id": "6", "imageUrl": "https://i.scdn.co/image/a5331d97356497ccb4a0d3d1b22a6223fb3efa94", "service": { "name": "Spotify" }, "name": "mariniere" } }, { "favorite": { "seq_id": 0, "description": "Liste de lecture Spotify", "id": "15", "imageUrl": "https://i.scdn.co/image/0406391f1afd5a3ec6105cfc4462dc54abcf25e2", "service": { "name": "Spotify" }, "name": "New Music Friday France" } }, { "favorite": { "seq_id": 0, "description": "De Bag Raiders", "id": "12", "imageUrl": "https://i.scdn.co/image/0436452ae40573e12391eb5fe805997395b9f587", "service": { "name": "Spotify" }, "name": "Shooting Stars - Original Mix" } }, { "favorite": { "seq_id": 0, "description": "De Foster The People", "id": "1", "imageUrl": "https://i.scdn.co/image/6af5ce94627ad636d5d41b5206d527a13347dac7", "service": { "name": "Spotify" }, "name": "Sit Next to Me" } }, { "favorite": { "seq_id": 0, "description": "De Metronomy", "id": "3", "imageUrl": "https://i.scdn.co/image/04af8a8c03eb7ac8c0c9fdf8054cce07ce3bfc98", "service": { "name": "Spotify" }, "name": "The Look" } }, { "favorite": { "seq_id": 0, "description": "De Miley Cyrus", "id": "0", "imageUrl": "https://i.scdn.co/image/a1fa7f590996868ebd1248aab3233e793db22fd7", "service": { "name": "Spotify" }, "name": "Thinkin'" } } ], "householdId": [ ], "groupId": { "RINCON_5CAAFDF5D49C01400:3291522576": { "core": { "seq_id": 1546116460920, "name": "Veranda", "coordinatorId": "RINCON_5CAAFDF5D49C01400", "id": "RINCON_5CAAFDF5D49C01400:3291522576", "playerIds": [ "RINCON_5CAAFDF5D49C01400" ] }, "groupVolume": { "seq_id": 1546117277880, "fixed": false, "muted": false, "volume": 69 }, "metadataStatus": { "seq_id": 1546117278286, "container": { "type": "station" } }, "playbackStatus": { "playbackState": "PLAYBACK_STATE_IDLE", "availablePlaybackActions": { "canSeek": false, "canSkip": false, "canShuffle": false, "canStop": true, "canSkipBack": false, "canPause": false, "canCrossfade": false, "canRepeat": false, "canRepeatOne": false }, "seq_id": 1546117283328, "positionMillis": 0, "playModes": { "repeatOne": false, "repeat": false, "shuffle": false, "crossfade": false }, "previousPositionMillis": 4935 }, "altsonos": { "trigger": false, "seq_id": 0, "action": "stopAfterPlay", "params": "{ \"gid\": \"RINCON_5CAAFDF5D49C01400:3291522576\", \"delta\": 10, \"lul_device\": 491 }" } }, "RINCON_949F3E85D3D001400:3235904393": { "metadataStatus": { "seq_id": 1546116476597, "currentItem": { "track": { "album": { "name": "Younger Now" }, "type": "track", "name": "Thinkin'", "durationMillis": 245000, "id": { "serviceId": "9", "objectId": "spotify:track:742YV8T9ZyczCNdrTIAhaw", "accountId": "sn_1" }, "imageUrl": "http://192.168.1.32:1400/getaa?s=1&u=x-sonos-spotify%3aspotify%253atrack%253a742YV8T9ZyczCNdrTIAhaw%3fsid%3d9%26flags%3d8224%26sn%3d1", "artist": { "name": "Miley Cyrus" }, "service": { "name": "Spotify" } } }, "container": { "id": { "serviceId": "9", "objectId": "spotify:track:742YV8T9ZyczCNdrTIAhaw", "accountId": "sn_1" }, "type": "track", "name": "Thinkin'", "service": { "name": "Spotify" } } }, "groupVolume": { "seq_id": 1546116476931, "fixed": false, "muted": false, "volume": 40 }, "playbackStatus": { "playbackState": "PLAYBACK_STATE_IDLE", "availablePlaybackActions": { "canSeek": true, "canSkip": false, "canShuffle": true, "canStop": true, "canSkipBack": false, "canPause": true, "canCrossfade": true, "canRepeat": true, "canRepeatOne": true }, "seq_id": 1546116477278, "positionMillis": 0, "playModes": { "repeatOne": false, "repeat": false, "shuffle": false, "crossfade": false }, "previousPositionMillis": 43974 }, "core": { "seq_id": 1546116460920, "name": "Cuisine", "coordinatorId": "RINCON_949F3E85D3D001400", "id": "RINCON_949F3E85D3D001400:3235904393", "playerIds": [ "RINCON_949F3E85D3D001400" ] } } } } } <0x770b9320> 50 12/29/18 22:20:39.117 luup_log:491: ALTSonos: debug: resetRefreshMetadataLoop(491), SeqId 5 <0x770b9320> 50 12/29/18 22:20:39.118 luup_log:491: ALTSonos: debug: setDBValue(491,0,Sonos_wFPVFKCnR9Fyxq0Gwy0xgxTz3Q.-KgfWIJj0GLlXDBN3Nyu,groupId,RINCON_5CAAFDF5D49C01400:3291522576,altsonos) <0x770b9320> 50 12/29/18 22:20:39.119 luup_log:491: ALTSonos: debug: target:RINCON_5CAAFDF5D49C01400:3291522576 type:altsonos body is { "trigger": false, "action": "stopAfterPlay", "params": "{ \"gid\": \"RINCON_5CAAFDF5D49C01400:3291522576\", \"delta\": -5, \"lul_device\": 491 }" } <0x770b9320> 50 12/29/18 22:20:39.120 luup_log:491: ALTSonos: debug: onDefaultNotification(491,0,Sonos_wFPVFKCnR9Fyxq0Gwy0xgxTz3Q.-KgfWIJj0GLlXDBN3Nyu,groupId,RINCON_5CAAFDF5D49C01400:3291522576,altsonos,{ "trigger": false, "action": "stopAfterPlay", "params": "{ \"gid\": \"RINCON_5CAAFDF5D49C01400:3291522576\", \"delta\": -5, \"lul_device\": 491 }" }) <0x770b9320> 50 12/29/18 22:20:39.120 luup_log:491: ALTSonos: debug: SonosHTTP(491,api.ws.sonos.com/control/api/v1/playbackSessions/RINCON_5CAAFDF5D49C01400:3291522576@3950131392/playbackSession/loadStreamUrl,POST,{ "playOnCompletion": true, "streamUrl": "http://192.168.1.27:8085/Poubelle recyclable.mp3" },Bearer ab8f688a-15c0-44ba-8b81-c8ff2683373f,application/json,[ ]) <0x770b9320> 50 12/29/18 22:20:39.121 luup_log:491: ALTSonos: debug: request headers:{ "Content-Type": "application/json", "Authorization": "Bearer ab8f688a-15c0-44ba-8b81-c8ff2683373f", "Cache-Control": "no-cache", "Content-Length": 93 } <0x770b9320> 50 12/29/18 22:20:39.500 luup_log:491: ALTSonos: debug: response request:1 <0x770b9320> 50 12/29/18 22:20:39.500 luup_log:491: ALTSonos: debug: code:200 <0x770b9320> 50 12/29/18 22:20:39.502 luup_log:491: ALTSonos: debug: headers:{ "connection": "close", "date": "Sat, 29 Dec 2018 21:20:39 GMT", "pragma": "no-cache", "x-sonos-user-id": "117838608", "cache-control": "max-age=0, no-cache, no-store", "access-control-allow-origin": "", "expires": "Sat, 29 Dec 2018 21:20:39 GMT", "x-sonos-session": "RINCON_5CAAFDF5D49C01400:3291522576@3950131392", "x-sonos-household-id": "Sonos_wFPVFKCnR9Fyxq0Gwy0xgxTz3Q.-KgfWIJj0GLlXDBN3Nyu", "content-length": "2", "content-type": "application/json", "x-sonos-type": "none", "x-sonos-group-id": "RINCON_5CAAFDF5D49C01400:3291522576", "x-sonos-mac": "5caafdf5d49c", "server": "Apache-Coyote/1.1" } <0x770b9320> 50 12/29/18 22:20:39.502 luup_log:491: ALTSonos: debug: data:"{}" <0x770b9320> 50 12/29/18 22:20:39.502 luup_log:491: ALTSonos: debug: setVariableIfChanged(urn:upnp-org:serviceId:altsonos1,IconCode,100,491) <0x770b9320> 50 12/29/18 22:20:39.503 luup_log:491: ALTSonos: debug: resetRefreshMetadataLoop(491), SeqId 5 <0x770b9320> 04 12/29/18 22:20:39.504 <0x770b9320> 06 12/29/18 22:20:40.648 Device_Variable::m_szValue_set device: 40 service: urn:futzle-com:serviceId:CurrentCostEnviR1 variable: Time was: 16:04:11 now: 16:04:14 #hooks: 0 upnp: 0 skip: 0 v:0x119d4a0/NONE duplicate:0 <0x70bcd520> 06 12/29/18 22:20:40.649 Device_Variable::m_szValue_set device: 43 service: urn:micasaverde-com:serviceId:EnergyMetering1 variable: Watts was: 1908 now: 1919 #hooks: 1 upnp: 0 skip: 0 v:0x1199238/NONE duplicate:0 <0x70bcd520> 06 12/29/18 22:20:40.703 Device_Variable::m_szValue_set device: 43 service: urn:micasaverde-com:serviceId:EnergyMetering1 variable: Log was: 1908,580,2117,1546118434,3035 now: 1919,582,2117,1546118440,3041 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x70bcd520> 06 12/29/18 22:20:40.704 Device_Variable::m_szValue_set device: 43 service: urn:futzle-com:serviceId:CurrentCostEnviR1 variable: Time was: 16:04:07 now: 16:04:14 #hooks: 0 upnp: 0 skip: 0 v:0x119d4a0/NONE duplicate:0 <0x70bcd520> 06 12/29/18 22:20:40.705 Device_Variable::m_szValue_set device: 43 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: CurrentTemperature was: 29.3 now: 29.4 #hooks: 0 upnp: 0 skip: 0 v:0x11dcd10/NONE duplicate:0 <0x70bcd520> 06 12/29/18 22:20:42.875 Device_Variable::m_szValue_set device: 40 service: urn:futzle-com:serviceId:CurrentCostEnviR1 variable: Time was: 16:04:14 now: 16:04:16 #hooks: 0 upnp: 0 skip: 0 v:0x119d4a0/NONE duplicate:0 <0x70bcd520> 06 12/29/18 22:20:42.877 Device_Variable::m_szValue_set device: 42 service: urn:micasaverde-com:serviceId:EnergyMetering1 variable: Watts was: 767 now: 757 #hooks: 1 upnp: 0 skip: 0 v:0x1199238/NONE duplicate:0 <0x70bcd520> 06 12/29/18 22:20:42.932 Device_Variable::m_szValue_set device: 42 service: urn:micasaverde-com:serviceId:EnergyMetering1 variable: Log was: 767,625,1446,1546118436,3037 now: 757,625,1446,1546118442,3043 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x70bcd520> 06 12/29/18 22:20:42.933 Device_Variable::m_szValue_set device: 42 service: urn:futzle-com:serviceId:CurrentCostEnviR1 variable: Time was: 16:04:10 now: 16:04:16 #hooks: 0 upnp: 0 skip: 0 v:0x119d4a0/NONE duplicate:0 <0x70bcd520> 06 12/29/18 22:20:42.933 Device_Variable::m_szValue_set device: 42 service: urn:upnp-org:serviceId:TemperatureSensor1 variable: CurrentTemperature was: 29.3 now: 29.4 #hooks: 0 upnp: 0 skip: 0 v:0x11dcd10/NONE duplicate:0 <0x70bcd520> 06 12/29/18 22:20:43.894 Device_Variable::m_szValue_set device: 40 service: urn:futzle-com:serviceId:CurrentCostEnviR1 variable: Time was: 16:04:16 now: 16:04:17 #hooks: 0 upnp: 0 skip: 0 v:0x119d4a0/NONE duplicate:0 <0x70bcd520> 50 12/29/18 22:20:43.923 luup_log:312: ALTUI: _deferredWgetThingspeak(url=https://api.thingspeak.com/update?api_key=57UG4KV6W6YWUE5V&field5=1919&field1=2642&field7=1370&field2=757) ==> code=0 httpcode=200 data=220545 <0x74828520> 06 12/29/18 22:20:43.926 Device_Variable::m_szValue_set device: 41 service: urn:micasaverde-com:serviceId:EnergyMetering1 variable: Watts was: 2642 now: 2582 #hooks: 1 upnp: 0 skip: 0 v:0x1199238/NONE duplicate:0 <0x70bcd520> 06 12/29/18 22:20:43.970 Device_Variable::m_szValue_set device: 41 service: urn:micasaverde-com:serviceId:EnergyMetering1 variable: Log was: 2642,1230,3126,1546118437,3038 now: 2582,1232,3126,1546118443,3044 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x70bcd520> 06 12/29/18 22:20:43.971 Device_Variable::m_szValue_set device: 41 service: urn:futzle-com:serviceId:CurrentCostEnviR1 variable: Time was: 16:04:11 now: 16:04:17 #hooks: 0 upnp: 0 skip: 0 v:0x119d4a0/NONE duplicate:0 <0x70bcd520> 06 12/29/18 22:20:43.971 Device_Variable::m_szValue_set device: 40 service: urn:micasaverde-com:serviceId:EnergyMetering1 variable: Watts was: 2642 now: 2582 #hooks: 0 upnp: 0 skip: 0 v:0x1199238/NONE duplicate:0 <0x70bcd520> 06 12/29/18 22:20:43.972 Device_Variable::m_szValue_set device: 40 service: urn:micasaverde-com:serviceId:EnergyMetering1 variable: Log was: 2642,1228,3126,1546118437,3038 now: 2582,1230,3126,1546118443,3044 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x70bcd520> 50 12/29/18 22:20:44.831 luup_log:312: ALTUI: _deferredWgetThingspeak(url=https://api.thingspeak.com/update?api_key=57UG4KV6W6YWUE5V&field1=2582) ==> code=0 httpcode=200 data=0 <0x74828520> 06 12/29/18 22:20:47.111 Device_Variable::m_szValue_set device: 40 service: urn:futzle-com:serviceId:CurrentCostEnviR1 variable: Time was: 16:04:17 now: 16:04:20 #hooks: 0 upnp: 0 skip: 0 v:0x119d4a0/NONE duplicate:0 <0x70bcd520> 06 12/29/18 22:20:47.143 Device_Variable::m_szValue_set device: 43 service: urn:micasaverde-com:serviceId:EnergyMetering1 variable: Watts was: 1919 now: 1942 #hooks: 1 upnp: 0 skip: 0 v:0x1199238/NONE duplicate:0 <0x70bcd520> 06 12/29/18 22:20:47.186 Device_Variable::m_szValue_set device: 43 service: urn:micasaverde-com:serviceId:EnergyMetering1 variable: Log was: 1919,582,2117,1546118440,3041 now: 1942,585,2117,1546118447,3048 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x70bcd520> 06 12/29/18 22:20:47.187 Device_Variable::m_szValue_set device: 43 service: urn:futzle-com:serviceId:CurrentCostEnviR1 variable: Time was: 16:04:14 now: 16:04:20 #hooks: 0 upnp: 0 skip: 0 v:0x119d4a0/NONE duplicate:0 <0x70bcd520> 06 12/29/18 22:20:48.898 Device_Variable::m_szValue_set device: 40 service: urn:futzle-com:serviceId:CurrentCostEnviR1 variable: Time was: 16:04:20 now: 16:04:22 #hooks: 0 upnp: 0 skip: 0 v:0x119d4a0/NONE duplicate:0 <0x70bcd520> 06 12/29/18 22:20:48.909 Device_Variable::m_szValue_set device: 42 service: urn:micasaverde-com:serviceId:EnergyMetering1 variable: Watts was: 757 now: 763 #hooks: 1 upnp: 0 skip: 0 v:0x1199238/NONE duplicate:0 <0x70bcd520> 06 12/29/18 22:20:48.953 Device_Variable::m_szValue_set device: 42 service: urn:micasaverde-com:serviceId:EnergyMetering1 variable: Log was: 757,625,1446,1546118442,3043 now: 763,625,1446,1546118448,3049 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x70bcd520> 06 12/29/18 22:20:48.954 Device_Variable::m_szValue_set device: 42 service: urn:futzle-com:serviceId:CurrentCostEnviR1 variable: Time was: 16:04:16 now: 16:04:22 #hooks: 0 upnp: 0 skip: 0 v:0x119d4a0/NONE duplicate:0 <0x70bcd520> 06 12/29/18 22:20:50.337 Device_Variable::m_szValue_set device: 40 service: urn:futzle-com:serviceId:CurrentCostEnviR1 variable: Time was: 16:04:22 now: 16:04:24 #hooks: 0 upnp: 0 skip: 0 v:0x119d4a0/NONE duplicate:0 <0x70bcd520> 06 12/29/18 22:20:50.339 Device_Variable::m_szValue_set device: 41 service: urn:micasaverde-com:serviceId:EnergyMetering1 variable: Watts was: 2582 now: 891 #hooks: 1 upnp: 0 skip: 0 v:0x1199238/NONE duplicate:0 <0x70bcd520> 06 12/29/18 22:20:50.391 Device_Variable::m_szValue_set device: 41 service: urn:micasaverde-com:serviceId:EnergyMetering1 variable: Log was: 2582,1232,3126,1546118443,3044 now: 891,1235,3126,1546118450,3051 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x70bcd520> 06 12/29/18 22:20:50.391 Device_Variable::m_szValue_set device: 41 service: urn:futzle-com:serviceId:CurrentCostEnviR1 variable: Time was: 16:04:17 now: 16:04:24 #hooks: 0 upnp: 0 skip: 0 v:0x119d4a0/NONE duplicate:0 <0x70bcd520> 06 12/29/18 22:20:50.392 Device_Variable::m_szValue_set device: 40 service: urn:micasaverde-com:serviceId:EnergyMetering1 variable: Watts was: 2582 now: 891 #hooks: 0 upnp: 0 skip: 0 v:0x1199238/NONE duplicate:0 <0x70bcd520> 06 12/29/18 22:20:50.392 Device_Variable::m_szValue_set device: 40 service: urn:micasaverde-com:serviceId:EnergyMetering1 variable: Log was: 2582,1230,3126,1546118443,3044 now: 891,1233,3126,1546118450,3051 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x70bcd520> 06 12/29/18 22:20:53.184 Device_Variable::m_szValue_set device: 40 service: urn:futzle-com:serviceId:CurrentCostEnviR1 variable: Time was: 16:04:24 now: 16:04:26 #hooks: 0 upnp: 0 skip: 0 v:0x119d4a0/NONE duplicate:0 <0x70bcd520> 06 12/29/18 22:20:53.196 Device_Variable::m_szValue_set device: 43 service: urn:micasaverde-com:serviceId:EnergyMetering1 variable: Watts was: 1942 now: 234 #hooks: 1 upnp: 0 skip: 0 v:0x1199238/NONE duplicate:0 <0x70bcd520> 06 12/29/18 22:20:53.238 Device_Variable::m_szValue_set device: 43 service: urn:micasaverde-com:serviceId:EnergyMetering1 variable: Log was: 1942,585,2117,1546118447,3048 now: 234,587,2117,1546118453,3054 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0 <0x70bcd520> 06 12/29/18 22:20:53.238 Device_Variable::m_szValue_set device: 43 service: urn:futzle-com:serviceId:CurrentCostEnviR1 variable: Time was: 16:04:20 now: 16:04:26 #hooks: 0 upnp: 0 skip: 0 v:0x119d4a0/NONE duplicate:0 <0x70bcd520> 06 12/29/18 22:20:54.953 Device_Variable::m_szValue_set device: 40 service: urn:futzle-com:serviceId:CurrentCostEnviR1 variable: Time was: 16:04:26 now: 16:04:28 #hooks: 0 upnp: 0 skip: 0 v:0x119d4a0/NONE duplicate:0 <0x70bcd520> 06 12/29/18 22:20:54.955 Device_Variable::m_szValue_set device: 42 service: urn:micasaverde-com:serviceId:EnergyMetering1 variable: Watts was: 763 now: 782 #hooks: 1 upnp: 0 skip: 0 v:0x1199238/NONE duplicate:0 <0x70bcd520> 06 12/29/18 22:20:55.007 Device_Variable::m_szValue_set device: 42 service: urn:micasaverde-com:serviceId:EnergyMetering1 variable: Log was: 763,625,1446,1546118448,3049 now: 782,625,1446,1546118455,3056 #hooks: 0 upnp: 0 skip: 0 v:(nil)/NONE duplicate:0