Devices do not show up - Githubissues

GoogleCodeExporter commented 9 years ago

What steps will reproduce the problem?
1. Open ozwcp
2. Add any amount of devices
3. Close ozwcp and close the process
4. Open ozwcp again
5. Initialize
6. See in the log that all devices are recognized which you just added
7. See none in the webpage... and no way to get them back

What is the expected output? What do you see instead?
I expect devices to show up. Always.

What version of the product are you using? On what operating system?
OpenZWave Control Panel v0.2a 8/8/2011
Debian Wheezy on a Raspberry Pi (armhf).

Please provide any additional information below.

Original issue reported on code.google.com by pim.bl...@gmail.com on 25 Nov 2012 at 7:18

Attachments:

[Screen Shot 2012-11-25 at 20.15.20.png](https://storage.googleapis.com/google-code-attachments/openzwave-control-panel/issue-5/comment-0/Screen Shot 2012-11-25 at 20.15.20.png)

GoogleCodeExporter commented 9 years ago

I also experience this issue on Ubuntu 12.04.1 LTS (GNU/Linux 3.2.0-34-generic 
x86_64).

* openzwave-control-panel SVN r28
* open-zwave SVN r579
* libmicrohttpd 0.9.20 compiled from tgz
* Aeon Labs Z-Stick Series 2 firmware 3.07
* GE 45603 Appliance Module
* GE 45602 Lamp Dimmer Module

The first time ozwcp is executed, my devices show up.  On subsequent launches, 
no devices show up although according to the log, the devices were queried 
correctly.

I've removed all devices and included only 1 GE 45603 appliance control module 
using the Z-Stick.

**If I remove all devices, re-flash the Z-Stick firmware, then re-include the 
modules, the devices show up again.  But only the first time owzcp is launched 
after the full reset.  Subsequent launches display no devices**

Original comment by icode4h...@gmail.com on 4 Dec 2012 at 12:29

GoogleCodeExporter commented 9 years ago

Is ozwcp left running or do you stop it and rerun it? Do you save the 
configuration using the save button? Quitting ozwcp and restarting it should 
work. There is a known problem reconnecting to an already running ozwcp that 
needs to be fixed.

Original comment by glsatz on 4 Dec 2012 at 3:52

GoogleCodeExporter commented 9 years ago

I do save the config and kill the process. Doesn't matter, still same 
symptoms...

Original comment by pim.bl...@gmail.com on 4 Dec 2012 at 4:22

GoogleCodeExporter commented 9 years ago

Please include the output from ozwcp. Capture it in a file and include it here. 
Without it there is little more to be done.

Original comment by glsatz on 4 Dec 2012 at 4:58

GoogleCodeExporter commented 9 years ago

I have to click "Initialize" twice.  The first time the logged stopped at 
"2012-12-04 12:28:14:621 contrlr, Queuing command: FUNC_ID_ZW_GET_VERSION: 
0x01, 0x03, 0x00, 0x15, 0xe9"

Clicking "Initialize" a second time produced the rest of the log up until I 
clicked "Close" which wrote the cumulative statistics.

Output as requested:

2012-12-04 12:28:14:612 mgr, Added driver for controller /dev/ttyUSB1
2012-12-04 12:28:14:613 Opening controller /dev/ttyUSB1
2012-12-04 12:28:14:613 Trying to open serial port /dev/ttyUSB1 (attempt 1)
2012-12-04 12:28:14:621 Serial port /dev/ttyUSB1 opened (attempt 1)
2012-12-04 12:28:14:621 contrlr, Queuing command: FUNC_ID_ZW_GET_VERSION: 0x01, 
0x03, 0x00, 0x15, 0xe9
2012-12-04 12:28:14:629 contrlr, Static Controller library, version Z-Wave 2.78
2012-12-04 12:28:14:629 contrlr, Expected reply was received
2012-12-04 12:28:14:629 contrlr, Message transaction complete
2012-12-04 12:28:14:630
2012-12-04 12:28:14:630 contrlr, WriteMsg WriteNextMsg m_currentMsg=e80013d0
2012-12-04 12:28:14:630
2012-12-04 12:28:14:631 contrlr, Sending command (Callback ID=0x00, Expected 
Reply=0x20) - FUNC_ID_ZW_MEMORY_GET_ID: 0x01, 0x03, 0x00, 0x20, 0xdc
2012-12-04 12:28:14:632 contrlr, ACK received CallbackId 0x00 Reply 0x20
2012-12-04 12:28:14:634 contrlr, Received: 0x01, 0x08, 0x01, 0x20, 0x01, 0x6a, 
0x0f, 0x3c, 0x01, 0x8f
2012-12-04 12:28:14:635
2012-12-04 12:28:14:635 contrlr, Received reply to FUNC_ID_ZW_MEMORY_GET_ID. 
Home ID = 0x016a0f3c. Our node ID = 1
2012-12-04 12:28:14:635 contrlr, Expected reply was received
2012-12-04 12:28:14:635 contrlr, Message transaction complete
2012-12-04 12:28:14:635
2012-12-04 12:28:14:636 contrlr, WriteMsg WriteNextMsg m_currentMsg=e8001650
2012-12-04 12:28:14:636
2012-12-04 12:28:14:636 contrlr, Sending command (Callback ID=0x00, Expected 
Reply=0x05) - FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES: 0x01, 0x03, 0x00, 0x05, 
0xf9
2012-12-04 12:28:14:639 contrlr, ACK received CallbackId 0x00 Reply 0x05
2012-12-04 12:28:14:640 contrlr, Received: 0x01, 0x04, 0x01, 0x05, 0x08, 0xf7
2012-12-04 12:28:14:640
2012-12-04 12:28:14:640 contrlr, Received reply to 
FUNC_ID_ZW_GET_CONTROLLER_CAPABILITIES:
2012-12-04 12:28:14:640 contrlr, There is no SUC ID Server (SIS) in this 
network.
2012-12-04 12:28:14:641 contrlr, The PC controller is a primary controller.
2012-12-04 12:28:14:641 contrlr, Expected reply was received
2012-12-04 12:28:14:641 contrlr, Message transaction complete
2012-12-04 12:28:14:642
2012-12-04 12:28:14:642 contrlr, WriteMsg WriteNextMsg m_currentMsg=e8001860
2012-12-04 12:28:14:642
2012-12-04 12:28:14:642 contrlr, Sending command (Callback ID=0x00, Expected 
Reply=0x07) - FUNC_ID_SERIAL_API_GET_CAPABILITIES: 0x01, 0x03, 0x00, 0x07, 0xfb
2012-12-04 12:28:14:648 contrlr, ACK received CallbackId 0x00 Reply 0x07
2012-12-04 12:28:14:649 contrlr, Received: 0x01, 0x2b, 0x01, 0x07, 0x03, 0x07, 
0x00, 0x86, 0x00, 0x02, 0x00, 0x01, 0xfe, 0x80, 0xfe, 0x88, 0x0f, 0x00, 0x00, 
0x00, 0xfb, 0x97, 0x7f, 0x82, 0x07, 0x00, 0x00, 0x80, 0x00, 0x80, 0x00, 0x00, 
0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0xc2
2012-12-04 12:28:14:649
2012-12-04 12:28:14:649 contrlr, Received reply to 
FUNC_ID_SERIAL_API_GET_CAPABILITIES
2012-12-04 12:28:14:649 contrlr, Serial API Version: 3.7
2012-12-04 12:28:14:650 contrlr, Manufacturer ID: 0x0086
2012-12-04 12:28:14:650 contrlr, Product Type: 0x0002
2012-12-04 12:28:14:650 contrlr, Product ID: 0x0001
2012-12-04 12:28:14:650 contrlr, Queuing command: FUNC_ID_ZW_GET_RANDOM: 0x01, 
0x04, 0x00, 0x1c, 0x20, 0xc7
2012-12-04 12:28:14:651 contrlr, Queuing command: 
FUNC_ID_SERIAL_API_GET_INIT_DATA: 0x01, 0x03, 0x00, 0x02, 0xfe
2012-12-04 12:28:14:651 contrlr, Expected reply was received
2012-12-04 12:28:14:651 contrlr, Message transaction complete
2012-12-04 12:28:14:651
2012-12-04 12:28:14:652 contrlr, WriteMsg WriteNextMsg m_currentMsg=e8001a70
2012-12-04 12:28:14:652
2012-12-04 12:28:14:652 contrlr, Sending command (Callback ID=0x00, Expected 
Reply=0x56) - FUNC_ID_ZW_GET_SUC_NODE_ID: 0x01, 0x03, 0x00, 0x56, 0xaa
2012-12-04 12:28:14:654 contrlr, ACK received CallbackId 0x00 Reply 0x56
2012-12-04 12:28:14:655 contrlr, Received: 0x01, 0x04, 0x01, 0x56, 0x00, 0xac
2012-12-04 12:28:14:655
2012-12-04 12:28:14:655 contrlr, Received reply to GET_SUC_NODE_ID. Node ID = 0
2012-12-04 12:28:14:655 contrlr, Expected reply was received
2012-12-04 12:28:14:655 contrlr, Message transaction complete
2012-12-04 12:28:14:656
2012-12-04 12:28:14:656 contrlr, WriteMsg WriteNextMsg m_currentMsg=e80026e0
2012-12-04 12:28:14:656
2012-12-04 12:28:14:656 contrlr, Sending command (Callback ID=0x00, Expected 
Reply=0x1c) - FUNC_ID_ZW_GET_RANDOM: 0x01, 0x04, 0x00, 0x1c, 0x20, 0xc7
2012-12-04 12:28:14:658 contrlr, ACK received CallbackId 0x00 Reply 0x1c
2012-12-04 12:28:14:710 contrlr, Received: 0x01, 0x25, 0x01, 0x1c, 0x01, 0x20, 
0x9d, 0x29, 0x75, 0xd1, 0x24, 0xa5, 0xce, 0x90, 0x67, 0x73, 0x0a, 0x29, 0x6f, 
0xb9, 0xb5, 0xd0, 0xd4, 0xaf, 0x7c, 0x8a, 0x1f, 0xe4, 0xbb, 0x12, 0x16, 0x84, 
0x83, 0x72, 0x2f, 0x93, 0x7a, 0xfb, 0x2c
2012-12-04 12:28:14:711
2012-12-04 12:28:14:711 contrlr, Received reply to FUNC_ID_ZW_GET_RANDOM: true
2012-12-04 12:28:14:711 contrlr, Expected reply was received
2012-12-04 12:28:14:711 contrlr, Message transaction complete
2012-12-04 12:28:14:712
2012-12-04 12:28:14:712 contrlr, WriteMsg WriteNextMsg m_currentMsg=e80029f0
2012-12-04 12:28:14:712
2012-12-04 12:28:14:713 contrlr, Sending command (Callback ID=0x00, Expected 
Reply=0x02) - FUNC_ID_SERIAL_API_GET_INIT_DATA: 0x01, 0x03, 0x00, 0x02, 0xfe
2012-12-04 12:28:14:715 contrlr, ACK received CallbackId 0x00 Reply 0x02
2012-12-04 12:28:14:762 contrlr, Received: 0x01, 0x25, 0x01, 0x02, 0x05, 0x00, 
0x1d, 0x01, 0x01, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 
0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 
0x00, 0x00, 0x00, 0x00, 0x03, 0x01, 0xc3
2012-12-04 12:28:14:763
2012-12-04 12:28:14:763 mgr, Driver with Home ID of 0x016a0f3c is now ready.
2012-12-04 12:28:14:763
2012-12-04 12:28:14:766 contrlr, Received reply to 
FUNC_ID_SERIAL_API_GET_INIT_DATA:
2012-12-04 12:28:14:766 contrlr, Node 001 - Known
2012-12-04 12:28:14:766 Node001, QueryStage_Associations
2012-12-04 12:28:14:766 Node001, QueryStage_Neighbors
2012-12-04 12:28:14:767 contrlr, Requesting routing info (neighbor list) for 
Node 1
2012-12-04 12:28:14:767 Node001, Queuing command: Get Routing Info (Node=1): 
0x01, 0x06, 0x00, 0x80, 0x01, 0x01, 0x01, 0x78
2012-12-04 12:28:14:767 Node001, Queuing Command: Query Stage Complete 
(Neighbors)
2012-12-04 12:28:14:768 contrlr, Node 009 - Known
2012-12-04 12:28:14:768 Node009, QueryStage_Associations
2012-12-04 12:28:14:768 Node009, QueryStage_Neighbors
2012-12-04 12:28:14:768 contrlr, Requesting routing info (neighbor list) for 
Node 9
2012-12-04 12:28:14:768 Node009, Queuing command: Get Routing Info (Node=9): 
0x01, 0x06, 0x00, 0x80, 0x09, 0x01, 0x01, 0x70
2012-12-04 12:28:14:769 Node009, Queuing Command: Query Stage Complete 
(Neighbors)
2012-12-04 12:28:14:769 contrlr, Expected reply was received
2012-12-04 12:28:14:769 contrlr, Message transaction complete
2012-12-04 12:28:14:769
2012-12-04 12:28:14:770 Notification: Driver Ready, homeId 016a0f3c, nodeId 1
2012-12-04 12:28:14:770 Notification: Node Added Home 016a0f3c Node 1 Genre 
basic Class NO OPERATION Instance 0 Index 0 Type bool
2012-12-04 12:28:14:770 Notification: Node Protocol Info Home 016a0f3c Node 1 
Genre basic Class NO OPERATION Instance 0 Index 0 Type bool
2012-12-04 12:28:14:770 Notification: Essential Node 1 Queries Complete
2012-12-04 12:28:14:770 Notification: Node Naming Home 016a0f3c Node 1 Genre 
basic Class NO OPERATION Instance 0 Index 0 Type bool
2012-12-04 12:28:14:771 Notification: Node Added Home 016a0f3c Node 9 Genre 
basic Class NO OPERATION Instance 0 Index 0 Type bool
2012-12-04 12:28:14:771 Notification: Node Protocol Info Home 016a0f3c Node 9 
Genre basic Class NO OPERATION Instance 0 Index 0 Type bool
2012-12-04 12:28:14:771 Notification: Essential Node 9 Queries Complete
2012-12-04 12:28:14:771 Notification: Value Added Home 0x016a0f3c Node 9 Genre 
user Class SWITCH BINARY Instance 1 Index 0 Type bool
2012-12-04 12:28:14:771 Notification: Value Added Home 0x016a0f3c Node 9 Genre 
system Class SWITCH ALL Instance 1 Index 0 Type list
2012-12-04 12:28:14:772 Notification: Value Added Home 0x016a0f3c Node 9 Genre 
system Class PROTECTION Instance 1 Index 0 Type list
2012-12-04 12:28:14:772 Notification: Value Added Home 0x016a0f3c Node 9 Genre 
system Class VERSION Instance 1 Index 0 Type string
2012-12-04 12:28:14:772 Notification: Value Added Home 0x016a0f3c Node 9 Genre 
system Class VERSION Instance 1 Index 1 Type string
2012-12-04 12:28:14:772 Notification: Value Added Home 0x016a0f3c Node 9 Genre 
system Class VERSION Instance 1 Index 2 Type string
2012-12-04 12:28:14:773 Notification: Node Naming Home 016a0f3c Node 9 Genre 
basic Class NO OPERATION Instance 0 Index 0 Type bool
2012-12-04 12:28:14:773 Node001, WriteMsg WriteNextMsg m_currentMsg=e8002160
2012-12-04 12:28:14:773
2012-12-04 12:28:14:773 Node001, Sending command (Callback ID=0x00, Expected 
Reply=0x80) - Get Routing Info (Node=1): 0x01, 0x06, 0x00, 0x80, 0x01, 0x01, 
0x01, 0x78
2012-12-04 12:28:14:775 Node001, ACK received CallbackId 0x00 Reply 0x80
2012-12-04 12:28:14:779 Node001, Received: 0x01, 0x20, 0x01, 0x80, 0x00, 0x01, 
0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 
0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 
0x00, 0x5f
2012-12-04 12:28:14:780
2012-12-04 12:28:14:780 Node001, Received reply to FUNC_ID_ZW_GET_ROUTING_INFO
2012-12-04 12:28:14:780 Node001, Neighbors of this node are:
2012-12-04 12:28:14:780 Node001, Node 9
2012-12-04 12:28:14:780 Node001, Expected reply was received
2012-12-04 12:28:14:781 Node001, Message transaction complete
2012-12-04 12:28:14:781
2012-12-04 12:28:14:781 Node009, WriteMsg WriteNextMsg m_currentMsg=e800c790
2012-12-04 12:28:14:781
2012-12-04 12:28:14:782 Node009, Sending command (Callback ID=0x00, Expected 
Reply=0x80) - Get Routing Info (Node=9): 0x01, 0x06, 0x00, 0x80, 0x09, 0x01, 
0x01, 0x70
2012-12-04 12:28:14:784 Node009, ACK received CallbackId 0x00 Reply 0x80
2012-12-04 12:28:14:788 Node009, Received: 0x01, 0x20, 0x01, 0x80, 0x01, 0x00, 
0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 
0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 0x00, 
0x00, 0x5f
2012-12-04 12:28:14:789
2012-12-04 12:28:14:789 Node009, Received reply to FUNC_ID_ZW_GET_ROUTING_INFO
2012-12-04 12:28:14:789 Node009, Neighbors of this node are:
2012-12-04 12:28:14:789 Node009, Node 1
2012-12-04 12:28:14:790 Node009, Expected reply was received
2012-12-04 12:28:14:790 Node009, Message transaction complete
2012-12-04 12:28:14:790
2012-12-04 12:28:14:791 Node001, Query Stage Complete (Neighbors)
2012-12-04 12:28:14:791 Node001, QueryStage_Session
2012-12-04 12:28:14:791 Node001, QueryStage_Dynamic
2012-12-04 12:28:14:791 Node001, QueryStage_Configuration
2012-12-04 12:28:14:792 Node001, QueryStage_Complete
2012-12-04 12:28:14:792 Notification: Node 1 Queries Complete
2012-12-04 12:28:14:792 Node009, Query Stage Complete (Neighbors)
2012-12-04 12:28:14:792 Node009, QueryStage_Session
2012-12-04 12:28:14:793 Node009, Queuing command: SwitchAllCmd_Get (Node=9): 
0x01, 0x09, 0x00, 0x13, 0x09, 0x02, 0x27, 0x02, 0x05, 0x01, 0xcf
2012-12-04 12:28:14:793 Node009, Queuing command: ProtectionCmd_Get (Node=9): 
0x01, 0x09, 0x00, 0x13, 0x09, 0x02, 0x75, 0x02, 0x05, 0x02, 0x9e
2012-12-04 12:28:14:793 Node009, Queuing command: NodeNamingCmd_LocationGet 
(Node=9): 0x01, 0x09, 0x00, 0x13, 0x09, 0x02, 0x77, 0x05, 0x05, 0x03, 0x9a
2012-12-04 12:28:14:793 Node009, Queuing Command: Query Stage Complete (Session)
2012-12-04 12:28:14:794 Node009, WriteMsg WriteNextMsg m_currentMsg=e800c790
2012-12-04 12:28:14:794
2012-12-04 12:28:14:794 Node009, Sending command (Callback ID=0x01, Expected 
Reply=0x04) - SwitchAllCmd_Get (Node=9): 0x01, 0x09, 0x00, 0x13, 0x09, 0x02, 
0x27, 0x02, 0x05, 0x01, 0xcf
2012-12-04 12:28:14:796 Node009, ACK received CallbackId 0x01 Reply 0x04
2012-12-04 12:28:14:799 Node009, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2012-12-04 12:28:14:800 Node009, ZW_SEND_DATA delivered to Z-Wave stack
2012-12-04 12:28:14:811 Node009, Received: 0x01, 0x05, 0x00, 0x13, 0x01, 0x00, 
0xe8
2012-12-04 12:28:14:812 Node009, ZW_SEND_DATA Request with callback ID 0x01 
received (expected 0x01)
2012-12-04 12:28:14:818 Node009, Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x09, 
0x03, 0x27, 0x03, 0xff, 0x23
2012-12-04 12:28:14:819
2012-12-04 12:28:14:819 Node009, Initial read of value
2012-12-04 12:28:14:819 Node009, Received SwitchAll report from node 9: On and 
Off Enabled
2012-12-04 12:28:14:819 Node009, Expected reply and command class was received
2012-12-04 12:28:14:820 Node009, Message transaction complete
2012-12-04 12:28:14:820
2012-12-04 12:28:14:820 Notification: Value Changed Home 0x016a0f3c Node 9 
Genre system Class SWITCH ALL Instance 1 Index 0 Type list
2012-12-04 12:28:14:821 Node009, WriteMsg WriteNextMsg m_currentMsg=e8002160
2012-12-04 12:28:14:821
2012-12-04 12:28:14:821 Node009, Sending command (Callback ID=0x02, Expected 
Reply=0x04) - ProtectionCmd_Get (Node=9): 0x01, 0x09, 0x00, 0x13, 0x09, 0x02, 
0x75, 0x02, 0x05, 0x02, 0x9e
2012-12-04 12:28:14:824 Node009, ACK received CallbackId 0x02 Reply 0x04
2012-12-04 12:28:14:827 Node009, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2012-12-04 12:28:14:828 Node009, ZW_SEND_DATA delivered to Z-Wave stack
2012-12-04 12:28:14:839 Node009, Received: 0x01, 0x05, 0x00, 0x13, 0x02, 0x00, 
0xeb
2012-12-04 12:28:14:840 Node009, ZW_SEND_DATA Request with callback ID 0x02 
received (expected 0x02)
2012-12-04 12:28:14:846 Node009, Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x09, 
0x03, 0x75, 0x03, 0x00, 0x8e
2012-12-04 12:28:14:847
2012-12-04 12:28:14:847 Node009, Received a Protection report: Unprotected
2012-12-04 12:28:14:847 Node009, Initial read of value
2012-12-04 12:28:14:847 Node009, Expected reply and command class was received
2012-12-04 12:28:14:848 Node009, Message transaction complete
2012-12-04 12:28:14:848
2012-12-04 12:28:14:848 Notification: Value Changed Home 0x016a0f3c Node 9 
Genre system Class PROTECTION Instance 1 Index 0 Type list
2012-12-04 12:28:14:849 Node009, WriteMsg WriteNextMsg m_currentMsg=e8002360
2012-12-04 12:28:14:849
2012-12-04 12:28:14:849 Node009, Sending command (Callback ID=0x03, Expected 
Reply=0x04) - NodeNamingCmd_LocationGet (Node=9): 0x01, 0x09, 0x00, 0x13, 0x09, 
0x02, 0x77, 0x05, 0x05, 0x03, 0x9a
2012-12-04 12:28:14:852 Node009, ACK received CallbackId 0x03 Reply 0x04
2012-12-04 12:28:14:855 Node009, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2012-12-04 12:28:14:856 Node009, ZW_SEND_DATA delivered to Z-Wave stack
2012-12-04 12:28:14:867 Node009, Received: 0x01, 0x05, 0x00, 0x13, 0x03, 0x00, 
0xea
2012-12-04 12:28:14:868 Node009, ZW_SEND_DATA Request with callback ID 0x03 
received (expected 0x03)
2012-12-04 12:28:14:874 Node009, Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x09, 
0x03, 0x77, 0x06, 0x00, 0x89
2012-12-04 12:28:14:875
2012-12-04 12:28:14:875 Node009, Received the location: .
2012-12-04 12:28:14:875 Node009, Expected reply and command class was received
2012-12-04 12:28:14:875 Node009, Message transaction complete
2012-12-04 12:28:14:875
2012-12-04 12:28:14:876 Notification: Node Naming Home 016a0f3c Node 9 Genre 
basic Class NO OPERATION Instance 0 Index 0 Type bool
2012-12-04 12:28:14:876 Node009, Query Stage Complete (Session)
2012-12-04 12:28:14:876 Node009, QueryStage_Dynamic
2012-12-04 12:28:14:876 Node009, Queuing command: SwitchBinaryCmd_Get (Node=9): 
0x01, 0x09, 0x00, 0x13, 0x09, 0x02, 0x25, 0x02, 0x05, 0x04, 0xc8
2012-12-04 12:28:14:877 Node009, Queuing Command: Query Stage Complete (Dynamic)
2012-12-04 12:28:14:877 Node009, WriteMsg WriteNextMsg m_currentMsg=e8002160
2012-12-04 12:28:14:877
2012-12-04 12:28:14:878 Node009, Sending command (Callback ID=0x04, Expected 
Reply=0x04) - SwitchBinaryCmd_Get (Node=9): 0x01, 0x09, 0x00, 0x13, 0x09, 0x02, 
0x25, 0x02, 0x05, 0x04, 0xc8
2012-12-04 12:28:14:880 Node009, ACK received CallbackId 0x04 Reply 0x04
2012-12-04 12:28:14:883 Node009, Received: 0x01, 0x04, 0x01, 0x13, 0x01, 0xe8
2012-12-04 12:28:14:884 Node009, ZW_SEND_DATA delivered to Z-Wave stack
2012-12-04 12:28:14:895 Node009, Received: 0x01, 0x05, 0x00, 0x13, 0x04, 0x00, 
0xed
2012-12-04 12:28:14:896 Node009, ZW_SEND_DATA Request with callback ID 0x04 
received (expected 0x04)
2012-12-04 12:28:14:902 Node009, Received: 0x01, 0x09, 0x00, 0x04, 0x00, 0x09, 
0x03, 0x25, 0x03, 0x00, 0xde
2012-12-04 12:28:14:903
2012-12-04 12:28:14:903 Node009, Received SwitchBinary report from node 9: 
level=Off
2012-12-04 12:28:14:903 Node009, Initial read of value
2012-12-04 12:28:14:904 Node009, Expected reply and command class was received
2012-12-04 12:28:14:904 Node009, Message transaction complete
2012-12-04 12:28:14:904
2012-12-04 12:28:14:905 Notification: Value Changed Home 0x016a0f3c Node 9 
Genre user Class SWITCH BINARY Instance 1 Index 0 Type bool
2012-12-04 12:28:14:905 Node009, Query Stage Complete (Dynamic)
2012-12-04 12:28:14:905 Node009, QueryStage_Configuration
2012-12-04 12:28:14:905 Node009, QueryStage_Complete
2012-12-04 12:28:14:906 Node query processing complete.
2012-12-04 12:28:14:906 Notification: Node 9 Queries Complete
2012-12-04 12:28:14:906 Notification: All Nodes Queried
2012-12-04 12:30:01:368 
***************************************************************************
2012-12-04 12:30:01:368 ********************* Cumulative Network Statistics 
*********************
2012-12-04 12:30:01:368 *** General
2012-12-04 12:30:01:369 Driver run time: . . . 0 days, 0 hours, 1 minutes
2012-12-04 12:30:01:369 Frames processed: . . . . . . . . . . . . . . . . . . . 
. 21
2012-12-04 12:30:01:369 [Device] Messages successfully received: . . . . . . . 
. 21
2012-12-04 12:30:01:370 [Device] Messages successfully sent: . . . . . . . . . 
. 13
2012-12-04 12:30:01:370 ACKs received from controller: . . . . . . . . . . . . 
. 13
2012-12-04 12:30:01:370 Controller messages received: . . . . . . . . . . . . . 
. 0
2012-12-04 12:30:01:370 Controller messages sent: . . . . . . . . . . . . . . . 
. 7
2012-12-04 12:30:01:370 *** Errors
2012-12-04 12:30:01:370 Unsolicited messages received while waiting for ACK: . 
. 0
2012-12-04 12:30:01:370 Reads aborted due to timeouts: . . . . . . . . . . . . 
. 0
2012-12-04 12:30:01:370 Bad checksum errors: . . . . . . . . . . . . . . . . . 
. 0
2012-12-04 12:30:01:371 CANs received from controller: . . . . . . . . . . . . 
. 0
2012-12-04 12:30:01:371 NAKs received from controller: . . . . . . . . . . . . 
. 0
2012-12-04 12:30:01:371 Out of frame data flow errors: . . . . . . . . . . . . 
. 0
2012-12-04 12:30:01:371 Messages retransmitted: . . . . . . . . . . . . . . . . 
. 0
2012-12-04 12:30:01:371 Messages dropped and not delivered: . . . . . . . . . . 
. 0
2012-12-04 12:30:01:371 
***************************************************************************

Original comment by icode4h...@gmail.com on 4 Dec 2012 at 5:32

GoogleCodeExporter commented 9 years ago

It looks like you sent me the contents of the OZW_Log.txt file. While this is 
useful, it shows the Z-Wave protocol process operating, it doesn't show the web 
interactions. Can you try capturing the output of ozwcp into a file by running 
it:

./ozwcp -d -p 9900 &> ozwcp.log

Replace 9900 with your port number. This assumes you are using the bash shell.

Thanks.

Original comment by glsatz on 4 Dec 2012 at 11:20

GoogleCodeExporter commented 9 years ago

Apologies.  Attached is the log generated as requested.

Original comment by icode4h...@gmail.com on 5 Dec 2012 at 12:45

Attachments:

owzp.log

GoogleCodeExporter commented 9 years ago

FWIW, this may be related to an issue in the OZW library: 
http://code.google.com/p/open-zwave/issues/detail?id=118

When I delete the zwcfg_*.xml and "Initialize" the interface, all devices 
return with correct library/application/protocol versions.  Performing a 
"close" (without an explicit "Save...") writes a correct zwcfg_*.xml the FIRST 
time.

A second Initialize/Close sequence writes a zwcfg_*.xml with empty 
library/application/protocol values.

In addition, clicking "Save..." the FIRST time (after deleting the zwcfg_*.xml 
file) re-writes a correct config file with library/application/protocol 
versions.  Subsequent saves generates empty values again.

Original comment by icode4h...@gmail.com on 5 Dec 2012 at 5:21

GoogleCodeExporter commented 9 years ago

What operating system/platform/browser are you using here? Is the log output 
from the working example, the first time, or the non-working example, the 
second time? I will need to be able to recreate this issue to solve it. Please 
provide the example steps you are taking to create the problem. The log file 
you sent shows what looks like a working result. Thanks.

Original comment by glsatz on 5 Dec 2012 at 7:47

GoogleCodeExporter commented 9 years ago

Platform: Ubuntu 12.04.1 LTS (GNU/Linux 3.2.0-34-generic x86_64)
Browsers: Firefox 17.0.1 and Chrome 23.0.1271.95 m
openzwave-control-panel rev: r28
open-zwave rev: r579
libmicrohttpd rev: 0.9.20

The log output is the same for working and non-working examples.  The only 
difference is the non-working examples have a zwcfg_*.xml file with empty 
lib/app/prog version numbers.

Steps to (hopefully) reproduce:

01. Delete zwcfg_*.xml from directory where ozwcp was built (leave zwscene.xml 
in place)
02. Run ozwcp on an arbitrary port: ./ozwcp -p 1234
03. Connect via browser: http://192.168.1.10:1234
04. Enter correct controller interface device name:  /dev/ttyUSB1
05. Click "Initialize" button.
06. Wait for querying to complete by monitoring the "Log output" window on the 
web page.  Wait for the last "Notification: Node X Queries complete"
07. Confirm that the correct included devices are listed and can be controlled 
via the web interface.
08. Click the "Close" button.
09. Verify that the zwcfg_*.xml config file has been written in the directory 
where ozwcp was built.  Note the timestamp.
10. Open the config file and confirm there are actual values for "Library 
Version", "Protocol Version", and "Application Version" under CommandClass node 
named "COMMAND_CLASS_VERSION" e.g. value="3.24"
11. Close and restart the browser.
12. Perform 03 thru 05
13. Wait for querying to complete similar to step 06.  Note that the last 
message in the "Log output" window is "Notification: All Nodes Queried" instead 
of "Notification: Node X Queries complete"
14. Confirm that the correct included devices are listed and can be controlled 
via the web interface.
15. Click the "Close" button.
16. Verify that the zwcfg_*.xml file has an updated timestamp.
17. Open the config file and confirm that there are empty values e.g.: value=""
18. Close and restart the browser
19. Perform steps 03 thru 06.
20. Confirm that no devices are listed in the "Devices" section and therefore 
cannot be controlled via the web interface.

Original comment by icode4h...@gmail.com on 5 Dec 2012 at 11:07

GoogleCodeExporter commented 9 years ago

Good catch. The javascript didn't handle the missing values. Now we just ignore 
the missing values and the library's latter discovery should update them. Give 
the fix a try and let me know how it goes.

Original comment by glsatz on 6 Dec 2012 at 12:58

Changed state: Fixed

GoogleCodeExporter commented 9 years ago

I can confirm this fix indeed works for me.  Thank you!

Original comment by icode4h...@gmail.com on 6 Dec 2012 at 12:20

google-code-export / openzwave-control-panel

Devices do not show up #5