Upgrade Issues

[CatmanV2]

After much (un-necessary) faffing on Debian 10 I got MSR up and running in about 10 minutes flat this morning, on the same box as AltUI

As a warning, expect lots of stupid questions.

C

[CatmanV2]

Ummm, ok this is embarrassing....

I'm actually stuck on creating the simplest rule set / reaction. I can't see anything in the manual that indicates my stupidity, and it's surely not a bug so here goes.

Bare metal install on Debian 10
Simple 'AND' test rule which I can see setting and resetting as the conditions change.

I have tried putting a reaction in that rule set to turn on a light. So literally
Entity action > Table Lamp > power_switch.on

If I press the 'Try this action' button from within the reaction, the light goes on.
If I press the 'Run Now' button from the Global reactions list:

1.0.2-21350]2022-05-26T16:20:45.257Z <Engine:NOTICE> Starting reaction Test motion lights<SET> (rule-l3n662g0:S)
[1.0.2-21350]2022-05-26T16:20:45.257Z <Engine:INFO> Test motion lights<SET> all actions completed.
[1.0.2-21350]2022-05-26T16:20:59.510Z <wsapi:INFO> wsapi: connection from ::ffff:192.168.70.64
[1.0.2-21350]2022-05-26T16:21:13.051Z <wsapi:INFO> client "192.168.70.64#4" closed, code=1001, reason=
[1.0.2-21350]2022-05-26T16:21:15.726Z <Rule:INFO> Test motion lights (Rule#rule-l3n662g0) RESET!
[1.0.2-21350]2022-05-26T16:25:03.556Z <VeraController:ERR> VeraController#vera update request failed: [FetchError]FetchError: network timeout at: http://192.168.70.249:3480/data_request?id=status&DataVersion=938493960&Timeout=15&MinimumDe
lay=50&output_format=json&_r=1653582285543
[1.0.2-21350]2022-05-26T16:26:17.531Z <Engine:INFO> Enqueueing "Simple Test Reaction" (re-l3n7rrij)
[1.0.2-21350]2022-05-26T16:26:17.536Z <Engine:NOTICE> Starting reaction Simple Test Reaction (re-l3n7rrij)
[1.0.2-21350]2022-05-26T16:26:17.537Z <Engine:INFO> Simple Test Reaction all actions completed.
[1.0.2-21350]2022-05-26T16:26:45.444Z <VeraController:null> VeraController#vera enqueued task for Entity#vera>device_20200 action power_switch.on: task [Object]{ "newTargetValue": "1", "DeviceNum": 20200, "id": "action", "serviceId": "urn
:upnp-org:serviceId:SwitchPower1", "action": "SetTarget" }
[1.0.2-21350]2022-05-26T16:26:49.094Z <VeraController:null> VeraController#vera enqueued task for Entity#vera>device_20200 action power_switch.on: task [Object]{ "newTargetValue": "1", "DeviceNum": 20200, "id": "action", "serviceId": "urn:upnp-org:serviceId:SwitchPower1", "action": "SetTarget" }
[1.0.2-21350]2022-05-26T16:26:59.543Z <Engine:INFO> Enqueueing "Simple Test Reaction" (re-l3n7rrij)
[1.0.2-21350]2022-05-26T16:26:59.543Z <Engine:NOTICE> Reaction re-l3n7rrij (Simple Test Reaction) is disabled; run aborted.
[1.0.2-21350]2022-05-26T16:27:03.286Z <Engine:INFO> Enqueueing "Simple Test Reaction" (re-l3n7rrij)
[1.0.2-21350]2022-05-26T16:27:03.287Z <Engine:NOTICE> Reaction re-l3n7rrij (Simple Test Reaction) is disabled; run aborted.
[1.0.2-21350]2022-05-26T16:27:20.861Z <Engine:INFO> Enqueueing "Simple Test Reaction" (re-l3n7rrij)
[1.0.2-21350]2022-05-26T16:27:20.861Z <Engine:NOTICE> Reaction re-l3n7rrij (Simple Test Reaction) is disabled; run aborted.
[1.0.2-21350]2022-05-26T16:27:23.575Z <Engine:INFO> Enqueueing "Simple Test Reaction" (re-l3n7rrij)
[1.0.2-21350]2022-05-26T16:27:23.576Z <Engine:NOTICE> Reaction re-l3n7rrij (Simple Test Reaction) is disabled; run aborted.
[1.0.2-21350]2022-05-26T16:27:47.538Z <Engine:INFO> Enqueueing "Simple Test Reaction" (re-l3n7rrij)
[1.0.2-21350]2022-05-26T16:27:47.542Z <Engine:NOTICE> Starting reaction Simple Test Reaction (re-l3n7rrij)
[1.0.2-21350]2022-05-26T16:27:47.543Z <Engine:INFO> Simple Test Reaction all actions completed.
[1.0.2-21350]2022-05-26T16:28:14.393Z <Engine:INFO> Enqueueing "Simple Test Reaction" (re-l3n7rrij)
[1.0.2-21350]2022-05-26T16:28:14.397Z <Engine:NOTICE> Starting reaction Simple Test Reaction (re-l3n7rrij)
[1.0.2-21350]2022-05-26T16:28:14.397Z <Engine:INFO> Simple Test Reaction all actions completed.
[1.0.2-21350]2022-05-26T16:28:24.225Z <Engine:INFO> Enqueueing "Simple Test Reaction" (re-l3n7rrij)
[1.0.2-21350]2022-05-26T16:28:24.228Z <Engine:NOTICE> Starting reaction Simple Test Reaction (re-l3n7rrij)
[1.0.2-21350]2022-05-26T16:28:24.229Z <Engine:INFO> Simple Test Reaction all actions completed.
[1.0.2-21350]2022-05-26T16:28:35.395Z <VeraController:null> VeraController#vera enqueued task for Entity#vera>device_20200 action power_switch.on: task [Object]{ "newTargetValue": "1", "DeviceNum": 20200, "id": "action", "serviceId": "urn:upnp-org:serviceId:SwitchPower1", "action": "SetTarget" }
[1.0.2-21350]2022-05-26T16:29:12.974Z <VeraController:null> VeraController#vera enqueued task for Entity#vera>device_20160 action power_switch.on: task [Object]{ "newTargetValue": "1", "DeviceNum": 20160, "id": "action", "serviceId": "urn:upnp-org:serviceId:SwitchPower1", "action": "SetTarget" }
[1.0.2-21350]2022-05-26T16:29:25.885Z <Engine:INFO> Enqueueing "Simple Test Reaction" (re-l3n7rrij)
[1.0.2-21350]2022-05-26T16:29:25.889Z <Engine:NOTICE> Starting reaction Simple Test Reaction (re-l3n7rrij)
[1.0.2-21350]2022-05-26T16:29:25.889Z <Engine:INFO> Simple Test Reaction all actions completed.
[1.0.2-21350]2022-05-26T16:29:50.139Z <Engine:INFO> Enqueueing "Simple Test Reaction" (re-l3n7rrij)
[1.0.2-21350]2022-05-26T16:29:50.143Z <Engine:NOTICE> Starting reaction Simple Test Reaction (re-l3n7rrij)
[1.0.2-21350]2022-05-26T16:29:50.143Z <Engine:INFO> Simple Test Reaction all actions completed.
[1.0.2-21350]2022-05-26T16:33:05.815Z <Engine:INFO> Enqueueing "Simple Test Reaction" (re-l3n7rrij)
[1.0.2-21350]2022-05-26T16:33:05.825Z <Engine:NOTICE> Starting reaction Simple Test Reaction (re-l3n7rrij)
[1.0.2-21350]2022-05-26T16:33:05.826Z <Engine:INFO> Simple Test Reaction all actions completed.
[1.0.2-21350]2022-05-26T16:35:15.688Z <Engine:INFO> Enqueueing "Simple Test Reaction" (re-l3n7rrij)
[1.0.2-21350]2022-05-26T16:35:15.692Z <Engine:NOTICE> Starting reaction Simple Test Reaction (re-l3n7rrij)
[1.0.2-21350]2022-05-26T16:35:15.692Z <Engine:INFO> Simple Test Reaction all actions completed.
[1.0.2-21350]2022-05-26T16:37:17.185Z <VeraController:null> VeraController#vera enqueued task for Entity#vera>device_20160 action power_switch.on: task [Object]{ "newTargetValue": "1", "DeviceNum": 20160, "id": "action", "serviceId": "urn:upnp-org:serviceId:SwitchPower1", "action": "SetTarget" }
[1.0.2-21350]2022-05-26T16:37:44.912Z <Engine:INFO> Enqueueing "Simple Test Reaction" (re-l3n7rrij)
[1.0.2-21350]2022-05-26T16:37:44.916Z <Engine:NOTICE> Starting reaction Simple Test Reaction (re-l3n7rrij)
[1.0.2-21350]2022-05-26T16:37:44.917Z <Engine:INFO> Simple Test Reaction all actions completed.
[1.0.2-21350]2022-05-26T16:38:39.696Z <Rule:INFO> Test motion lights (Rule#rule-l3n662g0) SET!
[1.0.2-21350]2022-05-26T16:38:39.703Z <Engine:INFO> Enqueueing "Test motion lights<SET>" (rule-l3n662g0:S)
[1.0.2-21350]2022-05-26T16:38:39.717Z <Engine:NOTICE> Starting reaction Test motion lights<SET> (rule-l3n662g0:S)
[1.0.2-21350]2022-05-26T16:38:39.717Z <Engine:INFO> Test motion lights<SET> all actions completed.
[1.0.2-21350]2022-05-26T16:39:10.418Z <Rule:INFO> Test motion lights (Rule#rule-l3n662g0) RESET!
[1.0.2-21350]2022-05-26T16:41:56.241Z <VeraController:ERR> VeraController#vera update request failed: [FetchError]FetchError: network timeout at: http://192.168.70.249:3480/data_request?id=status&DataVersion=938494294&Timeout=15&MinimumDelay=50&output_format=json&_r=1653583298238

But the light never comes on

Similarly although the test Rule set is supposed to trigger 'Simple Test Reaction', the light never turns on.

The reaction is enabled. The rule set is enabled.

Time zone is BST (trying to conform to the log requests in the manual!)

I'm sorry! It's going to be something really dumb but I've spent about an hour so far

C

[gwp1]

Screenshot of the ruleset?

[CatmanV2]

@gwp1 said in Up and running:

Screenshot of the ruleset?

Of course, but given the Reaction doesn't do anything...

It's crazy simple. Obviously the rule won't trigger under these circumstances as the motion sensor is not tripped, but even when it does....no reaction

Cheers

C

[toggledbits]

Well, first, you're on an ancient build. I would start by getting on latest.

After that, the log messages are telling you it can't talk to the Vera. Do you see those?

[1.0.2-21350]2022-05-26T16:25:03.556Z <VeraController:ERR> VeraController#vera update request failed: [FetchError]FetchError: network timeout at: http://192.168.70.249:3480/data_request?id=status&DataVersion=938493960&Timeout=15&MinimumDe
lay=50&output_format=json&_r=1653582285543

[CatmanV2]

@toggledbits Ahh, OK. I didn't check the version. I'll upgrade.

Yes I did see it can't talk to Vera but given that I can run the action directly I figured that was looking at something else it was trying to do.....

Clearly all the entities are in place, and updating in real time, as is the rule set so my takeaway was that it can talk to Vera (at least for these functions.

Cheers

C

[CatmanV2]

OK, updated and working.

Told you it would be something stupid, although it was only 2 months old

Thanks, as ever

C

[toggledbits]

@catmanv2 said in Up and running:

although it was only 2 months old

If you mean the release, the stamp 21350 means December 16, 2021, so that's more than five months ago. Things change fast around here!

[CatmanV2]

@toggledbits said in Up and running:

@catmanv2 said in Up and running:

although it was only 2 months old

If you mean the release, the stamp 21350 means December 16, 2021, so that's more than five months ago. Things change fast around here!

Ahh I was looking at the date stamp on the download. Thanks, as ever

C