Upgrade binding 1.0 to 1.1.4

robotsflo commented 6 years ago

Hi guys, My Xiaomi Bluetooth temperature and humidity Sensor is detected in the inbox. But when i add it i dont see the temperature infomation.

I have seen that it has a update for your binding. The newer version is : org.eclipse.smarthome.binding.bluetooth-1.1.4

But i can't install it in the PAPER UI. I have installed the 1.0 version.

How can i update to the 1.1.4 ?

info : My openHAB version : 2.2.0 Stable

Thx for your help

vkolotov commented 6 years ago

Hi @robotsflo, there is a bug in PaperUI that makes it to always display 1.0 irrespectively what version you are actually running. In order to check what version you are running you can log in to karaf console and run this:

openhab> bundle:list | grep bluetooth
188 │ Active   │  80 │ 1.1.5.SNAPSHOT         │ org.sputnikdev:org.eclipse.smarthome.binding.bluetooth
208 │ Active   │  80 │ 1.1.2.SNAPSHOT         │ org.sputnikdev:org.eclipse.smarthome.binding.bluetooth.transport.tinyb
210 │ Active   │  80 │ 1.2.2.SNAPSHOT         │ org.sputnikdev:org.eclipse.smarthome.binding.bluetooth.transport.bluegiga

vkolotov commented 6 years ago

As per your question about temperature reading. There is another bug in PaperUI that prevents adding dynamic channels for things. Just refresh your browser tab and you should see new channels.

robotsflo commented 6 years ago

Thx @vkolotov,

My binding versions :

openhab> bundle:list | grep bluetooth 233 │ Active │ 80 │ 1.1.4 │ org.sputnikdev:org.eclipse.smarthome.binding.bluetooth 235 │ Active │ 80 │ 1.2.1 │ org.sputnikdev:org.eclipse.smarthome.binding.bluetooth.transport.bluegiga 236 │ Active │ 80 │ 1.1.1 │ org.sputnikdev:org.eclipse.smarthome.binding.bluetooth.transport.tinyb

And for the channels i have this :

I don't see temperature / humidity and battery channels. Can i add them manually ? How do this ?

Thx a lot.

vkolotov commented 6 years ago

Do you use "simple mode" ?

Looks like not, then go here and select new channels:

robotsflo commented 6 years ago

I use the simple mode :

All of channels proposed are selected.

Do you have another idea ?

vkolotov commented 6 years ago

Hm. Do you have this issue only for Temp and Hum sensors? How do your MiFlora sensors behave? Do you see channels for them?

robotsflo commented 6 years ago

When i have seen your binding i have bought a 1 temp / hum sensor but i don't have MiFlora. When i delete the bluetooth device of openhab and i try another add it -> i have the same problem : Just theses channels (all selected and browser refreshed) :

vkolotov commented 6 years ago

Ok. First of all, please do not enable "Connection Control". Xiaomi sensors work without establishing connection to them.

Could you please confirm what type of adapter you are suing? What OS and hardware?

robotsflo commented 6 years ago

Ok, i have disable this.

I use a raspberry pi 3 with built-in bluetooth. My OS is Raspbian.

vkolotov commented 6 years ago

Please just double check now if you get the new channels.

If not, it is time to have a look what we have in the logs.

vkolotov commented 6 years ago

Please enable logging as per this in your karaf console:

openhab> log:info DEBUG org.sputnikdev.bluetooth.manager.transport.tinyb
openhab> log:set DEBUG org.sputnikdev.bluetooth.manager.impl

And then please capture logs from the point when you add a new device from the inbox (so you have to delete existing thing and then add new). Wait for 5 mins, capture that log and post it here. You may want to replace your MAC addresses if you have some concerns (I would not have any). If you do, pls replace adapter mac with 11:11:11:11:11:11:11 and the device mac with 22:22:22:22:22:22.

robotsflo commented 6 years ago

This is an extract of my logs : (sorry for layout)

23:43:07.741 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Current target / new target: /11:11:11:11:11:11/22:22:22:22:22:22 / /11:11:11:11:11:11/22:22:22:22:22:22
23:43:10.074 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /11:11:11:11:11:11/22:22:22:22:22:22
23:43:10.076 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Getting blocked: tinyb://11:11:11:11:11:11/22:22:22:22:22:22
23:43:10.077 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Checking if device connected: tinyb://11:11:11:11:11:11/22:22:22:22:22:22
23:43:10.079 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Updating online state: /11:11:11:11:11:11/22:22:22:22:22:22 : true (current) / false (new)
23:43:10.081 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Notifying device governor listener (online): /11:11:11:11:11:11/22:22:22:22:22:22 : 1 : false
23:43:10.082 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Setting online: /XX:XX:XX:XX:XX:XX/22:22:22:22:22:22 : true / false
23:43:10.084 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Governor has been updated: /11:11:11:11:11:11/22:22:22:22:22:22
23:43:10.093 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_ble_4C65A8D43A51_online changed from ON to OFF
23:43:10.094 [DEBUG] [manager.impl.CompletableFutureService] - Trying to complete futures: /11:11:11:11:11:11/22:22:22:22:22:22 : 0
23:43:10.103 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'bluetooth:ble:4C65A8D43A51' changed from ONLINE to OFFLINE
23:43:11.909 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 10 discovered devices
23:43:11.912 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://11:11:11:11:11:11/55:CF:18:D1:F4:8E [55-CF-18-D1-F4-8E] : 1
23:43:11.917 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://11:11:11:11:11:11/E9:6C:F6:6D:B9:68 [N013W] : 1
23:43:11.929 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://11:11:11:11:11:11/04:52:C7:B2:64:06 [LE-Bose Revolve SoundLink] : 1
23:43:11.940 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://11:11:11:11:11:11/F5:B3:4D:6E:03:66 [MI Band 2] : 1
23:43:11.952 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://11:11:11:11:11:11/22:22:22:22:22:22 [MJ_HT_V1] : 1
23:43:11.959 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://11:11:11:11:11:11/45:E7:DC:94:30:04 [45-E7-DC-94-30-04] : 1
23:43:11.968 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://11:11:11:11:11:11/2C:41:A1:02:09:EB [LE-L'ouïe fine] : 1
23:43:11.980 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://11:11:11:11:11:11/6B:1F:51:1A:28:F8 [6B-1F-51-1A-28-F8] : 1
23:43:11.991 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://11:11:11:11:11:11/78:4F:43:82:F7:31 [78-4F-43-82-F7-31] : 1
23:43:12.002 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://11:11:11:11:11:11/15:3D:3D:10:3D:3B [Wistiki] : 1
23:43:12.014 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Lost: 0; New: 0; Rediscovered: 10; Stale: 0
23:43:15.349 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 1 discovered adapters
23:43:17.100 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /11:11:11:11:11:11
23:43:17.102 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Updating adapter governor: /11:11:11:11:11:11
23:43:17.104 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Governor has been updated: /11:11:11:11:11:11
23:43:17.106 [DEBUG] [manager.impl.CompletableFutureService] - Trying to complete futures: /11:11:11:11:11:11 : 0
23:43:17.123 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_adapter_B827EB4683F3_last_updated changed from 2018-04-30T23:43:07.099+0200 to 2018-04-30T23:43:17.106+0200
23:43:17.743 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Checking if delegates are stale: /XX:XX:XX:XX:XX:XX/22:22:22:22:22:22 / false / false
23:43:17.751 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Device /11:11:11:11:11:11/22:22:22:22:22:22 last advertised (52s) ago and last interacted (38s) ago. Stale timeout: 60s. Device is considered stale: false
23:43:17.753 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Updating connection target: /XX:XX:XX:XX:XX:XX/22:22:22:22:22:22 : false
23:43:17.755 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Current target / new target: /11:11:11:11:11:11/22:22:22:22:22:22 / /11:11:11:11:11:11/22:22:22:22:22:22
23:43:20.097 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /11:11:11:11:11:11/22:22:22:22:22:22
23:43:20.101 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Getting blocked: tinyb://11:11:11:11:11:11/22:22:22:22:22:22
23:43:20.102 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Checking if device connected: tinyb://11:11:11:11:11:11/22:22:22:22:22:22
23:43:20.104 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Governor has been updated: /11:11:11:11:11:11/22:22:22:22:22:22
23:43:20.106 [DEBUG] [manager.impl.CompletableFutureService] - Trying to complete futures: /11:11:11:11:11:11/22:22:22:22:22:22 : 0
23:43:22.084 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 10 discovered devices
23:43:22.090 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://11:11:11:11:11:11/55:CF:18:D1:F4:8E [55-CF-18-D1-F4-8E] : 1
23:43:22.098 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://11:11:11:11:11:11/E9:6C:F6:6D:B9:68 [N013W] : 1
23:43:22.105 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://11:11:11:11:11:11/04:52:C7:B2:64:06 [LE-Bose Revolve SoundLink] : 1
23:43:22.113 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://11:11:11:11:11:11/F5:B3:4D:6E:03:66 [MI Band 2] : 1
23:43:22.121 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://11:11:11:11:11:11/22:22:22:22:22:22 [MJ_HT_V1] : 1
23:43:22.125 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://11:11:11:11:11:11/45:E7:DC:94:30:04 [45-E7-DC-94-30-04] : 1
23:43:22.133 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://11:11:11:11:11:11/2C:41:A1:02:09:EB [LE-L'ouïe fine] : 1
23:43:22.141 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://11:11:11:11:11:11/6B:1F:51:1A:28:F8 [6B-1F-51-1A-28-F8] : 1
23:43:22.148 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://11:11:11:11:11:11/78:4F:43:82:F7:31 [78-4F-43-82-F7-31] : 1
23:43:22.156 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://11:11:11:11:11:11/15:3D:3D:10:3D:3B [Wistiki] : 1
23:43:22.165 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Lost: 0; New: 0; Rediscovered: 10; Stale: 0
23:43:25.354 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 1 discovered adapters
23:43:27.109 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /11:11:11:11:11:11
23:43:27.118 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Updating adapter governor: /11:11:11:11:11:11
23:43:27.125 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Governor has been updated: /11:11:11:11:11:11
23:43:27.130 [DEBUG] [manager.impl.CompletableFutureService] - Trying to complete futures: /11:11:11:11:11:11 : 0
23:43:27.160 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_adapter_B827EB4683F3_last_updated changed from 2018-04-30T23:43:17.106+0200 to 2018-04-30T23:43:27.130+0200
23:43:27.757 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Checking if delegates are stale: /XX:XX:XX:XX:XX:XX/22:22:22:22:22:22 / false / false
23:43:27.766 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Device /11:11:11:11:11:11/22:22:22:22:22:22 last advertised (62s) ago and last interacted (48s) ago. Stale timeout: 60s. Device is considered stale: false
23:43:27.774 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Updating connection target: /XX:XX:XX:XX:XX:XX/22:22:22:22:22:22 : false
23:43:27.780 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Current target / new target: /11:11:11:11:11:11/22:22:22:22:22:22 / /11:11:11:11:11:11/22:22:22:22:22:22
23:43:30.106 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /11:11:11:11:11:11/22:22:22:22:22:22
23:43:30.114 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Getting blocked: tinyb://11:11:11:11:11:11/22:22:22:22:22:22
23:43:30.121 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Checking if device connected: tinyb://11:11:11:11:11:11/22:22:22:22:22:22
23:43:30.128 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Governor has been updated: /11:11:11:11:11:11/22:22:22:22:22:22
23:43:30.134 [DEBUG] [manager.impl.CompletableFutureService] - Trying to complete futures: /11:11:11:11:11:11/22:22:22:22:22:22 : 0
23:43:32.229 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 10 discovered devices
23:43:32.236 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://11:11:11:11:11:11/55:CF:18:D1:F4:8E [55-CF-18-D1-F4-8E] : 1
23:43:32.246 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://11:11:11:11:11:11/E9:6C:F6:6D:B9:68 [N013W] : 1
23:43:32.259 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://11:11:11:11:11:11/04:52:C7:B2:64:06 [LE-Bose Revolve SoundLink] : 1
23:43:32.344 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://11:11:11:11:11:11/F5:B3:4D:6E:03:66 [MI Band 2] : 1
23:43:32.351 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://11:11:11:11:11:11/22:22:22:22:22:22 [MJ_HT_V1] : 1
23:43:32.356 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://11:11:11:11:11:11/45:E7:DC:94:30:04 [45-E7-DC-94-30-04] : 1
23:43:32.363 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://11:11:11:11:11:11/2C:41:A1:02:09:EB [LE-L'ouïe fine] : 1
23:43:32.371 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://11:11:11:11:11:11/6B:1F:51:1A:28:F8 [6B-1F-51-1A-28-F8] : 1
23:43:32.378 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://11:11:11:11:11:11/78:4F:43:82:F7:31 [78-4F-43-82-F7-31] : 1
23:43:32.385 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://11:11:11:11:11:11/15:3D:3D:10:3D:3B [Wistiki] : 1
23:43:32.393 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Lost: 0; New: 0; Rediscovered: 10; Stale: 0
23:43:35.363 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 1 discovered adapters
23:43:37.131 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /11:11:11:11:11:11
23:43:37.137 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Updating adapter governor: /11:11:11:11:11:11
23:43:37.143 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Governor has been updated: /11:11:11:11:11:11
23:43:37.148 [DEBUG] [manager.impl.CompletableFutureService] - Trying to complete futures: /11:11:11:11:11:11 : 0
23:43:37.164 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_adapter_B827EB4683F3_last_updated changed from 2018-04-30T23:43:27.130+0200 to 2018-04-30T23:43:37.148+0200
23:43:37.788 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Checking if delegates are stale: /XX:XX:XX:XX:XX:XX/22:22:22:22:22:22 / false / false
23:43:37.793 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Device /11:11:11:11:11:11/22:22:22:22:22:22 last advertised (72s) ago and last interacted (58s) ago. Stale timeout: 60s. Device is considered stale: false
23:43:37.796 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Updating connection target: /XX:XX:XX:XX:XX:XX/22:22:22:22:22:22 : false
23:43:37.799 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Current target / new target: /11:11:11:11:11:11/22:22:22:22:22:22 / /11:11:11:11:11:11/22:22:22:22:22:22
23:43:40.134 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /11:11:11:11:11:11/22:22:22:22:22:22
23:43:40.141 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Getting blocked: tinyb://11:11:11:11:11:11/22:22:22:22:22:22
23:43:40.146 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Checking if device connected: tinyb://11:11:11:11:11:11/22:22:22:22:22:22
23:43:40.158 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Governor has been updated: /11:11:11:11:11:11/22:22:22:22:22:22
23:43:40.171 [DEBUG] [manager.impl.CompletableFutureService] - Trying to complete futures: /11:11:11:11:11:11/22:22:22:22:22:22 : 0
23:43:42.457 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 10 discovered devices
23:43:42.470 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://11:11:11:11:11:11/55:CF:18:D1:F4:8E [55-CF-18-D1-F4-8E] : 1
23:43:42.483 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://11:11:11:11:11:11/E9:6C:F6:6D:B9:68 [N013W] : 1
23:43:42.500 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://11:11:11:11:11:11/04:52:C7:B2:64:06 [LE-Bose Revolve SoundLink] : 1
23:43:42.516 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://11:11:11:11:11:11/F5:B3:4D:6E:03:66 [MI Band 2] : 1
23:43:42.530 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://11:11:11:11:11:11/22:22:22:22:22:22 [MJ_HT_V1] : 1
23:43:42.536 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://11:11:11:11:11:11/45:E7:DC:94:30:04 [45-E7-DC-94-30-04] : 1
23:43:42.544 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://11:11:11:11:11:11/2C:41:A1:02:09:EB [LE-L'ouïe fine] : 1
23:43:42.553 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://11:11:11:11:11:11/6B:1F:51:1A:28:F8 [6B-1F-51-1A-28-F8] : 1
23:43:42.561 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://11:11:11:11:11:11/78:4F:43:82:F7:31 [78-4F-43-82-F7-31] : 1
23:43:42.570 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://11:11:11:11:11:11/15:3D:3D:10:3D:3B [Wistiki] : 1
23:43:42.579 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Lost: 0; New: 0; Rediscovered: 10; Stale: 0

vkolotov commented 6 years ago

Thanks for that. But it does not seem to me what I wanted :)

Your log is only for 30 secs, and also it does not include the very beginning where you add a new device from the inbox.

Actually, can you please do the following?

Remove your thing from OH.
Restart OH
Start capturing (just open karaf console)
Add your device from the inbox
wait for 5 mins
Post logs here from the beginning.

robotsflo commented 6 years ago

                          __  _____    ____
  ____  ____  ___  ____  / / / /   |  / __ )
 / __ \/ __ \/ _ \/ __ \/ /_/ / /| | / __  |
/ /_/ / /_/ /  __/ / / / __  / ___ |/ /_/ /
\____/ .___/\___/_/ /_/_/ /_/_/  |_/_____/
    /_/                        2.2.0
                               Release Build

Hit '<tab>' for a list of available commands
and '[cmd] --help' for help on a specific command.
Hit '<ctrl-d>' or type 'system:shutdown' or 'logout' to shutdown openHAB.
openhab> log:tail
00:06:40.715 [INFO ] [legram.internal.TelegramActionService] - Bot bot1 loaded from config file
00:06:40.969 [INFO ] [b.io.caldav.internal.CalDavLoaderImpl] - CalDAV IO is properly configured.
00:06:40.998 [INFO ] [ab.core.service.AbstractActiveService] - CalDav Loader has been started
00:06:41.532 [INFO ] [b.io.caldav.internal.CalDavLoaderImpl] - reload job scheduled for: domotique
00:07:15.843 [INFO ] [thome.event.ItemChannelLinkAddedEvent] - Link 'bluetooth_adapter_B827EB4683F3_discovering_control-bluetooth:adapter:B827EB4683F3:discovering-control' has been added.
00:07:15.848 [INFO ] [thome.event.ItemChannelLinkAddedEvent] - Link 'bluetooth_adapter_B827EB4683F3_last_updated-bluetooth:adapter:B827EB4683F3:last-updated' has been added.
00:07:15.853 [INFO ] [thome.event.ItemChannelLinkAddedEvent] - Link 'bluetooth_adapter_B827EB4683F3_discovering-bluetooth:adapter:B827EB4683F3:discovering' has been added.
00:07:17.803 [INFO ] [i.dashboard.internal.DashboardService] - Started dashboard at http://10.10.10.14:8080
00:07:17.816 [INFO ] [i.dashboard.internal.DashboardService] - Started dashboard at https://10.10.10.14:8443
00:07:19.558 [INFO ] [.basic.internal.servlet.WebAppServlet] - Started Basic UI at /basicui/app
00:07:19.771 [INFO ] [marthome.ui.paper.internal.PaperUIApp] - Started Paper UI at /paperui
00:07:21.157 [INFO ] [bpanel.internal.HABPanelDashboardTile] - Started HABPanel at /habpanel
00:07:21.369 [INFO ] [ab.core.service.AbstractActiveService] - HTTP Refresh Service has been started
00:07:21.428 [INFO ] [openhab.io.transport.mqtt.MqttService] - MQTT Service initialization completed.
00:07:21.434 [INFO ] [rt.mqtt.internal.MqttBrokerConnection] - Starting MQTT broker connection 'mosquitto'
00:07:22.461 [ERROR] [ab.core.service.AbstractActiveService] - Error while executing background thread HTTP Refresh Service
java.lang.IllegalArgumentException: json string can not be null or empty
        at com.jayway.jsonpath.internal.Utils.notEmpty(Utils.java:386) [221:com.jayway.jsonpath.json-path:2.1.0]
        at com.jayway.jsonpath.internal.JsonContext.parse(JsonContext.java:81) [221:com.jayway.jsonpath.json-path:2.1.0]
        at com.jayway.jsonpath.JsonPath.read(JsonPath.java:502) [221:com.jayway.jsonpath.json-path:2.1.0]
        at org.eclipse.smarthome.transform.jsonpath.internal.JSonPathTransformationService.transform(JSonPathTransformationService.java:55) [223:org.eclipse.smarthome.transform.jsonpath:0.10.0.b1]
        at org.openhab.core.transform.TransformationHelper$TransformationServiceDelegate.transform(TransformationHelper.java:65) [208:org.openhab.core.compat1x:2.2.0]
        at org.openhab.binding.http.internal.HttpBinding.execute(HttpBinding.java:194) [213:org.openhab.binding.http:1.11.0]
        at org.openhab.core.binding.AbstractActiveBinding$BindingActiveService.execute(AbstractActiveBinding.java:144) [208:org.openhab.core.compat1x:2.2.0]
        at org.openhab.core.service.AbstractActiveService$RefreshThread.run(AbstractActiveService.java:166) [208:org.openhab.core.compat1x:2.2.0]
00:07:22.529 [INFO ] [smarthome.event.ItemStateChangedEvent] - Velo_LilleF changed from NULL to 21
00:07:22.619 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_adapter_B827EB4683F3_discovering_control changed from NULL to ON
00:07:22.652 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_adapter_B827EB4683F3_discovering changed from NULL to OFF
00:07:25.059 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Starting bluetooth manager: 1d16d7f
00:07:25.427 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'bluetooth:adapter:B827EB4683F3' changed from UNINITIALIZED to INITIALIZING
00:07:25.424 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Register tinyb transport
00:07:25.450 [WARN ] [e.core.thing.binding.BaseThingHandler] - BaseThingHandler.initialize() will be removed soon, ThingStatus can be set manually via updateStatus(ThingStatus.ONLINE)
00:07:25.456 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'bluetooth:adapter:B827EB4683F3' changed from INITIALIZING to ONLINE
00:07:25.472 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Creating a new governor: /B8:27:EB:46:83:F3
00:07:25.521 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Initializing governor: /B8:27:EB:46:83:F3
00:07:25.530 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /B8:27:EB:46:83:F3
00:07:25.538 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Trying to find object factory: /B8:27:EB:46:83:F3
00:07:25.547 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Protocol is unknown. Trying to find factory amongst discovered adapters: /B8:27:EB:46:83:F3
00:07:25.555 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 1 discovered adapters
00:07:25.565 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Governor is not ready. Enforcing an explicit update: /B8:27:EB:46:83:F3
00:07:25.565 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Governor is not ready. Enforcing an explicit update: /B8:27:EB:46:83:F3
00:07:25.578 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /B8:27:EB:46:83:F3
00:07:25.581 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /B8:27:EB:46:83:F3
00:07:25.579 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Governor is not ready. Enforcing an explicit update: /B8:27:EB:46:83:F3
00:07:25.583 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Trying to find object factory: /B8:27:EB:46:83:F3
00:07:25.602 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /B8:27:EB:46:83:F3
00:07:25.606 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Protocol is unknown. Trying to find factory amongst discovered adapters: /B8:27:EB:46:83:F3
00:07:25.615 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_adapter_B827EB4683F3_discovering_control changed from ON to OFF
00:07:25.620 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Governor is not ready. Enforcing an explicit update: /B8:27:EB:46:83:F3
00:07:25.634 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_adapter_B827EB4683F3_discovering_control changed from OFF to ON
00:07:25.637 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /B8:27:EB:46:83:F3
00:07:25.646 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Trying to find object factory: /B8:27:EB:46:83:F3
00:07:25.656 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Protocol is unknown. Trying to find factory amongst discovered adapters: /B8:27:EB:46:83:F3
00:07:25.666 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Matching adapter found amongst discovered adapters: /B8:27:EB:46:83:F3
00:07:25.677 [WARN ] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] is not registered.
00:07:25.734 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 10 discovered devices
00:07:25.767 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/E9:6C:F6:6D:B9:68 [N013W] : 1
00:07:25.796 [INFO ] [smarthome.event.InboxAddedEvent      ] - Discovery Result with UID 'bluetooth:ble:E96CF66DB968' has been added.
00:07:25.796 [INFO ] [ig.discovery.internal.PersistentInbox] - Added new thing 'bluetooth:ble:E96CF66DB968' to inbox.
00:07:25.808 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/F5:B3:4D:6E:03:66 [MI Band 2] : 1
00:07:25.821 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Governor is not ready. Enforcing an explicit update: /B8:27:EB:46:83:F3
00:07:25.828 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /B8:27:EB:46:83:F3
00:07:25.830 [INFO ] [smarthome.event.InboxAddedEvent      ] - Discovery Result with UID 'bluetooth:ble:F5B34D6E0366' has been added.
00:07:25.833 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Trying to find object factory: /B8:27:EB:46:83:F3
00:07:25.842 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Protocol is unknown. Trying to find factory amongst discovered adapters: /B8:27:EB:46:83:F3
00:07:25.846 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_adapter_B827EB4683F3_last_updated changed from NULL to 2018-05-01T00:07:25.688+0200
00:07:25.848 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Matching adapter found amongst discovered adapters: /B8:27:EB:46:83:F3
00:07:25.844 [INFO ] [ig.discovery.internal.PersistentInbox] - Added new thing 'bluetooth:ble:F5B34D6E0366' to inbox.
00:07:25.855 [WARN ] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] is not registered.
00:07:25.863 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/04:52:C7:B2:64:06 [LE-Bose Revolve SoundLink] : 1
00:07:25.873 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'bluetooth:adapter:B827EB4683F3' changed from ONLINE to OFFLINE

00:07:27.258 [INFO ] [smarthome.event.InboxAddedEvent      ] - Discovery Result with UID 'bluetooth:ble:0452C7B26406' has been added.
00:07:27.257 [INFO ] [ig.discovery.internal.PersistentInbox] - Added new thing 'bluetooth:ble:0452C7B26406' to inbox.
00:07:27.271 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/45:E7:DC:94:30:04 [45-E7-DC-94-30-04] : 1
00:07:27.694 [INFO ] [ig.discovery.internal.PersistentInbox] - Added new thing 'bluetooth:ble:45E7DC943004' to inbox.
00:07:27.699 [INFO ] [smarthome.event.InboxAddedEvent      ] - Discovery Result with UID 'bluetooth:ble:45E7DC943004' has been added.
00:07:27.703 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/2C:41:A1:02:09:EB [LE-L'ouïe fine] : 1
00:07:27.724 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'tradfri:0100:gwb072bf27b3d3:65539' changed from UNKNOWN to ONLINE
00:07:27.736 [INFO ] [ig.discovery.internal.PersistentInbox] - Added new thing 'bluetooth:ble:2C41A10209EB' to inbox.
00:07:27.738 [INFO ] [smarthome.event.InboxAddedEvent      ] - Discovery Result with UID 'bluetooth:ble:2C41A10209EB' has been added.
00:07:27.744 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/6B:1F:51:1A:28:F8 [6B-1F-51-1A-28-F8] : 1
00:07:27.750 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'tradfri:0100:gwb072bf27b3d3:65537' changed from UNKNOWN to ONLINE
00:07:27.766 [INFO ] [smarthome.event.InboxAddedEvent      ] - Discovery Result with UID 'bluetooth:ble:6B1F511A28F8' has been added.
00:07:27.765 [INFO ] [ig.discovery.internal.PersistentInbox] - Added new thing 'bluetooth:ble:6B1F511A28F8' to inbox.
00:07:27.776 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/59:AB:35:B4:D3:5D [59-AB-35-B4-D3-5D] : 1
00:07:27.796 [INFO ] [ig.discovery.internal.PersistentInbox] - Added new thing 'bluetooth:ble:59AB35B4D35D' to inbox.
00:07:27.798 [INFO ] [smarthome.event.InboxAddedEvent      ] - Discovery Result with UID 'bluetooth:ble:59AB35B4D35D' has been added.
00:07:27.805 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/78:4F:43:82:F7:31 [78-4F-43-82-F7-31] : 1
00:07:27.824 [INFO ] [ig.discovery.internal.PersistentInbox] - Added new thing 'bluetooth:ble:784F4382F731' to inbox.
00:07:27.826 [INFO ] [smarthome.event.InboxAddedEvent      ] - Discovery Result with UID 'bluetooth:ble:784F4382F731' has been added.
00:07:27.832 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/15:3D:3D:10:3D:3B [Wistiki] : 1
00:07:27.854 [INFO ] [smarthome.event.InboxAddedEvent      ] - Discovery Result with UID 'bluetooth:ble:153D3D103D3B' has been added.
00:07:27.852 [INFO ] [ig.discovery.internal.PersistentInbox] - Added new thing 'bluetooth:ble:153D3D103D3B' to inbox.
00:07:27.865 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/53:35:1D:15:FA:67 [53-35-1D-15-FA-67] : 1
00:07:27.882 [INFO ] [smarthome.event.InboxAddedEvent      ] - Discovery Result with UID 'bluetooth:ble:53351D15FA67' has been added.
00:07:27.880 [INFO ] [ig.discovery.internal.PersistentInbox] - Added new thing 'bluetooth:ble:53351D15FA67' to inbox.
00:07:27.897 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Lost: 0; New: 10; Rediscovered: 0; Stale: 0
00:07:30.566 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /B8:27:EB:46:83:F3
00:07:30.574 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Trying to find object factory: /B8:27:EB:46:83:F3
00:07:30.581 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Protocol is unknown. Trying to find factory amongst discovered adapters: /B8:27:EB:46:83:F3
00:07:30.588 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Matching adapter found amongst discovered adapters: /B8:27:EB:46:83:F3
00:07:30.606 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - A new native object has been acquired: /B8:27:EB:46:83:F3
00:07:30.614 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Initializing governor with the new native object: /B8:27:EB:46:83:F3
00:07:30.621 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Initializing adapter governor: /B8:27:EB:46:83:F3
00:07:30.629 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Enabling powered notifications: /B8:27:EB:46:83:F3 : true
00:07:30.641 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 1 discovered adapters
00:07:30.642 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Enable powered notifications: tinyb://B8:27:EB:46:83:F3
00:07:30.659 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Enabling discovering notifications: /B8:27:EB:46:83:F3 : true
00:07:30.669 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Enable discovering notifications: tinyb://B8:27:EB:46:83:F3
00:07:30.686 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Updating adapter governor: /B8:27:EB:46:83:F3
00:07:30.693 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Starting discovery: /B8:27:EB:46:83:F3
00:07:30.700 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Starting discovery: tinyb://B8:27:EB:46:83:F3
00:07:30.707 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'bluetooth:adapter:B827EB4683F3' changed from OFFLINE to ONLINE
00:07:30.705 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying manager listeners (governor ready): 0 : true
00:07:30.714 [WARN ] [.impl.AbstractBluetoothObjectGovernor] - Error occurred while updating governor: /B8:27:EB:46:83:F3 / d70bcb : GDBus.Error:org.freedesktop.DBus.Error.UnknownMethod: Method "SetDiscoveryFilter" with signature "a{sv}" on interface "org.bluez.Adapter1" doesn't exist

00:07:30.719 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Resetting governor. Descendants first: /B8:27:EB:46:83:F3
00:07:30.731 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Resetting adapter governor: /B8:27:EB:46:83:F3
00:07:30.735 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Disable powered notifications: tinyb://B8:27:EB:46:83:F3
00:07:30.740 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Disable discovering notifications: tinyb://B8:27:EB:46:83:F3
00:07:30.744 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Stopping discovery: tinyb://B8:27:EB:46:83:F3
00:07:30.751 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying manager listeners (governor ready): 0 : false
00:07:30.752 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'bluetooth:adapter:B827EB4683F3' changed from ONLINE to OFFLINE
00:07:30.749 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Trying to find object factory: tinyb://B8:27:EB:46:83:F3
00:07:30.768 [DEBUG] [.manager.transport.tinyb.TinyBFactory] - Bluetooth object disposal requested: tinyb://B8:27:EB:46:83:F3
00:07:30.774 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Disposing adapter: B8:27:EB:46:83:F3
00:07:30.823 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Disposing device: 78:4F:43:82:F7:31
00:07:30.848 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Disposing device: F5:B3:4D:6E:03:66
00:07:31.279 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Disposing device: 2C:41:A1:02:09:EB
00:07:31.299 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Disposing device: 6B:1F:51:1A:28:F8
00:07:31.310 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Disposing device: 59:AB:35:B4:D3:5D
00:07:31.321 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Disposing device: 15:3D:3D:10:3D:3B
00:07:31.355 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Disposing device: 45:E7:DC:94:30:04
00:07:31.366 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Disposing device: 53:35:1D:15:FA:67
00:07:31.376 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Disposing device: 04:52:C7:B2:64:06
00:07:31.396 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Disposing device: E9:6C:F6:6D:B9:68
00:07:31.426 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Governor has been reset: /B8:27:EB:46:83:F3
00:07:32.907 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 0 discovered devices
00:07:32.915 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Device has been lost: tinyb://B8:27:EB:46:83:F3/E9:6C:F6:6D:B9:68
00:07:32.929 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Device has been lost: tinyb://B8:27:EB:46:83:F3/04:52:C7:B2:64:06
00:07:32.936 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Device has been lost: tinyb://B8:27:EB:46:83:F3/F5:B3:4D:6E:03:66
00:07:32.943 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Device has been lost: tinyb://B8:27:EB:46:83:F3/45:E7:DC:94:30:04
00:07:32.950 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Device has been lost: tinyb://B8:27:EB:46:83:F3/6B:1F:51:1A:28:F8
00:07:32.957 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Device has been lost: tinyb://B8:27:EB:46:83:F3/2C:41:A1:02:09:EB
00:07:32.964 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Device has been lost: tinyb://B8:27:EB:46:83:F3/59:AB:35:B4:D3:5D
00:07:32.971 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Device has been lost: tinyb://B8:27:EB:46:83:F3/78:4F:43:82:F7:31
00:07:32.978 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Device has been lost: tinyb://B8:27:EB:46:83:F3/15:3D:3D:10:3D:3B
00:07:32.986 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Device has been lost: tinyb://B8:27:EB:46:83:F3/53:35:1D:15:FA:67
00:07:32.999 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Lost: 10; New: 0; Rediscovered: 0; Stale: 0
00:07:40.652 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 1 discovered adapters
00:07:40.878 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Governor is not ready. Enforcing an explicit update: /B8:27:EB:46:83:F3
00:07:40.886 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /B8:27:EB:46:83:F3
00:07:40.892 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Trying to find object factory: tinyb://B8:27:EB:46:83:F3
00:07:40.899 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - A new native object has been acquired: /B8:27:EB:46:83:F3
00:07:40.905 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Initializing governor with the new native object: /B8:27:EB:46:83:F3
00:07:40.911 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Initializing adapter governor: /B8:27:EB:46:83:F3
00:07:40.917 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Enabling powered notifications: /B8:27:EB:46:83:F3 : true
00:07:40.924 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Enable powered notifications: tinyb://B8:27:EB:46:83:F3
00:07:40.930 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Enabling discovering notifications: /B8:27:EB:46:83:F3 : true
00:07:40.937 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Enable discovering notifications: tinyb://B8:27:EB:46:83:F3
00:07:40.945 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying manager listeners (governor ready): 0 : true
00:07:40.949 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'bluetooth:adapter:B827EB4683F3' changed from OFFLINE to ONLINE
00:07:40.944 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Updating adapter governor: /B8:27:EB:46:83:F3
00:07:40.961 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Starting discovery: /B8:27:EB:46:83:F3
00:07:40.967 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Starting discovery: tinyb://B8:27:EB:46:83:F3
00:07:40.977 [WARN ] [.impl.AbstractBluetoothObjectGovernor] - Error occurred while updating governor: /B8:27:EB:46:83:F3 / 807d81 : GDBus.Error:org.freedesktop.DBus.Error.UnknownMethod: Method "SetDiscoveryFilter" with signature "a{sv}" on interface "org.bluez.Adapter1" doesn't exist

00:07:40.984 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Resetting governor. Descendants first: /B8:27:EB:46:83:F3
00:07:40.989 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Resetting adapter governor: /B8:27:EB:46:83:F3
00:07:40.994 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Disable powered notifications: tinyb://B8:27:EB:46:83:F3
00:07:41.000 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Disable discovering notifications: tinyb://B8:27:EB:46:83:F3
00:07:41.005 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Stopping discovery: tinyb://B8:27:EB:46:83:F3
00:07:41.010 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Trying to find object factory: tinyb://B8:27:EB:46:83:F3
00:07:41.015 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'bluetooth:adapter:B827EB4683F3' changed from ONLINE to OFFLINE
00:07:41.014 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying manager listeners (governor ready): 0 : false
00:07:41.025 [DEBUG] [.manager.transport.tinyb.TinyBFactory] - Bluetooth object disposal requested: tinyb://B8:27:EB:46:83:F3
00:07:41.033 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Disposing adapter: B8:27:EB:46:83:F3
00:07:41.038 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Governor has been reset: /B8:27:EB:46:83:F3
00:07:41.432 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /B8:27:EB:46:83:F3
00:07:41.438 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Trying to find object factory: tinyb://B8:27:EB:46:83:F3
00:07:41.443 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - A new native object has been acquired: /B8:27:EB:46:83:F3
00:07:41.448 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Initializing governor with the new native object: /B8:27:EB:46:83:F3
00:07:41.453 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Initializing adapter governor: /B8:27:EB:46:83:F3
00:07:41.458 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Enabling powered notifications: /B8:27:EB:46:83:F3 : true
00:07:41.463 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Enable powered notifications: tinyb://B8:27:EB:46:83:F3
00:07:41.468 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Enabling discovering notifications: /B8:27:EB:46:83:F3 : true
00:07:41.474 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Enable discovering notifications: tinyb://B8:27:EB:46:83:F3
00:07:41.479 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Updating adapter governor: /B8:27:EB:46:83:F3
00:07:41.482 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying manager listeners (governor ready): 0 : true
00:07:41.485 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'bluetooth:adapter:B827EB4683F3' changed from OFFLINE to ONLINE
00:07:41.485 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Starting discovery: /B8:27:EB:46:83:F3
00:07:41.497 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Starting discovery: tinyb://B8:27:EB:46:83:F3
00:07:41.506 [WARN ] [.impl.AbstractBluetoothObjectGovernor] - Error occurred while updating governor: /B8:27:EB:46:83:F3 / 1593327 : GDBus.Error:org.freedesktop.DBus.Error.UnknownMethod: Method "SetDiscoveryFilter" with signature "a{sv}" on interface "org.bluez.Adapter1" doesn't exist

00:07:41.512 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Resetting governor. Descendants first: /B8:27:EB:46:83:F3
00:07:41.515 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Resetting adapter governor: /B8:27:EB:46:83:F3
00:07:41.518 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Disable powered notifications: tinyb://B8:27:EB:46:83:F3
00:07:41.521 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Disable discovering notifications: tinyb://B8:27:EB:46:83:F3
00:07:41.524 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Stopping discovery: tinyb://B8:27:EB:46:83:F3
00:07:41.527 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Trying to find object factory: tinyb://B8:27:EB:46:83:F3
00:07:41.530 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'bluetooth:adapter:B827EB4683F3' changed from ONLINE to OFFLINE
00:07:41.530 [DEBUG] [.manager.transport.tinyb.TinyBFactory] - Bluetooth object disposal requested: tinyb://B8:27:EB:46:83:F3
00:07:41.529 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying manager listeners (governor ready): 0 : false
00:07:41.536 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Disposing adapter: B8:27:EB:46:83:F3
00:07:41.540 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Governor has been reset: /B8:27:EB:46:83:F3
00:07:43.009 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 0 discovered devices
00:07:43.016 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Lost: 0; New: 0; Rediscovered: 0; Stale: 0
00:07:50.660 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 1 discovered adapters
00:07:50.873 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Governor is not ready. Enforcing an explicit update: /B8:27:EB:46:83:F3
00:07:50.879 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /B8:27:EB:46:83:F3
00:07:50.884 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Trying to find object factory: tinyb://B8:27:EB:46:83:F3
00:07:50.890 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - A new native object has been acquired: /B8:27:EB:46:83:F3
00:07:50.895 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Initializing governor with the new native object: /B8:27:EB:46:83:F3
00:07:50.901 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Initializing adapter governor: /B8:27:EB:46:83:F3
00:07:50.906 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Enabling powered notifications: /B8:27:EB:46:83:F3 : true
00:07:50.911 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Enable powered notifications: tinyb://B8:27:EB:46:83:F3
00:07:50.916 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Enabling discovering notifications: /B8:27:EB:46:83:F3 : true
00:07:50.921 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Enable discovering notifications: tinyb://B8:27:EB:46:83:F3
00:07:50.927 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Updating adapter governor: /B8:27:EB:46:83:F3
00:07:50.929 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying manager listeners (governor ready): 0 : true
00:07:50.938 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Starting discovery: /B8:27:EB:46:83:F3
00:07:50.941 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'bluetooth:adapter:B827EB4683F3' changed from OFFLINE to ONLINE
00:07:50.944 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Starting discovery: tinyb://B8:27:EB:46:83:F3
00:07:50.954 [WARN ] [.impl.AbstractBluetoothObjectGovernor] - Error occurred while updating governor: /B8:27:EB:46:83:F3 / 478b21 : GDBus.Error:org.freedesktop.DBus.Error.UnknownMethod: Method "SetDiscoveryFilter" with signature "a{sv}" on interface "org.bluez.Adapter1" doesn't exist

00:07:50.961 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Resetting governor. Descendants first: /B8:27:EB:46:83:F3
00:07:50.967 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Resetting adapter governor: /B8:27:EB:46:83:F3
00:07:50.974 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Disable powered notifications: tinyb://B8:27:EB:46:83:F3
00:07:50.980 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Disable discovering notifications: tinyb://B8:27:EB:46:83:F3
00:07:50.985 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Stopping discovery: tinyb://B8:27:EB:46:83:F3
00:07:51.041 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying manager listeners (governor ready): 0 : false
00:07:51.045 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'bluetooth:adapter:B827EB4683F3' changed from ONLINE to OFFLINE
00:07:51.065 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Trying to find object factory: tinyb://B8:27:EB:46:83:F3
00:07:51.069 [DEBUG] [.manager.transport.tinyb.TinyBFactory] - Bluetooth object disposal requested: tinyb://B8:27:EB:46:83:F3
00:07:51.073 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Disposing adapter: B8:27:EB:46:83:F3
00:07:51.076 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Governor has been reset: /B8:27:EB:46:83:F3
00:07:51.547 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /B8:27:EB:46:83:F3
00:07:51.550 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Trying to find object factory: tinyb://B8:27:EB:46:83:F3
00:07:51.554 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - A new native object has been acquired: /B8:27:EB:46:83:F3
00:07:51.558 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Initializing governor with the new native object: /B8:27:EB:46:83:F3
00:07:51.561 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Initializing adapter governor: /B8:27:EB:46:83:F3
00:07:51.565 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Enabling powered notifications: /B8:27:EB:46:83:F3 : true
00:07:51.569 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Enable powered notifications: tinyb://B8:27:EB:46:83:F3
00:07:51.573 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Enabling discovering notifications: /B8:27:EB:46:83:F3 : true
00:07:51.577 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Enable discovering notifications: tinyb://B8:27:EB:46:83:F3
00:07:51.582 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying manager listeners (governor ready): 0 : true
00:07:51.581 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Updating adapter governor: /B8:27:EB:46:83:F3
00:07:51.584 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'bluetooth:adapter:B827EB4683F3' changed from OFFLINE to ONLINE
00:07:51.589 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Starting discovery: /B8:27:EB:46:83:F3
00:07:51.593 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Starting discovery: tinyb://B8:27:EB:46:83:F3
00:07:51.600 [WARN ] [.impl.AbstractBluetoothObjectGovernor] - Error occurred while updating governor: /B8:27:EB:46:83:F3 / e3b82d : GDBus.Error:org.freedesktop.DBus.Error.UnknownMethod: Method "SetDiscoveryFilter" with signature "a{sv}" on interface "org.bluez.Adapter1" doesn't exist

00:07:51.605 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Resetting governor. Descendants first: /B8:27:EB:46:83:F3
00:07:51.610 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Resetting adapter governor: /B8:27:EB:46:83:F3
00:07:51.614 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Disable powered notifications: tinyb://B8:27:EB:46:83:F3
00:07:51.618 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Disable discovering notifications: tinyb://B8:27:EB:46:83:F3
00:07:51.621 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Stopping discovery: tinyb://B8:27:EB:46:83:F3
00:07:51.627 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying manager listeners (governor ready): 0 : false
00:07:51.629 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'bluetooth:adapter:B827EB4683F3' changed from ONLINE to OFFLINE
00:07:51.628 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Trying to find object factory: tinyb://B8:27:EB:46:83:F3
00:07:51.637 [DEBUG] [.manager.transport.tinyb.TinyBFactory] - Bluetooth object disposal requested: tinyb://B8:27:EB:46:83:F3
00:07:51.640 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Disposing adapter: B8:27:EB:46:83:F3
00:07:51.644 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Governor has been reset: /B8:27:EB:46:83:F3
00:07:53.025 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 0 discovered devices
00:07:53.033 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Lost: 0; New: 0; Rediscovered: 0; Stale: 0
00:08:00.668 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 1 discovered adapters
00:08:00.873 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Governor is not ready. Enforcing an explicit update: /B8:27:EB:46:83:F3
00:08:00.879 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /B8:27:EB:46:83:F3
00:08:00.884 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Trying to find object factory: tinyb://B8:27:EB:46:83:F3
00:08:00.890 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - A new native object has been acquired: /B8:27:EB:46:83:F3
00:08:00.895 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Initializing governor with the new native object: /B8:27:EB:46:83:F3
00:08:00.900 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Initializing adapter governor: /B8:27:EB:46:83:F3
00:08:00.906 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Enabling powered notifications: /B8:27:EB:46:83:F3 : true
00:08:00.911 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Enable powered notifications: tinyb://B8:27:EB:46:83:F3
00:08:00.916 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Enabling discovering notifications: /B8:27:EB:46:83:F3 : true
00:08:00.922 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Enable discovering notifications: tinyb://B8:27:EB:46:83:F3
00:08:00.927 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Updating adapter governor: /B8:27:EB:46:83:F3
00:08:00.933 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'bluetooth:adapter:B827EB4683F3' changed from OFFLINE to ONLINE
00:08:00.929 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying manager listeners (governor ready): 0 : true
00:08:00.937 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Starting discovery: /B8:27:EB:46:83:F3
00:08:00.947 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Starting discovery: tinyb://B8:27:EB:46:83:F3
00:08:00.956 [WARN ] [.impl.AbstractBluetoothObjectGovernor] - Error occurred while updating governor: /B8:27:EB:46:83:F3 / 7816f1 : GDBus.Error:org.freedesktop.DBus.Error.UnknownMethod: Method "SetDiscoveryFilter" with signature "a{sv}" on interface "org.bluez.Adapter1" doesn't exist

00:08:00.962 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Resetting governor. Descendants first: /B8:27:EB:46:83:F3
00:08:00.966 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Resetting adapter governor: /B8:27:EB:46:83:F3
00:08:00.969 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Disable powered notifications: tinyb://B8:27:EB:46:83:F3
00:08:00.972 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Disable discovering notifications: tinyb://B8:27:EB:46:83:F3
00:08:00.975 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Stopping discovery: tinyb://B8:27:EB:46:83:F3
00:08:00.978 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Trying to find object factory: tinyb://B8:27:EB:46:83:F3
00:08:00.981 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'bluetooth:adapter:B827EB4683F3' changed from ONLINE to OFFLINE
00:08:00.980 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying manager listeners (governor ready): 0 : false
00:08:00.985 [DEBUG] [.manager.transport.tinyb.TinyBFactory] - Bluetooth object disposal requested: tinyb://B8:27:EB:46:83:F3
00:08:00.991 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Disposing adapter: B8:27:EB:46:83:F3
00:08:00.995 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Governor has been reset: /B8:27:EB:46:83:F3
00:08:01.647 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /B8:27:EB:46:83:F3
00:08:01.650 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Trying to find object factory: tinyb://B8:27:EB:46:83:F3
00:08:01.653 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - A new native object has been acquired: /B8:27:EB:46:83:F3
00:08:01.656 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Initializing governor with the new native object: /B8:27:EB:46:83:F3
00:08:01.660 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Initializing adapter governor: /B8:27:EB:46:83:F3
00:08:01.664 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Enabling powered notifications: /B8:27:EB:46:83:F3 : true
00:08:01.668 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Enable powered notifications: tinyb://B8:27:EB:46:83:F3
00:08:01.675 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Enabling discovering notifications: /B8:27:EB:46:83:F3 : true
00:08:01.682 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Enable discovering notifications: tinyb://B8:27:EB:46:83:F3
00:08:01.691 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying manager listeners (governor ready): 0 : true
00:08:01.696 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'bluetooth:adapter:B827EB4683F3' changed from OFFLINE to ONLINE
00:08:01.691 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Updating adapter governor: /B8:27:EB:46:83:F3
00:08:01.709 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Starting discovery: /B8:27:EB:46:83:F3
00:08:01.716 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Starting discovery: tinyb://B8:27:EB:46:83:F3
00:08:01.727 [WARN ] [.impl.AbstractBluetoothObjectGovernor] - Error occurred while updating governor: /B8:27:EB:46:83:F3 / 14c880f : GDBus.Error:org.freedesktop.DBus.Error.UnknownMethod: Method "SetDiscoveryFilter" with signature "a{sv}" on interface "org.bluez.Adapter1" doesn't exist

00:08:01.736 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Resetting governor. Descendants first: /B8:27:EB:46:83:F3
00:08:01.743 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Resetting adapter governor: /B8:27:EB:46:83:F3
00:08:01.750 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Disable powered notifications: tinyb://B8:27:EB:46:83:F3
00:08:01.757 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Disable discovering notifications: tinyb://B8:27:EB:46:83:F3
00:08:01.765 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Stopping discovery: tinyb://B8:27:EB:46:83:F3
00:08:01.772 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying manager listeners (governor ready): 0 : false
00:08:01.775 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Trying to find object factory: tinyb://B8:27:EB:46:83:F3
00:08:01.776 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'bluetooth:adapter:B827EB4683F3' changed from ONLINE to OFFLINE
00:08:01.779 [DEBUG] [.manager.transport.tinyb.TinyBFactory] - Bluetooth object disposal requested: tinyb://B8:27:EB:46:83:F3
00:08:01.785 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Disposing adapter: B8:27:EB:46:83:F3
00:08:01.789 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Governor has been reset: /B8:27:EB:46:83:F3
00:08:03.044 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 0 discovered devices
00:08:03.053 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Lost: 0; New: 0; Rediscovered: 0; Stale: 0
00:08:10.674 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 1 discovered adapters
00:08:10.873 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Governor is not ready. Enforcing an explicit update: /B8:27:EB:46:83:F3
00:08:10.880 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /B8:27:EB:46:83:F3
00:08:10.885 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Trying to find object factory: tinyb://B8:27:EB:46:83:F3
00:08:10.890 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - A new native object has been acquired: /B8:27:EB:46:83:F3
00:08:10.895 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Initializing governor with the new native object: /B8:27:EB:46:83:F3
00:08:10.900 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Initializing adapter governor: /B8:27:EB:46:83:F3
00:08:10.905 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Enabling powered notifications: /B8:27:EB:46:83:F3 : true
00:08:10.910 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Enable powered notifications: tinyb://B8:27:EB:46:83:F3
00:08:10.915 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Enabling discovering notifications: /B8:27:EB:46:83:F3 : true
00:08:10.920 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Enable discovering notifications: tinyb://B8:27:EB:46:83:F3
00:08:10.925 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Updating adapter governor: /B8:27:EB:46:83:F3
00:08:10.929 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'bluetooth:adapter:B827EB4683F3' changed from OFFLINE to ONLINE
00:08:10.927 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying manager listeners (governor ready): 0 : true
00:08:10.932 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Starting discovery: /B8:27:EB:46:83:F3
00:08:10.937 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Starting discovery: tinyb://B8:27:EB:46:83:F3
00:08:10.942 [WARN ] [.impl.AbstractBluetoothObjectGovernor] - Error occurred while updating governor: /B8:27:EB:46:83:F3 / 17a811a : GDBus.Error:org.freedesktop.DBus.Error.UnknownMethod: Method "SetDiscoveryFilter" with signature "a{sv}" on interface "org.bluez.Adapter1" doesn't exist

00:08:10.945 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Resetting governor. Descendants first: /B8:27:EB:46:83:F3
00:08:10.948 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Resetting adapter governor: /B8:27:EB:46:83:F3
00:08:10.952 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Disable powered notifications: tinyb://B8:27:EB:46:83:F3
00:08:10.955 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Disable discovering notifications: tinyb://B8:27:EB:46:83:F3
00:08:10.958 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Stopping discovery: tinyb://B8:27:EB:46:83:F3
00:08:10.962 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Trying to find object factory: tinyb://B8:27:EB:46:83:F3
00:08:10.964 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying manager listeners (governor ready): 0 : false
00:08:10.968 [DEBUG] [.manager.transport.tinyb.TinyBFactory] - Bluetooth object disposal requested: tinyb://B8:27:EB:46:83:F3
00:08:10.968 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'bluetooth:adapter:B827EB4683F3' changed from ONLINE to OFFLINE
00:08:10.973 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Disposing adapter: B8:27:EB:46:83:F3
00:08:10.977 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Governor has been reset: /B8:27:EB:46:83:F3
00:08:11.798 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /B8:27:EB:46:83:F3
00:08:11.804 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Trying to find object factory: tinyb://B8:27:EB:46:83:F3
00:08:11.809 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - A new native object has been acquired: /B8:27:EB:46:83:F3
00:08:11.814 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Initializing governor with the new native object: /B8:27:EB:46:83:F3
00:08:11.819 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Initializing adapter governor: /B8:27:EB:46:83:F3
00:08:11.823 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Enabling powered notifications: /B8:27:EB:46:83:F3 : true
00:08:11.834 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Enable powered notifications: tinyb://B8:27:EB:46:83:F3
00:08:11.839 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Enabling discovering notifications: /B8:27:EB:46:83:F3 : true
00:08:11.844 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Enable discovering notifications: tinyb://B8:27:EB:46:83:F3
00:08:11.849 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Updating adapter governor: /B8:27:EB:46:83:F3
00:08:11.851 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying manager listeners (governor ready): 0 : true
00:08:11.854 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Starting discovery: /B8:27:EB:46:83:F3
00:08:11.864 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'bluetooth:adapter:B827EB4683F3' changed from OFFLINE to ONLINE
00:08:11.866 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Starting discovery: tinyb://B8:27:EB:46:83:F3
00:08:11.878 [WARN ] [.impl.AbstractBluetoothObjectGovernor] - Error occurred while updating governor: /B8:27:EB:46:83:F3 / 845595 : GDBus.Error:org.freedesktop.DBus.Error.UnknownMethod: Method "SetDiscoveryFilter" with signature "a{sv}" on interface "org.bluez.Adapter1" doesn't exist

00:08:11.887 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Resetting governor. Descendants first: /B8:27:EB:46:83:F3
00:08:11.894 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Resetting adapter governor: /B8:27:EB:46:83:F3
00:08:11.900 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Disable powered notifications: tinyb://B8:27:EB:46:83:F3
00:08:11.907 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Disable discovering notifications: tinyb://B8:27:EB:46:83:F3
00:08:11.913 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Stopping discovery: tinyb://B8:27:EB:46:83:F3
00:08:11.922 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying manager listeners (governor ready): 0 : false
00:08:11.929 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Trying to find object factory: tinyb://B8:27:EB:46:83:F3
00:08:11.927 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'bluetooth:adapter:B827EB4683F3' changed from ONLINE to OFFLINE
00:08:11.939 [DEBUG] [.manager.transport.tinyb.TinyBFactory] - Bluetooth object disposal requested: tinyb://B8:27:EB:46:83:F3
00:08:11.942 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Disposing adapter: B8:27:EB:46:83:F3
00:08:11.945 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Governor has been reset: /B8:27:EB:46:83:F3
00:08:13.063 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 0 discovered devices
00:08:13.071 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Lost: 0; New: 0; Rediscovered: 0; Stale: 0
00:08:16.045 [INFO ] [smarthome.event.InboxRemovedEvent    ] - Discovery Result with UID 'bluetooth:ble:0452C7B26406' has been removed.
00:08:16.055 [INFO ] [smarthome.event.InboxRemovedEvent    ] - Discovery Result with UID 'bluetooth:ble:F5B34D6E0366' has been removed.
00:08:16.060 [INFO ] [smarthome.event.InboxRemovedEvent    ] - Discovery Result with UID 'bluetooth:ble:45E7DC943004' has been removed.
00:08:16.068 [INFO ] [smarthome.event.InboxRemovedEvent    ] - Discovery Result with UID 'bluetooth:ble:153D3D103D3B' has been removed.
00:08:16.075 [INFO ] [smarthome.event.InboxRemovedEvent    ] - Discovery Result with UID 'bluetooth:ble:E96CF66DB968' has been removed.
00:08:16.082 [INFO ] [smarthome.event.InboxRemovedEvent    ] - Discovery Result with UID 'bluetooth:ble:53351D15FA67' has been removed.
00:08:16.088 [INFO ] [smarthome.event.InboxRemovedEvent    ] - Discovery Result with UID 'bluetooth:ble:6B1F511A28F8' has been removed.
00:08:16.093 [INFO ] [smarthome.event.InboxRemovedEvent    ] - Discovery Result with UID 'bluetooth:ble:2C41A10209EB' has been removed.
00:08:16.100 [INFO ] [smarthome.event.InboxRemovedEvent    ] - Discovery Result with UID 'bluetooth:ble:784F4382F731' has been removed.
00:08:16.104 [INFO ] [smarthome.event.InboxRemovedEvent    ] - Discovery Result with UID 'bluetooth:ble:59AB35B4D35D' has been removed.
00:08:20.683 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 1 discovered adapters
00:08:20.878 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Governor is not ready. Enforcing an explicit update: /B8:27:EB:46:83:F3
00:08:20.884 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /B8:27:EB:46:83:F3
00:08:20.889 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Trying to find object factory: tinyb://B8:27:EB:46:83:F3
00:08:20.894 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - A new native object has been acquired: /B8:27:EB:46:83:F3
00:08:20.900 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Initializing governor with the new native object: /B8:27:EB:46:83:F3
00:08:20.905 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Initializing adapter governor: /B8:27:EB:46:83:F3
00:08:20.909 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Enabling powered notifications: /B8:27:EB:46:83:F3 : true
00:08:20.915 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Enable powered notifications: tinyb://B8:27:EB:46:83:F3
00:08:20.920 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Enabling discovering notifications: /B8:27:EB:46:83:F3 : true
00:08:20.926 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Enable discovering notifications: tinyb://B8:27:EB:46:83:F3
00:08:20.931 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Updating adapter governor: /B8:27:EB:46:83:F3
00:08:20.933 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying manager listeners (governor ready): 0 : true
00:08:20.940 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'bluetooth:adapter:B827EB4683F3' changed from OFFLINE to ONLINE
00:08:20.939 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Starting discovery: /B8:27:EB:46:83:F3
00:08:20.951 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Starting discovery: tinyb://B8:27:EB:46:83:F3
00:08:20.961 [WARN ] [.impl.AbstractBluetoothObjectGovernor] - Error occurred while updating governor: /B8:27:EB:46:83:F3 / 124c536 : GDBus.Error:org.freedesktop.DBus.Error.UnknownMethod: Method "SetDiscoveryFilter" with signature "a{sv}" on interface "org.bluez.Adapter1" doesn't exist

00:08:20.968 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Resetting governor. Descendants first: /B8:27:EB:46:83:F3
00:08:20.975 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Resetting adapter governor: /B8:27:EB:46:83:F3
00:08:20.982 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Disable powered notifications: tinyb://B8:27:EB:46:83:F3
00:08:20.988 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Disable discovering notifications: tinyb://B8:27:EB:46:83:F3
00:08:20.995 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Stopping discovery: tinyb://B8:27:EB:46:83:F3
00:08:21.003 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying manager listeners (governor ready): 0 : false
00:08:21.001 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Trying to find object factory: tinyb://B8:27:EB:46:83:F3
00:08:21.005 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'bluetooth:adapter:B827EB4683F3' changed from ONLINE to OFFLINE
00:08:21.012 [DEBUG] [.manager.transport.tinyb.TinyBFactory] - Bluetooth object disposal requested: tinyb://B8:27:EB:46:83:F3
00:08:21.016 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Disposing adapter: B8:27:EB:46:83:F3
00:08:21.020 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Governor has been reset: /B8:27:EB:46:83:F3
00:08:21.949 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /B8:27:EB:46:83:F3
00:08:21.954 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Trying to find object factory: tinyb://B8:27:EB:46:83:F3
00:08:21.960 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - A new native object has been acquired: /B8:27:EB:46:83:F3
00:08:21.966 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Initializing governor with the new native object: /B8:27:EB:46:83:F3
00:08:21.972 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Initializing adapter governor: /B8:27:EB:46:83:F3
00:08:21.978 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Enabling powered notifications: /B8:27:EB:46:83:F3 : true
00:08:21.984 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Enable powered notifications: tinyb://B8:27:EB:46:83:F3
00:08:21.990 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Enabling discovering notifications: /B8:27:EB:46:83:F3 : true
00:08:21.996 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Enable discovering notifications: tinyb://B8:27:EB:46:83:F3
00:08:22.002 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Updating adapter governor: /B8:27:EB:46:83:F3
00:08:22.008 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'bluetooth:adapter:B827EB4683F3' changed from OFFLINE to ONLINE
00:08:22.004 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying manager listeners (governor ready): 0 : true
00:08:22.010 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Starting discovery: /B8:27:EB:46:83:F3
00:08:22.017 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Starting discovery: tinyb://B8:27:EB:46:83:F3
00:08:22.023 [WARN ] [.impl.AbstractBluetoothObjectGovernor] - Error occurred while updating governor: /B8:27:EB:46:83:F3 / 43b4b5 : GDBus.Error:org.freedesktop.DBus.Error.UnknownMethod: Method "SetDiscoveryFilter" with signature "a{sv}" on interface "org.bluez.Adapter1" doesn't exist

00:08:22.027 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Resetting governor. Descendants first: /B8:27:EB:46:83:F3
00:08:22.030 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Resetting adapter governor: /B8:27:EB:46:83:F3
00:08:22.035 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Disable powered notifications: tinyb://B8:27:EB:46:83:F3
00:08:22.039 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Disable discovering notifications: tinyb://B8:27:EB:46:83:F3
00:08:22.043 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Stopping discovery: tinyb://B8:27:EB:46:83:F3
00:08:22.048 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying manager listeners (governor ready): 0 : false
00:08:22.049 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'bluetooth:adapter:B827EB4683F3' changed from ONLINE to OFFLINE
00:08:22.046 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Trying to find object factory: tinyb://B8:27:EB:46:83:F3
00:08:22.057 [DEBUG] [.manager.transport.tinyb.TinyBFactory] - Bluetooth object disposal requested: tinyb://B8:27:EB:46:83:F3
00:08:22.061 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Disposing adapter: B8:27:EB:46:83:F3
00:08:22.065 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Governor has been reset: /B8:27:EB:46:83:F3
00:08:22.850 [ERROR] [ab.core.service.AbstractActiveService] - Error while executing background thread HTTP Refresh Service
java.lang.IllegalArgumentException: json string can not be null or empty
        at com.jayway.jsonpath.internal.Utils.notEmpty(Utils.java:386) [221:com.jayway.jsonpath.json-path:2.1.0]
        at com.jayway.jsonpath.internal.JsonContext.parse(JsonContext.java:81) [221:com.jayway.jsonpath.json-path:2.1.0]
        at com.jayway.jsonpath.JsonPath.read(JsonPath.java:502) [221:com.jayway.jsonpath.json-path:2.1.0]
        at org.eclipse.smarthome.transform.jsonpath.internal.JSonPathTransformationService.transform(JSonPathTransformationService.java:55) [223:org.eclipse.smarthome.transform.jsonpath:0.10.0.b1]
        at org.openhab.core.transform.TransformationHelper$TransformationServiceDelegate.transform(TransformationHelper.java:65) [208:org.openhab.core.compat1x:2.2.0]
        at org.openhab.binding.http.internal.HttpBinding.execute(HttpBinding.java:194) [213:org.openhab.binding.http:1.11.0]
        at org.openhab.core.binding.AbstractActiveBinding$BindingActiveService.execute(AbstractActiveBinding.java:144) [208:org.openhab.core.compat1x:2.2.0]
        at org.openhab.core.service.AbstractActiveService$RefreshThread.run(AbstractActiveService.java:166) [208:org.openhab.core.compat1x:2.2.0]
00:08:23.077 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 0 discovered devices
00:08:23.082 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Lost: 0; New: 0; Rediscovered: 0; Stale: 0
00:08:23.873 [ERROR] [ab.core.service.AbstractActiveService] - Error while executing background thread HTTP Refresh Service
java.lang.IllegalArgumentException: json string can not be null or empty
        at com.jayway.jsonpath.internal.Utils.notEmpty(Utils.java:386) [221:com.jayway.jsonpath.json-path:2.1.0]
        at com.jayway.jsonpath.internal.JsonContext.parse(JsonContext.java:81) [221:com.jayway.jsonpath.json-path:2.1.0]
        at com.jayway.jsonpath.JsonPath.read(JsonPath.java:502) [221:com.jayway.jsonpath.json-path:2.1.0]
        at org.eclipse.smarthome.transform.jsonpath.internal.JSonPathTransformationService.transform(JSonPathTransformationService.java:55) [223:org.eclipse.smarthome.transform.jsonpath:0.10.0.b1]
        at org.openhab.core.transform.TransformationHelper$TransformationServiceDelegate.transform(TransformationHelper.java:65) [208:org.openhab.core.compat1x:2.2.0]
        at org.openhab.binding.http.internal.HttpBinding.execute(HttpBinding.java:194) [213:org.openhab.binding.http:1.11.0]
        at org.openhab.core.binding.AbstractActiveBinding$BindingActiveService.execute(AbstractActiveBinding.java:144) [208:org.openhab.core.compat1x:2.2.0]
        at org.openhab.core.service.AbstractActiveService$RefreshThread.run(AbstractActiveService.java:166) [208:org.openhab.core.compat1x:2.2.0]
00:08:27.968 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Unregister tinyb transport
00:08:28.001 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Resetting governor: /B8:27:EB:46:83:F3
00:08:28.751 [INFO ] [smarthome.event.ExtensionEvent       ] - Extension 'market:binding-3769218' has been uninstalled.
00:08:30.873 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Governor is not ready. Enforcing an explicit update: /B8:27:EB:46:83:F3
00:08:30.882 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /B8:27:EB:46:83:F3
00:08:30.888 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Trying to find object factory: tinyb://B8:27:EB:46:83:F3
00:08:30.895 [WARN ] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] is not registered.
00:08:32.070 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /B8:27:EB:46:83:F3
00:08:32.077 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Trying to find object factory: tinyb://B8:27:EB:46:83:F3
00:08:32.084 [WARN ] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] is not registered.
00:08:34.012 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Register tinyb transport
00:08:34.029 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 1 discovered adapters
00:08:34.067 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 9 discovered devices
00:08:34.072 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/E9:6C:F6:6D:B9:68 [N013W] : 1
00:08:34.078 [INFO ] [smarthome.event.InboxAddedEvent      ] - Discovery Result with UID 'bluetooth:ble:E96CF66DB968' has been added.
00:08:34.077 [INFO ] [ig.discovery.internal.PersistentInbox] - Added new thing 'bluetooth:ble:E96CF66DB968' to inbox.
00:08:34.085 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/F5:B3:4D:6E:03:66 [MI Band 2] : 1
00:08:34.093 [INFO ] [smarthome.event.InboxAddedEvent      ] - Discovery Result with UID 'bluetooth:ble:F5B34D6E0366' has been added.
00:08:34.093 [INFO ] [ig.discovery.internal.PersistentInbox] - Added new thing 'bluetooth:ble:F5B34D6E0366' to inbox.
00:08:34.099 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/04:52:C7:B2:64:06 [LE-Bose Revolve SoundLink] : 1
00:08:35.037 [INFO ] [smarthome.event.InboxAddedEvent      ] - Discovery Result with UID 'bluetooth:ble:0452C7B26406' has been added.
00:08:35.036 [INFO ] [ig.discovery.internal.PersistentInbox] - Added new thing 'bluetooth:ble:0452C7B26406' to inbox.
00:08:35.043 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 [MJ_HT_V1] : 1
00:08:35.305 [INFO ] [smarthome.event.InboxAddedEvent      ] - Discovery Result with UID 'bluetooth:ble:4C65A8D43A51' has been added.
00:08:35.303 [INFO ] [ig.discovery.internal.PersistentInbox] - Added new thing 'bluetooth:ble:4C65A8D43A51' to inbox.
00:08:35.310 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/45:E7:DC:94:30:04 [45-E7-DC-94-30-04] : 1
00:08:35.318 [INFO ] [smarthome.event.InboxAddedEvent      ] - Discovery Result with UID 'bluetooth:ble:45E7DC943004' has been added.
00:08:35.318 [INFO ] [ig.discovery.internal.PersistentInbox] - Added new thing 'bluetooth:ble:45E7DC943004' to inbox.
00:08:35.324 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/2C:41:A1:02:09:EB [LE-L'ouïe fine] : 1
00:08:35.333 [INFO ] [smarthome.event.InboxAddedEvent      ] - Discovery Result with UID 'bluetooth:ble:2C41A10209EB' has been added.
00:08:35.332 [INFO ] [ig.discovery.internal.PersistentInbox] - Added new thing 'bluetooth:ble:2C41A10209EB' to inbox.
00:08:35.338 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/59:AB:35:B4:D3:5D [59-AB-35-B4-D3-5D] : 1
00:08:35.346 [INFO ] [smarthome.event.InboxAddedEvent      ] - Discovery Result with UID 'bluetooth:ble:59AB35B4D35D' has been added.
00:08:35.345 [INFO ] [ig.discovery.internal.PersistentInbox] - Added new thing 'bluetooth:ble:59AB35B4D35D' to inbox.
00:08:35.353 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/78:4F:43:82:F7:31 [78-4F-43-82-F7-31] : 1
00:08:35.362 [INFO ] [smarthome.event.InboxAddedEvent      ] - Discovery Result with UID 'bluetooth:ble:784F4382F731' has been added.
00:08:35.362 [INFO ] [ig.discovery.internal.PersistentInbox] - Added new thing 'bluetooth:ble:784F4382F731' to inbox.
00:08:35.369 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/15:3D:3D:10:3D:3B [Wistiki] : 1
00:08:35.377 [INFO ] [smarthome.event.InboxAddedEvent      ] - Discovery Result with UID 'bluetooth:ble:153D3D103D3B' has been added.
00:08:35.376 [INFO ] [ig.discovery.internal.PersistentInbox] - Added new thing 'bluetooth:ble:153D3D103D3B' to inbox.
00:08:35.383 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Lost: 0; New: 9; Rediscovered: 0; Stale: 0
00:08:35.392 [INFO ] [smarthome.event.ExtensionEvent       ] - Extension 'market:binding-3769218' has been installed.
00:08:39.040 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 1 discovered adapters
00:08:40.450 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 9 discovered devices
00:08:40.474 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/E9:6C:F6:6D:B9:68 [N013W] : 1
00:08:40.483 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/F5:B3:4D:6E:03:66 [MI Band 2] : 1
00:08:40.491 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/04:52:C7:B2:64:06 [LE-Bose Revolve SoundLink] : 1
00:08:40.499 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 [MJ_HT_V1] : 1
00:08:40.507 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/45:E7:DC:94:30:04 [45-E7-DC-94-30-04] : 1
00:08:40.516 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/2C:41:A1:02:09:EB [LE-L'ouïe fine] : 1
00:08:40.524 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/59:AB:35:B4:D3:5D [59-AB-35-B4-D3-5D] : 1
00:08:40.562 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/78:4F:43:82:F7:31 [78-4F-43-82-F7-31] : 1
00:08:40.571 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/15:3D:3D:10:3D:3B [Wistiki] : 1
00:08:40.580 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Lost: 0; New: 0; Rediscovered: 9; Stale: 0
00:08:40.873 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Governor is not ready. Enforcing an explicit update: /B8:27:EB:46:83:F3
00:08:40.876 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /B8:27:EB:46:83:F3
00:08:40.879 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Trying to find object factory: tinyb://B8:27:EB:46:83:F3
00:08:40.887 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - A new native object has been acquired: /B8:27:EB:46:83:F3
00:08:40.890 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Initializing governor with the new native object: /B8:27:EB:46:83:F3
00:08:40.893 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Initializing adapter governor: /B8:27:EB:46:83:F3
00:08:40.895 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Enabling powered notifications: /B8:27:EB:46:83:F3 : true
00:08:40.899 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Enable powered notifications: tinyb://B8:27:EB:46:83:F3
00:08:40.904 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Enabling discovering notifications: /B8:27:EB:46:83:F3 : true
00:08:40.907 [DEBUG] [.manager.transport.tinyb.TinyBAdapter] - Enable discovering notifications: tinyb://B8:27:EB:46:83:F3
00:08:40.912 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Updating adapter governor: /B8:27:EB:46:83:F3
00:08:40.913 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying manager listeners (governor ready): 0 : true
00:08:40.917 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Governor has been updated: /B8:27:EB:46:83:F3
00:08:40.917 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'bluetooth:adapter:B827EB4683F3' changed from OFFLINE to ONLINE
00:08:40.923 [DEBUG] [manager.impl.CompletableFutureService] - Trying to complete futures: /B8:27:EB:46:83:F3 : 0
00:08:40.938 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_adapter_B827EB4683F3_last_updated changed from 2018-05-01T00:07:25.688+0200 to 2018-05-01T00:08:40.928+0200
00:08:42.091 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /B8:27:EB:46:83:F3
00:08:42.096 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Updating adapter governor: /B8:27:EB:46:83:F3
00:08:42.101 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Governor has been updated: /B8:27:EB:46:83:F3
00:08:42.106 [DEBUG] [manager.impl.CompletableFutureService] - Trying to complete futures: /B8:27:EB:46:83:F3 : 0
00:08:42.123 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_adapter_B827EB4683F3_last_updated changed from 2018-05-01T00:08:40.928+0200 to 2018-05-01T00:08:42.107+0200
00:08:49.048 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 1 discovered adapters
00:08:50.640 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 9 discovered devices
00:08:50.646 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/E9:6C:F6:6D:B9:68 [N013W] : 1
00:08:50.654 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/F5:B3:4D:6E:03:66 [MI Band 2] : 1
00:08:50.669 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/04:52:C7:B2:64:06 [LE-Bose Revolve SoundLink] : 1
00:08:50.992 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 [MJ_HT_V1] : 1
00:08:51.008 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/45:E7:DC:94:30:04 [45-E7-DC-94-30-04] : 1
00:08:51.023 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/2C:41:A1:02:09:EB [LE-L'ouïe fine] : 1
00:08:51.037 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/59:AB:35:B4:D3:5D [59-AB-35-B4-D3-5D] : 1
00:08:51.050 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/78:4F:43:82:F7:31 [78-4F-43-82-F7-31] : 1
00:08:51.059 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/15:3D:3D:10:3D:3B [Wistiki] : 1
00:08:51.067 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Lost: 0; New: 0; Rediscovered: 9; Stale: 0
00:08:52.107 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /B8:27:EB:46:83:F3
00:08:52.110 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Updating adapter governor: /B8:27:EB:46:83:F3
00:08:52.114 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Governor has been updated: /B8:27:EB:46:83:F3
00:08:52.117 [DEBUG] [manager.impl.CompletableFutureService] - Trying to complete futures: /B8:27:EB:46:83:F3 : 0
00:08:52.134 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_adapter_B827EB4683F3_last_updated changed from 2018-05-01T00:08:42.107+0200 to 2018-05-01T00:08:52.120+0200
00:08:56.944 [INFO ] [thome.event.ItemChannelLinkAddedEvent] - Link 'bluetooth_ble_4C65A8D43A51_online-bluetooth:ble:4C65A8D43A51:online' has been added.
00:08:56.949 [INFO ] [thome.event.ItemChannelLinkAddedEvent] - Link 'bluetooth_ble_4C65A8D43A51_connected-bluetooth:ble:4C65A8D43A51:connected' has been added.
00:08:56.955 [INFO ] [thome.event.ItemChannelLinkAddedEvent] - Link 'bluetooth_ble_4C65A8D43A51_connection_control-bluetooth:ble:4C65A8D43A51:connection-control' has been added.
00:08:56.959 [INFO ] [thome.event.ItemChannelLinkAddedEvent] - Link 'bluetooth_ble_4C65A8D43A51_connected_adapter-bluetooth:ble:4C65A8D43A51:connected-adapter' has been added.
00:08:56.962 [INFO ] [thome.event.ItemChannelLinkAddedEvent] - Link 'bluetooth_ble_4C65A8D43A51_adapter-bluetooth:ble:4C65A8D43A51:adapter' has been added.
00:08:56.966 [INFO ] [thome.event.ItemChannelLinkAddedEvent] - Link 'bluetooth_ble_4C65A8D43A51_location-bluetooth:ble:4C65A8D43A51:location' has been added.
00:08:56.974 [INFO ] [thome.event.ItemChannelLinkAddedEvent] - Link 'bluetooth_ble_4C65A8D43A51_last_updated-bluetooth:ble:4C65A8D43A51:last-updated' has been added.
00:08:56.978 [INFO ] [smarthome.event.InboxRemovedEvent    ] - Discovery Result with UID 'bluetooth:ble:4C65A8D43A51' has been removed.
00:08:56.983 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_ble_4C65A8D43A51_online changed from NULL to OFF
00:08:56.991 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_ble_4C65A8D43A51_connected changed from NULL to OFF
00:08:56.999 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_ble_4C65A8D43A51_connection_control changed from NULL to OFF
00:08:57.059 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'bluetooth:ble:4C65A8D43A51' changed from UNINITIALIZED to INITIALIZING
00:08:57.073 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Creating a new governor: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51
00:08:57.091 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Initializing governor: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51
00:08:57.094 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Initializing combined device governor: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51
00:08:57.105 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Creating a new governor: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:08:57.119 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Initializing governor: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:08:57.122 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:08:57.125 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Trying to find object factory: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:08:57.135 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Protocol is unknown. Trying to find factory amongst discovered adapters: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:08:57.139 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Matching adapter found amongst discovered adapters: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:08:57.151 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - A new native object has been acquired: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:08:57.156 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Initializing governor with the new native object: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:08:57.160 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Initializing device governor: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:08:57.163 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Enabling RSSI notification: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : true
00:08:57.169 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Enable RSSI notifications: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:08:57.175 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Enabling connection notification: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : true
00:08:57.179 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Enable connected notifications: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:08:57.184 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Enabling services resolved notification: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : true
00:08:57.189 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Enable service resolved notifications: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:08:57.194 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Enabling blocked notification: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : true
00:08:57.199 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Enable blocked notifications: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:08:57.204 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Enabling manufacturer data notification: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : true
00:08:57.209 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Enable manufacturer data notifications: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:08:57.214 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Enabling service data notification: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : true
00:08:57.219 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Enable service data notifications: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:08:57.225 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying manager listeners (governor ready): 0 : true
00:08:57.227 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Getting blocked: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:08:57.231 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Checking if device connected: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:08:57.235 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Governor has been updated: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:08:57.238 [DEBUG] [manager.impl.CompletableFutureService] - Trying to complete futures: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : 0
00:08:57.244 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Registering a new delegate: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : 0
00:08:57.251 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Initializing safe operations: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:08:57.254 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Setting blocked control: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : false
00:08:57.258 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Setting connection control: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : false / false
00:08:57.261 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Setting online: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 : false / false
00:08:57.275 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Initializing unsafe operations: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : true
00:08:57.279 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Setting ready: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 : false / true
00:08:57.286 [DEBUG] [manager.impl.CompletableFutureService] - Trying to complete futures: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 : 0
00:08:57.286 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Getting bluetooth class: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:08:57.293 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Getting bluetooth class: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:08:57.298 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Getting name: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:08:57.303 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Getting alias: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:08:57.308 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Getting RSSI: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:08:57.312 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Getting RSSI: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:08:57.316 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Getting TxPower: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:08:57.320 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Estimated distance: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : 6.6834391756861455
00:08:57.326 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Calculating nearest delegate (current / new): null / /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:08:57.334 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Getting blocked: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:08:57.337 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Setting blocked: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 : false / false
00:08:57.343 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Checking if device connected: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:08:57.347 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Setting connected: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 : false / false
00:08:57.354 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Is services resolved?: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:08:57.358 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Initializing unsafe operations successfully completed: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:08:57.362 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Combined device governor initialization performed: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51
00:08:57.367 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Setting blocked control: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : false
00:08:57.371 [WARN ] [e.core.thing.binding.BaseThingHandler] - BaseThingHandler.initialize() will be removed soon, ThingStatus can be set manually via updateStatus(ThingStatus.ONLINE)
00:08:57.391 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'bluetooth:ble:4C65A8D43A51' changed from INITIALIZING to ONLINE
00:08:57.406 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_ble_4C65A8D43A51_adapter changed from NULL to B8:27:EB:46:83:F3
00:08:57.418 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Setting connection control: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 : true
00:08:57.426 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Updating connection target: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 : true
00:08:57.430 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_ble_4C65A8D43A51_location changed from NULL to Bluetooth Adapters
00:08:57.433 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Current target / new target: null / /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:08:57.435 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_ble_4C65A8D43A51_connected_adapter changed from NULL to UNDEF
00:08:57.441 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_ble_4C65A8D43A51_last_updated changed from NULL to UNDEF
00:08:57.446 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Setting connection control: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : false / true
00:08:57.455 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:08:57.460 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_ble_4C65A8D43A51_connection_control changed from OFF to ON
00:08:57.459 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Getting blocked: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:08:57.464 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Checking if device connected: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:08:57.468 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Connecting device: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:08:57.481 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Connecting: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:08:57.468 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Getting RSSI: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:08:57.492 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Getting TxPower: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:08:57.496 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Estimated distance: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : 6.6834391756861455
00:08:57.548 [INFO ] [smarthome.event.ThingUpdatedEvent    ] - Thing 'bluetooth:ble:4C65A8D43A51' has been updated.
00:08:57.575 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Setting alias: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 / MJ_HT_V1
00:08:57.585 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Setting alias: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 / MJ_HT_V1
00:08:57.597 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'bluetooth:ble:4C65A8D43A51' changed from ONLINE to OFFLINE
00:08:59.057 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 1 discovered adapters
00:09:01.105 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 9 discovered devices
00:09:01.109 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/E9:6C:F6:6D:B9:68 [N013W] : 1
00:09:01.114 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/F5:B3:4D:6E:03:66 [MI Band 2] : 1
00:09:01.128 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/04:52:C7:B2:64:06 [LE-Bose Revolve SoundLink] : 1
00:09:01.136 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 [MJ_HT_V1] : 1
00:09:01.141 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/45:E7:DC:94:30:04 [45-E7-DC-94-30-04] : 1
00:09:01.148 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/2C:41:A1:02:09:EB [LE-L'ouïe fine] : 1
00:09:01.159 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/59:AB:35:B4:D3:5D [59-AB-35-B4-D3-5D] : 1
00:09:01.177 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/78:4F:43:82:F7:31 [78-4F-43-82-F7-31] : 1
00:09:01.193 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/15:3D:3D:10:3D:3B [Wistiki] : 1
00:09:01.211 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Lost: 0; New: 0; Rediscovered: 9; Stale: 0
00:09:01.609 [WARN ] [.impl.AbstractBluetoothObjectGovernor] - Error occurred while updating governor: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 / 197403f : GDBus.Error:org.bluez.Error.Failed: Software caused connection abort
00:09:01.612 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Resetting governor. Descendants first: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:01.615 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Resetting device governor: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:01.618 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Disable connected notifications: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:01.621 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Disable service resolved notifications: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:01.625 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Disable RSSI notifications: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:01.628 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Disable blocked notifications: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:01.632 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Disable service data notifications: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:01.636 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Disable manufacturer data notifications: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:01.639 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Checking if device connected: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:01.645 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Delegate changed ready state: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : false
00:09:01.645 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Trying to find object factory: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:01.649 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Setting ready: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 : true / false
00:09:01.650 [DEBUG] [.manager.transport.tinyb.TinyBFactory] - Bluetooth object disposal requested: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:01.652 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying manager listeners (governor ready): 0 : false
00:09:01.660 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Disposing device: 4C:65:A8:D4:3A:51
00:09:01.693 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Governor has been reset: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:02.122 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /B8:27:EB:46:83:F3
00:09:02.128 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Updating adapter governor: /B8:27:EB:46:83:F3
00:09:02.133 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Governor has been updated: /B8:27:EB:46:83:F3
00:09:02.138 [DEBUG] [manager.impl.CompletableFutureService] - Trying to complete futures: /B8:27:EB:46:83:F3 : 0
00:09:02.158 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_adapter_B827EB4683F3_last_updated changed from 2018-05-01T00:08:52.120+0200 to 2018-05-01T00:09:02.142+0200
00:09:02.238 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:02.244 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Trying to find object factory: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:02.373 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Checking if delegates are stale: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 / false / false
00:09:02.383 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Updating connection target: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 : true
00:09:02.390 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Current target / new target: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 / /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:02.606 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Governor is not ready. Enforcing an explicit update: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51
00:09:02.612 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Checking if delegates are stale: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 / false / false
00:09:02.617 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Updating connection target: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 : true
00:09:02.622 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Current target / new target: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 / /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:07.733 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_ble_4C65A8D43A51_rssi changed from NULL to -84
00:09:07.737 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Governor is not ready. Enforcing an explicit update: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51
00:09:07.743 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Checking if delegates are stale: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 / false / false
00:09:07.752 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Updating connection target: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 : true
00:09:07.746 [INFO ] [thome.event.ItemChannelLinkAddedEvent] - Link 'bluetooth_ble_4C65A8D43A51_rssi-bluetooth:ble:4C65A8D43A51:rssi' has been added.
00:09:07.761 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Current target / new target: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 / /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:07.783 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_ble_4C65A8D43A51_rssi changed from -84 to UNDEF
00:09:08.162 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_ble_4C65A8D43A51_tx_power changed from NULL to 0
00:09:08.162 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Governor is not ready. Enforcing an explicit update: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51
00:09:08.168 [INFO ] [thome.event.ItemChannelLinkAddedEvent] - Link 'bluetooth_ble_4C65A8D43A51_tx_power-bluetooth:ble:4C65A8D43A51:tx-power' has been added.
00:09:08.169 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Checking if delegates are stale: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 / false / false
00:09:08.174 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Updating connection target: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 : true
00:09:08.177 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Current target / new target: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 / /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:08.186 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_ble_4C65A8D43A51_tx_power changed from 0 to UNDEF
00:09:08.567 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_ble_4C65A8D43A51_estimated_distance changed from NULL to 5.30884444230988350454936153255403041839599609375
00:09:08.570 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Governor is not ready. Enforcing an explicit update: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51
00:09:08.577 [INFO ] [thome.event.ItemChannelLinkAddedEvent] - Link 'bluetooth_ble_4C65A8D43A51_estimated_distance-bluetooth:ble:4C65A8D43A51:estimated-distance' has been added.
00:09:08.591 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Checking if delegates are stale: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 / false / false
00:09:08.598 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Updating connection target: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 : true
00:09:08.604 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Current target / new target: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 / /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:08.628 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_ble_4C65A8D43A51_estimated_distance changed from 5.30884444230988350454936153255403041839599609375 to UNDEF
00:09:09.068 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 1 discovered adapters
00:09:11.247 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 9 discovered devices
00:09:11.252 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/E9:6C:F6:6D:B9:68 [N013W] : 1
00:09:11.257 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/F5:B3:4D:6E:03:66 [MI Band 2] : 1
00:09:11.263 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/04:52:C7:B2:64:06 [LE-Bose Revolve SoundLink] : 1
00:09:11.270 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 [MJ_HT_V1] : 1
00:09:11.275 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/45:E7:DC:94:30:04 [45-E7-DC-94-30-04] : 1
00:09:11.282 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/2C:41:A1:02:09:EB [LE-L'ouïe fine] : 1
00:09:11.289 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/59:AB:35:B4:D3:5D [59-AB-35-B4-D3-5D] : 1
00:09:11.296 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/78:4F:43:82:F7:31 [78-4F-43-82-F7-31] : 1
00:09:11.303 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/15:3D:3D:10:3D:3B [Wistiki] : 1
00:09:11.311 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Lost: 0; New: 0; Rediscovered: 9; Stale: 0
00:09:12.143 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /B8:27:EB:46:83:F3
00:09:12.146 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Updating adapter governor: /B8:27:EB:46:83:F3
00:09:12.149 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Governor has been updated: /B8:27:EB:46:83:F3
00:09:12.151 [DEBUG] [manager.impl.CompletableFutureService] - Trying to complete futures: /B8:27:EB:46:83:F3 : 0
00:09:12.164 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_adapter_B827EB4683F3_last_updated changed from 2018-05-01T00:09:02.142+0200 to 2018-05-01T00:09:12.154+0200
00:09:12.250 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:12.253 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Trying to find object factory: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:12.260 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - A new native object has been acquired: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:12.263 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Initializing governor with the new native object: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:12.266 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Initializing device governor: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:12.270 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Enabling RSSI notification: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : true
00:09:12.273 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Enable RSSI notifications: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:12.277 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Enabling connection notification: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : true
00:09:12.280 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Enable connected notifications: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:12.284 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Enabling services resolved notification: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : true
00:09:12.287 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Enable service resolved notifications: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:12.291 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Enabling blocked notification: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : true
00:09:12.295 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Enable blocked notifications: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:12.298 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Enabling manufacturer data notification: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : true
00:09:12.302 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Enable manufacturer data notifications: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:12.305 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Enabling service data notification: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : true
00:09:12.309 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Enable service data notifications: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:12.312 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Delegate changed ready state: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : true
00:09:12.312 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Getting blocked: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:12.315 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Setting ready: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 : false / true
00:09:12.318 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Checking if device connected: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:12.319 [DEBUG] [manager.impl.CompletableFutureService] - Trying to complete futures: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 : 0
00:09:12.319 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying manager listeners (governor ready): 0 : true
00:09:12.321 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Connecting device: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:12.327 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Connecting: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:12.395 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Checking if delegates are stale: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 / false / false
00:09:12.398 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Updating connection target: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 : true
00:09:12.401 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Current target / new target: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 / /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:14.960 [INFO ] [smarthome.event.ItemCommandEvent     ] - Item 'bluetooth_ble_4C65A8D43A51_connection_control' received command OFF
00:09:14.982 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_ble_4C65A8D43A51_connection_control changed from ON to OFF
00:09:15.009 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Setting connection control: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 : false
00:09:15.019 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Setting connection control: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : true / false
00:09:15.026 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:15.051 [INFO ] [smarthome.event.ThingUpdatedEvent    ] - Thing 'bluetooth:ble:4C65A8D43A51' has been updated.
00:09:15.550 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Connected (notification): /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : true
00:09:15.554 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Notifying device governor listener (connected): /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : 1 : true
00:09:15.559 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Setting connected: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 : false / true
00:09:15.567 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Getting RSSI: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:15.573 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_ble_4C65A8D43A51_connected changed from OFF to ON
00:09:15.574 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Getting TxPower: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:15.578 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Estimated distance: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : 6.6834391756861455
00:09:15.592 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_ble_4C65A8D43A51_connected_adapter changed from UNDEF to B8:27:EB:46:83:F3
00:09:15.597 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_ble_4C65A8D43A51_estimated_distance changed from UNDEF to 6.6834391756861455
00:09:15.601 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_ble_4C65A8D43A51_location changed from Bluetooth Adapters to UNDEF
00:09:15.604 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_ble_4C65A8D43A51_adapter changed from B8:27:EB:46:83:F3 to UNDEF
00:09:15.881 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Checking if device is still alive by getting its RSSI: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:15.886 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Getting RSSI: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:15.894 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Getting RSSI: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:15.900 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Getting TxPower: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:15.905 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Estimated distance: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : 6.6834391756861455
00:09:15.910 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Calculating nearest delegate (current / new): /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 / /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:15.917 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Getting RSSI: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:15.926 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_ble_4C65A8D43A51_rssi changed from UNDEF to -88
00:09:15.928 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Getting TxPower: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:15.934 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Estimated distance: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : 6.6834391756861455
00:09:15.945 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Updating online state: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : false (current) / true (new)
00:09:15.953 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Notifying device governor listener (online): /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : 1 : true
00:09:15.961 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Setting online: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 : false / true
00:09:15.981 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_ble_4C65A8D43A51_online changed from OFF to ON
00:09:15.979 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Governor has been updated: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:15.986 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'bluetooth:ble:4C65A8D43A51' changed from OFFLINE to ONLINE
00:09:15.991 [DEBUG] [manager.impl.CompletableFutureService] - Trying to complete futures: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : 0
00:09:16.003 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_ble_4C65A8D43A51_last_updated changed from UNDEF to 2018-05-01T00:09:15.996+0200
00:09:19.075 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 1 discovered adapters
00:09:21.321 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 2 discovered devices
00:09:21.324 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Device has been lost: tinyb://B8:27:EB:46:83:F3/E9:6C:F6:6D:B9:68
00:09:21.328 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Device has been lost: tinyb://B8:27:EB:46:83:F3/04:52:C7:B2:64:06
00:09:21.332 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Device has been lost: tinyb://B8:27:EB:46:83:F3/F5:B3:4D:6E:03:66
00:09:21.336 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Device has been lost: tinyb://B8:27:EB:46:83:F3/45:E7:DC:94:30:04
00:09:21.339 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Device has been lost: tinyb://B8:27:EB:46:83:F3/2C:41:A1:02:09:EB
00:09:21.343 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Device has been lost: tinyb://B8:27:EB:46:83:F3/59:AB:35:B4:D3:5D
00:09:21.347 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Device has been lost: tinyb://B8:27:EB:46:83:F3/78:4F:43:82:F7:31
00:09:21.351 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 [MJ_HT_V1] : 1
00:09:21.356 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/15:3D:3D:10:3D:3B [Wistiki] : 1
00:09:21.361 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Lost: 7; New: 0; Rediscovered: 2; Stale: 0
00:09:21.889 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Connected (notification): /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : false
00:09:21.892 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Notifying device governor listener (connected): /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : 1 : false
00:09:21.895 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Setting connected: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 : true / false
00:09:21.904 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_ble_4C65A8D43A51_connected changed from ON to OFF
00:09:21.909 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_ble_4C65A8D43A51_connected_adapter changed from B8:27:EB:46:83:F3 to UNDEF
00:09:22.154 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /B8:27:EB:46:83:F3
00:09:22.160 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Updating adapter governor: /B8:27:EB:46:83:F3
00:09:22.166 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Governor has been updated: /B8:27:EB:46:83:F3
00:09:22.171 [DEBUG] [manager.impl.CompletableFutureService] - Trying to complete futures: /B8:27:EB:46:83:F3 : 0
00:09:22.191 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_adapter_B827EB4683F3_last_updated changed from 2018-05-01T00:09:12.154+0200 to 2018-05-01T00:09:22.176+0200
00:09:22.405 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Checking if delegates are stale: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 / false / true
00:09:22.411 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Updating connection target: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 : false
00:09:22.416 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Current target / new target: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 / /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:25.996 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:26.002 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Getting blocked: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:26.006 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Checking if device connected: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:26.011 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Governor has been updated: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:26.016 [DEBUG] [manager.impl.CompletableFutureService] - Trying to complete futures: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : 0
00:09:26.032 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_ble_4C65A8D43A51_last_updated changed from 2018-05-01T00:09:15.996+0200 to 2018-05-01T00:09:26.017+0200
00:09:29.082 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 1 discovered adapters
00:09:29.655 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Connected (notification): /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : true
00:09:29.661 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Notifying device governor listener (connected): /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : 1 : true
00:09:29.666 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Setting connected: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 : false / true
00:09:29.677 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Getting RSSI: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:29.687 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_ble_4C65A8D43A51_connected changed from OFF to ON
00:09:29.698 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_ble_4C65A8D43A51_connected_adapter changed from UNDEF to B8:27:EB:46:83:F3
00:09:29.704 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Getting TxPower: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:29.710 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Estimated distance: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : 6.6834391756861455
00:09:31.384 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 3 discovered devices
00:09:31.391 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/04:52:C7:B2:64:06 [LE-Bose Revolve SoundLink] : 1
00:09:31.399 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 [MJ_HT_V1] : 1
00:09:31.414 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/15:3D:3D:10:3D:3B [Wistiki] : 1
00:09:31.427 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Lost: 0; New: 1; Rediscovered: 2; Stale: 0
00:09:32.182 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /B8:27:EB:46:83:F3
00:09:32.187 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Updating adapter governor: /B8:27:EB:46:83:F3
00:09:32.193 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Governor has been updated: /B8:27:EB:46:83:F3
00:09:32.199 [DEBUG] [manager.impl.CompletableFutureService] - Trying to complete futures: /B8:27:EB:46:83:F3 : 0
00:09:32.213 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_adapter_B827EB4683F3_last_updated changed from 2018-05-01T00:09:22.176+0200 to 2018-05-01T00:09:32.199+0200
00:09:32.421 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Checking if delegates are stale: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 / true / true
00:09:32.427 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Updating connection target: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 : false
00:09:36.017 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:36.021 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Connected (notification): /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : false
00:09:36.023 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Getting blocked: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:36.026 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Notifying device governor listener (connected): /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : 1 : false
00:09:36.030 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Checking if device connected: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:36.035 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Setting connected: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 : true / false
00:09:36.036 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Governor has been updated: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:36.047 [DEBUG] [manager.impl.CompletableFutureService] - Trying to complete futures: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : 0
00:09:36.059 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_ble_4C65A8D43A51_connected_adapter changed from B8:27:EB:46:83:F3 to UNDEF
00:09:36.073 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_ble_4C65A8D43A51_connected changed from ON to OFF
00:09:36.079 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_ble_4C65A8D43A51_last_updated changed from 2018-05-01T00:09:26.017+0200 to 2018-05-01T00:09:36.054+0200
00:09:39.091 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 1 discovered adapters
00:09:39.781 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Connected (notification): /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : true
00:09:39.787 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Notifying device governor listener (connected): /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : 1 : true
00:09:39.792 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Setting connected: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 : false / true
00:09:39.800 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Getting RSSI: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:39.812 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Getting TxPower: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:39.821 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_ble_4C65A8D43A51_connected changed from OFF to ON
00:09:39.828 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Estimated distance: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : 6.6834391756861455
00:09:39.835 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_ble_4C65A8D43A51_connected_adapter changed from UNDEF to B8:27:EB:46:83:F3
00:09:41.485 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 8 discovered devices
00:09:41.492 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/E9:6C:F6:6D:B9:68 [N013W] : 1
00:09:41.502 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/F5:B3:4D:6E:03:66 [MI Band 2] : 1
00:09:41.527 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/45:E7:DC:94:30:04 [45-E7-DC-94-30-04] : 1
00:09:41.541 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/2C:41:A1:02:09:EB [LE-L'ouïe fine] : 1
00:09:41.559 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/59:AB:35:B4:D3:5D [59-AB-35-B4-D3-5D] : 1
00:09:41.582 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/04:52:C7:B2:64:06 [LE-Bose Revolve SoundLink] : 1
00:09:41.592 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 [MJ_HT_V1] : 1
00:09:41.597 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/15:3D:3D:10:3D:3B [Wistiki] : 1
00:09:41.606 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Lost: 0; New: 5; Rediscovered: 3; Stale: 0
00:09:42.199 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /B8:27:EB:46:83:F3
00:09:42.202 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Updating adapter governor: /B8:27:EB:46:83:F3
00:09:42.206 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Governor has been updated: /B8:27:EB:46:83:F3
00:09:42.209 [DEBUG] [manager.impl.CompletableFutureService] - Trying to complete futures: /B8:27:EB:46:83:F3 : 0
00:09:42.220 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_adapter_B827EB4683F3_last_updated changed from 2018-05-01T00:09:32.199+0200 to 2018-05-01T00:09:42.212+0200
00:09:42.432 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Checking if delegates are stale: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 / true / true
00:09:42.437 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Updating connection target: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 : false
00:09:45.939 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Getting TxPower: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:45.945 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Estimated distance: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : 3.9810717055349722
00:09:45.950 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Calculating nearest delegate (current / new): /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 / /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:45.967 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Getting TxPower: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:45.972 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_ble_4C65A8D43A51_rssi changed from -88 to -79
00:09:45.986 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Estimated distance: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : 3.9810717055349722
00:09:46.002 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_ble_4C65A8D43A51_estimated_distance changed from 6.6834391756861455 to 3.9810717055349722
00:09:46.054 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:46.060 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Getting blocked: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:46.065 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Checking if device connected: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:46.070 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Disconnecting device: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:46.075 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Resetting device governor characteristics: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:46.080 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Disconnecting: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:46.117 [INFO ] [smarthome.event.InboxRemovedEvent    ] - Discovery Result with UID 'bluetooth:ble:784F4382F731' has been removed.
00:09:46.179 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Governor has been updated: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:46.184 [DEBUG] [manager.impl.CompletableFutureService] - Trying to complete futures: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : 0
00:09:46.185 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Connected (notification): /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : false
00:09:46.199 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_ble_4C65A8D43A51_last_updated changed from 2018-05-01T00:09:36.054+0200 to 2018-05-01T00:09:46.184+0200
00:09:46.203 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Notifying device governor listener (connected): /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : 1 : false
00:09:46.212 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Setting connected: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 : true / false
00:09:46.229 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_ble_4C65A8D43A51_connected changed from ON to OFF
00:09:46.243 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_ble_4C65A8D43A51_connected_adapter changed from B8:27:EB:46:83:F3 to UNDEF
00:09:49.098 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 1 discovered adapters
00:09:51.663 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 9 discovered devices
00:09:51.672 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/78:4F:43:82:F7:31 [78-4F-43-82-F7-31] : 1
00:09:51.682 [INFO ] [smarthome.event.InboxAddedEvent      ] - Discovery Result with UID 'bluetooth:ble:784F4382F731' has been added.
00:09:51.682 [INFO ] [ig.discovery.internal.PersistentInbox] - Added new thing 'bluetooth:ble:784F4382F731' to inbox.
00:09:51.692 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/E9:6C:F6:6D:B9:68 [N013W] : 1
00:09:51.703 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/F5:B3:4D:6E:03:66 [MI Band 2] : 1
00:09:51.719 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/04:52:C7:B2:64:06 [LE-Bose Revolve SoundLink] : 1
00:09:51.734 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 [MJ_HT_V1] : 1
00:09:51.744 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/45:E7:DC:94:30:04 [45-E7-DC-94-30-04] : 1
00:09:51.756 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/2C:41:A1:02:09:EB [LE-L'ouïe fine] : 1
00:09:51.768 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/59:AB:35:B4:D3:5D [59-AB-35-B4-D3-5D] : 1
00:09:51.780 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/15:3D:3D:10:3D:3B [Wistiki] : 1
00:09:51.793 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Lost: 0; New: 1; Rediscovered: 8; Stale: 0
00:09:52.214 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /B8:27:EB:46:83:F3
00:09:52.218 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Updating adapter governor: /B8:27:EB:46:83:F3
00:09:52.220 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Governor has been updated: /B8:27:EB:46:83:F3
00:09:52.223 [DEBUG] [manager.impl.CompletableFutureService] - Trying to complete futures: /B8:27:EB:46:83:F3 : 0
00:09:52.232 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_adapter_B827EB4683F3_last_updated changed from 2018-05-01T00:09:42.212+0200 to 2018-05-01T00:09:52.223+0200
00:09:52.442 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Checking if delegates are stale: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 / false / true
00:09:52.446 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Updating connection target: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 : false
00:09:52.449 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Current target / new target: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 / /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:56.185 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:56.191 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Getting blocked: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:56.196 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Checking if device connected: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:56.201 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Governor has been updated: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:09:56.206 [DEBUG] [manager.impl.CompletableFutureService] - Trying to complete futures: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : 0
00:09:56.239 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_ble_4C65A8D43A51_last_updated changed from 2018-05-01T00:09:46.184+0200 to 2018-05-01T00:09:56.211+0200
00:09:59.107 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 1 discovered adapters
00:10:01.860 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 9 discovered devices
00:10:01.867 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/E9:6C:F6:6D:B9:68 [N013W] : 1
00:10:01.876 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/F5:B3:4D:6E:03:66 [MI Band 2] : 1
00:10:01.895 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/04:52:C7:B2:64:06 [LE-Bose Revolve SoundLink] : 1
00:10:01.908 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 [MJ_HT_V1] : 1
00:10:01.916 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/45:E7:DC:94:30:04 [45-E7-DC-94-30-04] : 1
00:10:01.927 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/2C:41:A1:02:09:EB [LE-L'ouïe fine] : 1
00:10:01.938 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/59:AB:35:B4:D3:5D [59-AB-35-B4-D3-5D] : 1
00:10:01.944 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/78:4F:43:82:F7:31 [78-4F-43-82-F7-31] : 1
00:10:01.951 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/15:3D:3D:10:3D:3B [Wistiki] : 1
00:10:01.959 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Lost: 0; New: 0; Rediscovered: 9; Stale: 0
00:10:02.223 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /B8:27:EB:46:83:F3
00:10:02.227 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Updating adapter governor: /B8:27:EB:46:83:F3
00:10:02.230 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Governor has been updated: /B8:27:EB:46:83:F3
00:10:02.232 [DEBUG] [manager.impl.CompletableFutureService] - Trying to complete futures: /B8:27:EB:46:83:F3 : 0
00:10:02.245 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_adapter_B827EB4683F3_last_updated changed from 2018-05-01T00:09:52.223+0200 to 2018-05-01T00:10:02.232+0200
00:10:02.452 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Checking if delegates are stale: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 / false / true
00:10:02.455 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Updating connection target: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 : false
00:10:02.458 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Current target / new target: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 / /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:10:06.215 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:10:06.221 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Getting blocked: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:10:06.226 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Checking if device connected: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:10:06.232 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Governor has been updated: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:10:06.238 [DEBUG] [manager.impl.CompletableFutureService] - Trying to complete futures: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : 0
00:10:09.115 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 1 discovered adapters
00:10:12.016 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 9 discovered devices
00:10:12.024 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/E9:6C:F6:6D:B9:68 [N013W] : 1
00:10:12.037 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/F5:B3:4D:6E:03:66 [MI Band 2] : 1
00:10:12.049 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/04:52:C7:B2:64:06 [LE-Bose Revolve SoundLink] : 1
00:10:12.061 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 [MJ_HT_V1] : 1
00:10:12.069 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/45:E7:DC:94:30:04 [45-E7-DC-94-30-04] : 1
00:10:12.081 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/2C:41:A1:02:09:EB [LE-L'ouïe fine] : 1
00:10:12.092 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/59:AB:35:B4:D3:5D [59-AB-35-B4-D3-5D] : 1
00:10:12.104 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/78:4F:43:82:F7:31 [78-4F-43-82-F7-31] : 1
00:10:12.111 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/15:3D:3D:10:3D:3B [Wistiki] : 1
00:10:12.119 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Lost: 0; New: 0; Rediscovered: 9; Stale: 0
00:10:12.234 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /B8:27:EB:46:83:F3
00:10:12.240 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Updating adapter governor: /B8:27:EB:46:83:F3
00:10:12.243 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Governor has been updated: /B8:27:EB:46:83:F3
00:10:12.246 [DEBUG] [manager.impl.CompletableFutureService] - Trying to complete futures: /B8:27:EB:46:83:F3 : 0
00:10:12.255 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_adapter_B827EB4683F3_last_updated changed from 2018-05-01T00:10:02.232+0200 to 2018-05-01T00:10:12.246+0200
00:10:12.463 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Checking if delegates are stale: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 / false / true
00:10:12.469 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Updating connection target: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 : false
00:10:12.473 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Current target / new target: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 / /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:10:16.238 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:10:16.249 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Getting blocked: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:10:16.254 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Checking if device connected: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:10:16.262 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Updating online state: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : true (current) / false (new)
00:10:16.270 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Notifying device governor listener (online): /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : 1 : false
00:10:16.276 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Setting online: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 : true / false
00:10:16.282 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Governor has been updated: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:10:16.294 [DEBUG] [manager.impl.CompletableFutureService] - Trying to complete futures: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : 0
00:10:16.295 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_ble_4C65A8D43A51_online changed from ON to OFF
00:10:16.305 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'bluetooth:ble:4C65A8D43A51' changed from ONLINE to OFFLINE
00:10:19.124 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 1 discovered adapters
00:10:22.153 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 9 discovered devices
00:10:22.161 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/E9:6C:F6:6D:B9:68 [N013W] : 1
00:10:22.167 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/F5:B3:4D:6E:03:66 [MI Band 2] : 1
00:10:22.176 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/04:52:C7:B2:64:06 [LE-Bose Revolve SoundLink] : 1
00:10:22.184 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 [MJ_HT_V1] : 1
00:10:22.191 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/45:E7:DC:94:30:04 [45-E7-DC-94-30-04] : 1
00:10:22.198 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/2C:41:A1:02:09:EB [LE-L'ouïe fine] : 1
00:10:22.207 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/59:AB:35:B4:D3:5D [59-AB-35-B4-D3-5D] : 1
00:10:22.216 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/78:4F:43:82:F7:31 [78-4F-43-82-F7-31] : 1
00:10:22.225 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/15:3D:3D:10:3D:3B [Wistiki] : 1
00:10:22.234 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Lost: 0; New: 0; Rediscovered: 9; Stale: 0
00:10:22.246 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /B8:27:EB:46:83:F3
00:10:22.250 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Updating adapter governor: /B8:27:EB:46:83:F3
00:10:22.254 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Governor has been updated: /B8:27:EB:46:83:F3
00:10:22.258 [DEBUG] [manager.impl.CompletableFutureService] - Trying to complete futures: /B8:27:EB:46:83:F3 : 0
00:10:22.270 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_adapter_B827EB4683F3_last_updated changed from 2018-05-01T00:10:12.246+0200 to 2018-05-01T00:10:22.262+0200
00:10:22.476 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Checking if delegates are stale: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 / false / false
00:10:22.491 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Device /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 last advertised (37s) ago and last interacted (37s) ago. Stale timeout: 60s. Device is considered stale: false
00:10:22.497 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Updating connection target: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 : false
00:10:22.502 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Current target / new target: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 / /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:10:26.293 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:10:26.299 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Getting blocked: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:10:26.306 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Checking if device connected: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:10:26.313 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Governor has been updated: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:10:26.319 [DEBUG] [manager.impl.CompletableFutureService] - Trying to complete futures: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : 0
00:10:29.132 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 1 discovered adapters
00:10:32.264 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /B8:27:EB:46:83:F3
00:10:32.271 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Updating adapter governor: /B8:27:EB:46:83:F3
00:10:32.278 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Governor has been updated: /B8:27:EB:46:83:F3
00:10:32.284 [DEBUG] [manager.impl.CompletableFutureService] - Trying to complete futures: /B8:27:EB:46:83:F3 : 0
00:10:32.302 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_adapter_B827EB4683F3_last_updated changed from 2018-05-01T00:10:22.262+0200 to 2018-05-01T00:10:32.286+0200
00:10:32.331 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 9 discovered devices
00:10:32.335 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/E9:6C:F6:6D:B9:68 [N013W] : 1
00:10:32.340 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/F5:B3:4D:6E:03:66 [MI Band 2] : 1
00:10:32.349 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/04:52:C7:B2:64:06 [LE-Bose Revolve SoundLink] : 1
00:10:32.358 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 [MJ_HT_V1] : 1
00:10:32.364 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/45:E7:DC:94:30:04 [45-E7-DC-94-30-04] : 1
00:10:32.371 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/2C:41:A1:02:09:EB [LE-L'ouïe fine] : 1
00:10:32.380 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/59:AB:35:B4:D3:5D [59-AB-35-B4-D3-5D] : 1
00:10:32.387 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/78:4F:43:82:F7:31 [78-4F-43-82-F7-31] : 1
00:10:32.395 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/15:3D:3D:10:3D:3B [Wistiki] : 1
00:10:32.402 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Lost: 0; New: 0; Rediscovered: 9; Stale: 0
00:10:32.508 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Checking if delegates are stale: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 / false / false
00:10:32.511 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Device /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 last advertised (47s) ago and last interacted (47s) ago. Stale timeout: 60s. Device is considered stale: false
00:10:32.514 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Updating connection target: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 : false
00:10:32.517 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Current target / new target: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 / /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:10:36.319 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:10:36.326 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Getting blocked: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:10:36.342 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Checking if device connected: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:10:36.348 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Governor has been updated: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:10:36.353 [DEBUG] [manager.impl.CompletableFutureService] - Trying to complete futures: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : 0
00:10:39.142 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 1 discovered adapters
00:10:42.287 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /B8:27:EB:46:83:F3
00:10:42.292 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Updating adapter governor: /B8:27:EB:46:83:F3
00:10:42.298 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Governor has been updated: /B8:27:EB:46:83:F3
00:10:42.303 [DEBUG] [manager.impl.CompletableFutureService] - Trying to complete futures: /B8:27:EB:46:83:F3 : 0
00:10:42.321 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_adapter_B827EB4683F3_last_updated changed from 2018-05-01T00:10:32.286+0200 to 2018-05-01T00:10:42.303+0200
00:10:42.459 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 9 discovered devices
00:10:42.465 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/E9:6C:F6:6D:B9:68 [N013W] : 1
00:10:42.473 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/F5:B3:4D:6E:03:66 [MI Band 2] : 1
00:10:42.530 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Checking if delegates are stale: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 / false / false
00:10:42.534 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Device /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 last advertised (57s) ago and last interacted (57s) ago. Stale timeout: 60s. Device is considered stale: false
00:10:42.537 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Updating connection target: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 : false
00:10:42.550 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Current target / new target: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 / /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:10:42.560 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/04:52:C7:B2:64:06 [LE-Bose Revolve SoundLink] : 1
00:10:42.584 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 [MJ_HT_V1] : 1
00:10:42.589 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/45:E7:DC:94:30:04 [45-E7-DC-94-30-04] : 1
00:10:42.596 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/2C:41:A1:02:09:EB [LE-L'ouïe fine] : 1
00:10:42.605 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/59:AB:35:B4:D3:5D [59-AB-35-B4-D3-5D] : 1
00:10:42.615 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/78:4F:43:82:F7:31 [78-4F-43-82-F7-31] : 1
00:10:42.622 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/15:3D:3D:10:3D:3B [Wistiki] : 1
00:10:42.630 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Lost: 0; New: 0; Rediscovered: 9; Stale: 0
00:10:46.353 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:10:46.360 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Getting blocked: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:10:46.366 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Checking if device connected: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:10:46.371 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Governor has been updated: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:10:46.376 [DEBUG] [manager.impl.CompletableFutureService] - Trying to complete futures: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : 0
00:10:48.064 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Getting TxPower: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:10:48.071 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Estimated distance: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : 4.466835921509632
00:10:48.076 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Calculating nearest delegate (current / new): /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 / /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:10:48.084 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Getting TxPower: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:10:48.090 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Estimated distance: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : 4.466835921509632
00:10:48.109 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_ble_4C65A8D43A51_rssi changed from -79 to -81
00:10:48.120 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_ble_4C65A8D43A51_estimated_distance changed from 3.9810717055349722 to 4.466835921509632
00:10:48.129 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_ble_4C65A8D43A51_location changed from UNDEF to Bluetooth Adapters
00:10:48.139 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_ble_4C65A8D43A51_adapter changed from UNDEF to B8:27:EB:46:83:F3
00:10:49.149 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 1 discovered adapters
00:10:52.304 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /B8:27:EB:46:83:F3
00:10:52.311 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Updating adapter governor: /B8:27:EB:46:83:F3
00:10:52.316 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Governor has been updated: /B8:27:EB:46:83:F3
00:10:52.321 [DEBUG] [manager.impl.CompletableFutureService] - Trying to complete futures: /B8:27:EB:46:83:F3 : 0
00:10:52.340 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_adapter_B827EB4683F3_last_updated changed from 2018-05-01T00:10:42.303+0200 to 2018-05-01T00:10:52.321+0200
00:10:52.555 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Checking if delegates are stale: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 / false / false
00:10:52.561 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Device /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 last advertised (5s) ago and last interacted (67s) ago. Stale timeout: 60s. Device is considered stale: false
00:10:52.568 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Updating connection target: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 : false
00:10:52.573 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Current target / new target: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 / /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:10:52.692 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 9 discovered devices
00:10:52.700 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/E9:6C:F6:6D:B9:68 [N013W] : 1
00:10:52.709 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/F5:B3:4D:6E:03:66 [MI Band 2] : 1
00:10:52.729 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/04:52:C7:B2:64:06 [LE-Bose Revolve SoundLink] : 1
00:10:52.749 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 [MJ_HT_V1] : 1
00:10:52.757 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/45:E7:DC:94:30:04 [45-E7-DC-94-30-04] : 1
00:10:52.771 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/2C:41:A1:02:09:EB [LE-L'ouïe fine] : 1
00:10:52.784 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/59:AB:35:B4:D3:5D [59-AB-35-B4-D3-5D] : 1
00:10:52.792 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/78:4F:43:82:F7:31 [78-4F-43-82-F7-31] : 1
00:10:52.801 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/15:3D:3D:10:3D:3B [Wistiki] : 1
00:10:52.810 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Lost: 0; New: 0; Rediscovered: 9; Stale: 0
00:10:56.376 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:10:56.384 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Getting blocked: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:10:56.390 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Checking if device connected: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:10:56.397 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Updating online state: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : false (current) / true (new)
00:10:56.403 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Notifying device governor listener (online): /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : 1 : true
00:10:56.410 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Setting online: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 : false / true
00:10:56.417 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Governor has been updated: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:10:56.426 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'bluetooth:ble:4C65A8D43A51' changed from OFFLINE to ONLINE
00:10:56.436 [DEBUG] [manager.impl.CompletableFutureService] - Trying to complete futures: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : 0
00:10:56.442 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_ble_4C65A8D43A51_online changed from OFF to ON
00:10:56.455 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_ble_4C65A8D43A51_last_updated changed from 2018-05-01T00:09:56.211+0200 to 2018-05-01T00:10:56.436+0200
00:10:59.158 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 1 discovered adapters
00:11:02.321 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /B8:27:EB:46:83:F3
00:11:02.326 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Updating adapter governor: /B8:27:EB:46:83:F3
00:11:02.332 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Governor has been updated: /B8:27:EB:46:83:F3
00:11:02.342 [DEBUG] [manager.impl.CompletableFutureService] - Trying to complete futures: /B8:27:EB:46:83:F3 : 0
00:11:02.360 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_adapter_B827EB4683F3_last_updated changed from 2018-05-01T00:10:52.321+0200 to 2018-05-01T00:11:02.337+0200
00:11:02.579 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Checking if delegates are stale: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 / false / false
00:11:02.584 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Device /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 last advertised (15s) ago and last interacted (77s) ago. Stale timeout: 60s. Device is considered stale: false
00:11:02.590 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Updating connection target: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 : false
00:11:02.601 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Current target / new target: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 / /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:11:02.868 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 9 discovered devices
00:11:02.876 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/E9:6C:F6:6D:B9:68 [N013W] : 1
00:11:02.890 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/F5:B3:4D:6E:03:66 [MI Band 2] : 1
00:11:02.905 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/04:52:C7:B2:64:06 [LE-Bose Revolve SoundLink] : 1
00:11:02.922 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 [MJ_HT_V1] : 1
00:11:02.932 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/45:E7:DC:94:30:04 [45-E7-DC-94-30-04] : 1
00:11:02.946 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/2C:41:A1:02:09:EB [LE-L'ouïe fine] : 1
00:11:02.956 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/59:AB:35:B4:D3:5D [59-AB-35-B4-D3-5D] : 1
00:11:02.964 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/78:4F:43:82:F7:31 [78-4F-43-82-F7-31] : 1
00:11:02.972 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/15:3D:3D:10:3D:3B [Wistiki] : 1
00:11:02.979 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Lost: 0; New: 0; Rediscovered: 9; Stale: 0
00:11:04.099 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Getting TxPower: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:11:04.104 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Estimated distance: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : 4.731512589614805
00:11:04.110 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Calculating nearest delegate (current / new): /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 / /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:11:04.122 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Getting TxPower: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:11:04.128 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Estimated distance: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : 4.731512589614805
00:11:04.134 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_ble_4C65A8D43A51_rssi changed from -81 to -82
00:11:04.161 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_ble_4C65A8D43A51_estimated_distance changed from 4.466835921509632 to 4.731512589614805
00:11:06.436 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:11:06.442 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Getting blocked: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:11:06.447 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Checking if device connected: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:11:06.453 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Governor has been updated: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:11:06.458 [DEBUG] [manager.impl.CompletableFutureService] - Trying to complete futures: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : 0
00:11:06.481 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_ble_4C65A8D43A51_last_updated changed from 2018-05-01T00:10:56.436+0200 to 2018-05-01T00:11:06.458+0200
00:11:09.167 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 1 discovered adapters
00:11:12.337 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /B8:27:EB:46:83:F3
00:11:12.342 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Updating adapter governor: /B8:27:EB:46:83:F3
00:11:12.348 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Governor has been updated: /B8:27:EB:46:83:F3
00:11:12.353 [DEBUG] [manager.impl.CompletableFutureService] - Trying to complete futures: /B8:27:EB:46:83:F3 : 0
00:11:12.374 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_adapter_B827EB4683F3_last_updated changed from 2018-05-01T00:11:02.337+0200 to 2018-05-01T00:11:12.353+0200
00:11:12.609 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Checking if delegates are stale: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 / false / false
00:11:12.615 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Device /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 last advertised (9s) ago and last interacted (87s) ago. Stale timeout: 60s. Device is considered stale: false
00:11:12.621 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Updating connection target: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 : false
00:11:12.627 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Current target / new target: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 / /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:11:13.042 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 10 discovered devices
00:11:13.051 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/6F:64:2E:C0:00:FE [6F-64-2E-C0-00-FE] : 1
00:11:13.060 [INFO ] [ig.discovery.internal.PersistentInbox] - Added new thing 'bluetooth:ble:6F642EC000FE' to inbox.
00:11:13.061 [INFO ] [smarthome.event.InboxAddedEvent      ] - Discovery Result with UID 'bluetooth:ble:6F642EC000FE' has been added.
00:11:13.067 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/E9:6C:F6:6D:B9:68 [N013W] : 1
00:11:13.081 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/F5:B3:4D:6E:03:66 [MI Band 2] : 1
00:11:13.097 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/04:52:C7:B2:64:06 [LE-Bose Revolve SoundLink] : 1
00:11:13.112 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 [MJ_HT_V1] : 1
00:11:13.123 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/45:E7:DC:94:30:04 [45-E7-DC-94-30-04] : 1
00:11:13.136 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/2C:41:A1:02:09:EB [LE-L'ouïe fine] : 1
00:11:13.145 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/59:AB:35:B4:D3:5D [59-AB-35-B4-D3-5D] : 1
00:11:13.154 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/78:4F:43:82:F7:31 [78-4F-43-82-F7-31] : 1
00:11:13.161 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/15:3D:3D:10:3D:3B [Wistiki] : 1
00:11:13.169 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Lost: 0; New: 1; Rediscovered: 9; Stale: 0
00:11:16.458 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:11:16.464 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Getting blocked: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:11:16.469 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Checking if device connected: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:11:16.474 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Governor has been updated: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:11:16.480 [DEBUG] [manager.impl.CompletableFutureService] - Trying to complete futures: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : 0
00:11:19.175 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 1 discovered adapters
00:11:22.354 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /B8:27:EB:46:83:F3
00:11:22.360 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Updating adapter governor: /B8:27:EB:46:83:F3
00:11:22.365 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Governor has been updated: /B8:27:EB:46:83:F3
00:11:22.370 [DEBUG] [manager.impl.CompletableFutureService] - Trying to complete futures: /B8:27:EB:46:83:F3 : 0
00:11:22.389 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_adapter_B827EB4683F3_last_updated changed from 2018-05-01T00:11:12.353+0200 to 2018-05-01T00:11:22.370+0200
00:11:22.633 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Checking if delegates are stale: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 / false / false
00:11:22.639 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Device /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 last advertised (19s) ago and last interacted (97s) ago. Stale timeout: 60s. Device is considered stale: false
00:11:22.644 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Updating connection target: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 : false
00:11:22.649 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Current target / new target: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 / /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:11:23.241 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 10 discovered devices
00:11:23.248 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/E9:6C:F6:6D:B9:68 [N013W] : 1
00:11:23.266 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/F5:B3:4D:6E:03:66 [MI Band 2] : 1
00:11:23.282 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/04:52:C7:B2:64:06 [LE-Bose Revolve SoundLink] : 1
00:11:23.310 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 [MJ_HT_V1] : 1
00:11:23.320 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/45:E7:DC:94:30:04 [45-E7-DC-94-30-04] : 1
00:11:23.332 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/2C:41:A1:02:09:EB [LE-L'ouïe fine] : 1
00:11:23.345 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/6F:64:2E:C0:00:FE [6F-64-2E-C0-00-FE] : 1
00:11:23.353 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/59:AB:35:B4:D3:5D [59-AB-35-B4-D3-5D] : 1
00:11:23.361 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/78:4F:43:82:F7:31 [78-4F-43-82-F7-31] : 1
00:11:23.369 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/15:3D:3D:10:3D:3B [Wistiki] : 1
00:11:23.381 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Lost: 0; New: 0; Rediscovered: 10; Stale: 0
00:11:23.934 [INFO ] [smarthome.event.ItemStateChangedEvent] - Temperature_Salle_de_bain changed from 21.39999999999999857891452847979962825775146484375 to 21.3
00:11:23.973 [INFO ] [smarthome.event.ItemStateChangedEvent] - Humidity_Salle_de_bain changed from 64.400000000000005684341886080801486968994140625 to 47.1
00:11:26.480 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:11:26.484 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Getting blocked: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:11:26.488 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Checking if device connected: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:11:26.492 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Governor has been updated: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:11:26.495 [DEBUG] [manager.impl.CompletableFutureService] - Trying to complete futures: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : 0
00:11:29.183 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 1 discovered adapters
00:11:32.378 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /B8:27:EB:46:83:F3
00:11:32.386 [DEBUG] [ooth.manager.impl.AdapterGovernorImpl] - Updating adapter governor: /B8:27:EB:46:83:F3
00:11:32.393 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Governor has been updated: /B8:27:EB:46:83:F3
00:11:32.399 [DEBUG] [manager.impl.CompletableFutureService] - Trying to complete futures: /B8:27:EB:46:83:F3 : 0
00:11:32.418 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_adapter_B827EB4683F3_last_updated changed from 2018-05-01T00:11:22.370+0200 to 2018-05-01T00:11:32.403+0200
00:11:32.655 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Checking if delegates are stale: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 / false / false
00:11:32.662 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Device /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 last advertised (29s) ago and last interacted (107s) ago. Stale timeout: 60s. Device is considered stale: false
00:11:32.667 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Updating connection target: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 : false
00:11:32.673 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Current target / new target: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 / /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:11:33.447 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 10 discovered devices
00:11:33.454 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/E9:6C:F6:6D:B9:68 [N013W] : 1
00:11:33.464 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/F5:B3:4D:6E:03:66 [MI Band 2] : 1
00:11:33.478 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/04:52:C7:B2:64:06 [LE-Bose Revolve SoundLink] : 1
00:11:33.493 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 [MJ_HT_V1] : 1
00:11:33.502 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/45:E7:DC:94:30:04 [45-E7-DC-94-30-04] : 1
00:11:33.512 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/2C:41:A1:02:09:EB [LE-L'ouïe fine] : 1
00:11:33.520 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/6F:64:2E:C0:00:FE [6F-64-2E-C0-00-FE] : 1
00:11:33.528 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/59:AB:35:B4:D3:5D [59-AB-35-B4-D3-5D] : 1
00:11:33.536 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/78:4F:43:82:F7:31 [78-4F-43-82-F7-31] : 1
00:11:33.544 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Notifying device discovery listeners (discovered): [Device] tinyb://B8:27:EB:46:83:F3/15:3D:3D:10:3D:3B [Wistiki] : 1
00:11:33.552 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Lost: 0; New: 0; Rediscovered: 10; Stale: 0
00:11:36.495 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Updating governor. Trying to acquire lock: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:11:36.502 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Getting blocked: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:11:36.509 [DEBUG] [h.manager.transport.tinyb.TinyBDevice] - Checking if device connected: tinyb://B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:11:36.516 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Updating online state: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : true (current) / false (new)
00:11:36.523 [DEBUG] [tooth.manager.impl.DeviceGovernorImpl] - Notifying device governor listener (online): /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : 1 : false
00:11:36.529 [DEBUG] [nager.impl.CombinedDeviceGovernorImpl] - Setting online: /XX:XX:XX:XX:XX:XX/4C:65:A8:D4:3A:51 : true / false
00:11:36.536 [DEBUG] [.impl.AbstractBluetoothObjectGovernor] - Governor has been updated: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51
00:11:36.543 [INFO ] [smarthome.event.ItemStateChangedEvent] - bluetooth_ble_4C65A8D43A51_online changed from ON to OFF
00:11:36.555 [INFO ] [ome.event.ThingStatusInfoChangedEvent] - 'bluetooth:ble:4C65A8D43A51' changed from ONLINE to OFFLINE
00:11:36.562 [DEBUG] [manager.impl.CompletableFutureService] - Trying to complete futures: /B8:27:EB:46:83:F3/4C:65:A8:D4:3A:51 : 0
00:11:39.193 [DEBUG] [oth.manager.impl.BluetoothManagerImpl] - Transport [tinyb] reported 1 discovered adapters

^C~                                                                                                                                                                                      openhab>

robotsflo commented 6 years ago

I have to reinstall tinyb for it to show new devices... After that I put the battery in the sensor. When it was discovered in the inbox, i added it.

vkolotov commented 6 years ago

Hey @robotsflo, it seems to me working:

00:11:23.934 [INFO ] [smarthome.event.ItemStateChangedEvent] - Temperature_Salle_de_bain changed from 21.39999999999999857891452847979962825775146484375 to 21.3
00:11:23.973 [INFO ] [smarthome.event.ItemStateChangedEvent] - Humidity_Salle_de_bain changed from 64.400000000000005684341886080801486968994140625 to 47.1

Double check your thing in OH, refresh your browser tab.

robotsflo commented 6 years ago

I forgot to remove this line, it's my legacy system

vkolotov commented 6 years ago

Ok, I'll double check your logs again.

vkolotov commented 6 years ago

Just checking, what version of Bluez are you using?

vkolotov commented 6 years ago

Can you please do this?

pi@raspberrypi:~ $ sudo systemctl status bluetooth

robotsflo commented 6 years ago

sudo systemctl status bluetooth
● bluetooth.service - Bluetooth service
   Loaded: loaded (/lib/systemd/system/bluetooth.service; enabled)
   Active: active (running) since lun. 2018-04-30 23:29:57 CEST; 1h 15min ago
     Docs: man:bluetoothd(8)
 Main PID: 641 (bluetoothd)
   Status: "Running"
   CGroup: /system.slice/bluetooth.service
           └─641 /usr/lib/bluetooth/bluetoothd

avril 30 23:29:57 openhab systemd[1]: Started Bluetooth service.
avril 30 23:29:57 openhab bluetoothd[641]: Starting SDP server
avril 30 23:29:57 openhab bluetoothd[641]: Bluetooth management interface 1.14 initialized
avril 30 23:29:57 openhab bluetoothd[641]: Sap driver initialization failed.
avril 30 23:29:57 openhab bluetoothd[641]: sap-server: Operation not permitted (1)
avril 30 23:42:04 openhab bluetoothd[641]: g_attrib_unregister: invalid id
avril 30 23:42:26 openhab bluetoothd[641]: g_attrib_unregister: invalid id
avril 30 23:42:40 openhab bluetoothd[641]: g_attrib_unregister: invalid id
mai 01 00:09:36 openhab bluetoothd[641]: g_attrib_unregister: invalid id
mai 01 00:09:46 openhab bluetoothd[641]: g_attrib_unregister: invalid id

vkolotov commented 6 years ago

Ok, can you please do this now:

pi@raspberrypi:~ $ /usr/lib/bluetooth/bluetoothd --version

robotsflo commented 6 years ago

root@openhab:~# dpkg -s bluez | grep Version
Version: 5.23-2+rpi2
root@openhab:~# /usr/lib/bluetooth/bluetoothd --version
5.23

vkolotov commented 6 years ago

Right... that's the thing :) You are running very-very ancient version of Bluez.

Please use your package manager to update it exactly to 5.47, or if your package manager does not update it, use this guide to build it from sources (not very difficult to do): https://github.com/sputnikdev/bluetooth-manager-tinyb#prerequisites

Please report how it goes.

robotsflo commented 6 years ago

Sorry, i'm updating it now and i will report back to you. Thx

robotsflo commented 6 years ago

Thank you very much.

The next time, i will read the read me :)

sputnikdev / eclipse-smarthome-bluetooth-binding

Upgrade binding 1.0 to 1.1.4 #49