activate_scenario.sh
Fri Sep 13 16:04:22 CEST 2024
scenario: vorraumOn
phoneName: 0
'day' <--> DayTime=checked , NightTime=checked
-> execution of scenario
-> sending email(s) to 'thomas@miglinci.name'
scenario: 'Vorzimmer EIN' - 'vorraumOn' - 'vorraumon'
Licht Vorzimmer (/srv/www/viehai/shelly/log_scenarios/2024/09/13/_0.log)
vorher: 16:04:22.273
http://192.168.12.103/relay/0?turn=on |
|---|
"ison": true,
"has_timer": false,
"timer_started": 0,
"timer_duration": 0,
"timer_remaining": 0,
"overpower": false,
"source": "input"
|
nachher: 16:04:22.338
Dauer: 66 msec
phName: 'phoneName:'0''
scenario: 'Vorzimmer EIN' - 'vorraumOn' - 'vorraumon'
urls : 1
vorher : 16:04:22.254
nachher: 16:04:22.345
Dauer : 90 msec
outfile: '/srv/www/viehai/shelly/log_scenarios/2024/09/13//scene_vorraumOn_20240913_160422.txt'
syslog
Sep 13 16:01:01 srv4hai CRON[8497]: (root) CMD (/etc/linetest/linetest.sh)
Sep 13 16:01:37 srv4hai fetchmail[1651]: 7 messages (7 seen) for rozmig at mail.akis.at (1753652 octets).
Sep 13 16:01:38 srv4hai fetchmail[1651]: 52252 messages (52252 seen) for tmiglinci at mail.akis.at (7698469 octets).
Sep 13 16:01:38 srv4hai fetchmail[1651]: 10716 messages (10716 seen) for srozum at mail.akis.at (1301407812 octets).
Sep 13 16:01:39 srv4hai fetchmail[1651]: 25892 messages (25892 seen) for mrozum at mail.akis.at (208057424 octets).
Sep 13 16:01:39 srv4hai fetchmail[1651]: 1143 messages (1143 seen) for admin@bastelstube.wien at pop3.world4you.com (51639660 octets).
Sep 13 16:02:01 srv4hai CRON[8520]: (root) CMD (/etc/linetest/linetest.sh)
Sep 13 16:02:31 srv4hai dhcpd[30731]: DHCPDISCOVER from f0:39:65:25:8d:c7 via enp2s0
Sep 13 16:02:31 srv4hai dhcpd[30731]: DHCPOFFER on 192.168.12.62 to f0:39:65:25:8d:c7 via enp2s0
Sep 13 16:02:31 srv4hai dhcpd[30731]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Sep 13 16:02:31 srv4hai dhcpd[30731]: execute_statement argv[1] = 192.168.12.62
Sep 13 16:02:31 srv4hai dhcpd[30731]: execute_statement argv[2] = GreteS20
Sep 13 16:02:31 srv4hai common[8526]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/smartphone.sh
Sep 13 16:02:31 srv4hai smartphone.sh: SHELLY - ping_SP.sh started for phoneIP: '192.168.12.62' / 'GreteS20' / '1'
Sep 13 16:02:31 srv4hai common[8540]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//ping_SP.sh
Sep 13 16:02:31 srv4hai dhcpd[30731]: DHCPREQUEST for 192.168.12.62 (192.168.12.252) from f0:39:65:25:8d:c7 via enp2s0
Sep 13 16:02:31 srv4hai dhcpd[30731]: DHCPACK on 192.168.12.62 to f0:39:65:25:8d:c7 via enp2s0
Sep 13 16:02:31 srv4hai named[30406]: client @0x7f933004a8d0 192.168.12.252#51671/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: GreteS20.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Sep 13 16:02:31 srv4hai named[30406]: client @0x7f931c098670 192.168.12.252#47275/key rndc-key: signer "rndc-key" approved
Sep 13 16:02:31 srv4hai named[30406]: client @0x7f931c098670 192.168.12.252#47275/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'GreteS20.viehai.at' TXT
Sep 13 16:02:31 srv4hai named[30406]: client @0x7f931c098670 192.168.12.252#47275/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'GreteS20.viehai.at' TXT "318a9f974c0d8a2868f64397b15c3ed3a0"
Sep 13 16:02:31 srv4hai named[30406]: client @0x7f931c098670 192.168.12.252#47275/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'GreteS20.viehai.at' A
Sep 13 16:02:31 srv4hai named[30406]: client @0x7f931c098670 192.168.12.252#47275/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'GreteS20.viehai.at' A 192.168.12.62
Sep 13 16:02:31 srv4hai dhcpd[30731]: Added new forward map from GreteS20.viehai.at to 192.168.12.62
Sep 13 16:02:31 srv4hai named[30406]: client @0x7f93100b4de0 192.168.12.252#39929/key rndc-key: signer "rndc-key" approved
Sep 13 16:02:31 srv4hai named[30406]: client @0x7f93100b4de0 192.168.12.252#39929/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '62.12.168.192.in-addr.arpa' PTR
Sep 13 16:02:31 srv4hai named[30406]: client @0x7f93100b4de0 192.168.12.252#39929/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': adding an RR at '62.12.168.192.in-addr.arpa' PTR GreteS20.viehai.at.
Sep 13 16:02:31 srv4hai dhcpd[30731]: Added reverse map from 62.12.168.192.in-addr.arpa. to GreteS20.viehai.at
Sep 13 16:02:31 srv4hai ping_SP[8540]: SHELLY ping_SP.sh started from smartphone, user:'root'
Sep 13 16:02:41 srv4hai common[8576]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//check_presence.sh
Sep 13 16:02:41 srv4hai common.sh: SHELLY: BTstatus[0]:'Off' dweetAge:'9'=1726236161-1726236152
Sep 13 16:02:41 srv4hai check_presence[8576]: SHELLY (0) found /srv/www/viehai/shelly//meter/ping_SP_0.DEAD and Off//srv/www/viehai/shelly//meter/BTscan_BTid_0.No
Sep 13 16:02:41 srv4hai common.sh: SHELLY: BTstatus[1]:'Off' dweetAge:'9'=1726236161-1726236152
Sep 13 16:02:41 srv4hai check_presence[8576]: SHELLY (1) found /srv/www/viehai/shelly//meter/ping_SP_1.LIVE and Off//srv/www/viehai/shelly//meter/BTscan_BTid_1.No
Sep 13 16:02:41 srv4hai check_presence[8576]: SHELLY (ping_SP__smartphone)-(1) found '/srv/www/viehai/shelly//meter/ping_SP_1.LIVE' for what: 'coming'
Sep 13 16:02:41 srv4hai common.sh: SHELLY: BTstatus[2]:'Off' dweetAge:'9'=1726236161-1726236152
Sep 13 16:02:41 srv4hai check_presence[8576]: SHELLY (2) found /srv/www/viehai/shelly//meter/ping_SP_2.DEAD and Off//srv/www/viehai/shelly//meter/BTscan_BTid_2.No
Sep 13 16:02:41 srv4hai check_presence[8576]: SHELLY (ping_SP__smartphone)-(1) what: 'coming' - persPresent: '(__/__) (__/LIVE) (__/__)' - anyPresent: 'true' - otherPresent:'false'
Sep 13 16:02:41 srv4hai check_presence[8576]: SHELLY - check 'coming': anyPresent(true) - lScene(scene_offAll) - sceneONsmall(vorraumOn)
Sep 13 16:02:41 srv4hai check_presence[8576]: SHELLY (ping_SP__smartphone)-(1)-coming: activate_scenario.shtml: vorraumOn
Sep 13 16:02:42 srv4hai activate_scenario[8724]: SHELLY scenario: 'vorraumOn(vorraumon)' started
Sep 13 16:02:42 srv4hai activate_scenario[8724]: SHELLY dayState='day' <--> DayTime=checked NightTime=checked - phoneName:'1'
Sep 13 16:02:42 srv4hai activate_scenario[8724]: SHELLY scenario: 'vorraumOn(vorraumon)' TO BE EXECUTED, phoneName:'1'
Sep 13 16:02:42 srv4hai postfix/pickup[1165]: 0CE586345B: uid=33 from=
Sep 13 16:02:42 srv4hai postfix/cleanup[8774]: 0CE586345B: message-id=<20240913140242.0CE586345B@mail.viehai.at>
Sep 13 16:02:42 srv4hai postfix/qmgr[8566]: 0CE586345B: from=, size=9720, nrcpt=1 (queue active)
Sep 13 16:02:42 srv4hai activate_scenario[8724]: SHELLY: scenario: 'vorraumOn(vorraumon)'-'shelly-03'-'Licht Vorzimmer' --> '0?turn=on'
Sep 13 16:02:42 srv4hai proc_url[8791]: SHELLY: OF:'/srv/www/viehai/shelly/log_scenarios/2024/09/13/_0.log' - shellyUsage:'Licht Vorzimmer' - scUrl:'http://192.168.12.103/relay/0?turn=on' - phName:'phoneName:'1''
Sep 13 16:02:42 srv4hai activate_scenario[8724]: SHELLY scenario: 'vorraumOn(vorraumon)' took 140 msec, phoneName:'1'
Sep 13 16:02:42 srv4hai ping_SP[8540]: SHELLY check_presence.sh coming 1 ping_SP__smartphone
Sep 13 16:02:42 srv4hai postfix/relay/smtp[8787]: 0CE586345B: to=, relay=mx03.nic.name[209.131.161.54]:25, delay=0.93, delays=0.04/0.02/0.61/0.26, dsn=2.0.0, status=sent (250 2.0.0 Ok: queued as BB87C800A0)
Sep 13 16:02:42 srv4hai postfix/qmgr[8566]: 0CE586345B: removed
Sep 13 16:02:44 srv4hai ping_SP[8540]: SHELLY - new process for '192.168.12.62' - '/srv/www/viehai/shelly//meter/ping_SP_1' @~1726236164
Sep 13 16:02:57 srv4hai ping_SP[8540]: SHELLY (1) - ping 192.168.12.62 0 sucessfully @~1726236177 - 'ping_SP_1'
Sep 13 16:02:57 srv4hai ping_SP[8540]: SHELLY (1) - ping 192.168.12.62 pCnt:'1'
Sep 13 16:03:01 srv4hai CRON[8848]: (root) CMD (/etc/linetest/linetest.sh)
Sep 13 16:03:09 srv4hai activate_scenario[8854]: SHELLY scenario: 'sekr_UM(sekr_um)' started
Sep 13 16:03:09 srv4hai activate_scenario[8854]: SHELLY dayState='day' <--> DayTime=checked NightTime=checked - shelly-10:'WZ Bücherregal'
Sep 13 16:03:09 srv4hai activate_scenario[8854]: SHELLY scenario: 'sekr_UM(sekr_um)' TO BE EXECUTED, shelly-10:'WZ Bücherregal'
Sep 13 16:03:09 srv4hai postfix/pickup[1165]: 14AC66345B: uid=33 from=
Sep 13 16:03:09 srv4hai postfix/cleanup[8774]: 14AC66345B: message-id=<20240913140309.14AC66345B@mail.viehai.at>
Sep 13 16:03:09 srv4hai postfix/qmgr[8566]: 14AC66345B: from=, size=11610, nrcpt=1 (queue active)
Sep 13 16:03:09 srv4hai postfix/local[8910]: 14AC66345B: to=, orig_to=, relay=local, delay=0.05, delays=0.01/0.01/0/0.03, dsn=5.1.1, status=bounced (unknown user: "null")
Sep 13 16:03:09 srv4hai postfix/cleanup[8774]: 20D7F6345C: message-id=<20240913140309.20D7F6345C@mail.viehai.at>
Sep 13 16:03:09 srv4hai postfix/bounce[8916]: 14AC66345B: sender non-delivery notification: 20D7F6345C
Sep 13 16:03:09 srv4hai postfix/qmgr[8566]: 20D7F6345C: from=<>, size=13532, nrcpt=1 (queue active)
Sep 13 16:03:09 srv4hai postfix/qmgr[8566]: 14AC66345B: removed
Sep 13 16:03:09 srv4hai activate_scenario[8854]: SHELLY: scenario: 'sekr_UM(sekr_um)'-'shelly-23'-'Licht Sekretär' --> '0?turn=toggle'
Sep 13 16:03:09 srv4hai postfix/local[8910]: 20D7F6345C: to=, relay=local, delay=0.01, delays=0/0/0/0.01, dsn=2.0.0, status=sent (delivered to command: /bin/procmail)
Sep 13 16:03:09 srv4hai postfix/qmgr[8566]: 20D7F6345C: removed
Sep 13 16:03:09 srv4hai proc_url[8924]: SHELLY: OF:'/srv/www/viehai/shelly/log_scenarios/2024/09/13/_0.log' - shellyUsage:'Licht Sekretär' - scUrl:'http://192.168.12.123/relay/0?turn=toggle' - phName:'shelly-10:'WZ Bücherregal''
Sep 13 16:03:09 srv4hai activate_scenario[8854]: SHELLY scenario: 'sekr_UM(sekr_um)' took 80 msec, shelly-10:'WZ Bücherregal'
Sep 13 16:03:17 srv4hai common[8951]: SHELLY common.sh inkludiert von ./bt_scan.sh
Sep 13 16:03:27 srv4hai bt_scan[8951]: SHELLY - who: 'Grete' / phoneName: 'S20_Grete' / '1' / shellyIP: '192.168.12.62' - found: 'Yes'
Sep 13 16:03:27 srv4hai bt_scan[8951]: SHELLY - ping_SP.sh already running for BTmac: '1' - IPv4: '192.168.12.62'
Sep 13 16:03:34 srv4hai dhcpd[30731]: DHCPDISCOVER from 98:cd:ac:2e:1c:da via enp2s0
Sep 13 16:03:34 srv4hai dhcpd[30731]: DHCPOFFER on 192.168.12.122 to 98:cd:ac:2e:1c:da via enp2s0
Sep 13 16:03:34 srv4hai dhcpd[30731]: execute_statement argv[0] = /srv/www/viehai/shelly/json.sh
Sep 13 16:03:34 srv4hai dhcpd[30731]: execute_statement argv[1] = 192.168.12.122
Sep 13 16:03:34 srv4hai dhcpd[30731]: execute_statement argv[2] = shelly-22
Sep 13 16:03:34 srv4hai dhcpd[30731]: DHCPREQUEST for 192.168.12.122 (192.168.12.252) from 98:cd:ac:2e:1c:da via enp2s0
Sep 13 16:03:34 srv4hai dhcpd[30731]: DHCPACK on 192.168.12.122 to 98:cd:ac:2e:1c:da via enp2s0
Sep 13 16:03:34 srv4hai named[30406]: client @0x7f932c0cbfc0 192.168.12.252#58995/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: shelly-22.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Sep 13 16:03:34 srv4hai named[30406]: client @0x7f93300ede20 192.168.12.252#52895/key rndc-key: signer "rndc-key" approved
Sep 13 16:03:34 srv4hai named[30406]: client @0x7f93300ede20 192.168.12.252#52895/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'shelly-22.viehai.at' TXT
Sep 13 16:03:34 srv4hai named[30406]: client @0x7f93300ede20 192.168.12.252#52895/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'shelly-22.viehai.at' TXT "0051fce7ef7edfb3d29643aefa7bc5b7f2"
Sep 13 16:03:34 srv4hai named[30406]: client @0x7f93300ede20 192.168.12.252#52895/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'shelly-22.viehai.at' A
Sep 13 16:03:34 srv4hai named[30406]: client @0x7f93300ede20 192.168.12.252#52895/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'shelly-22.viehai.at' A 192.168.12.122
Sep 13 16:03:34 srv4hai dhcpd[30731]: Added new forward map from shelly-22.viehai.at to 192.168.12.122
Sep 13 16:03:34 srv4hai named[30406]: client @0x7f93480deab0 192.168.12.252#36221/key rndc-key: signer "rndc-key" approved
Sep 13 16:03:34 srv4hai named[30406]: client @0x7f93480deab0 192.168.12.252#36221/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '122.12.168.192.in-addr.arpa' PTR
Sep 13 16:03:34 srv4hai named[30406]: client @0x7f93480deab0 192.168.12.252#36221/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': adding an RR at '122.12.168.192.in-addr.arpa' PTR shelly-22.viehai.at.
Sep 13 16:03:34 srv4hai dhcpd[30731]: Added reverse map from 122.12.168.192.in-addr.arpa. to shelly-22.viehai.at
Sep 13 16:03:34 srv4hai common[8986]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/grab_shelly.sh
Sep 13 16:03:34 srv4hai grab_shelly.sh: SHELLY shellyName/1:'shelly-22' - shIP/2:'' perform:'conf'
Sep 13 16:03:35 srv4hai mosquitto[18551]: New connection from 192.168.12.122 on port 1883.
Sep 13 16:03:35 srv4hai mosquitto[18551]: New client connected from 192.168.12.122 as shelly1-98CDAC2E1CDA (c1, k60, u'shmqtt').
Sep 13 16:03:35 srv4hai conf_shelly.sh: SHELLY: PARAMS shName/1:'shelly-22' - shIP/2:'192.168.12.122'
Sep 13 16:03:35 srv4hai conf_shelly.sh: SHELLY: usage/name 'WC' already set for '192.168.12.122'
Sep 13 16:03:35 srv4hai conf_shelly.sh: SHELLY:'shelly-22' 'conf(0)' - allready transmitted - skipping
Sep 13 16:03:35 srv4hai conf_shelly.sh: SHELLY:'shelly-22' 'conf(1)' - allready transmitted - skipping
Sep 13 16:03:35 srv4hai conf_shelly.sh: SHELLY:'shelly-22' 'conf(2)' - allready transmitted - skipping
Sep 13 16:03:35 srv4hai conf_shelly.sh: SHELLY:'shelly-22' 'conf(3)' - allready transmitted - skipping
Sep 13 16:03:35 srv4hai conf_shelly.sh: SHELLY:'shelly-22' 'conf(4)' - allready transmitted - skipping
Sep 13 16:03:35 srv4hai conf_shelly.sh: SHELLY:'shelly-22' out:0 ON : MISSING
Sep 13 16:03:35 srv4hai conf_shelly.sh: SHELLY:'shelly-22' out:0 OFF: MISSING
Sep 13 16:04:01 srv4hai CRON[9121]: (root) CMD (/etc/linetest/linetest.sh)
Sep 13 16:04:10 srv4hai ping_SP[8540]: SHELLY (1) - ping 192.168.12.62 1 sucessfully @~1726236250 - 'ping_SP_1'
Sep 13 16:04:10 srv4hai ping_SP[8540]: SHELLY (1) - ping 192.168.12.62 pCnt:'1'
Sep 13 16:04:11 srv4hai common[9142]: SHELLY common.sh inkludiert von ./bt_scan.sh
Sep 13 16:04:21 srv4hai bt_scan[9142]: SHELLY - who: 'Thomas' / phoneName: 'FP4_Thomas' / '0' / shellyIP: '192.168.12.61' - found: 'Yes'
Sep 13 16:04:21 srv4hai bt_scan[9142]: SHELLY - ping_SP.sh started for BTmac: '0' - IPv4: '192.168.12.61'
Sep 13 16:04:21 srv4hai common[9173]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//ping_SP.sh
Sep 13 16:04:21 srv4hai ping_SP[9173]: SHELLY ping_SP.sh started from bt_scan, user:'www-data'
Sep 13 16:04:21 srv4hai common[9194]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//check_presence.sh
Sep 13 16:04:21 srv4hai common.sh: SHELLY: BTstatus[0]:'On' dweetAge:'3'=1726236261-1726236258
Sep 13 16:04:21 srv4hai check_presence[9194]: SHELLY (0) found /srv/www/viehai/shelly//meter/ping_SP_0.TRY} and On//srv/www/viehai/shelly//meter/BTscan_BTid_0.Yes
Sep 13 16:04:21 srv4hai common.sh: SHELLY: BTstatus[1]:'On' dweetAge:'3'=1726236261-1726236258
Sep 13 16:04:21 srv4hai check_presence[9194]: SHELLY (1) found /srv/www/viehai/shelly//meter/ping_SP_1.LIVE and On//srv/www/viehai/shelly//meter/BTscan_BTid_1.Yes
Sep 13 16:04:21 srv4hai check_presence[9194]: SHELLY (ping_SP__bt_scan)-(0) found '/srv/www/viehai/shelly//meter/ping_SP_1.LIVE' for what: 'coming'
Sep 13 16:04:22 srv4hai common.sh: SHELLY: BTstatus[2]:'Off' dweetAge:'4'=1726236262-1726236258
Sep 13 16:04:22 srv4hai check_presence[9194]: SHELLY (2) found /srv/www/viehai/shelly//meter/ping_SP_2.DEAD and Off//srv/www/viehai/shelly//meter/BTscan_BTid_2.No
Sep 13 16:04:22 srv4hai check_presence[9194]: SHELLY (ping_SP__bt_scan)-(0) what: 'coming' - persPresent: '(BT/__) (BT/LIVE) (__/__)' - anyPresent: 'true' - otherPresent:'true'
Sep 13 16:04:22 srv4hai check_presence[9194]: SHELLY - check 'coming': anyPresent(true) - lScene(scene_sekr_UM) - sceneONsmall(vorraumOn)
Sep 13 16:04:22 srv4hai check_presence[9194]: SHELLY (ping_SP__bt_scan)-(0)-coming: activate_scenario.shtml: vorraumOn
Sep 13 16:04:22 srv4hai activate_scenario[9342]: SHELLY scenario: 'vorraumOn(vorraumon)' started
Sep 13 16:04:22 srv4hai activate_scenario[9342]: SHELLY dayState='day' <--> DayTime=checked NightTime=checked - phoneName:'0'
finished