{"action":"created","issue":{"url":"https://api.github.com/repos/openhab/openhab/issues/3297","labels_url":"https://api.github.com/repos/openhab/openhab/issues/3297/labels{/name}","comments_url":"https://api.github.com/repos/openhab/openhab/issues/3297/comments","events_url":"https://api.github.com/repos/openhab/openhab/issues/3297/events","html_url":"https://github.com/openhab/openhab/issues/3297","id":111536626,"number":3297,"title":"Aeon Labs minimote, unable to fully configure","user":{"login":"khendricks","id":981958,"avatar_url":"https://avatars.githubusercontent.com/u/981958?v=3","gravatar_id":"","url":"https://api.github.com/users/khendricks","html_url":"https://github.com/khendricks","followers_url":"https://api.github.com/users/khendricks/followers","following_url":"https://api.github.com/users/khendricks/following{/other_user}","gists_url":"https://api.github.com/users/khendricks/gists{/gist_id}","starred_url":"https://api.github.com/users/khendricks/starred{/owner}{/repo}","subscriptions_url":"https://api.github.com/users/khendricks/subscriptions","organizations_url":"https://api.github.com/users/khendricks/orgs","repos_url":"https://api.github.com/users/khendricks/repos","events_url":"https://api.github.com/users/khendricks/events{/privacy}","received_events_url":"https://api.github.com/users/khendricks/received_events","type":"User","site_admin":false},"labels":[],"state":"open","locked":false,"assignee":null,"milestone":null,"comments":1,"created_at":"2015-10-15T03:02:57Z","updated_at":"2015-10-15T15:56:00Z","closed_at":null,"body":"I am able to get the device included in the network but it never seems to be able to finish configuring itself.  I am able to see the name of the remote and all the different configurations in habmin but am never able to actually set any of the configurations.\r\n\r\nOnce I got the remote included, you can press a button the remote to \"wake\" it.  Every time I do that it seems like openhab is trying to send out the pending messages but never is able to.\r\n\r\nI am using a 1.8.0 build of the zwave plugin.\r\n\r\nThe following are some logs from when I \"wake\" the remote.\r\n\r\n```\r\n2015-10-14 22:55:26.989 [DEBUG] [eController$ZWaveReceiveThread:1528]- Receive Message = 01 11 00 49 84 19 0B 01 01 01 86 72 70 9B EF 85 2B 26 48\r\n2015-10-14 22:55:26.989 [DEBUG] [eController$ZWaveReceiveThread:1452]- Receive queue ADD: Length=1\r\n2015-10-14 22:55:26.989 [DEBUG] [b.z.i.protocol.ZWaveController:1210]- Receive queue TAKE: Length=0\r\n2015-10-14 22:55:26.990 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 11 00 49 84 19 0B 01 01 01 86 72 70 9B EF 85 2B 26 48\r\n2015-10-14 22:55:26.990 [DEBUG] [b.z.i.protocol.ZWaveController:1211]- Process Message = 01 11 00 49 84 19 0B 01 01 01 86 72 70 9B EF 85 2B 26 48\r\n2015-10-14 22:55:26.990 [DEBUG] [b.z.i.protocol.ZWaveController:190 ]- Message: class = ApplicationUpdate (0x49), type = Request (0x00), payload = 84 19 0B 01 01 01 86 72 70 9B EF 85 2B 26\r\n2015-10-14 22:55:26.990 [DEBUG] [.ApplicationUpdateMessageClass:44  ]- NODE 25: Application update request. Node information received.\r\n2015-10-14 22:55:26.991 [DEBUG] [.o.b.z.i.p.c.ZWaveCommandClass:224 ]- NODE 25: Creating new instance of command class VERSION\r\n2015-10-14 22:55:26.991 [DEBUG] [.o.b.z.i.p.c.ZWaveCommandClass:224 ]- NODE 25: Creating new instance of command class MANUFACTURER_SPECIFIC\r\n2015-10-14 22:55:26.991 [DEBUG] [.o.b.z.i.p.c.ZWaveCommandClass:224 ]- NODE 25: Creating new instance of command class CONFIGURATION\r\n2015-10-14 22:55:26.991 [WARN ] [.o.b.z.i.p.c.ZWaveCommandClass:221 ]- NODE 25: Unsupported command class ASSOCIATION_COMMAND_CONFIGURATION\r\n2015-10-14 22:55:26.991 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:411 ]- NODE 25: Is awake with 2 messages in the wake-up queue.\r\n2015-10-14 22:55:26.992 [DEBUG] [b.z.i.protocol.ZWaveController:682 ]- Notifying event listeners: ZWaveWakeUpEvent\r\n2015-10-14 22:55:26.992 [DEBUG] [.z.internal.ZWaveActiveBinding:433 ]- ZwaveIncomingEvent\r\n2015-10-14 22:55:26.992 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:1028]- NODE 25: Wakeup during initialisation.\r\n2015-10-14 22:55:26.992 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:267 ]- NODE 25: Node advancer - STATIC_VALUES: queue length(1), free to send(false)\r\n2015-10-14 22:55:26.992 [DEBUG] [b.z.i.protocol.ZWaveController:985 ]- Callback ID = 93\r\n2015-10-14 22:55:26.992 [DEBUG] [b.z.i.protocol.ZWaveController:667 ]- Enqueueing message. Queue length = 1\r\n2015-10-14 22:55:26.992 [DEBUG] [WaveController$ZWaveSendThread:1258]- Took message from queue for sending. Queue length = 0\r\n2015-10-14 22:55:26.993 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:240 ]- NODE 25: Node advancer - queued packet. Queue length is 1\r\n2015-10-14 22:55:26.993 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 09 00 13 19 02 84 05 25 5D 07\r\n2015-10-14 22:55:26.993 [DEBUG] [b.z.i.protocol.ZWaveController:985 ]- Callback ID = 94\r\n2015-10-14 22:55:26.993 [DEBUG] [WaveController$ZWaveSendThread:1315]- NODE 25: Sending REQUEST Message = 01 09 00 13 19 02 84 05 25 5D 07\r\n2015-10-14 22:55:26.993 [DEBUG] [b.z.i.protocol.ZWaveController:667 ]- Enqueueing message. Queue length = 1\r\n2015-10-14 22:55:26.993 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:63  ]- Sent message Message: class = SendData (0x13), type = Request (0x00), payload = 04 01 00\r\n2015-10-14 22:55:26.994 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:64  ]- Recv message Message: class = ApplicationUpdate (0x49), type = Request (0x00), payload = 84 19 0B 01 01 01 86 72 70 9B EF 85 2B 26\r\n2015-10-14 22:55:26.994 [DEBUG] [.z.i.p.s.ZWaveCommandProcessor:65  ]- Checking transaction complete: class=ApplicationUpdate, expected=SendData, cancelled=false\r\n2015-10-14 22:55:26.997 [DEBUG] [eController$ZWaveReceiveThread:1528]- Receive Message = 01 04 01 13 01 E8\r\n2015-10-14 22:55:26.997 [DEBUG] [eController$ZWaveReceiveThread:1452]- Receive queue ADD: Length=1\r\n2015-10-14 22:55:26.997 [DEBUG] [b.z.i.protocol.ZWaveController:1210]- Receive queue TAKE: Length=0\r\n2015-10-14 22:55:26.998 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 04 01 13 01 E8\r\n2015-10-14 22:55:26.998 [DEBUG] [b.z.i.protocol.ZWaveController:1211]- Process Message = 01 04 01 13 01 E8\r\n2015-10-14 22:55:26.998 [DEBUG] [b.z.i.protocol.ZWaveController:190 ]- Message: class = SendData (0x13), type = Response (0x01), payload = 01\r\n2015-10-14 22:55:26.998 [DEBUG] [b.z.i.p.s.SendDataMessageClass:38  ]- NODE 25: Sent Data successfully placed on stack.\r\n2015-10-14 22:55:27.008 [DEBUG] [eController$ZWaveReceiveThread:1528]- Receive Message = 01 08 00 04 04 19 02 84 07 6F\r\n2015-10-14 22:55:27.008 [DEBUG] [eController$ZWaveReceiveThread:1452]- Receive queue ADD: Length=1\r\n2015-10-14 22:55:27.008 [DEBUG] [b.z.i.protocol.ZWaveController:1210]- Receive queue TAKE: Length=0\r\n2015-10-14 22:55:27.009 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 08 00 04 04 19 02 84 07 6F\r\n2015-10-14 22:55:27.009 [DEBUG] [b.z.i.protocol.ZWaveController:1211]- Process Message = 01 08 00 04 04 19 02 84 07 6F\r\n2015-10-14 22:55:27.009 [DEBUG] [b.z.i.protocol.ZWaveController:190 ]- Message: class = ApplicationCommandHandler (0x04), type = Request (0x00), payload = 04 19 02 84 07\r\n2015-10-14 22:55:27.009 [DEBUG] [ApplicationCommandMessageClass:38  ]- NODE 25: Application Command Request (ALIVE:STATIC_VALUES)\r\n2015-10-14 22:55:27.009 [DEBUG] [ApplicationCommandMessageClass:56  ]- NODE 25: Incoming command class WAKE_UP\r\n2015-10-14 22:55:27.009 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:136 ]- NODE 25: Received Wake Up Request\r\n2015-10-14 22:55:27.009 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:182 ]- NODE 25: Received WAKE_UP_NOTIFICATION\r\n2015-10-14 22:55:27.009 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:411 ]- NODE 25: Is awake with 1 messages in the wake-up queue.\r\n2015-10-14 22:55:27.009 [DEBUG] [b.z.i.protocol.ZWaveController:682 ]- Notifying event listeners: ZWaveWakeUpEvent\r\n2015-10-14 22:55:27.009 [DEBUG] [.z.internal.ZWaveActiveBinding:433 ]- ZwaveIncomingEvent\r\n2015-10-14 22:55:27.010 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:1028]- NODE 25: Wakeup during initialisation.\r\n2015-10-14 22:55:27.010 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:267 ]- NODE 25: Node advancer - STATIC_VALUES: queue length(1), free to send(false)\r\n2015-10-14 22:55:27.010 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:333 ]- NODE 25: Node advancer: loop - STATIC_VALUES try 3: stageAdvanced(false)\r\n2015-10-14 22:55:27.010 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:580 ]- NODE 25: Node advancer: STATIC_VALUES - checking BASIC\r\n2015-10-14 22:55:27.010 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:580 ]- NODE 25: Node advancer: STATIC_VALUES - checking WAKE_UP\r\n2015-10-14 22:55:27.010 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:583 ]- NODE 25: Node advancer: STATIC_VALUES - found    WAKE_UP\r\n2015-10-14 22:55:27.010 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:587 ]- NODE 25: Found 1 instances of WAKE_UP\r\n2015-10-14 22:55:27.010 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:253 ]- NODE 25: Creating new message for application command WAKE_UP_INTERVAL_GET\r\n2015-10-14 22:55:27.011 [DEBUG] [o.b.z.i.protocol.SerialMessage:109 ]- NODE 25: Creating empty message of class = SendData (0x13), type = Request (0x00)\r\n2015-10-14 22:55:27.011 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:580 ]- NODE 25: Node advancer: STATIC_VALUES - checking VERSION\r\n2015-10-14 22:55:27.011 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:580 ]- NODE 25: Node advancer: STATIC_VALUES - checking NO_OPERATION\r\n2015-10-14 22:55:27.011 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:580 ]- NODE 25: Node advancer: STATIC_VALUES - checking MANUFACTURER_SPECIFIC\r\n2015-10-14 22:55:27.011 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:580 ]- NODE 25: Node advancer: STATIC_VALUES - checking CONFIGURATION\r\n2015-10-14 22:55:27.011 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:615 ]- NODE 25: Node advancer: STATIC_VALUES - queued 1 frames\r\n2015-10-14 22:55:27.012 [DEBUG] [b.z.i.protocol.ZWaveController:985 ]- Callback ID = 95\r\n2015-10-14 22:55:27.012 [DEBUG] [b.z.i.protocol.ZWaveController:667 ]- Enqueueing message. Queue length = 2\r\n2015-10-14 22:55:27.012 [DEBUG] [z.i.p.i.ZWaveNodeStageAdvancer:240 ]- NODE 25: Node advancer - queued packet. Queue length is 1\r\n2015-10-14 22:55:27.012 [DEBUG] [b.z.i.protocol.ZWaveController:985 ]- Callback ID = 96\r\n2015-10-14 22:55:27.012 [DEBUG] [b.z.i.protocol.ZWaveController:667 ]- Enqueueing message. Queue length = 3\r\n2015-10-14 22:55:27.057 [DEBUG] [eController$ZWaveReceiveThread:1528]- Receive Message = 01 05 00 13 5D 00 B4\r\n2015-10-14 22:55:27.058 [DEBUG] [eController$ZWaveReceiveThread:1452]- Receive queue ADD: Length=1\r\n2015-10-14 22:55:27.058 [DEBUG] [b.z.i.protocol.ZWaveController:1210]- Receive queue TAKE: Length=0\r\n2015-10-14 22:55:27.058 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 07 00 13 5D 00 00 00 B6\r\n2015-10-14 22:55:27.058 [DEBUG] [b.z.i.protocol.ZWaveController:1211]- Process Message = 01 07 00 13 5D 00 00 00 B6\r\n2015-10-14 22:55:27.059 [DEBUG] [b.z.i.protocol.ZWaveController:190 ]- Message: class = SendData (0x13), type = Request (0x00), payload = 5D 00\r\n2015-10-14 22:55:27.059 [DEBUG] [b.z.i.p.s.SendDataMessageClass:74  ]- NODE 25: SendData Request. CallBack ID = 93, Status = Transmission complete and ACK received(0)\r\n2015-10-14 22:55:27.059 [WARN ] [b.z.i.p.s.SendDataMessageClass:77  ]- NODE 25: Already processed another send data request for this callback Id, ignoring.\r\n2015-10-14 22:55:31.994 [DEBUG] [o.b.z.i.protocol.SerialMessage:109 ]- NODE 255: Creating empty message of class = SendDataAbort (0x16), type = Request (0x00)\r\n2015-10-14 22:55:31.994 [DEBUG] [o.b.z.i.protocol.SerialMessage:233 ]- Assembled message buffer = 01 03 00 16 EA\r\n2015-10-14 22:55:31.994 [DEBUG] [WaveController$ZWaveSendThread:1341]- NODE 25: Sending ABORT Message = 01 03 00 16 EA\r\n2015-10-14 22:55:31.999 [DEBUG] [z.internal.ZWaveNetworkMonitor:315 ]- Network Monitor: Queue length is 3 - deferring network monitor functions.\r\n2015-10-14 22:55:32.002 [ERROR] [WaveController$ZWaveSendThread:1356]- NODE 25: Timeout while sending message. Requeueing - 1 attempts left!\r\n2015-10-14 22:55:32.002 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:435 ]- NODE 25: Is sleeping\r\n2015-10-14 22:55:32.002 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:241 ]- NODE 25: Putting message SendData in wakeup queue.\r\n2015-10-14 22:55:32.003 [DEBUG] [WaveController$ZWaveSendThread:1258]- Took message from queue for sending. Queue length = 2\r\n2015-10-14 22:55:32.003 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:241 ]- NODE 25: Putting message SendData in wakeup queue.\r\n2015-10-14 22:55:32.003 [DEBUG] [WaveController$ZWaveSendThread:1258]- Took message from queue for sending. Queue length = 1\r\n2015-10-14 22:55:32.003 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:237 ]- NODE 25: Message already on the wake-up queue. Removing original.\r\n2015-10-14 22:55:32.003 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:241 ]- NODE 25: Putting message SendData in wakeup queue.\r\n2015-10-14 22:55:32.003 [DEBUG] [WaveController$ZWaveSendThread:1258]- Took message from queue for sending. Queue length = 0\r\n2015-10-14 22:55:32.003 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:237 ]- NODE 25: Message already on the wake-up queue. Removing original.\r\n2015-10-14 22:55:32.003 [DEBUG] [.i.p.c.ZWaveWakeUpCommandClass:241 ]- NODE 25: Putting message SendData in wakeup queue.\r\n2015-10-14 22:55:32.529 [DEBUG] [eController$ZWaveReceiveThread:1528]- Receive Message = 01 14 00 04 00 0B 0E 32 02 21 74 00 00 00 00 00 DF 00 01 17 BD FB\r\n```"},"comment":{"url":"https://api.github.com/repos/openhab/openhab/issues/comments/148431498","html_url":"https://github.com/openhab/openhab/issues/3297#issuecomment-148431498","issue_url":"https://api.github.com/repos/openhab/openhab/issues/3297","id":148431498,"user":{"login":"cdjackson","id":1041475,"avatar_url":"https://avatars.githubusercontent.com/u/1041475?v=3","gravatar_id":"","url":"https://api.github.com/users/cdjackson","html_url":"https://github.com/cdjackson","followers_url":"https://api.github.com/users/cdjackson/followers","following_url":"https://api.github.com/users/cdjackson/following{/other_user}","gists_url":"https://api.github.com/users/cdjackson/gists{/gist_id}","starred_url":"https://api.github.com/users/cdjackson/starred{/owner}{/repo}","subscriptions_url":"https://api.github.com/users/cdjackson/subscriptions","organizations_url":"https://api.github.com/users/cdjackson/orgs","repos_url":"https://api.github.com/users/cdjackson/repos","events_url":"https://api.github.com/users/cdjackson/events{/privacy}","received_events_url":"https://api.github.com/users/cdjackson/received_events","type":"User","site_admin":false},"created_at":"2015-10-15T15:56:00Z","updated_at":"2015-10-15T15:56:00Z","body":"I don’t think this is a bug - it appears the device is timing out, and not a problem in the server (as best as I can tell from this log)."}}