[RELEASE] cast-web 1.0.0 - Chromecast Device Handler & SmartApps

dth_av
requires_server
project_av
chromecast
googlehome

(Satish P) #290

Matt,

Sorry got confused. You want me to increase refresh rate higher than 5mins
or lower than 5min? I changed it to 2min from 5


(Tobias) #291

I think we both mean lower than 5min.

My point was just that you shouldn’t forget to also change the polling interval of the actual group device.


(Ryan Murphy) #293

@vervallsweg or any other experts,
I’m noticing that my Google Home speakers are no longer doing the TTS like they used to. I had upgraded to 0.2 and am wondering if that may be the cause.

What’s odd is that when my Piston is triggered, I can hear the speaker doing the double knock sound (which I believe is associated with the volume changing or maybe) but I don’t hear the “chime” sound or the spoken text.

I’ve even deleted all my GH devices from ST, deleted the SmartApp from my hub (but left the same updated code for the DH and SmartApp). My node.js hasn’t been changed but I had even restarted that for good measure.

Here are the piston logs:

2/24/2018, 5:07:11 PM +38ms
+1ms	╔Received event [Ring Doorbell].button = pushed with a delay of 92ms
+198ms	║RunTime Analysis CS > 24ms > PS > 60ms > PE > 114ms > CE
+200ms	║Runtime (36904 bytes) successfully initialized in 60ms (v0.2.102.20180116) (198ms)
+201ms	║╔Execution stage started
+210ms	║║Comparison (enum) pushed gets (string) pushed = true (1ms)
+211ms	║║Cancelling condition #4's schedules...
+212ms	║║Condition #4 evaluated true (5ms)
+213ms	║║Cancelling condition #1's schedules...
+214ms	║║Condition group #1 evaluated true (state changed) (8ms)
+216ms	║║Cancelling statement #2's schedules...
+1163ms	║║Executed physical command [All Speakers].playTextAndResume([Someone is at the front door., 100]) (940ms)
+1164ms	║║Executed [All Speakers].playTextAndResume (942ms)
+1167ms	║╚Execution stage complete. (965ms)
+1168ms	╚Event processed successfully (1168ms)

Here’s my piston:

I’m looking through older logs from when I know it was working and they seem to match so I’m stumped. Is there something obvious that I may be missing here?


(Rejiofoh) #294

Greetings Everyone… Hope this meets everyone well. I am new to ST and have been trying to get cast-web -api running. I have installed on Mac with static IP and am able to run commands from every device on my network with no issue. But when I use the smart app in ST, it just spins and spins and does n0t respond. I use the DEBUG=* node and can see responses when I access through web browsers but no response when i use the ST app. Are there firewall settings I need to change? I have installed and reinstalled at least 3 times… Please point me in the right direction.

Mac OSX 10.13.3
cast-web-api version 0.2.3
node 9.5.0
npm 5.6.0


(Tobias) #295

Sounds pretty strange…

So you enter the hostname:port that the console outputs on startup, in your phones browser for example and you get a response? I ran the API with my Mac firewall on and it worked.

I’d double check the hostname and port settings on the API and whether you can really access it from any other device.


(Rejiofoh) #296

Yes. while in DEBUG, if I enter the “hostname:port” or “hostname:port/getDevices” from a web browser (on any computer or phone on my network) I can see a response in terminal on the mac (showing there is an activity). When I enter the same in the API there is no response/ activity at all. so the API is not communicating at all with the node. Just to make sure, I completely removed the device handler and smartapp and readded but still no dice.
I added your github repo and added the handler and app from there.


(Satish P) #297

@vervallsweg @Matt_Behnken I tried setting all the devices and group polling to 2mins. I dont see the group status being updated after it plays and stops the notification (sent from BigTalker2). It still show the value Playing unless I click the STOP button and refresh (multiple times)… The status of the group (AllSpeakers) on the ST app under Right Now shows the pause button - since its still think its playing, below it shows “Ready to Cast”…

I tried to pause, stop, refresh mutlple times but it does nothing… Here is the log from the All Speakers group…

11:58:17 AM: debug Executing 'sendHttpRequest' host: 10.0.0.120:3000 path: /getDeviceStatus?address=10.0.0.16:42022
11:58:17 AM: debug Executing 'getDeviceStatus'
11:58:12 AM: debug Executing 'sendHttpRequest' host: 10.0.0.120:3000 path: /getDeviceStatus?address=10.0.0.16:42022
11:58:12 AM: debug Executing 'getDeviceStatus'
11:58:07 AM: debug getTrackData, keys: [sessionId]
11:58:07 AM: debug Executing 'stop'
11:58:01 AM: debug Executing 'sendHttpRequest' host: 10.0.0.120:3000 path: /getDeviceStatus?address=10.0.0.16:42022
11:58:01 AM: debug Executing 'getDeviceStatus'
11:57:58 AM: debug getTrackData, keys: [sessionId]
11:57:58 AM: debug Executing 'stop'
11:57:56 AM: debug getTrackData, keys: [sessionId]
11:57:56 AM: debug Executing 'pause'
11:57:43 AM: debug Executing 'sendHttpRequest' host: 10.0.0.120:3000 path: /getDeviceStatus?address=10.0.0.16:42022
11:57:43 AM: debug Executing 'getDeviceStatus'
11:57:40 AM: debug getTrackData, keys: [sessionId]
11:57:40 AM: debug Executing 'pause'

Here is the log from Cast WEb group sync app…

11:50:17 AM: debug groupStatusUpdated called, theAudioGroup.currentState('status').value: playing
11:49:41 AM: debug groupStatusUpdated called, theAudioGroup.currentState('status').value: playing
11:48:41 AM: debug groupStatusUpdated called, theAudioGroup.currentState('status').value: playing
11:48:29 AM: debug groupStatusUpdated called, theAudioGroup.currentState('status').value: playing
11:48:17 AM: debug groupStatusUpdated called, theAudioGroup.currentState('status').value: playing
11:47:29 AM: debug groupStatusUpdated called, theAudioGroup.currentState('status').value: playing
11:47:29 AM: debug getLatestVersion(), data: { "version": 0.2, "type": "release", "date": "February 11, 2018", "changelog": "https://github.com/vervallsweg/smartthings/blob/master/smartapps/vervallsweg/cast-web-group-sync.src/README.md#version-02" }
11:47:29 AM: debug getLatestVersion(), response status: 200
11:47:29 AM: error getLatestVersion(), something went wrong: java.lang.NullPointerException: Cannot get property 'smartAppVersionDTO' on null object
11:47:29 AM: error getLatestVersion(), something went wrong: java.lang.NullPointerException: Cannot get property 'smartAppVersionDTO' on null object
11:46:44 AM: debug groupStatusUpdated called, theAudioGroup.currentState('status').value: playing
11:46:17 AM: debug groupStatusUpdated called, theAudioGroup.currentState('status').value: playing
11:45:44 AM: debug groupStatusUpdated called, theAudioGroup.currentState('status').value: playing
11:45:40 AM: debug groupStatusUpdated called, theAudioGroup.currentState('status').value: playing
11:45:39 AM: debug getLatestVersion(), data: { "version": 0.2, "type": "release", "date": "February 11, 2018", "changelog": "https://github.com/vervallsweg/smartthings/blob/master/smartapps/vervallsweg/cast-web-group-sync.src/README.md#version-02" }
11:45:39 AM: debug getLatestVersion(), response status: 200
11:45:34 AM: debug getLatestVersion(), data: { "version": 0.2, "type": "release", "date": "February 11, 2018", "changelog": "https://github.com/vervallsweg/smartthings/blob/master/smartapps/vervallsweg/cast-web-group-sync.src/README.md#version-02" }
11:45:34 AM: debug getLatestVersion(), response status: 200
11:45:27 AM: debug groupStatusUpdated called, theAudioGroup.currentState('status').value: playing
11:44:27 AM: debug groupStatusUpdated called, theAudioGroup.currentState('status').value: playing
11:44:27 AM: debug getLatestVersion(), data: { "version": 0.2, "type": "release", "date": "February 11, 2018", "changelog": "https://github.com/vervallsweg/smartthings/blob/master/smartapps/vervallsweg/cast-web-group-sync.src/README.md#version-02" }
11:44:27 AM: debug getLatestVersion(), response status: 200
11:44:27 AM: error getLatestVersion(), something went wrong: java.lang.NullPointerException: Cannot get property 'smartAppVersionDTO' on null object
11:44:27 AM: error getLatestVersion(), something went wrong: java.lang.NullPointerException: Cannot get property 'smartAppVersionDTO' on null object
11:44:17 AM: debug groupStatusUpdated called, theAudioGroup.currentState('status').value: playing
11:44:17 AM: debug getLatestVersion(), data: { "version": 0.2, "type": "release", "date": "February 11, 2018", "changelog": "https://github.com/vervallsweg/smartthings/blob/master/smartapps/vervallsweg/cast-web-group-sync.src/README.md#version-02" }
11:44:17 AM: debug getLatestVersion(), response status: 200
11:44:17 AM: error getLatestVersion(), something went wrong: java.lang.NullPointerException: Cannot get property 'smartAppVersionDTO' on null object
11:44:17 AM: error getLatestVersion(), something went wrong: java.lang.NullPointerException: Cannot get property 'smartAppVersionDTO' on null object
11:43:27 AM: debug groupStatusUpdated called, theAudioGroup.currentState('status').value: playing
11:43:27 AM: error getLatestVersion(), something went wrong: java.lang.NullPointerException: Cannot get property 'smartAppVersionDTO' on null object
11:43:27 AM: error getLatestVersion(), something went wrong: java.lang.NullPointerException: Cannot get property 'smartAppVersionDTO' on null object

(Rejiofoh) #298

@vervallsweg

from the G.H update from repo, I noticed there is another cast-web-service-manager

  • smartapps/vervallsweg/cast-web-service-manager.src/version.json

when i try to add thois one i get…

Oh No! Something Went Wrong!
Error
500: Internal Server Error
URI
/ide/app/doRepoUpdates
Reference Id
1a5ef732-ccf8-45f9-b62d-9d478c36a6da
Date
Mon Feb 26 20:40:32 UTC 2018

is this the one I should be using? the other one installs just fine but still not working.


(Satish P) #299

I did find something interesting today in the logs… Somehow the player (group) status has reset (in one of the scenario) to READY from PLAYING. And another scenario where it keep continuing to stay in PLAYING status forever.

Scenario 1: continue to stay in Playing status forever

  • Group player in READY state
  • Receives a notification event (message)
  • Plays the message
  • Continue to be in PLAYING status without resetting the status to READY. (A second message received when the status of the group show as PLAYING)

logs

1:31:59 PM: debug Executing 'sendHttpRequest' host: 10.0.0.120:3000 path: /getDeviceStatus?address=10.0.0.16:42022
1:31:59 PM: debug Executing 'getDeviceStatus'
1:30:59 PM: debug Executing 'sendHttpRequest' host: 10.0.0.120:3000 path: /getDeviceStatus?address=10.0.0.16:42022
1:30:59 PM: debug Executing 'getDeviceStatus'
1:30:17 PM: debug Executing 'sendHttpRequest' host: 10.0.0.120:3000 path: /getDeviceStatus?address=10.0.0.16:42022
1:30:17 PM: debug Executing 'getDeviceStatus'
1:14:17 PM: debug Executing 'sendHttpRequest' host: 10.0.0.120:3000 path: /getDeviceStatus?address=10.0.0.16:42022
1:14:17 PM: debug Executing 'getDeviceStatus'
1:13:59 PM: debug Executing 'sendHttpRequest' host: 10.0.0.120:3000 path: /getDeviceStatus?address=10.0.0.16:42022
1:13:59 PM: debug Executing 'getDeviceStatus'
1:12:59 PM: debug Executing 'sendHttpRequest' host: 10.0.0.120:3000 path: /getDeviceStatus?address=10.0.0.16:42022
1:12:59 PM: debug Executing 'getDeviceStatus'
1:12:17 PM: debug Executing 'sendHttpRequest' host: 10.0.0.120:3000 path: /getDeviceStatus?address=10.0.0.16:42022
1:12:17 PM: debug Executing 'getDeviceStatus'
1:11:59 PM: debug Executing 'sendHttpRequest' host: 10.0.0.120:3000 path: /getDeviceStatus?address=10.0.0.16:42022
1:11:59 PM: debug Executing 'getDeviceStatus'
1:10:59 PM: debug Executing 'sendHttpRequest' host: 10.0.0.120:3000 path: /getDeviceStatus?address=10.0.0.16:42022
1:10:59 PM: debug Executing 'getDeviceStatus'
1:09:59 PM: debug Executing 'sendHttpRequest' host: 10.0.0.120:3000 path: /getDeviceStatus?address=10.0.0.16:42022
1:09:59 PM: debug Executing 'getDeviceStatus'
1:08:17 PM: debug Executing 'sendHttpRequest' host: 10.0.0.120:3000 path: /getDeviceStatus?address=10.0.0.16:42022
1:08:17 PM: debug Executing 'getDeviceStatus'
1:07:59 PM: debug Executing 'sendHttpRequest' host: 10.0.0.120:3000 path: /setMediaPlaybackShort?address=10.0.0.16:42022&mediaType=audio/mp3&mediaUrl=https://s3.amazonaws.com/smartapp-media/polly-tts/salli/5ad33bc755e6f5c167e5ef4f83bb96e3bf9f010a.mp3&mediaStreamType=BUFFERED&mediaTitle=SmartThings&mediaSubtitle=SmartThings+playback&mediaImageUrl=https://lh3.googleusercontent.com/nQBLtHKqZycERjdjMGulMLMLDoPXnrZKYoJ8ijaVs8tDD6cypInQRtxgngk9SAXHkA=w300
1:07:59 PM: debug Executing 'setMediaPlaybackShort'; mediaType: audio/mp3 mediaUrl: https://s3.amazonaws.com/smartapp-media/polly-tts/salli/5ad33bc755e6f5c167e5ef4f83bb96e3bf9f010a.mp3 mediaStreamType: BUFFERED mediaTitle: SmartThings mediaSubtitle: SmartThings playback mediaImageUrl: https://lh3.googleusercontent.com/nQBLtHKqZycERjdjMGulMLMLDoPXnrZKYoJ8ijaVs8tDD6cypInQRtxgngk9SAXHkA=w300
1:07:59 PM: info Executing 'playTrack', uri: https://s3.amazonaws.com/smartapp-media/polly-tts/salli/5ad33bc755e6f5c167e5ef4f83bb96e3bf9f010a.mp3 level: 0
1:07:59 PM: info Executing 'playTrackAndResume', uri: https://s3.amazonaws.com/smartapp-media/polly-tts/salli/5ad33bc755e6f5c167e5ef4f83bb96e3bf9f010a.mp3 duration: 7 level: 0
1:07:54 PM: debug Executing 'sendHttpRequest' host: 10.0.0.120:3000 path: /getDeviceStatus?address=10.0.0.16:42022
1:07:54 PM: debug Executing 'getDeviceStatus'
1:06:54 PM: debug Executing 'sendHttpRequest' host: 10.0.0.120:3000 path: /getDeviceStatus?address=10.0.0.16:42022
1:06:54 PM: debug Executing 'getDeviceStatus'
1:06:18 PM: debug Executing 'sendHttpRequest' host: 10.0.0.120:3000 path: /getDeviceStatus?address=10.0.0.16:42022
1:06:18 PM: debug Executing 'getDeviceStatus'
1:05:54 PM: debug Executing 'sendHttpRequest' host: 10.0.0.120:3000 path: /getDeviceStatus?address=10.0.0.16:42022
1:05:54 PM: debug Executing 'getDeviceStatus'
1:04:17 PM: debug Executing 'sendHttpRequest' host: 10.0.0.120:3000 path: /getDeviceStatus?address=10.0.0.16:42022
1:04:17 PM: debug Executing 'getDeviceStatus'
1:03:18 PM: debug Executing 'sendHttpRequest' host: 10.0.0.120:3000 path: /setMediaPlaybackShort?address=10.0.0.16:42022&mediaType=audio/mp3&mediaUrl=https://s3.amazonaws.com/smartapp-media/polly-tts/salli/XXXXXXXXXXXXXXXXXXXX.mp3&mediaStreamType=BUFFERED&mediaTitle=SmartThings&mediaSubtitle=SmartThings+playback&mediaImageUrl=https://lh3.googleusercontent.com/nQBLtHKqZycERjdjMGulMLMLDoPXnrZKYoJ8ijaVs8tDD6cypInQRtxgngk9SAXHkA=w300
1:03:18 PM: debug Executing 'setMediaPlaybackShort'; mediaType: audio/mp3 mediaUrl: https://s3.amazonaws.com/smartapp-media/polly-tts/salli/XXXXXXXXXXXXXXXXXXXX.mp3 mediaStreamType: BUFFERED mediaTitle: SmartThings mediaSubtitle: SmartThings playback mediaImageUrl: https://lh3.googleusercontent.com/nQBLtHKqZycERjdjMGulMLMLDoPXnrZKYoJ8ijaVs8tDD6cypInQRtxgngk9SAXHkA=w300
1:03:18 PM: info Executing 'playTrack', uri: https://s3.amazonaws.com/smartapp-media/polly-tts/salli/XXXXXXXXXXXXXXXXXXXX.mp3 level: 0
1:03:18 PM: info Executing 'playTrackAndRestore', uri: https://s3.amazonaws.com/smartapp-media/polly-tts/salli/XXXXXXXXXXXXXXXXXXXX.mp3 duration: 7 level: 0

Scenario 2: Only one notification is received to play and no notification is received before the status of the group resets to READY… (sometimes 5 mins to 10mins)

Logs ~~~~~~

12:46:17 PM	debug generateSwitchStatus, nothing playing														
12:46:17 PM	debug getTrackData, keys: [title]														
12:46:17 PM	debug Executing 'generateSwitchStatus', length:2														
12:46:17 PM	debug Executing 'generateTrackDescription', trackDescription: Ready to cast														
																														
12:46:17 PM	debug getTrackData, keys: [title]														
12:46:17 PM	debug getTrackData, keys: [subtitle]														
12:46:17 PM	debug deviceStatus.volume.muted: false														
12:46:17 PM	debug getTrackData, keys: [displayName]														
12:46:17 PM	debug deviceStatus.volume.level: 70														
12:46:17 PM	debug Executing 'parseReceiverStatus'														
12:46:17 PM	debug parse, msg.json: [status:[volume:[level:0.699999988079071, muted:false, controlType:master, stepInterval:0.05000000074505806], userEq	:[high_shelf:[gain_db:1.5, quality:0.707,	frequency:4500],	peaking_eqs:[],	low_shelf:[gain_db:2,	quality:0.707,	frequency:150],	max_peaking_eqs:0]]	, requestId:3578, type:RECEIVER	_STATUS]					
12:46:17 PM	debug 'parse', parsing: 'index:16, mac:9C4E36504EA8, ip:0A000000, port:0BB8, requestId:258a05b4-5c20-446f-b36c-d276ab9e49f6, callback:true,	headers:SFRUUC8xLjEgMjAwIE9LDQpDb250ZW50	LVR5cGU6IGFwcGxpY	2F0aW9uL2pzb247I	GNoYXJzZXQ9dXRmLTgNCkR	hdGU6IE1vbiwgMj	YgRmViIDIwMTggMj	A6NDY6MTcgR01UDQpDb2	5uZWN0aW9uOiBrZWVwLWFsaXZlDQpDb	250ZW50LUxlbmd0aD	ogMzMx,	bod	y:eyJyZXF1	ZXN0SWQiOjM1NzgsInN0YXR1cyI6eyJ1c2VyRXEiOnsiaGlnaF9zaGVsZiI6eyJmcmVxdWVuY3kiOjQ1MDAsImdhaW5fZGIiOjEuNSwicXVhbGl0eSI6MC43MDd9LCJsb3dfc2hlbGYiOnsiZnJlcXVlbmN5IjoxNTAsImdhaW5fZGIiOjIsInF1YWxpdHkiOjAuNzA3fSwibWF4X3BlYWtpbmdfZXFzIjowLCJwZWFraW5nX2VxcyI6W119LCJ2b2x1bWUiOnsiY29udHJvbFR5cGUiOiJtYXN0ZXIiLCJsZXZlbCI6MC42OTk5OTk5ODgwNzkwNzEsIm11dGVkIjpmYWxzZSwic3RlcEludGVydmFsIjowLjA1MDAwMDAwMDc0NTA1ODA2fX0sInR5cGUiOiJSRUNFSVZFUl9TVEFUVVMifQ=='	
12:46:17 PM	debug Executing 'sendHttpRequest' host: 10.0.0.120:3000 path: /getDeviceStatus?address=10.0.0.16:42022														
12:46:17 PM	debug Executing 'getDeviceStatus'														
12:45:25 PM	debug Executing 'generateSwitchStatus', length:2														
12:45:25 PM	debug removeTrackData, removing key: displayName, value: Default Media Receiver														
12:45:25 PM	debug deviceStatus.volume.level: 70														
12:45:25 PM	debug deviceStatus.volume.muted: false														
12:45:25 PM	debug parse, msg.json: [status:[volume:[level:0.699999988079071, muted:false, controlType:master, stepInterval:0.05000000074505806], userEq	:[high_shelf:[gain_db:1.5, quality:0.707,	frequency:4500],	peaking_eqs:[],	low_shelf:[gain_db:2,	quality:0.707,	frequency:150],	max_peaking_eqs:0]]	, requestId:3571, type:RECEIVER	_STATUS]					
12:45:25 PM	debug Executing 'parseReceiverStatus'														
12:45:25 PM	debug 'parse', parsing: 'index:15, mac:9C4E36504EA8, ip:0A000000, port:0BB8, requestId:985a5782-ce30-478c-a653-282912bb787e, callback:true,	headers:SFRUUC8xLjEgMjAwIE9LDQpDb250ZW50	LVR5cGU6IGFwcGxpY	2F0aW9uL2pzb247I	GNoYXJzZXQ9dXRmLTgNCkR	hdGU6IE1vbiwgMj	YgRmViIDIwMTggMj	A6NDU6MjUgR01UDQpDb2	5uZWN0aW9uOiBrZWVwLWFsaXZlDQpDb	250ZW50LUxlbmd0aD	ogMzMx,	bod	y:eyJyZXF1	ZXN0SWQiOjM1NzEsInN0YXR1cyI6eyJ1c2VyRXEiOnsiaGlnaF9zaGVsZiI6eyJmcmVxdWVuY3kiOjQ1MDAsImdhaW5fZGIiOjEuNSwicXVhbGl0eSI6MC43MDd9LCJsb3dfc2hlbGYiOnsiZnJlcXVlbmN5IjoxNTAsImdhaW5fZGIiOjIsInF1YWxpdHkiOjAuNzA3fSwibWF4X3BlYWtpbmdfZXFzIjowLCJwZWFraW5nX2VxcyI6W119LCJ2b2x1bWUiOnsiY29udHJvbFR5cGUiOiJtYXN0ZXIiLCJsZXZlbCI6MC42OTk5OTk5ODgwNzkwNzEsIm11dGVkIjpmYWxzZSwic3RlcEludGVydmFsIjowLjA1MDAwMDAwMDc0NTA1ODA2fX0sInR5cGUiOiJSRUNFSVZFUl9TVEFUVVMifQ=='	
12:45:25 PM	debug Executing 'sendHttpRequest' host: 10.0.0.120:3000 path: /getDeviceStatus?address=10.0.0.16:42022														
12:45:25 PM	debug Executing 'getDeviceStatus'														
12:44:25 PM	debug generateSwitchStatus, playing														
12:44:25 PM	debug Executing 'generateSwitchStatus', length:91														
12:44:25 PM	debug Executing 'generateTrackDescription', trackDescription: Default Media Receiver														
															
12:41:26 PM	debug getTrackData, keys: [subtitle]														
12:41:26 PM	debug getTrackData, keys: [title]														
12:41:26 PM	debug getTrackData, keys: [displayName]														
12:41:26 PM	debug Executing 'parseMediaStatus', mediaStatus: []														
12:41:26 PM	debug parse, msg.json: [requestId:3558, status:[], type:MEDIA_STATUS]														
12:41:26 PM	debug 'parse', parsing: 'index:15, mac:9C4E36504EA8, ip:0A000000, port:0BB8, requestId:4e65deda-d809-4129-ba0a-86bac887d81c, callback:true,	headers:SFRUUC8xLjEgMjAwIE9LDQpDb250ZW50	LVR5cGU6IGFwcGxpY	2F0aW9uL2pzb247I	GNoYXJzZXQ9dXRmLTgNCkR	hdGU6IE1vbiwgMj	YgRmViIDIwMTggMj	A6NDE6MjUgR01UDQpDb2	5uZWN0aW9uOiBrZWVwLWFsaXZlDQpDb	250ZW50LUxlbmd0aD	ogNTI=,	bod	y:eyJ0eXBl	IjoiTUVESUFfU1RBVFVTIiwic3RhdHVzIjpbXSwicmVxdWVzdElkIjozNTU4fQ=='	
12:41:25 PM	debug generateSwitchStatus, playing														
12:41:25 PM	debug Executing 'generateTrackDescription', trackDescription: Default Media Receiver														
															
12:41:25 PM	debug getTrackData, keys: [subtitle]														
12:41:25 PM	debug getTrackData, keys: [title]														
12:41:25 PM	debug Executing 'generateSwitchStatus', length:91														
12:41:25 PM	debug Receiver application running, displayName: Default Media Receiver														
12:41:25 PM	debug Executing 'sendHttpRequest' host: 10.0.0.120:3000 path: /getMediaStatus?address=10.0.0.16:42022&sessionId=4be73a0c-c4f0-42b8-9f6c-1c4	4254faf555													
12:41:25 PM	debug Executing 'getMediaStatus' sessionId: 4be73a0c-c4f0-42b8-9f6c-1c44254faf31														
12:41:25 PM	debug deviceStatus.volume.muted: false														
12:41:25 PM	debug currentTrackData: {"sessionId":"4be73a0c-c4f0-42b8-9f6c-1c44254faf31","displayName":"Default Media Receiver"}, newTrackData: [session	Id:4be73a0c-c4f0-42b8-9f6c-1c44254faf31]													
12:41:25 PM	debug deviceStatus.volume.level: 70														
12:41:25 PM	debug Executing 'parseReceiverStatus'														
12:41:25 PM	debug Receiver has sessionId: 4be73a0c-c4f0-42b8-9f6c-1c44254faf31														
12:41:25 PM	debug 'parse', parsing: 'index:15, mac:9C4E36504EA8, ip:0A000000, port:0BB8, requestId:8a41c84d-151a-444a-9ce8-67ce83f0ff72, callback:true,	headers:SFRUUC8xLjEgMjAwIE9LDQpDb250ZW50	LVR5cGU6IGFwcGxpY	2F0aW9uL2pzb247I	GNoYXJzZXQ9dXRmLTgNCkR	hdGU6IE1vbiwgMj	YgRmViIDIwMTggMj	A6NDE6MjUgR01UDQpDb2	5uZWN0aW9uOiBrZWVwLWFsaXZlDQpDb	250ZW50LUxlbmd0aD	ogNzQ2,	bod	y:eyJyZXF1	ZXN0SWQiOjM1NTcsInN0YXR1cyI6eyJhcHBsaWNhdGlvbnMiOlt7ImFwcElkIjoiQ0MxQUQ4NDUiLCJkaXNwbGF5TmFtZSI6IkRlZmF1bHQgTWVkaWEgUmVjZWl2ZXIiLCJpc0lkbGVTY3JlZW4iOmZhbHNlLCJsYXVuY2hlZEZyb21DbG91ZCI6ZmFsc2UsIm5hbWVzcGFjZXMiOlt7Im5hbWUiOiJ1cm46eC1jYXN0OmNvbS5nb29nbGUuY2FzdC5kZWJ1Z292ZXJsYXkifSx7Im5hbWUiOiJ1cm46eC1jYXN0OmNvbS5nb29nbGUuY2FzdC5icm9hZGNhc3QifSx7Im5hbWUiOiJ1cm46eC1jYXN0OmNvbS5nb29nbGUuY2FzdC5tZWRpYSJ9XSwic2Vzc2lvbklkIjoiNGJlNzNhMGMtYzRmMC00MmI4LTlmNmMtMWM0NDI1NGZhZjM4Iiwic3RhdHVzVGV4dCI6IlJlYWR5IFRvIENhc3QiLCJ0cmFuc3BvcnRJZCI6IjRiZTczYTBjLWM0ZjAtNDJiOC05ZjZjLTFjNDQyNTRmYWYzOCJ9XSwidXNlckVxIjp7ImhpZ2hfc2hlbGYiOnsiZnJlcXVlbmN5Ijo0NTAwLCJnYWluX2RiIjoxLjUsInF1YWxpdHkiOjAuNzA3fSwibG93X3NoZWxmIjp7ImZyZXF1ZW5jeSI6MTUwLCJnYWluX2RiIjoyLCJxdWFsaXR5IjowLjcwN30sIm1heF9wZWFraW5nX2VxcyI6MCwicGVha2luZ19lcXMiOltdfSwidm9sdW1lIjp7ImNvbnRyb2xUeXBlIjoibWFzdGVyIiwibGV2ZWwiOjAuNjk5OTk5OTg4MDc5MDcxLCJtdXRlZCI6ZmFsc2UsInN0ZXBJbnRlcnZhbCI6MC4wNTAwMDAwMDA3NDUwNTgwNn19LCJ0eXBlIjoiUkVDRUlWRVJfU1RBVFVTIn0='	
12:41:25 PM	debug parse, msg.json: [status:[volume:[level:0.699999988079071, muted:false, controlType:master, stepInterval:0.05000000074505806], userEq	:[high_shelf:[gain_db:1.5, quality:0.707,	frequency:4500],	peaking_eqs:[],	low_shelf:[gain_db:2,	quality:0.707,	frequency:150],	max_peaking_eqs:0],	applications:[[appId:CC1AD845,	sessionId:4be73a	0c-c4f0	-42b	8-9f6c-1c4	4254faf38, transportId:4be73a0c-c4f0-42b8-9f6c-1c44254faf31, isIdleScreen:false, statusText:Ready To Cast, launchedFromCloud:false, displayName:Default Media Receiver, namespaces:[[name:urn:x-cast:com.google.cast.debugoverlay], [name:urn:x-cast:com.google.cast.broadcast], [name:urn:x-cast:com.google.cast.media]]]]], requestId:3557, type:RECEIVER_STATUS]	
12:41:25 PM	debug Executing 'sendHttpRequest' host: 10.0.0.120:3000 path: /getDeviceStatus?address=10.0.0.16:42022														
12:41:25 PM	debug Executing 'getDeviceStatus'														
12:40:17 PM	debug generateSwitchStatus, playing														
12:40:17 PM	debug Executing 'getMediaStatus' sessionId: 4be73a0c-c4f0-42b8-9f6c-1c44254faf31														
12:40:17 PM	debug getTrackData, keys: [subtitle]														
12:40:17 PM	debug getTrackData, keys: [title]														
12:40:17 PM	debug getTrackData, keys: [displayName]														
12:40:17 PM	debug currentTrackData: {"sessionId":"4be73a0c-c4f0-42b8-9f6c-1c44254faf31"}, newTrackData: [displayName:Default Media Receiver]														
12:40:17 PM	debug Receiver has sessionId: 4be73a0c-c4f0-42b8-9f6c-1c44254faf31														
12:40:17 PM	debug Executing 'sendHttpRequest' host: 10.0.0.120:3000 path: /getMediaStatus?address=10.0.0.16:42022&sessionId=4be73a0c-c4f0-42b8-9f6c-1c4	4254faf38													
12:40:17 PM	debug deviceStatus.volume.level: 70														
12:40:17 PM	debug currentTrackData: {}, newTrackData: [sessionId:4be73a0c-c4f0-42b8-9f6c-1c44254faf31]														
12:40:17 PM	debug deviceStatus.volume.muted: false														
12:40:17 PM	debug Executing 'parseReceiverStatus'														
12:40:17 PM	debug parse, msg.json: [status:[volume:[level:0.699999988079071, muted:false, controlType:master, stepInterval:0.05000000074505806], userEq	:[high_shelf:[gain_db:1.5, quality:0.707,	frequency:4500],	peaking_eqs:[],	low_shelf:[gain_db:2,	quality:0.707,	frequency:150],	max_peaking_eqs:0],	applications:[[appId:CC1AD845,	sessionId:4be73a	0c-c4f0	-42b	8-9f6c-1c4	4254faf38, transportId:4be73a0c-c4f0-42b8-9f6c-1c44254faf31, isIdleScreen:false, statusText:Ready To Cast, launchedFromCloud:false, displayName:Default Media Receiver, namespaces:[[name:urn:x-cast:com.google.cast.debugoverlay], [name:urn:x-cast:com.google.cast.broadcast], [name:urn:x-cast:com.google.cast.media]]]]], requestId:3554, type:RECEIVER_STATUS]	
12:40:17 PM	debug 'parse', parsing: 'index:16, mac:9C4E36504EA8, ip:0A000000, port:0BB8, requestId:6f8614d3-9f7c-4579-8374-006e1ae1c932, callback:true,	headers:SFRUUC8xLjEgMjAwIE9LDQpDb250ZW50	LVR5cGU6IGFwcGxpY	2F0aW9uL2pzb247I	GNoYXJzZXQ9dXRmLTgNCkR	hdGU6IE1vbiwgMj	YgRmViIDIwMTggMj	A6NDA6MTcgR01UDQpDb2	5uZWN0aW9uOiBrZWVwLWFsaXZlDQpDb	250ZW50LUxlbmd0aD	ogNzQ2,	bod	y:eyJyZXF1	ZXN0SWQiOjM1NTQsInN0YXR1cyI6eyJhcHBsaWNhdGlvbnMiOlt7ImFwcElkIjoiQ0MxQUQ4NDUiLCJkaXNwbGF5TmFtZSI6IkRlZmF1bHQgTWVkaWEgUmVjZWl2ZXIiLCJpc0lkbGVTY3JlZW4iOmZhbHNlLCJsYXVuY2hlZEZyb21DbG91ZCI6ZmFsc2UsIm5hbWVzcGFjZXMiOlt7Im5hbWUiOiJ1cm46eC1jYXN0OmNvbS5nb29nbGUuY2FzdC5kZWJ1Z292ZXJsYXkifSx7Im5hbWUiOiJ1cm46eC1jYXN0OmNvbS5nb29nbGUuY2FzdC5icm9hZGNhc3QifSx7Im5hbWUiOiJ1cm46eC1jYXN0OmNvbS5nb29nbGUuY2FzdC5tZWRpYSJ9XSwic2Vzc2lvbklkIjoiNGJlNzNhMGMtYzRmMC00MmI4LTlmNmMtMWM0NDI1NGZhZjM4Iiwic3RhdHVzVGV4dCI6IlJlYWR5IFRvIENhc3QiLCJ0cmFuc3BvcnRJZCI6IjRiZTczYTBjLWM0ZjAtNDJiOC05ZjZjLTFjNDQyNTRmYWYzOCJ9XSwidXNlckVxIjp7ImhpZ2hfc2hlbGYiOnsiZnJlcXVlbmN5Ijo0NTAwLCJnYWluX2RiIjoxLjUsInF1YWxpdHkiOjAuNzA3fSwibG93X3NoZWxmIjp7ImZyZXF1ZW5jeSI6MTUwLCJnYWluX2RiIjoyLCJxdWFsaXR5IjowLjcwN30sIm1heF9wZWFraW5nX2VxcyI6MCwicGVha2luZ19lcXMiOltdfSwidm9sdW1lIjp7ImNvbnRyb2xUeXBlIjoibWFzdGVyIiwibGV2ZWwiOjAuNjk5OTk5OTg4MDc5MDcxLCJtdXRlZCI6ZmFsc2UsInN0ZXBJbnRlcnZhbCI6MC4wNTAwMDAwMDA3NDUwNTgwNn19LCJ0eXBlIjoiUkVDRUlWRVJfU1RBVFVTIn0='	
12:40:18 PM	debug generateSwitchStatus, playing														
12:40:18 PM	debug Executing 'generateSwitchStatus', length:91														
12:40:17 PM	debug Executing 'generateTrackDescription', trackDescription: Default Media Receiver														
															
12:40:17 PM	debug getTrackData, keys: [subtitle]														
12:40:17 PM	debug getTrackData, keys: [title]														
12:40:17 PM	debug getTrackData, keys: [displayName]														
12:40:17 PM	debug Executing 'parseMediaStatus', mediaStatus: []														
12:40:17 PM	debug parse, msg.json: [requestId:3555, status:[], type:MEDIA_STATUS]														
12:40:17 PM	debug 'parse', parsing: 'index:16, mac:9C4E36504EA8, ip:0A000000, port:0BB8, requestId:cacf0f3d-16b3-4a94-a3da-039ada5e43ad, callback:true,	headers:SFRUUC8xLjEgMjAwIE9LDQpDb250ZW50	LVR5cGU6IGFwcGxpY	2F0aW9uL2pzb247I	GNoYXJzZXQ9dXRmLTgNCkR	hdGU6IE1vbiwgMj	YgRmViIDIwMTggMj	A6NDA6MTcgR01UDQpDb2	5uZWN0aW9uOiBrZWVwLWFsaXZlDQpDb	250ZW50LUxlbmd0aD	ogNTI=,	bod	y:eyJ0eXBl	IjoiTUVESUFfU1RBVFVTIiwic3RhdHVzIjpbXSwicmVxdWVzdElkIjozNTU1fQ=='	
12:39:29 PM	debug parse, msg.json: [currentTime:0, mediaSessionId:1, repeatMode:REPEAT_OFF, playerState:BUFFERING, items:[[activeTrackIds:[], itemId:1,	media:[duration:1.462833, streamType:BUF	FERED, contentTyp	e:audio/mp3, con	tentId:https://s3.amaz	onaws.com/smart	app-media/polly-	tts/salli/front_door	_is_now_locked.mp3, metadata:[t	itle:SmartThings,	subtit	le:S	martThings	playback, images:[[url:https://lh3.googleusercontent.com/nQBLtHKqZycERjdjMGulMLMLDoPXnrZKYoJ8ijaVs8tDD6cypInQRtxgngk9SAXHkA=w300]], type:0, metadataType:0]], autoplay:true]], playbackRate:1, activeTrackIds:[], volume:[level:1, muted:false], currentItemId:1, media:[duration:1.462833, streamType:BUFFERED, contentType:audio/mp3, contentId:https://s3.amazonaws.com/smartapp-media/polly-tts/salli/front_door_is_now_locked.mp3, metadata:[title:SmartThings, subtitle:SmartThings playback, images:[[url:https://lh3.googleusercontent.com/nQBLtHKqZycERjdjMGulMLMLDoPXnrZKYoJ8ijaVs8tDD6cypInQRtxgngk9SAXHkA=w300]], type:0, metadataType:0]], supportedMediaCommands:15]	
12:39:29 PM	debug 'parse', parsing: 'index:16, mac:9C4E36504EA8, ip:0A000000, port:0BB8, requestId:29e58ede-e4fb-4a5f-83d8-619390165cf7, callback:true,	headers:SFRUUC8xLjEgMjAwIE9LDQpDb250ZW50	LVR5cGU6IGFwcGxpY	2F0aW9uL2pzb247I	GNoYXJzZXQ9dXRmLTgNCkR	hdGU6IE1vbiwgMj	YgRmViIDIwMTggMj	A6Mzk6MjkgR01UDQpDb2	5uZWN0aW9uOiBrZWVwLWFsaXZlDQpDb	250ZW50LUxlbmd0aD	ogMTA2N	w==,	body:eyJt	ZWRpYVNlc3Npb25JZCI6MSwicGxheWJhY2tSYXRlIjoxLCJwbGF5ZXJTdGF0ZSI6IkJVRkZFUklORyIsImN1cnJlbnRUaW1lIjowLCJzdXBwb3J0ZWRNZWRpYUNvbW1hbmRzIjoxNSwidm9sdW1lIjp7ImxldmVsIjoxLCJtdXRlZCI6ZmFsc2V9LCJhY3RpdmVUcmFja0lkcyI6W10sIm1lZGlhIjp7ImNvbnRlbnRJZCI6Imh0dHBzOi8vczMuYW1hem9uYXdzLmNvbS9zbWFydGFwcC1tZWRpYS9wb2xseS10dHMvc2FsbGkvZnJvbnRfZG9vcl9pc19ub3dfbG9ja2VkLm1wMyIsImNvbnRlbnRUeXBlIjoiYXVkaW8vbXAzIiwic3RyZWFtVHlwZSI6IkJVRkZFUkVEIiwibWV0YWRhdGEiOnsidHlwZSI6MCwibWV0YWRhdGFUeXBlIjowLCJ0aXRsZSI6IlNtYXJ0VGhpbmdzIiwic3VidGl0bGUiOiJTbWFydFRoaW5ncyBwbGF5YmFjayIsImltYWdlcyI6W3sidXJsIjoiaHR0cHM6Ly9saDMuZ29vZ2xldXNlcmNvbnRlbnQuY29tL25RQkx0SEtxWnljRVJqZGpNR3VsTUxNTERvUFhuclpLWW9KOGlqYVZzOHRERDZjeXBJblFSdHhnbmdrOVNBWEhrQT13MzAwIn1dfSwiZHVyYXRpb24iOjEuNDYyODMzfSwiY3VycmVudEl0ZW1JZCI6MSwiaXRlbXMiOlt7Iml0ZW1JZCI6MSwibWVkaWEiOnsiY29udGVudElkIjoiaHR0cHM6Ly9zMy5hbWF6b25hd3MuY29tL3NtYXJ0YXBwLW1lZGlhL3BvbGx5LXR0cy9zYWxsaS9mcm9udF9kb29yX2lzX25vd19sb2NrZWQubXAzIiwiY29udGVudFR5cGUiOiJhdWRpby9tcDMiLCJzdHJlYW1UeXBlIjoiQlVGRkVSRUQiLCJtZXRhZGF0YSI6eyJ0eXBlIjowLCJtZXRhZGF0YVR5cGUiOjAsInRpdGxlIjoiU21hcnRUaGluZ3MiLCJzdWJ0aXRsZSI6IlNtYXJ0VGhpbmdzIHBsYXliYWNrIiwiaW1hZ2VzIjpbeyJ1cmwiOiJodHRwczovL2xoMy5nb29nbGV1c2VyY29udGVudC5jb20vblFCTHRIS3FaeWNFUmpkak1HdWxNTE1MRG9QWG5yWktZb0o4aWphVnM4dERENmN5cEluUVJ0eGduZ2s5U0FYSGtBPXczMDAifV19LCJkdXJhdGlvbiI6MS40NjI4MzN9LCJhdXRvcGxheSI6dHJ1ZSwiYWN0aXZlVHJhY2tJZHMiOltdfV0sInJlcGVhdE1vZGUiOiJSRVBFQVRfT0ZGIn0='	
12:39:25 PM	debug Executing 'sendHttpRequest' host: 10.0.0.120:3000 path: /setMediaPlaybackShort?address=10.0.0.16:42022&mediaType=audio/mp3&mediaUrl=h	ttps://s3.amazonaws.com/smartapp-media/po	lly-tts/salli/fro	nt_door_is_now_l	ocked.mp3&mediaStreamT	ype=BUFFERED&me	diaTitle=SmartTh	ings&mediaSubtitle=S	martThings+playback&mediaImageU	rl=https://lh3.go	ogleuse	rcon	tent.com/n	QBLtHKqZycERjdjMGulMLMLDoPXnrZKYoJ8ijaVs8tDD6cypInQRtxgngk9SAXHkA=w300	
12:39:25 PM	debug Executing 'setMediaPlaybackShort'; mediaType: audio/mp3 mediaUrl: https://s3.amazonaws.com/smartapp-media/polly-tts/salli/front_door_	is_now_locked.mp3 mediaStreamType: BUFFER	ED mediaTitle: Sm	artThings mediaS	ubtitle: SmartThings p	layback mediaIm	ageUrl: https://	lh3.googleuserconten	t.com/nQBLtHKqZycERjdjMGulMLMLD	oPXnrZKYoJ8ijaVs8	tDD6cyp	InQR	txgngk9SAX	HkA=w300	
12:39:25 PM	info Executing 'playTrack', uri: https://s3.amazonaws.com/smartapp-media/polly-tts/salli/front_door_is_now_locked.mp3 level: 0														
12:39:25 PM	info Executing 'playTrackAndRestore', uri: https://s3.amazonaws.com/smartapp-media/polly-tts/salli/front_door_is_now_locked.mp3 duration: 6	level: 0													
12:38:49 PM	debug getTrackData, keys: [title]														
12:38:49 PM	debug 'parse', parsing: 'index:15, mac:9C4E36504EA8, ip:0A000000, port:0BB8, requestId:1c00cf23-ff61-4bd6-b4dc-ca42f7226aed, callback:true,	headers:SFRUUC8xLjEgMjAwIE9LDQpDb250ZW50	LVR5cGU6IGFwcGxpY	2F0aW9uL2pzb247I	GNoYXJzZXQ9dXRmLTgNCkR	hdGU6IE1vbiwgMj	YgRmViIDIwMTggMj	A6Mzg6NDkgR01UDQpDb2	5uZWN0aW9uOiBrZWVwLWFsaXZlDQpDb	250ZW50LUxlbmd0aD	ogMzMx,	bod	y:eyJyZXF1	ZXN0SWQiOjM1NTEsInN0YXR1cyI6eyJ1c2VyRXEiOnsiaGlnaF9zaGVsZiI6eyJmcmVxdWVuY3kiOjQ1MDAsImdhaW5fZGIiOjEuNSwicXVhbGl0eSI6MC43MDd9LCJsb3dfc2hlbGYiOnsiZnJlcXVlbmN5IjoxNTAsImdhaW5fZGIiOjIsInF1YWxpdHkiOjAuNzA3fSwibWF4X3BlYWtpbmdfZXFzIjowLCJwZWFraW5nX2VxcyI6W119LCJ2b2x1bWUiOnsiY29udHJvbFR5cGUiOiJtYXN0ZXIiLCJsZXZlbCI6MC42OTk5OTk5ODgwNzkwNzEsIm11dGVkIjpmYWxzZSwic3RlcEludGVydmFsIjowLjA1MDAwMDAwMDc0NTA1ODA2fX0sInR5cGUiOiJSRUNFSVZFUl9TVEFUVVMifQ=='	
12:38:49 PM	debug Executing 'sendHttpRequest' host: 10.0.0.120:3000 path: /getDeviceStatus?address=10.0.0.16:42022														
12:38:49 PM: debug Executing 'getDeviceStatus'
12:38:17 PM: debug generateSwitchStatus, nothing playing

#300

@vervallsweg or anyone else with expertise,

I first want to say THANK YOU! I am running this on my Raspberry Pi (have configured it to auto load at boot and continuously run), and have several Google Home and Mini’s along with a Chromecast Audio. Using ST Speaker Companion smartapp, I am able to get custom notifications when a door opens, or when someone in my house is arriving. I have also configured a custom alarm notification with ST Talking Alarm Clock smartapp.

Question: do you or anyone else know how to play podcasts from the presets? The problem I’m running into is that, for example, each new daily podcast has a different URL. I would like to create a WebCore piston that plays a Cast’s certain preset each morning, which is linked to a daily podcast. The only other alternative I can think of is to somehow configure a custom alarm through my Logitech Media Server that can play on any of the cast devices.


(Matt Behnken) #301

That is the latest release version.

when does that happen (the spin and freeze up) in the smart app “cast web - service manager” ?


(Tobias) #302

You wanted to trigger it through WebCoRE right? WebCoRE has the option to just play a track if you provide the url to the mp3 file. So maybe you can use webcore to generate the link to your podcast (for example counting +1 every day) and just use the play method with the generated link.


(Tobias) #303

Cannot really follow. There should be only one version of the service manager and thats here in the master branch.

Hmm… that’s strange indeed.
So it’s not the firewall for sure. Sounds almost like a network problem. Is the hub on the same subnet, address range, etc.? Maybe try pinging the hub ip from your mac?
Sorry but I don’t really think this is an API or service manager problem. The service manager in every version calls /getDevices. This was never ever changed. The API’s /getDevices seems to be working in LAN. So I can only assume it has something to do with your network and the way your hub is connected.


(Tobias) #304

I’ll investigate. I opened a ticket and if I can find the problem, will fix it in the next release.

Thanks a lot for the detailed descriptions and logs. I think I roughly know whats wrong already :slight_smile:


(Satish P) #305

awesome @vervallsweg. Really appreciate your time and efforts. I am trying to debug too but I am still learning this ST stuff… The only thing i found confusing is that - sometimes the getDeviceStatus debug message keep continuing forever (over 10-15mins) without a Parse message… All that time, the status is PLAYING. When I see the parse debug message that trickles to getReceiverStatus or getSwitchStatus etc… then it resets the status to READY.

What is the triggering point for the parse to execute? Is it the HubResponseReceived (callback) should become TRUE?

Sorry for my ignorance in this… Trying to learn… Hope you have sometime to enlighten me.


(Tobias) #306

You mean in the ST IDE logs? If you see smth like ‘making request to /getDeviceStatus’ and then no ‘parsing msg’ or ‘hub response received’ that is either the API not answering (very unlikely) or ST just dropping logs. Yes often ST just drops some logged lines. if you log three lines in row, as a developer, it can happen that ST only shows you line 1, line 3.

Whenever the DTH makes an HTTP request and ST hub receives an answer for that request.

Somewhat. The callback is only executed once the hub receives a reply to a request it made.


(Satish P) #307

Yes, I am in the Live Logging page of the ST IDE to capture all the logs… For example look below the logs that I have captured… If you observe, the last line of the log is playing the notification (this time the status of the group is PLAYING and the time is 2:14) and all subsequent calls to getDeviceStatus doesn’t follow any parse message and then suddenly at 2:32:20 I see that Parse is executed followed by series of commands with final command being getSwitchStatus which shows Nothing Playing… At this point the group status resets to READY…

I am trying to figure out on why each call of getDeviceStatus from 2:14:58 to 2:32:17PM doesn’t get any Parse… Does this mean ST hub doesn’t receive an answer after we made the HTTP request?

 2:32:20 PM: debug generateSwitchStatus, nothing playing
 2:32:20 PM: debug Executing 'generateSwitchStatus', length:2
 2:32:20 PM: debug getTrackData, keys: [displayName]
 2:32:20 PM: debug deviceStatus.volume.muted: false
 2:32:20 PM: debug getTrackData, keys: [title]
 2:32:20 PM: debug deviceStatus.volume.level: 70
 2:32:20 PM: debug Executing 'parseReceiverStatus'
 2:32:20 PM: debug parse, msg.json: [status:[volume:[level:0.699999988079071, muted:false, controlType:master, stepInterval:0.05000000074505806], userEq:[high_shelf:[gain_db:1.5, quality:0.707, frequency:4500], peaking_eqs:[], low_shelf:[gain_db:2, quality:0.707, frequency:150], max_peaking_eqs:0]], requestId:5832, type:RECEIVER_STATUS]
 2:32:20 PM: debug 'parse', parsing: 'index:16, mac:9C4E36504E00, ip:0A000000, port:0BB0, requestId:95e89cea-c33a-49ae-8853-221abdd65700, callback:true, headers:SFRUUC8xLjEgMjAwIE9LDQpDb250ZW50LVR5cGU6IGFwcGxpY2F0aW9uL2pzb247IGNoYXJzZXQ9dXRmLTgNCkRhdGU6IFR1ZSwgMjcgRmViIDIwMTggMjI6MzI6MTkgR01UDQpDb25uZWN0aW9uOiBrZWVwLWFsaXZlDQpDb250ZW50LUxlbmd0aDogMzMx, body:eyJyZXF1ZXN0SWQiOjU4MzIsInN0YXR1cyI6eyJ1c2VyRXEiOnsiaGlnaF9zaGVsZiI6eyJmcmVxdWVuY3kiOjQ1MDAsImdhaW5fZGIiOjEuNSwicXVhbGl0eSI6MC43MDd9LCJsb3dfc2hlbGYiOnsiZnJlcXVlbmN5IjoxNTAsImdhaW5fZGIiOjIsInF1YWxpdHkiOjAuNzA3fSwibWF4X3BlYWtpbmdfZXFzIjowLCJwZWFraW5nX2VxcyI6W119LCJ2b2x1bWUiOnsiY29udHJvbFR5cGUiOiJtYXN0ZXIiLCJsZXZlbCI6MC42OTk5OTk5ODgwNzkwNzEsIm11dGVkIjpmYWxzZSwic3RlcEludGVydmFsIjowLjA1MDAwMDAwMDc0NTA1ODA2fX0sInR5cGUiOiJSRUNFSVZFUl9TVEFUVVMifQ=='
 2:32:17 PM: debug Executing 'sendHttpRequest' host: 10.0.0.120:3000 path: /getDeviceStatus?address=10.0.0.16:42022
 2:32:17 PM: debug Executing 'getDeviceStatus'
 2:31:56 PM: debug Executing 'sendHttpRequest' host: 10.0.0.120:3000 path: /getDeviceStatus?address=10.0.0.16:42022
 2:31:56 PM: debug Executing 'getDeviceStatus'
 2:30:56 PM: debug Executing 'sendHttpRequest' host: 10.0.0.120:3000 path: /getDeviceStatus?address=10.0.0.16:42022
 2:30:56 PM: debug Executing 'getDeviceStatus'
 2:30:17 PM: debug Executing 'sendHttpRequest' host: 10.0.0.120:3000 path: /getDeviceStatus?address=10.0.0.16:42022
 2:30:17 PM: debug Executing 'getDeviceStatus'
 2:29:56 PM: debug Executing 'sendHttpRequest' host: 10.0.0.120:3000 path: /getDeviceStatus?address=10.0.0.16:42022
 2:29:56 PM: debug Executing 'getDeviceStatus'
 2:28:57 PM: debug Executing 'sendHttpRequest' host: 10.0.0.120:3000 path: /getDeviceStatus?address=10.0.0.16:42022
 2:28:57 PM: debug Executing 'getDeviceStatus'
 2:28:17 PM: debug Executing 'sendHttpRequest' host: 10.0.0.120:3000 path: /getDeviceStatus?address=10.0.0.16:42022
 2:28:17 PM: debug Executing 'getDeviceStatus'
 2:27:57 PM: debug Executing 'sendHttpRequest' host: 10.0.0.120:3000 path: /getDeviceStatus?address=10.0.0.16:42022
 2:27:57 PM: debug Executing 'getDeviceStatus'
 2:26:57 PM: debug Executing 'sendHttpRequest' host: 10.0.0.120:3000 path: /getDeviceStatus?address=10.0.0.16:42022
 2:26:57 PM: debug Executing 'getDeviceStatus'
 2:26:17 PM: debug Executing 'sendHttpRequest' host: 10.0.0.120:3000 path: /getDeviceStatus?address=10.0.0.16:42022
 2:26:17 PM: debug Executing 'getDeviceStatus'
 2:25:57 PM: debug Executing 'sendHttpRequest' host: 10.0.0.120:3000 path: /getDeviceStatus?address=10.0.0.16:42022
 2:25:57 PM: debug Executing 'getDeviceStatus'
 2:24:57 PM: debug Executing 'sendHttpRequest' host: 10.0.0.120:3000 path: /getDeviceStatus?address=10.0.0.16:42022
 2:24:57 PM: debug Executing 'getDeviceStatus'
 2:24:17 PM: debug Executing 'sendHttpRequest' host: 10.0.0.120:3000 path: /getDeviceStatus?address=10.0.0.16:42022
 2:24:17 PM: debug Executing 'getDeviceStatus'
 2:23:57 PM: debug Executing 'sendHttpRequest' host: 10.0.0.120:3000 path: /getDeviceStatus?address=10.0.0.16:42022
 2:23:57 PM: debug Executing 'getDeviceStatus'
 2:22:57 PM: debug Executing 'sendHttpRequest' host: 10.0.0.120:3000 path: /getDeviceStatus?address=10.0.0.16:42022
 2:22:57 PM: debug Executing 'getDeviceStatus'
 2:22:17 PM: debug Executing 'sendHttpRequest' host: 10.0.0.120:3000 path: /getDeviceStatus?address=10.0.0.16:42022
 2:22:17 PM: debug Executing 'getDeviceStatus'
 2:21:57 PM: debug Executing 'sendHttpRequest' host: 10.0.0.120:3000 path: /getDeviceStatus?address=10.0.0.16:42022
 2:21:57 PM: debug Executing 'getDeviceStatus'
 2:20:57 PM: debug Executing 'sendHttpRequest' host: 10.0.0.120:3000 path: /getDeviceStatus?address=10.0.0.16:42022
 2:20:57 PM: debug Executing 'getDeviceStatus'
 2:20:17 PM: debug Executing 'sendHttpRequest' host: 10.0.0.120:3000 path: /getDeviceStatus?address=10.0.0.16:42022
 2:20:17 PM: debug Executing 'getDeviceStatus'
 2:19:57 PM: debug Executing 'sendHttpRequest' host: 10.0.0.120:3000 path: /getDeviceStatus?address=10.0.0.16:42022
 2:19:57 PM: debug Executing 'getDeviceStatus'
 2:18:57 PM: debug Executing 'sendHttpRequest' host: 10.0.0.120:3000 path: /getDeviceStatus?address=10.0.0.16:42022
 2:18:57 PM: debug Executing 'getDeviceStatus'
 2:18:17 PM: debug Executing 'sendHttpRequest' host: 10.0.0.120:3000 path: /getDeviceStatus?address=10.0.0.16:42022
 2:18:17 PM: debug Executing 'getDeviceStatus'
 2:17:57 PM: debug Executing 'sendHttpRequest' host: 10.0.0.120:3000 path: /getDeviceStatus?address=10.0.0.16:42022
 2:17:57 PM: debug Executing 'getDeviceStatus'
 2:16:57 PM: debug Executing 'sendHttpRequest' host: 10.0.0.120:3000 path: /getDeviceStatus?address=10.0.0.16:42022
 2:16:57 PM: debug Executing 'getDeviceStatus'
 2:16:17 PM: debug Executing 'sendHttpRequest' host: 10.0.0.120:3000 path: /getDeviceStatus?address=10.0.0.16:42022
 2:16:17 PM: debug Executing 'getDeviceStatus'
 2:14:58 PM: debug Executing 'sendHttpRequest' host: 10.0.0.120:3000 path: /setMediaPlaybackShort?address=10.0.0.16:42022&mediaType=audio/mp3&mediaUrl=https://s3.amazonaws.com/smartapp-media/polly-tts/salli/front_door_is_now_locked.mp3&mediaStreamType=BUFFERED&mediaTitle=SmartThings&mediaSubtitle=SmartThings+playback&mediaImageUrl=https://lh3.googleusercontent.com/nQBLtHKqZycERjdjMGulMLMLDoPXnrZKYoJ8ijaVs8tDD6cypInQRtxgngk9SAXHkA=w300

(Ryan Murphy) #308

Anyone else seeing the issues I described in my previous post? It seems like the volume is changing but the TTS doesn’t actually execute. I’m on the latest code but I don’t see any others reporting this issue so I’m wondering if this is a fluke.


(Rejiofoh) #309

Yes in the cast web service manager app. After I put in IPADDRESS:PORT and hit the discover devices


(Matt Behnken) #310

All my tts stopped working a week or two ago until I upgraded the cast web api to 0.2.3. the Google home would sometimes play the chime but nothing after. Actually it was really difficult to update since I had installed the api in so many places. Eventually I deleted the repo and re cloned and finally in a browser ip:port showed the version 0.2.3. then my Google homes started doing tts again.

Not to be confused with the cast web service manager smart app in ST app, being on version 0.2.