Here is my starting sequence after enabling DEBUG logs for MiOS binding:
2016-03-06 19:23:58.649 [DEBUG] [org.openhab.binding.mios ] - BundleEvent STARTING - org.openhab.binding.mios
2016-03-06 19:23:58.658 [DEBUG] [.binding.mios.internal.MiosActivator] - MiOS binding has been started
2016-03-06 19:23:58.719 [DEBUG] [org.openhab.binding.mios ] - ServiceEvent REGISTERED - {org.openhab.model.item.binding.BindingConfigReader, org.openhab.binding.mios.MiosBindingProvider}={component.name=org.openhab.binding.mios.genericbindingprovider, component.id=162, service.id=270, service.bundleid=155, service.scope=bundle} - org.openhab.binding.mios
2016-03-06 19:23:58.739 [DEBUG] [ios.internal.MiosBindingProviderImpl] - setItemRegistry: called
2016-03-06 19:23:58.821 [DEBUG] [org.openhab.binding.mios ] - ServiceEvent REGISTERED - {org.osgi.service.event.EventHandler, org.osgi.service.cm.ManagedService, org.openhab.binding.mios.MiosActionProvider}={event.topics=openhab/*, service.pid=org.openhab.mios, component.name=org.openhab.binding.mios.activebinding, component.id=161, service.id=272, service.bundleid=155, service.scope=bundle} - org.openhab.binding.mios
2016-03-06 19:23:58.853 [DEBUG] [ab.binding.mios.internal.MiosBinding] - allBindingsChanged: start provider 'org.openhab.binding.mios.internal.MiosBindingProviderImpl@f078bd'
2016-03-06 19:23:58.858 [DEBUG] [ab.binding.mios.internal.MiosBinding] - MiosBinding activate()
2016-03-06 19:23:58.897 [DEBUG] [org.openhab.binding.mios ] - BundleEvent STARTED - org.openhab.binding.mios
2016-03-06 19:24:01.586 [DEBUG] [ab.binding.mios.internal.MiosBinding] - updated: Created Unit 'house'
2016-03-06 19:24:01.588 [DEBUG] [ab.binding.mios.internal.MiosBinding] - registerAllWatches: start
2016-03-06 19:24:01.590 [DEBUG] [ab.binding.mios.internal.MiosBinding] - registerAllWatches: provider 'class org.openhab.binding.mios.internal.MiosBindingProviderImpl'
2016-03-06 19:24:04.999 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'maisonOH2.sitemap'
2016-03-06 19:24:06.233 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'rrd4j.persist'
2016-03-06 19:24:06.505 [INFO ] [el.core.internal.ModelRepositoryImpl] - Loading model 'maison.items'
2016-03-06 19:24:06.866 [DEBUG] [os.internal.config.MiosBindingConfig] - Successfully loaded UPnP Service Aliases from 'org/openhab/binding/mios/internal/config/ServiceAliases.properties', entries '133'
2016-03-06 19:24:06.873 [DEBUG] [os.internal.config.MiosBindingConfig] - Successfully loaded Device Parameter defaults from 'org/openhab/binding/mios/internal/config/DeviceDefaults.properties', entries '23'
2016-03-06 19:24:06.884 [DEBUG] [ios.internal.MiosBindingProviderImpl] - processBindingConfiguration: Adding Item 'MiniMoteSceneActivated' Binding 'unit:house,device:6/service/urn:micasaverde-com:serviceId:SceneController1/sl_SceneActivated', from 'maison.items'
2016-03-06 19:24:06.887 [DEBUG] [ab.binding.mios.internal.MiosBinding] - bindingChanged: start provider 'org.openhab.binding.mios.internal.MiosBindingProviderImpl@f078bd', itemName 'MiniMoteSceneActivated'
2016-03-06 19:24:06.890 [DEBUG] [ab.binding.mios.internal.MiosBinding] - registerWatch: start miosProvider 'org.openhab.binding.mios.internal.MiosBindingProviderImpl@f078bd', itemName 'MiniMoteSceneActivated'
2016-03-06 19:24:06.892 [DEBUG] [ab.binding.mios.internal.MiosBinding] - Creating new MiosConnector for 'house' on 192.168.1.19
2016-03-06 19:24:06.902 [DEBUG] [ding.mios.internal.MiosUnitConnector] - Constructor: unit 'org.openhab.binding.mios.internal.MiosUnit@fec825', binding 'org.openhab.binding.mios.internal.MiosBinding@1158818'
2016-03-06 19:24:07.286 [INFO ] [ternal.scheduler.WeatherJobScheduler] - Disabling weather locationId 'home', no binding available
2016-03-06 19:24:07.696 [DEBUG] [ios.internal.MiosBindingProviderImpl] - processBindingConfiguration: Adding Item 'MiniMoteSceneDeactivated' Binding 'unit:house,device:6/service/urn:micasaverde-com:serviceId:SceneController1/sl_SceneDeactivated', from 'maison.items'
2016-03-06 19:24:07.696 [DEBUG] [ding.mios.internal.MiosUnitConnector] - run: URI Built was 'http://192.168.1.19:3480/data_request?id=status2' loop '0'
2016-03-06 19:24:07.698 [DEBUG] [ab.binding.mios.internal.MiosBinding] - bindingChanged: start provider 'org.openhab.binding.mios.internal.MiosBindingProviderImpl@f078bd', itemName 'MiniMoteSceneDeactivated'
2016-03-06 19:24:07.704 [DEBUG] [ab.binding.mios.internal.MiosBinding] - registerWatch: start miosProvider 'org.openhab.binding.mios.internal.MiosBindingProviderImpl@f078bd', itemName 'MiniMoteSceneDeactivated'
Then you have all calls to bindingChanged including the call for item LampeSalon:
2016-03-06 19:24:07.781 [DEBUG] [ios.internal.MiosBindingProviderImpl] - processBindingConfiguration: Adding Item 'LampeSalon' Binding 'unit:house,device:4/service/urn:upnp-org:serviceId:SwitchPower1/Status,command:ON|OFF,in:MAP(miosSwitchIn.map),out:MAP(miosSwitchOut.map)', from 'maison.items'
2016-03-06 19:24:07.782 [DEBUG] [ab.binding.mios.internal.MiosBinding] - bindingChanged: start provider 'org.openhab.binding.mios.internal.MiosBindingProviderImpl@f078bd', itemName 'LampeSalon'
2016-03-06 19:24:07.784 [DEBUG] [ab.binding.mios.internal.MiosBinding] - registerWatch: start miosProvider 'org.openhab.binding.mios.internal.MiosBindingProviderImpl@f078bd', itemName 'LampeSalon'
And then just after:
2016-03-06 19:24:08.560 [DEBUG] [ding.mios.internal.MiosUnitConnector] - processResponse: success! loadTime=1457206105, dataVersion=206114798 devices(29) scenes(1) rooms(0) sections(0)
2016-03-06 19:24:08.560 [INFO ] [ome.event.GroupItemStateChangedEvent] - All changed from NULL to UNDEF through GPersist
2016-03-06 19:24:08.566 [INFO ] [ome.event.GroupItemStateChangedEvent] - GPersist changed from NULL to UNDEF through GVirtualData
2016-03-06 19:24:08.571 [INFO ] [ome.event.GroupItemStateChangedEvent] - GVirtualData changed from NULL to 0 through TempChart1Periode
2016-03-06 19:24:08.576 [INFO ] [marthome.event.ItemStateChangedEvent] - TempChart1Periode changed from NULL to 0
2016-03-06 19:24:08.581 [INFO ] [marthome.event.ItemStateChangedEvent] - TempChart2Periode changed from NULL to 0
2016-03-06 19:24:08.585 [INFO ] [marthome.event.ItemStateChangedEvent] - TempChart3Periode changed from NULL to 0
2016-03-06 19:24:08.589 [INFO ] [marthome.event.ItemStateChangedEvent] - HumChart1Periode changed from NULL to 0
2016-03-06 19:24:08.597 [INFO ] [marthome.event.ItemStateChangedEvent] - HumChart2Periode changed from NULL to 0
2016-03-06 19:24:08.635 [INFO ] [marthome.event.ItemStateChangedEvent] - Date changed from NULL to 2016-03-06T19:24:08.599+0100
2016-03-06 19:24:08.674 [ERROR] [ding.mios.internal.MiosUnitConnector] - Exception 'Item 'LampeSalon' could not be found in the item registry' raised pushing property 'unit:house,device:4/service/urn:upnp-org:serviceId:SwitchPower1/Status' value '1' into openHAB
org.openhab.core.items.ItemNotFoundException: Item 'LampeSalon' could not be found in the item registry
at org.openhab.core.items.internal.ItemUIRegistryDelegate.getItem(ItemUIRegistryDelegate.java:49)[154:org.openhab.core.compat1x:2.0.0.201603021555]
at org.openhab.binding.mios.internal.MiosBinding.internalPropertyUpdate(MiosBinding.java:493)[155:org.openhab.binding.mios:1.9.0.201603050212]
at org.openhab.binding.mios.internal.MiosBinding.postPropertyUpdate(MiosBinding.java:430)[155:org.openhab.binding.mios:1.9.0.201603050212]
at org.openhab.binding.mios.internal.MiosUnitConnector$LongPoll.publish(MiosUnitConnector.java:471)[155:org.openhab.binding.mios:1.9.0.201603050212]
at org.openhab.binding.mios.internal.MiosUnitConnector$LongPoll.processDevices(MiosUnitConnector.java:595)[155:org.openhab.binding.mios:1.9.0.201603050212]
at org.openhab.binding.mios.internal.MiosUnitConnector$LongPoll.processResponse(MiosUnitConnector.java:706)[155:org.openhab.binding.mios:1.9.0.201603050212]
at org.openhab.binding.mios.internal.MiosUnitConnector$LongPoll.run(MiosUnitConnector.java:782)[155:org.openhab.binding.mios:1.9.0.201603050212]
at java.lang.Thread.run(Thread.java:744)[:1.8.0]
2016-03-06 19:24:08.707 [DEBUG] [ab.binding.mios.internal.MiosBinding] - internalPropertyUpdate: BOUND (Full) Updating 'MiniMoteSceneActivated {mios="unit:house,device:6/service/urn:micasaverde-com:serviceId:SceneController1/sl_SceneActivated"}' to '2', was 'Uninitialized'
2016-03-06 19:24:08.716 [DEBUG] [ab.binding.mios.internal.MiosBinding] - internalPropertyUpdate: BOUND (Full) Updating 'MiniMoteSceneDeactivated {mios="unit:house,device:6/service/urn:micasaverde-com:serviceId:SceneController1/sl_SceneDeactivated"}' to '3', was 'Uninitialized'
2016-03-06 19:24:08.730 [INFO ] [marthome.event.ItemStateChangedEvent] - MiniMoteSceneActivated changed from NULL to 2
2016-03-06 19:24:08.734 [ERROR] [ding.mios.internal.MiosUnitConnector] - Exception 'Item 'SpotsCinema' could not be found in the item registry' raised pushing property 'unit:house,device:7/service/urn:upnp-org:serviceId:Dimming1/LoadLevelStatus' value '0' into openHAB
org.openhab.core.items.ItemNotFoundException: Item 'SpotsCinema' could not be found in the item registry
at org.openhab.core.items.internal.ItemUIRegistryDelegate.getItem(ItemUIRegistryDelegate.java:49)[154:org.openhab.core.compat1x:2.0.0.201603021555]
at org.openhab.binding.mios.internal.MiosBinding.internalPropertyUpdate(MiosBinding.java:493)[155:org.openhab.binding.mios:1.9.0.201603050212]
at org.openhab.binding.mios.internal.MiosBinding.postPropertyUpdate(MiosBinding.java:430)[155:org.openhab.binding.mios:1.9.0.201603050212]
at org.openhab.binding.mios.internal.MiosUnitConnector$LongPoll.publish(MiosUnitConnector.java:471)[155:org.openhab.binding.mios:1.9.0.201603050212]
at org.openhab.binding.mios.internal.MiosUnitConnector$LongPoll.processDevices(MiosUnitConnector.java:595)[155:org.openhab.binding.mios:1.9.0.201603050212]
at org.openhab.binding.mios.internal.MiosUnitConnector$LongPoll.processResponse(MiosUnitConnector.java:706)[155:org.openhab.binding.mios:1.9.0.201603050212]
at org.openhab.binding.mios.internal.MiosUnitConnector$LongPoll.run(MiosUnitConnector.java:782)[155:org.openhab.binding.mios:1.9.0.201603050212]
at java.lang.Thread.run(Thread.java:744)[:1.8.0]
2016-03-06 19:24:08.738 [INFO ] [marthome.event.ItemStateChangedEvent] - MiniMoteSceneDeactivated changed from NULL to 3
So it first fails for LampeSalon and then immediately works for MiniMoteSceneActivated
and MiniMoteSceneDeactivated, then fails for SpotsCinema…
That is really weird !!!