Scene execution not working

[Anonymous]

#40351

I am having troubles with a scene executing correctly. I have traced the execution via telnet (see below). Can anyone help me figure this one out?

Debug Mode started for EZServe

10/03/2012 18.48.00: In process actions: Activate effect

10/03/2012 18.48.00: In process actions: Effect to process and action Night ON

10/03/2012 18.48.00: In form cluster actor queue: attr name Status, attr value 0xFF

10/03/2012 18.48.00: Form clustr: CB-0: [4] CB-I: [4] CB-II: [E] CB-III: [0] CB-IV: [61] CB-V: [FF] CB-VI: [0] CB-VII: [0] CB-VIII: [0] CB-IX: [0]

10/03/2012 18.48.00: In actor requst process: actor queue ActorArray0 4,ActorArray4 61,ActorArray5 ff

10/03/2012 18.48.00: In plm msg handler: Ins grp 0x4, Grpcmd 0x11

10/03/2012 18.48.00: Done: MSG Code 61, ID 04.11.04 Cmd1 4D, Cmd2 67 Ack 2B

10/03/2012 18.48.00: In actor resp processor: cluster api in progress for ID[04.11.04], RespArray9[0], RespArray10[0]

10/03/2012 18.48.00: In get cluster action: CID 0, attrnum 0

10/03/2012 18.48.00: In update cluster ram insstd: attr 0 value FF

10/03/2012 18.48.00: In form cluster rep insstd: tot attr 1, cid 0

10/03/2012 18.48.00: Error Sending

10/03/2012 18.48.00: Done: PTH_StdMsg 50, ID 19.05.8F Flag 61, Cmd1 11, Cmd2 4

10/03/2012 18.48.00: Done: PTH_StdMsg 50, ID 18.88.47 Flag 61, Cmd1 11, Cmd2 4

10/03/2012 18.48.01: Done: PTH_StdMsg 50, ID 14.FF.A8 Flag 61, Cmd1 11, Cmd2 4

10/03/2012 18.48.01: Done: PTH_StdMsg 50, ID 12.C4.86 Flag 61, Cmd1 11, Cmd2 4

10/03/2012 18.48.01: Done: MSG Code 58, ID 06.C4.86 Cmd1 4D, Cmd2 67 Ack 61

10/03/2012 18.48.01: File: PLM.c Fn: PLM_Process - All Link Clean-Up Group Status/Failure Report - 58 N/Ack/Grp - 6

10/03/2012 18.48.01: In update dev status: dev 04 status updated cmd FF, Grp FFFFFFFF

10/03/2012 18.48.01: In form cluster rep insstd: tot attr 1, cid 0

10/03/2012 18.48.01: Error Sending

10/03/2012 18.48.01: In poll scene dev: devrec 15

10/03/2012 18.48.01: Done: PTH_StdMsg 50, ID 15.04.47 Flag 61, Cmd1 11, Cmd2 4

10/03/2012 18.48.01: Done: MSG Code 62, ID 14.FF.A8 Cmd1 19, Cmd2 0 Ack 6

10/03/2012 18.48.01: Done: PTH_StdMsg 50, ID 14.FF.A8 Flag 2B, Cmd1 1B, Cmd2 FF

10/03/2012 18.48.01: In update dev status: dev 14.FF.A8 status updated cmd FF, Grp FFFFFFFF

10/03/2012 18.48.01: In form cluster rep insstd: tot attr 1, cid 0

10/03/2012 18.48.01: Error Sending

10/03/2012 18.48.02: In poll scene dev: send command to next device or resend to prev dev

10/03/2012 18.48.02: ezdevice.c - SaveDeviceXML: File Open Success!

10/03/2012 18.48.07: ReadWriteApi.c - SaveSysVarXML: File Open Success!

10/03/2012 18.48.07: ezaction.c - SaveActionsSysVarXML Done!

Thanks,
Steve

[Anonymous]

#43866

For further assistance (maybe), I get the following errors when I try to execute the devices individually:

10/03/2012 18.53.07: Done: MSG Code 62, ID 12.79.6D Cmd1 11, Cmd2 FF Ack 6

10/03/2012 18.53.07: Done: PTH_StdMsg 50, ID 12.79.6D Flag 27, Cmd1 11, Cmd2 FF

10/03/2012 18.53.07: In update dev status: dev 12.79.6D status updated cmd FF, Grp FFFFFFFF

10/03/2012 18.53.07: In form cluster rep insstd: tot attr 1, cid 0

10/03/2012 18.53.07: Error Sending

10/03/2012 18.53.11: In refresh_v.c: new update FF, old update 0

10/03/2012 18.53.11: Done: MSG Code 62, ID 12.79.6D Cmd1 19, Cmd2 0 Ack 6

10/03/2012 18.53.11: Done: PTH_StdMsg 50, ID 12.79.6D Flag 27, Cmd1 17, Cmd2 FF

10/03/2012 18.53.11: In update dev status: dev 12.79.6D status updated cmd FF, Grp FFFFFFFF

10/03/2012 18.53.11: In form cluster rep insstd: tot attr 1, cid 0

10/03/2012 18.53.11: Error Sending

10/03/2012 18.53.15: Done: MSG Code 62, ID 12.76.E6 Cmd1 11, Cmd2 FF Ack 6

10/03/2012 18.53.16: Done: PTH_StdMsg 50, ID 12.76.E6 Flag 27, Cmd1 11, Cmd2 FF

10/03/2012 18.53.16: In update dev status: dev 12.76.E6 status updated cmd FF, Grp FFFFFFFF

10/03/2012 18.53.16: In form cluster rep insstd: tot attr 1, cid 0

10/03/2012 18.53.16: Error Sending

10/03/2012 18.53.19: In refresh_v.c: new update FF, old update 0

10/03/2012 18.53.19: Done: MSG Code 62, ID 12.76.E6 Cmd1 19, Cmd2 0 Ack 6

10/03/2012 18.53.20: Done: PTH_StdMsg 50, ID 12.76.E6 Flag 27, Cmd1 11, Cmd2 FF

10/03/2012 18.53.20: In update dev status: dev 12.76.E6 status updated cmd FF, Grp FFFFFFFF

10/03/2012 18.53.20: In form cluster rep insstd: tot attr 1, cid 0

10/03/2012 18.53.20: Error Sending

[Anonymous]

#43867

What are the device types of the two devices? How old are they?

[Anonymous]

#43868

They are 1kW SwitchLincs and are about two years old.

[Anonymous]

#43869

The Scene On request got ACKs from 4 devices

10/03/2012 18.48.00: Done: PTH_StdMsg 50, ID 19.05.8F Flag 61, Cmd1 11, Cmd2 4
10/03/2012 18.48.00: Done: PTH_StdMsg 50, ID 18.88.47 Flag 61, Cmd1 11, Cmd2 4
10/03/2012 18.48.01: Done: PTH_StdMsg 50, ID 14.FF.A8 Flag 61, Cmd1 11, Cmd2 4
10/03/2012 18.48.01: Done: PTH_StdMsg 50, ID 12.C4.86 Flag 61, Cmd1 11, Cmd2 4

If you are referring to the Error Sending messages they are not errors.

What is the actual problem?

[Anonymous]

#43870

That is funny. That is what I was coming to the conclusion.

The problem is that the scene executes at the scheduled time (it just did). But 4 of the devices are not turning on, but 6 of the other devices in that scene are.

The 4 devices that are not turning on are all 1kW SwitchLincs. The other devices are either plug-in modules or sockets. All devices are Insteon (no Icon).

Is there something else (other than telnet) that I can use to monitor the execution of the scene that will help me determine what is going wrong?

Thanks,
Steve

[Anonymous]

#43871

Telnet is as detailed as it gets. There does not appear to be 10 devices in the Group 4 Scene.

What are the Insteon addresses of the devices that do respond and the Insteon addresses of the devices that do not.

What is the LD3 value in the four devices that do not respond. Smartlabs has changed the LD3 value from 0 to 1 but I do not think that change went back two years.

[Anonymous]

#43872

Apparently I was wrong once again, or maybe things are not being consistent. Either way, I just turned everything OFF. Then I turned ON the scene. All the devices turned on, with the exception of two devices (1A.A6.B6, 1A.A8.B4) both of which are 1kW SwitchLincs that I purchased about 2 years ago.

Here is the full scene being turned on:

10/04/2012 19.34.16: Done: MSG Code 61, ID 07.11.07 Cmd1 4D, Cmd2 67 Ack 2B

10/04/2012 19.34.17: Done: PTH_StdMsg 50, ID 15.04.47 Flag 61, Cmd1 11, Cmd2 7

10/04/2012 19.34.17: Done: PTH_StdMsg 50, ID 12.79.6D Flag 61, Cmd1 11, Cmd2 7

10/04/2012 19.34.17: Done: PTH_StdMsg 50, ID 12.76.E6 Flag 61, Cmd1 11, Cmd2 7

10/04/2012 19.34.17: Done: PTH_StdMsg 50, ID 1A.A6.B6 Flag 61, Cmd1 11, Cmd2 7

10/04/2012 19.34.18: Done: PTH_StdMsg 50, ID 1A.A8.B4 Flag 61, Cmd1 11, Cmd2 7

10/04/2012 19.34.18: Done: PTH_StdMsg 50, ID 19.05.8F Flag 61, Cmd1 11, Cmd2 7

10/04/2012 19.34.18: Done: PTH_StdMsg 50, ID 14.FF.A8 Flag 61, Cmd1 11, Cmd2 7

10/04/2012 19.34.18: Done: PTH_StdMsg 50, ID 14.1D.AA Flag 61, Cmd1 11, Cmd2 7

10/04/2012 19.34.19: Done: PTH_StdMsg 50, ID 14.2E.1B Flag 61, Cmd1 11, Cmd2 7

10/04/2012 19.34.19: Done: PTH_StdMsg 50, ID 14.2D.2A Flag 61, Cmd1 11, Cmd2 7

10/04/2012 19.34.19: Done: PTH_StdMsg 50, ID 18.85.5F Flag 61, Cmd1 11, Cmd2 7

10/04/2012 19.34.19: Done: PTH_StdMsg 50, ID 14.37.9D Flag 61, Cmd1 11, Cmd2 7

10/04/2012 19.34.20: Done: PTH_StdMsg 50, ID 14.35.2F Flag 61, Cmd1 11, Cmd2 7

10/04/2012 19.34.20: Done: PTH_StdMsg 50, ID 18.88.47 Flag 61, Cmd1 11, Cmd2 7

10/04/2012 19.34.20: Done: PTH_StdMsg 50, ID 13.FB.30 Flag 61, Cmd1 11, Cmd2 7

10/04/2012 19.34.20: Done: MSG Code 58, ID 06.FB.30 Cmd1 4D, Cmd2 67 Ack 61

10/04/2012 19.34.20: File: PLM.c Fn: PLM_Process - All Link Clean-Up Group Status/Failure Report - 58 N/Ack/Grp - 6

10/04/2012 19.34.20: In update dev status: dev 07 status updated cmd FF, Grp FFFFFFFF

10/04/2012 19.34.20: In form cluster rep insstd: tot attr 1, cid 0

10/04/2012 19.34.20: Error Sending

10/04/2012 19.34.20: In poll scene dev: devrec 15

10/04/2012 19.34.20: Done: PTH_StdMsg 50, ID 14.00.5A Flag 61, Cmd1 11, Cmd2 7

10/04/2012 19.34.20: Done: MSG Code 62, ID 14.FF.A8 Cmd1 19, Cmd2 0 Ack 6

10/04/2012 19.34.21: Done: PTH_StdMsg 50, ID 14.FF.A8 Flag 2B, Cmd1 1B, Cmd2 FF

10/04/2012 19.34.21: In update dev status: dev 14.FF.A8 status updated cmd FF, Grp FFFFFFFF

10/04/2012 19.34.21: In form cluster rep insstd: tot attr 1, cid 0

10/04/2012 19.34.21: Error Sending

10/04/2012 19.34.21: In poll scene dev: send command to next device or resend to prev dev

10/04/2012 19.34.21: Done: MSG Code 62, ID 14.1D.AA Cmd1 19, Cmd2 0 Ack 6

10/04/2012 19.34.21: Done: PTH_StdMsg 50, ID 14.1D.AA Flag 2B, Cmd1 1B, Cmd2 FF

10/04/2012 19.34.21: In update dev status: dev 14.1D.AA status updated cmd FF, Grp FFFFFFFF

10/04/2012 19.34.21: In form cluster rep insstd: tot attr 1, cid 0

10/04/2012 19.34.21: Error Sending

10/04/2012 19.34.21: In poll scene dev: send command to next device or resend to prev dev

10/04/2012 19.34.21: Done: MSG Code 62, ID 14.2E.1B Cmd1 19, Cmd2 0 Ack 6

10/04/2012 19.34.22: Done: PTH_StdMsg 50, ID 14.2E.1B Flag 2B, Cmd1 1B, Cmd2 FF

10/04/2012 19.34.22: In update dev status: dev 14.2E.1B status updated cmd FF, Grp FFFFFFFF

10/04/2012 19.34.22: In form cluster rep insstd: tot attr 1, cid 0

10/04/2012 19.34.22: Error Sending

10/04/2012 19.34.22: In poll scene dev: send command to next device or resend to prev dev

10/04/2012 19.34.22: Done: MSG Code 62, ID 14.2D.2A Cmd1 19, Cmd2 0 Ack 6

10/04/2012 19.34.22: Done: PTH_StdMsg 50, ID 14.2D.2A Flag 2B, Cmd1 13, Cmd2 FF

10/04/2012 19.34.22: In update dev status: dev 14.2D.2A status updated cmd FF, Grp FFFFFFFF

10/04/2012 19.34.22: In form cluster rep insstd: tot attr 1, cid 0

10/04/2012 19.34.22: Error Sending

10/04/2012 19.34.22: In poll scene dev: send command to next device or resend to prev dev

10/04/2012 19.34.22: In refresh_v.c: new update FF, old update 0

10/04/2012 19.34.22: ezdevice.c - SaveDeviceXML: File Open Success!

10/04/2012 19.34.28: ReadWriteApi.c - SaveSysVarXML: File Open Success!

10/04/2012 19.34.28: ezaction.c - SaveActionsSysVarXML Done!

Here are the two devices that didn’t turn ON with the scene, but they did turn on when I did them individually:

10/04/2012 19.35.24: Done: MSG Code 62, ID 1A.A6.B6 Cmd1 11, Cmd2 FF Ack 6

10/04/2012 19.35.24: Done: PTH_StdMsg 50, ID 1A.A6.B6 Flag 2B, Cmd1 11, Cmd2 FF

10/04/2012 19.35.28: Done: MSG Code 62, ID 1A.A6.B6 Cmd1 19, Cmd2 0 Ack 6

10/04/2012 19.35.28: Done: PTH_StdMsg 50, ID 1A.A6.B6 Flag 2B, Cmd1 2F, Cmd2 FF

10/04/2012 19.35.31: Done: MSG Code 62, ID 1A.A8.B4 Cmd1 11, Cmd2 FF Ack 6

10/04/2012 19.35.31: Done: PTH_StdMsg 50, ID 1A.A8.B4 Flag 2B, Cmd1 11, Cmd2 FF

10/04/2012 19.35.35: Done: MSG Code 62, ID 1A.A8.B4 Cmd1 19, Cmd2 0 Ack 6

10/04/2012 19.35.36: Done: PTH_StdMsg 50, ID 1A.A8.B4 Flag 2B, Cmd1 2E, Cmd2 FF

I don’t know if this helps, but here are the link records for one of the 1kW SL that does not work (1A.A6.B6) and for one that does (12.76.E6):

[Anonymous]

#43873

The earlier post is for Group (Scene) 4. The latest post is for Group (Scene) 7.

[Anonymous]

#43874

Sorry about that, I believe I pasted the original scene before I did some other testing and deleted that one. The Scene 7 is the full scene and the one that is causing the problems now. Sorry for any confusion.
Steve

[Anonymous]

#43875

Both 1A.A6.B6 and 1A.A8.B4 ACKed the Group (Scene) 7 On.

10/04/2012 19.34.17: Done: PTH_StdMsg 50, ID 1A.A6.B6 Flag 61, Cmd1 11, Cmd2 7
10/04/2012 19.34.18: Done: PTH_StdMsg 50, ID 1A.A8.B4 Flag 61, Cmd1 11, Cmd2 7

This means both devices received the On command and accepted it. Try setting the LD3 value to 1 in those two devices.

From

To

[Anonymous]

#43876

That was it!

Thank you so much (again).

I will document that, since I obviously had it right before now. Have a great evening.

[Author]

Posts