1) Give a description of the problem
I created a virtual device (Simulated Minimote) with 4 buttons to control a piston. I have triggers for 3 of the buttons and all 3 button presses are logged in the ST. But the piston only seems to receive the first button press.
2) What is the expected behavior?
I expect that each of the 3 buttons should trigger a separate part of the piston.
3) What is happening/not happening?
Button presses for button #2 and button #3 are not being detected.
4) Post a Green Snapshot of the piston
5) Attach any logs (From ST IDE and by turning logging level to Full)
ST Log of Virtual Scene Controller (Floodlight Debug) device
e19eb1ca-339c-44ac-a25c-1080a7b550e1 2:50:40 PM: debug Floodlight Debug button 3 was pushed
e19eb1ca-339c-44ac-a25c-1080a7b550e1 2:50:25 PM: debug Floodlight Debug button 2 was pushed
e19eb1ca-339c-44ac-a25c-1080a7b550e1 2:50:09 PM: debug Floodlight Debug button 1 was pushed
WebCore Log for Piston (note there is no action shown for button 2 or button 3)
| +2ms | ╔Received event [Floodlight Debug].button = pushed with a delay of 214ms |
|---|---|
| +3697ms | ║RunTime Analysis CS > 26ms > PS > 3644ms > PE > 27ms > CE |
| +3698ms | ║Piston waited at a semaphore for 3508ms |
| +3701ms | ║Runtime (39797 bytes) successfully initialized in 3644ms (v0.3.109.20181207) (3697ms) |
| +3702ms | ║╔Execution stage started |
| +3715ms | ║║Comparison (enum) pushed gets (string) pushed = true (1ms) |
| +3717ms | ║║Condition #32 evaluated true (7ms) |
| +3718ms | ║║Condition group #31 evaluated true (state did not change) (10ms) |
| +3721ms | ║║Cancelling statement #48’s schedules… |
| +3727ms | ║║Executed virtual command setVariable (2ms) |
| +3737ms | ║║Comparison (enum) pushed gets (string) pushed = false (1ms) |
| +3739ms | ║║Condition #36 evaluated false (7ms) |
| +3741ms | ║║Condition group #35 evaluated false (state did not change) (9ms) |
| +3750ms | ║║Comparison (enum) pushed gets (string) pushed = false (2ms) |
| +3752ms | ║║Condition #43 evaluated false (7ms) |
| +3753ms | ║║Condition group #42 evaluated false (state did not change) (9ms) |
| +3756ms | ║╚Execution stage complete. (55ms) |
| +3758ms | ╚Event processed successfully (3759ms) |
ST Log for Piston
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:51:05 PM: debug ║║ Cancelling statement #46’s schedules…
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:51:05 PM: debug ║║ Cancelling statement #44’s schedules…
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:51:05 PM: debug ║║ Condition group #42 evaluated true (state changed) (10ms)
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:51:05 PM: debug ║║ Cancelling condition #42’s schedules…
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:51:05 PM: debug ║║ Condition #43 evaluated true (7ms)
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:51:05 PM: debug ║║ Cancelling condition #43’s schedules…
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:51:05 PM: debug ║║ Comparison (enum) pushed gets (string) pushed = true (1ms)
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:51:05 PM: debug ║║ Condition group #35 evaluated false (state did not change) (7ms)
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:51:05 PM: debug ║║ Condition group #31 evaluated false (state changed) (10ms)
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:51:05 PM: debug ║║ Cancelling condition #32’s schedules…
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:51:05 PM: debug ║║ Comparison (enum) pushed gets (string) pushed = false (1ms)
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:51:05 PM: trace ║╚ Execution stage started
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:51:05 PM: trace ║ Runtime (39799 bytes) successfully initialized in 9111ms (v0.3.109.20181207) (9150ms)
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:51:05 PM: warn ║ Piston waited at a semaphore for 9019ms
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:51:05 PM: debug ║ RunTime Analysis CS > 16ms > PS > 9111ms > PE > 21ms > CE
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:55 PM: debug ║║ Cancelling condition #32’s schedules…
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:55 PM: debug ║║ Cancelling condition #43’s schedules…
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:55 PM: debug ║║ Comparison (enum) pushed gets (string) pushed = true (1ms)
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:55 PM: debug ║║ Condition #43 evaluated true (8ms)
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:55 PM: warn ║ Piston waited at a semaphore for 8848ms
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:46 PM: info ╚ Received event [Floodlight Debug].button = pushed with a delay of 5900ms
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:45 PM: debug ║║ Cancelling statement #37’s schedules…
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:45 PM: trace ║║ Executed virtual command setVariable (1ms)
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:45 PM: debug ║║ Cancelling statement #39’s schedules…
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:45 PM: debug ║║ Condition group #35 evaluated true (state changed) (11ms)
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:45 PM: debug ║║ Cancelling condition #35’s schedules…
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:45 PM: debug ║║ Condition #36 evaluated true (6ms)
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:45 PM: debug ║║ Cancelling condition #36’s schedules…
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:45 PM: debug ║║ Comparison (enum) pushed gets (string) pushed = true (1ms)
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:45 PM: debug ║║ Condition group #31 evaluated false (state changed) (9ms)
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:45 PM: debug ║║ Cancelling condition #31’s schedules…
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:45 PM: debug ║║ Condition #32 evaluated false (7ms)
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:45 PM: debug ║║ Cancelling condition #32’s schedules…
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:45 PM: debug ║║ Comparison (enum) pushed gets (string) pushed = false (1ms)
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:45 PM: trace ║╚ Execution stage started
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:45 PM: trace ║ Runtime (39799 bytes) successfully initialized in 8902ms (v0.3.109.20181207) (8952ms)
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:45 PM: warn ║ Piston waited at a semaphore for 8767ms
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:45 PM: debug ║ RunTime Analysis CS > 20ms > PS > 8902ms > PE > 25ms > CE
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:36 PM: info ╚ Received event [Floodlight Debug].button = pushed with a delay of 11421ms
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:45 PM: debug ║║ Cancelling statement #46’s schedules…
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:45 PM: trace ║║ Executed virtual command setVariable (1ms)
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:45 PM: debug ║║ Condition group #42 evaluated true (state changed) (10ms)
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:45 PM: debug ║║ Condition #43 evaluated true (8ms)
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:45 PM: trace ║║ Executed virtual command setVariable (1ms)
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:45 PM: debug ║║ Comparison (enum) pushed gets (string) pushed = true (2ms)
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:45 PM: debug ║║ Cancelling statement #44’s schedules…
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:45 PM: debug ║║ Condition #36 evaluated false (6ms)
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:45 PM: debug ║║ Cancelling condition #42’s schedules…
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:45 PM: debug ║║ Condition group #31 evaluated false (state changed) (13ms)
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:45 PM: debug ║║ Condition group #35 evaluated false (state did not change) (7ms)
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:45 PM: debug ║║ Comparison (enum) pushed gets (string) pushed = false (1ms)
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:45 PM: debug ║║ Comparison (enum) pushed gets (string) pushed = false (4ms)
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:45 PM: debug ║║ Cancelling condition #31’s schedules…
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:45 PM: debug ║║ Cancelling condition #32’s schedules…
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:45 PM: warn ║ Piston waited at a semaphore for 4510ms
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:45 PM: trace ║ Runtime (39796 bytes) successfully initialized in 4595ms (v0.3.109.20181207) (4630ms)
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:45 PM: debug ║ RunTime Analysis CS > 12ms > PS > 4595ms > PE > 22ms > CE
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:40 PM: info ╚ Received event [Floodlight Debug].button = pushed with a delay of 51ms
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:35 PM: debug ║║ Cancelling statement #37’s schedules…
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:35 PM: trace ║║ Executed virtual command setVariable (1ms)
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:35 PM: debug ║║ Cancelling condition #31’s schedules…
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:35 PM: trace ║╚ Execution stage started
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:26 PM: info ╚ Received event [Floodlight Debug].button = pushed with a delay of 1531ms
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:25 PM: debug ║║ Cancelling statement #39’s schedules…
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:25 PM: debug ║║ Cancelling condition #35’s schedules…
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:25 PM: debug ║║ Cancelling condition #36’s schedules…
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:25 PM: debug ║║ Comparison (enum) pushed gets (string) pushed = true (1ms)
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:25 PM: debug ║║ Comparison (enum) pushed gets (string) pushed = false (1ms)
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:25 PM: trace ║╚ Execution stage started
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:25 PM: trace ║ Runtime (39724 bytes) successfully initialized in 146ms (v0.3.109.20181207) (192ms)
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:25 PM: debug ║ RunTime Analysis CS > 20ms > PS > 146ms > PE > 26ms > CE
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:25 PM: info ╚ Received event [Floodlight Debug].button = pushed with a delay of 64ms
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:13 PM: trace ║║ Executed virtual command setVariable (1ms)
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:13 PM: debug ║║ Condition group #35 evaluated true (state changed) (12ms)
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:13 PM: debug ║║ Cancelling condition #35’s schedules…
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:13 PM: debug ║║ Cancelling condition #36’s schedules…
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:13 PM: debug ║║ Cancelling condition #31’s schedules…
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:13 PM: debug ║║ Cancelling condition #32’s schedules…
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:13 PM: debug ║║ Cancelling statement #37’s schedules…
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:13 PM: debug ║║ Cancelling statement #39’s schedules…
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:13 PM: trace ║╚ Execution stage started
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:13 PM: debug ║║ Condition #36 evaluated true (8ms)
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:13 PM: debug ║ RunTime Analysis CS > 21ms > PS > 8886ms > PE > 24ms > CE
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:13 PM: debug ║║ Condition group #31 evaluated false (state changed) (12ms)
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:13 PM: debug ║║ Condition #32 evaluated false (8ms)
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:13 PM: debug ║║ Comparison (enum) pushed gets (string) pushed = false (1ms)
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:13 PM: warn ║ Piston waited at a semaphore for 8769ms
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:04 PM: info ╚ Received event [Floodlight Debug].button = pushed with a delay of 11368ms
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:13 PM: info ╔ Event processed successfully (3759ms)
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:13 PM: debug ║║ Condition group #42 evaluated false (state did not change) (9ms)
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:13 PM: trace ║╔ Execution stage complete. (55ms)
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:13 PM: debug ║║ Condition group #35 evaluated false (state did not change) (9ms)
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:13 PM: debug ║║ Comparison (enum) pushed gets (string) pushed = false (1ms)
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:13 PM: debug ║║ Condition #32 evaluated true (7ms)
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:13 PM: warn ║ Piston waited at a semaphore for 3508ms
84054beb-ed2d-43d9-bb1a-c7bd2fce9457 2:50:10 PM: info ╚ Received event [Floodlight Debug].button = pushed with a delay of 214ms
