Unrecognized SSL message, plaintext connection?

maticslaszlo commented 2 years ago

My Hubitat can't communicate to Hue abot a few hours ago wants to repair, but there is an error:

LOGS:

app:2842021-10-17 21:19:29.620 infoAdding Bridge device with DNI: hue-ECB5FA2BA6C9
app:2842021-10-17 21:19:29.616 debugHub linking completed for Philips hue (2BA6C9)
app:2842021-10-17 21:19:27.543 debugSearching for Hub additions and updates
app:2842021-10-17 21:19:05.258 debugSearching for Hub additions and updates
app:2842021-10-17 21:19:03.539 errororg.codehaus.groovy.runtime.InvokerInvocationException: javax.net.ssl.SSLException: Unrecognized SSL message, plaintext connection? (bridgeLinking)
app:2842021-10-17 21:19:03.495 infoAdding Bridge device with DNI: hue-ECB5FA2BA6C9
app:2842021-10-17 21:19:03.491 debugHub linking completed for Philips hue (2BA6C9)
app:2842021-10-17 21:16:03.050 errororg.codehaus.groovy.runtime.InvokerInvocationException: javax.net.ssl.SSLException: Unrecognized SSL message, plaintext connection? (bridgeLinking)
app:2842021-10-17 21:16:02.992 infoAdding Bridge device with DNI: hue-ECB5FA2BA6C9
app:2842021-10-17 21:16:02.987 debugHub linking completed for Philips hue (2BA6C9)
app:2842021-10-17 21:16:00.710 debugSearching for Hub additions and updates
app:2842021-10-17 21:15:57.928 debugSearching for Hub additions and updates
app:2842021-10-17 21:15:55.238 debugSearching for Hub additions and updates
app:2842021-10-17 21:14:53.214 errororg.codehaus.groovy.runtime.InvokerInvocationException: javax.net.ssl.SSLException: Unrecognized SSL message, plaintext connection? (bridgeLinking)
app:2842021-10-17 21:14:53.144 infoAdding Bridge device with DNI: hue-ECB5FA2BA6C9
app:2842021-10-17 21:14:53.137 debugHub linking completed for Philips hue (2BA6C9)
app:2842021-10-17 21:14:46.597 debugSearching for Hub additions and updates

apwelsh commented 2 years ago

Thank you for reporting it here. I went through a bunch of tests for adding new hubs from scratch to make sure all my new code was working. Apparently, I missed re-testing when I converted the default protocol used to https (hue is pushing to convert to https). I reverted the hub status refresh to plain text http, and this solved the problem for me.

Let me know if the current version that I just pushed works for you.

maticslaszlo commented 2 years ago

Connect is OK for now, but when I switch from device, another error occured:


app:2842021-10-18 07:17:01.123 debugURL: https://null/api/XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX//lights/13
app:2842021-10-18 07:16:39.510 errorjava.net.UnknownHostException: null on line 937 (method getDeviceState)
app:2842021-10-18 07:16:39.480 debugURL: https://null/api/XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX//groups/9
app:2842021-10-18 07:16:36.402 errorjava.net.UnknownHostException: null on line 882 (method componentOn)
app:2842021-10-18 07:16:36.370 debugargs: [on:true]
app:2842021-10-18 07:16:36.367 debugURL: https://null/api/XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX//groups/9/action
app:2842021-10-18 07:16:33.842 errorjava.net.UnknownHostException: null: Name or service not known on line 882 (method componentOn)
app:2842021-10-18 07:16:33.805 debugargs: [on:true]
app:2842021-10-18 07:16:33.802 debugURL: https://null/api/XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX//groups/9/action
app:2842021-10-18 07:16:11.869 debugSearching for Hub additions and updates
app:2842021-10-18 07:16:10.106 errorjava.net.UnknownHostException: null: Name or service not known on line 882 (method componentOn)
app:2842021-10-18 07:16:10.050 debugargs: [on:true]
app:2842021-10-18 07:16:10.047 debugURL: https://null/api/XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX//groups/9/action
app:2842021-10-18 07:16:02.122 debugSearching for Hub additions and updates
app:2842021-10-18 07:15:52.964 errorjava.net.UnknownHostException: null: Name or service not known on line 882 (method componentOn)
app:2842021-10-18 07:15:52.839 debugargs: [on:true]
app:2842021-10-18 07:15:52.836 debugURL: https://null/api/XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX//groups/1/action
app:2842021-10-18 07:15:51.913 debugSearching for Hub additions and updates
app:2842021-10-18 07:15:41.326 debugSearching for Hub additions and updates
app:2842021-10-18 07:15:38.956 debugParsing response: [state:[on:false, alert:select, mode:homeautomation, reachable:true], swupdate:[state:notupdatable, lastinstall:null], type:On/Off light, name:Fountain pump, modelid:01MINIZB, manufacturername:SONOFF, productname:On/Off light, capabilities:[certified:false, control:[:], streaming:[renderer:false, proxy:false]], config:[archetype:plug, function:functional, direction:omnidirectional], uniqueid:84:fd:27:ff:fe:96:35:89-01, swversion:] for 19
app:2842021-10-18 07:15:38.903 debugParsing response: [state:[on:false, bri:254, hue:60292, sat:254, effect:none, xy:[0.3593, 0.3718], alert:select, colormode:xy, mode:homeautomation, reachable:true], swupdate:[state:notupdatable, lastinstall:2020-10-04T10:28:03], type:Color light, name:Color floor lamp, modelid:TRADFRI bulb E27 CWS opal 600lm, manufacturername:IKEA of Sweden, productname:Color light, capabilities:[certified:false, control:[colorgamuttype:other], streaming:[renderer:false, proxy:false]], config:[archetype:floorshade, function:decorative, direction:omnidirectional], uniqueid:90:fd:9f:ff:fe:51:ef:8b-01, swversion:1.3.013] for 13
app:2842021-10-18 07:15:38.840 debugParsing response: [state:[on:false, bri:254, hue:40170, sat:25, effect:none, xy:[0.3584, 0.3650], ct:218, alert:none, colormode:ct, mode:homeautomation, reachable:true], swupdate:[state:noupdates, lastinstall:2021-08-20T12:52:25], type:Extended color light, name:Garden table, modelid:LST003, manufacturername:Signify Netherlands B.V., productname:Hue lightstrip outdoor, capabilities:[certified:true, control:[mindimlevel:1500, colorgamuttype:C, colorgamut:[[0.6915, 0.3083], [0.1700, 0.7000], [0.1532, 0.0475]], ct:[min:153, max:500]], streaming:[renderer:true, proxy:true]], config:[archetype:huelightstrip, function:mixed, direction:omnidirectional, startup:[mode:powerfail, configured:true]], uniqueid:00:17:88:01:03:a3:89:c7-0b, swversion:1.88.1, swconfigid:6069F025, productid:Philips-LST003-1-LedStripsOutv1] for 4
app:2842021-10-18 07:15:38.796 debugParsing response: [name:Fountain, lights:[19], sensors:[], type:Room, state:[all_on:false, any_on:false], recycle:false, class:Bedroom, action:[on:false, alert:select]] for 12
app:2842021-10-18 07:15:38.760 debugParsing response: [name:Entry, lights:[18], sensors:[], type:Room, state:[all_on:false, any_on:false], recycle:false, class:Living room, action:[on:false, bri:178, alert:select]] for 10
app:2842021-10-18 07:15:38.725 debugParsing response: [name:Main, lights:[14, 15, 16, 17], sensors:[], type:Room, state:[all_on:false, any_on:false], recycle:false, class:Living room, action:[on:false, bri:244, alert:select]] for 9
app:2842021-10-18 07:15:38.676 debugParsing response: [name:Floor lamp, lights:[13], sensors:[], type:Room, state:[all_on:false, any_on:false], recycle:false, class:Living room, action:[on:false, bri:254, hue:60292, sat:254, effect:none, xy:[0.3593, 0.3718], alert:select, colormode:xy]] for 8
app:2842021-10-18 07:15:38.629 debugParsing response: [name:Fountain lights, lights:[12], sensors:[], type:Room, state:[all_on:false, any_on:false], recycle:false, class:Living room, action:[on:false, bri:1, hue:32768, sat:254, effect:none, xy:[0.1043, 0.4011], ct:153, alert:select, colormode:hs]] for 7
app:2842021-10-18 07:15:38.568 debugParsing response: [name:Garden, lights:[1, 2, 3, 5, 6, 7, 8, 9], sensors:[], type:Room, state:[all_on:false, any_on:false], recycle:false, class:Living room, action:[on:false, bri:126, hue:52428, sat:206, effect:none, xy:[0.4577, 0.4100], ct:366, alert:select, colormode:xy]] for 6
app:2842021-10-18 07:15:38.530 debugParsing response: [name:Kitchen, lights:[10, 11], sensors:[], type:Room, state:[all_on:false, any_on:false], recycle:false, class:Living room, action:[on:false, bri:254, alert:select]] for 5
app:2842021-10-18 07:15:38.450 debugParsing response: [name:Entertainment area 2, lights:[1, 2, 3, 4, 5, 6, 7], sensors:[], type:Entertainment, state:[all_on:false, any_on:false], recycle:false, class:TV, stream:[proxymode:manual, proxynode:/bridge, active:false, owner:null], locations:[1:[0.93, -0.76, 0.00], 2:[0.95, -0.03, 1.00], 3:[0.65, 0.52, 0.00], 4:[0.08, -0.30, 0.00], 5:[0.09, 0.57, 0.00], 6:[-0.28, 0.55, 0.00], 7:[-0.74, 0.54, 1.00]], action:[on:false, bri:254, hue:8401, sat:140, effect:none, xy:[0.4575, 0.4099], ct:366, alert:select, colormode:xy]] for 4
app:2842021-10-18 07:15:38.392 debugParsing response: [name:Entertainment area 1, lights:[4], sensors:[], type:Entertainment, state:[all_on:false, any_on:false], recycle:false, class:TV, stream:[proxymode:auto, proxynode:/bridge, active:false, owner:null], locations:[4:[0.08, 0.64, 0.00]], action:[on:false, bri:254, hue:40170, sat:25, effect:none, xy:[0.3584, 0.3650], ct:218, alert:none, colormode:ct]] for 3
app:2842021-10-18 07:15:38.342 debugParsing response: [name:Whole Garden, lights:[12, 9, 8, 2, 3, 1, 5, 6, 7, 4], sensors:[], type:Zone, state:[all_on:false, any_on:false], recycle:false, class:Downstairs, action:[on:false, bri:254, hue:40170, sat:25, effect:none, xy:[0.3584, 0.3650], ct:218, alert:none, colormode:ct]] for 2
app:2842021-10-18 07:15:36.840 debugParsing response: [name:Terrace, lights:[4], sensors:[], type:Room, state:[all_on:false, any_on:false], recycle:false, class:Living room, action:[on:false, bri:254, hue:40170, sat:25, effect:none, xy:[0.3584, 0.3650], ct:218, alert:none, colormode:ct]] for 1
app:2842021-10-18 07:15:36.670 infoAdding Bridge device with DNI: hue-ECB5FA2BA6C9
app:2842021-10-18 07:15:36.663 debugHub linking completed for Philips hue (2BA6C9)
app:2842021-10-18 07:15:35.210 debugSearching for Hub additions and updates
app:2842021-10-18 07:15:32.190 debugSearching for Hub additions and updates

apwelsh commented 2 years ago

Delete the app and re-install it. Then if going through the pairing process you still get this, let me know. I do not have this issue. Also, make sure you Hue software is up to date.

maticslaszlo commented 2 years ago

I've reinstalled it HUB is uptodate, Hubitat too. Pairing was successful.

app:6392021-10-18 09:03:04.470 errorjava.net.UnknownHostException: null: Name or service not known on line 937 (method getDeviceState)
app:6392021-10-18 09:01:52.770 errorjava.net.UnknownHostException: null on line 745 (method refreshHubStatus)
app:6392021-10-18 09:01:52.738 errorjava.net.UnknownHostException: null: Name or service not known on line 937 (method getDeviceState)
app:6392021-10-18 09:01:13.510 debugSearching for Hub additions and updates
app:6392021-10-18 09:01:11.956 infoAdding Bridge device with DNI: hue-ECB5FA2BA6C9
app:6392021-10-18 09:01:11.955 debugHub linking completed for Philips hue (2BA6C9)
app:6392021-10-18 09:01:10.384 debugSearching for Hub additions and updates
app:6392021-10-18 09:00:41.382 debugSearching for Hub additions and updates
app:6392021-10-18 09:00:32.003 debugSearching for Hub additions and updates
app:6392021-10-18 09:00:21.968 debugSearching for Hub additions and updates
app:6392021-10-18 09:00:11.990 debugSearching for Hub additions and updates
app:6392021-10-18 09:00:01.323 debugSearching for Hub additions and updates
app:6392021-10-18 08:59:59.875 debugSearching for Hub additions and updates
app:6392021-10-18 08:59:58.304 infoAdding Bridge device with DNI: hue-ECB5FA2BA6C9
app:6392021-10-18 08:59:58.303 debugHub linking completed for Philips hue (2BA6C9)
app:6392021-10-18 08:59:56.452 debugSearching for Hub additions and updates
app:6392021-10-18 08:59:53.820 debugCreating Hue Bridge device with dni: hue-ECB5FA2BA6C9
app:6392021-10-18 08:59:53.666 infoAdding Bridge device with DNI: hue-ECB5FA2BA6C9
app:6392021-10-18 08:59:53.659 debugHub linking completed for Philips hue (2BA6C9)
app:6392021-10-18 08:59:50.713 debugObtained credentials: [serialNumber:ecb5fa2ba6c9, networkAddress:192.168.1.187, mac:ECB5FA2BA6C9, url:http://192.168.1.187:80/, deviceAddress:80, ssdpTerm:upnp:rootdevice, hub:1, clientkey:C1F8C4E3E2549CE278AD865312B5419C, name:Philips hue (2BA6C9), ssdpPath:/description.xml, ssdpUSN:uuid:2f402f80-da50-11e1-9b23-ecb5fa2ba6c9::upnp:rootdevice, ssdpNTS:null, username:NHOIyAzQwrRvd1qwWHChpBn7PTAGshbo6G993OMt]
app:6392021-10-18 08:59:44.684 errorlink button not pressed
app:6392021-10-18 08:59:38.688 errorlink button not pressed
app:6392021-10-18 08:59:32.622 errorlink button not pressed
app:6392021-10-18 08:59:31.782 debugSearching for Hub additions and updates
app:6392021-10-18 08:59:30.064 debugSearching for Hub additions and updates
app:6392021-10-18 08:59:27.795 debugDiscovered new hub: Philips hue (2BA6C9)
app:6392021-10-18 08:59:27.778 debugIdentified model: Philips hue bridge 2015


search
dev:7132021-10-18 09:03:04.644 infoEvent Stream disconnected
dev:7132021-10-18 09:03:04.589 debugReceived unhandled Event Stream status message: ERROR: Exception during EventStream Request: java.net.UnknownHostException: null
dev:7132021-10-18 09:03:04.578 infoEvent Stream connected
dev:7132021-10-18 09:03:04.528 debugAttempting to establish streaming connection to https://null/eventstream/clip/v2
dev:7132021-10-18 09:03:04.427 debugBridge (Philips hue (2BA6C9)) refreshing
dev:7132021-10-18 09:02:04.380 infoEvent Stream disconnected
dev:7132021-10-18 09:02:04.325 debugReceived unhandled Event Stream status message: ERROR: Exception during EventStream Request: java.net.UnknownHostException: null: Name or service not known
dev:7132021-10-18 09:02:04.308 infoEvent Stream connected
dev:7132021-10-18 09:02:04.280 debugAttempting to establish streaming connection to https://null/eventstream/clip/v2
dev:7132021-10-18 09:02:03.243 debugPreferences updated
dev:7132021-10-18 09:01:52.874 infoEvent Stream disconnected
dev:7132021-10-18 09:01:52.834 infoEvent Stream connected
dev:7132021-10-18 09:01:42.316 infoEvent Stream disconnected
dev:7132021-10-18 09:01:42.275 infoEvent Stream connected
dev:7132021-10-18 08:59:55.710 infoEvent Stream disconnected
dev:7132021-10-18 08:59:55.623 infoEvent Stream connected
dev:7132021-10-18 08:59:54.484 debugPreferences updated

apwelsh commented 2 years ago

I dug deeper. I installed to a new hub, and found the actual source. I put back the secure web call, and I fixed the code that sets the bridge host setting. Now it is working on my new hub. This should be fixed for you too now. Sorry

apwelsh commented 2 years ago

the pairing only partially worked. The data was not being stored correctly. run through the hub pairing, and confirm it works

maticslaszlo commented 2 years ago

Thanks Armand, it seem, everything is working now well.

apwelsh commented 2 years ago

You're welcome. I should have spun up a new instance on the first attempt to correct this.

apwelsh / hubitat

Unrecognized SSL message, plaintext connection? #15