activate_scenario.sh
Tue Jan 9 11:27:16 CET 2024
scenario: test_41_ON
phoneName: 1
'day' <--> DayTime=checked , NightTime=checked
-> execution of scenario
-> sending email(s) to 'null'
scenario: 'test 41 EIN' - 'test_41_ON' - 'test_41_on'
41-TBD-Dimmer (/srv/www/viehai/shelly/log_scenarios/2024/01/09/_0.log)
vorher: 11:27:16.796
http://192.168.12.141/light/0?turn=on&brightness=25 |
|---|
"ison": true,
"source": "http",
"has_timer": false,
"timer_started": 0,
"timer_duration": 0,
"timer_remaining": 0,
"mode": "white",
"brightness": 25,
"transition": 0
|
nachher: 11:27:16.824
Dauer: 29 msec
phName: '1'
scenario: 'test 41 EIN' - 'test_41_ON' - 'test_41_on'
urls : 1
vorher : 11:27:16.778
nachher: 11:27:16.831
Dauer : 53 msec
outfile: '/srv/www/viehai/shelly/log_scenarios/2024/01/09//scene_test_41_ON_20240109_112716.txt'
syslog
Jan 9 11:25:01 srv4hai sntp[7997]: sntp 4.2.8p12@1.3728-o (1)
Jan 9 11:25:01 srv4hai sntp[7997]: 2024-01-09 11:25:01.771443 (-0100) -0.002206 +/- 0.001577 time.google.com 216.239.35.4 s1 no-leap
Jan 9 11:25:01 srv4hai CRON[8002]: (root) CMD (/etc/linetest/linetest.sh)
Jan 9 11:25:07 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=8011, secured, session=
Jan 9 11:25:07 srv4hai dovecot: imap(rozumm)<8011>: Logged out in=92 out=1060 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Jan 9 11:25:08 srv4hai ping_SP.sh: SHELLY (1)- pinging 192.168.12.62 1 not answered (1) @~1704795908 - '/srv/www/viehai/shelly//meter/ping_SP_1'
Jan 9 11:25:19 srv4hai dhcpd[1107]: DHCPDISCOVER from 00:22:61:a1:fb:40 via enp2s0
Jan 9 11:25:19 srv4hai dhcpd[1107]: DHCPOFFER on 192.168.12.17 to 00:22:61:a1:fb:40 via enp2s0
Jan 9 11:25:21 srv4hai ping_SP.sh: SHELLY (1)- pinging 192.168.12.62 2 not answered (1) @~1704795921 - '/srv/www/viehai/shelly//meter/ping_SP_1'
Jan 9 11:25:21 srv4hai ping_SP.sh: SHELLY (1)- pinging 192.168.12.62 3 not answered - aborting (10591) @~1704795921 - rm '/srv/www/viehai/shelly//meter/ping_SP_1'
Jan 9 11:25:22 srv4hai check_presence.sh: SHELLY (1) what: 'presence' - persPresent: 'false/no IP, false/no IP, false/no IP,' - anyPresent: 'false'
Jan 9 11:25:22 srv4hai check_presence.sh: SHELLY (1)-presence: activate_scenario.shtml: test_41_OFF
Jan 9 11:25:22 srv4hai activate_scenario.sh: SHELLY scenario: 'test_41_OFF(test_41_off)' TO BE EXECUTED
Jan 9 11:25:22 srv4hai postfix/pickup[27039]: 11D8860244: uid=33 from=
Jan 9 11:25:22 srv4hai postfix/cleanup[8079]: 11D8860244: message-id=<20240109102522.11D8860244@mail.viehai.at>
Jan 9 11:25:22 srv4hai postfix/qmgr[28610]: 11D8860244: from=, size=4717, nrcpt=1 (queue active)
Jan 9 11:25:22 srv4hai activate_scenario.sh: SHELLY: 'shelly-41'-'41-TBD-Dimmer' --> '0?turn=off&brightness=50'
Jan 9 11:25:22 srv4hai proc_url.sh: SHELLY: OF:'/srv/www/viehai/shelly/log_scenarios/2024/01/09/_0.log' - shellyUsage:'41-TBD-Dimmer' - scUrl:'http://192.168.12.141/light/0?turn=off&brightness=50' - phName:'NONE'
Jan 9 11:25:22 srv4hai postfix/local[8093]: 11D8860244: to=, orig_to=, relay=local, delay=0.05, delays=0.03/0/0/0.02, dsn=5.1.1, status=bounced (unknown user: "null")
Jan 9 11:25:22 srv4hai postfix/cleanup[8079]: 1A07A60245: message-id=<20240109102522.1A07A60245@mail.viehai.at>
Jan 9 11:25:22 srv4hai postfix/bounce[8098]: 11D8860244: sender non-delivery notification: 1A07A60245
Jan 9 11:25:22 srv4hai postfix/qmgr[28610]: 1A07A60245: from=<>, size=6596, nrcpt=1 (queue active)
Jan 9 11:25:22 srv4hai postfix/qmgr[28610]: 11D8860244: removed
Jan 9 11:25:22 srv4hai postfix/local[8093]: 1A07A60245: to=, relay=local, delay=0.01, delays=0/0/0/0.01, dsn=2.0.0, status=sent (delivered to command: /bin/procmail)
Jan 9 11:25:22 srv4hai postfix/qmgr[28610]: 1A07A60245: removed
Jan 9 11:25:22 srv4hai activate_scenario.sh: SHELLY scenario: 'test_41_OFF(test_41_off)' took 68 msec
Jan 9 11:25:22 srv4hai proc_url.sh: SHELLY: grab status via - 'http://192.168.12.141/status'
Jan 9 11:25:22 srv4hai proc_url.sh: SHELLY: status: '{"wifi_sta":{"connected":true,"ssid":"viehai","ip":"192.168.12.141","rssi":-43},"cloud":{"enabled":false,"connected":false},"mqtt":{"connected":true},"time":"11:25","unixtime":1704795922,"serial":1,"has_update":false,"mac":"349454723B21","cfg_changed_cnt":0,"actions_stats":{"skipped":0},"lights":[{"ison":false,"source":"http","has_timer":false,"timer_started":0,"timer_duration":0,"timer_remaining":0,"mode":"white","brightness":50,"transition":0}],"meters":[{"power":0.00,"overpower":0.00,"is_valid":true, "timestamp":1704799522,"counters":[0.000, 0.000, 0.000],"total":19741}],"inputs":[{"input":0,"event":"","event_cnt":0},{"input":0,"event":"","event_cnt":0}],"tmp":{"tC":40.54,"tF":104.97, "is_valid":true},"calibrated":true,"calib_progress":0,"calib_status":0,"calib_running":0,"wire_mode":1,"forced_neutral":false,"overtemperature":false,"loaderror":0,"overpower":false,"debug":0,"update":{"status":"idle","has_update":false,"new_version":"20230913-114008/v1.14.0-gcb84623","old_version":"20230913-
Jan 9 11:25:23 srv4hai named[14530]: resolver priming query complete
Jan 9 11:25:24 srv4hai named[14530]: resolver priming query complete
Jan 9 11:25:33 srv4hai fetchmail[1651]: 7 messages (7 seen) for rozmig at mail.akis.at (1753652 octets).
Jan 9 11:25:33 srv4hai fetchmail[1651]: 35183 messages (35182 seen) for tmiglinci at mail.akis.at (-1344014850 octets).
Jan 9 11:25:34 srv4hai postfix/smtpd[8131]: connect from localhost[127.0.0.1]
Jan 9 11:25:34 srv4hai postfix/smtpd[8131]: 072646011A: client=localhost[127.0.0.1]
Jan 9 11:25:34 srv4hai postfix/cleanup[8079]: 072646011A: message-id=<20240109102133.735D260244@mail.viehai.at>
Jan 9 11:25:34 srv4hai fetchmail[1651]: reading message tmiglinci@mail.akis.at:35183 of 35183 (1490 octets) not flushed
Jan 9 11:25:34 srv4hai postfix/qmgr[28610]: 072646011A: from=, size=1770, nrcpt=1 (queue active)
Jan 9 11:25:34 srv4hai postfix/local[8093]: 072646011A: to=, relay=local, delay=0.06, delays=0.05/0/0/0.01, dsn=2.0.0, status=sent (delivered to command: /bin/procmail)
Jan 9 11:25:34 srv4hai postfix/qmgr[28610]: 072646011A: removed
Jan 9 11:25:34 srv4hai postfix/smtpd[8131]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1 data=1 quit=1 commands=5
Jan 9 11:25:34 srv4hai fetchmail[1651]: 9745 messages (9745 seen) for srozum at mail.akis.at (1178490913 octets).
Jan 9 11:25:35 srv4hai fetchmail[1651]: 20963 messages (20963 seen) for mrozum at mail.akis.at (-745830423 octets).
Jan 9 11:25:35 srv4hai fetchmail[1651]: 45 messages (45 seen) for admin@bastelstube.wien at pop3.world4you.com (12035480 octets).
Jan 9 11:25:41 srv4hai named[14530]: resolver priming query complete
Jan 9 11:26:01 srv4hai CRON[8148]: (root) CMD (/etc/linetest/linetest.sh)
Jan 9 11:26:07 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=8158, secured, session=<6zcQvoAOtrYAAAAAAAAAAAAAAAAAAAAB>
Jan 9 11:26:07 srv4hai dovecot: imap(rozumm)<8158><6zcQvoAOtrYAAAAAAAAAAAAAAAAAAAAB>: Logged out in=92 out=1060 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Jan 9 11:26:07 srv4hai named[14530]: resolver priming query complete
Jan 9 11:26:10 srv4hai named[14530]: resolver priming query complete
Jan 9 11:26:23 srv4hai dhcpd[1107]: DHCPDISCOVER from 00:22:61:a1:fb:40 via enp2s0
Jan 9 11:26:23 srv4hai dhcpd[1107]: DHCPOFFER on 192.168.12.17 to 00:22:61:a1:fb:40 via enp2s0
Jan 9 11:26:33 srv4hai bt_scan.sh: SHELLY - who: 'Grete' / phoneName: 'S20_Grete' / '1'
Jan 9 11:26:33 srv4hai bt_scan.sh: SHELLY - ping_SP.sh started for BTmac: '1' - IPv4: '192.168.12.62'
Jan 9 11:26:33 srv4hai ping_SP.sh: SHELLY ping_SP.sh started
Jan 9 11:26:33 srv4hai ping_SP.sh: SHELLY - new process: '' - '/srv/www/viehai/shelly//meter/ping_SP_1' @~1704795993
Jan 9 11:26:33 srv4hai check_presence.sh: SHELLY (1) what: 'coming' - persPresent: 'false/no IP, scanBT/no ping, false/no IP,' - anyPresent: 'true'
Jan 9 11:26:33 srv4hai check_presence.sh: SHELLY (1)-coming: activate_scenario.shtml: test_41_ON
Jan 9 11:26:34 srv4hai activate_scenario.sh: SHELLY scenario: 'test_41_ON(test_41_on)' TO BE EXECUTED
Jan 9 11:26:34 srv4hai postfix/pickup[27039]: 05AF760244: uid=33 from=
Jan 9 11:26:34 srv4hai postfix/cleanup[8079]: 05AF760244: message-id=<20240109102634.05AF760244@mail.viehai.at>
Jan 9 11:26:34 srv4hai postfix/qmgr[28610]: 05AF760244: from=, size=9200, nrcpt=1 (queue active)
Jan 9 11:26:34 srv4hai postfix/local[8093]: 05AF760244: to=, orig_to=, relay=local, delay=0.02, delays=0.01/0/0/0.01, dsn=5.1.1, status=bounced (unknown user: "null")
Jan 9 11:26:34 srv4hai postfix/cleanup[8079]: 0982960245: message-id=<20240109102634.0982960245@mail.viehai.at>
Jan 9 11:26:34 srv4hai postfix/bounce[8098]: 05AF760244: sender non-delivery notification: 0982960245
Jan 9 11:26:34 srv4hai postfix/qmgr[28610]: 0982960245: from=<>, size=11079, nrcpt=1 (queue active)
Jan 9 11:26:34 srv4hai postfix/qmgr[28610]: 05AF760244: removed
Jan 9 11:26:34 srv4hai postfix/local[8093]: 0982960245: to=, relay=local, delay=0.01, delays=0/0/0/0.01, dsn=2.0.0, status=sent (delivered to command: /bin/procmail)
Jan 9 11:26:34 srv4hai postfix/qmgr[28610]: 0982960245: removed
Jan 9 11:26:34 srv4hai activate_scenario.sh: SHELLY: 'shelly-41'-'41-TBD-Dimmer' --> '0?turn=on&brightness=25'
Jan 9 11:26:34 srv4hai proc_url.sh: SHELLY: OF:'/srv/www/viehai/shelly/log_scenarios/2024/01/09/_0.log' - shellyUsage:'41-TBD-Dimmer' - scUrl:'http://192.168.12.141/light/0?turn=on&brightness=25' - phName:'1'
Jan 9 11:26:34 srv4hai activate_scenario.sh: SHELLY scenario: 'test_41_ON(test_41_on)' took 64 msec
Jan 9 11:26:34 srv4hai proc_url.sh: SHELLY: grab status via - 'http://192.168.12.141/status'
Jan 9 11:26:34 srv4hai proc_url.sh: SHELLY: status: '{"wifi_sta":{"connected":true,"ssid":"viehai","ip":"192.168.12.141","rssi":-44},"cloud":{"enabled":false,"connected":false},"mqtt":{"connected":true},"time":"11:26","unixtime":1704795994,"serial":1,"has_update":false,"mac":"349454723B21","cfg_changed_cnt":0,"actions_stats":{"skipped":0},"lights":[{"ison":true,"source":"http","has_timer":false,"timer_started":0,"timer_duration":0,"timer_remaining":0,"mode":"white","brightness":25,"transition":0}],"meters":[{"power":0.00,"overpower":0.00,"is_valid":true, "timestamp":1704799594,"counters":[0.000, 0.000, 0.000],"total":19741}],"inputs":[{"input":0,"event":"","event_cnt":0},{"input":0,"event":"","event_cnt":0}],"tmp":{"tC":40.74,"tF":105.34, "is_valid":true},"calibrated":true,"calib_progress":0,"calib_status":0,"calib_running":0,"wire_mode":1,"forced_neutral":false,"overtemperature":false,"loaderror":0,"overpower":false,"debug":0,"update":{"status":"idle","has_update":false,"new_version":"20230913-114008/v1.14.0-gcb84623","old_version":"20230913-1
Jan 9 11:26:36 srv4hai ping_SP.sh: SHELLY (1)- pinging 192.168.12.62 0 not answered (1) @~1704795996 - '/srv/www/viehai/shelly//meter/ping_SP_1'
Jan 9 11:26:45 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=8313, secured, session=
Jan 9 11:26:45 srv4hai named[14530]: resolver priming query complete
Jan 9 11:26:45 srv4hai dovecot: imap(miglincit)<8313>: Logged out in=749 out=43075 deleted=0 expunged=0 trashed=0 hdr_count=50 hdr_bytes=19862 body_count=0 body_bytes=0
Jan 9 11:26:50 srv4hai ping_SP.sh: SHELLY (1)- pinging 192.168.12.62 1 not answered (1) @~1704796010 - '/srv/www/viehai/shelly//meter/ping_SP_1'
Jan 9 11:27:01 srv4hai CRON[8327]: (root) CMD (/etc/linetest/linetest.sh)
Jan 9 11:27:03 srv4hai ping_SP.sh: SHELLY (1)- pinging 192.168.12.62 2 not answered (1) @~1704796023 - '/srv/www/viehai/shelly//meter/ping_SP_1'
Jan 9 11:27:03 srv4hai ping_SP.sh: SHELLY (1)- pinging 192.168.12.62 3 not answered - aborting (8199) @~1704796023 - rm '/srv/www/viehai/shelly//meter/ping_SP_1'
Jan 9 11:27:03 srv4hai check_presence.sh: SHELLY (1) what: 'presence' - persPresent: 'false/no IP, scanBT/no IP, false/no IP,' - anyPresent: 'true'
Jan 9 11:27:03 srv4hai check_presence.sh: SHELLY (1)-presence: NOT activate_scenario.shtml: test_41_OFF
Jan 9 11:27:07 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=8350, secured, session=
Jan 9 11:27:07 srv4hai dovecot: imap(rozumm)<8350>: Logged out in=92 out=1060 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Jan 9 11:27:15 srv4hai named[14530]: resolver priming query complete
Jan 9 11:27:16 srv4hai dhcpd[1107]: DHCPDISCOVER from f0:39:65:25:8d:c7 via enp2s0
Jan 9 11:27:16 srv4hai dhcpd[1107]: DHCPOFFER on 192.168.12.62 to f0:39:65:25:8d:c7 via enp2s0
Jan 9 11:27:16 srv4hai dhcpd[1107]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Jan 9 11:27:16 srv4hai dhcpd[1107]: execute_statement argv[1] = 192.168.12.62
Jan 9 11:27:16 srv4hai dhcpd[1107]: execute_statement argv[2] = GreteS20
Jan 9 11:27:16 srv4hai smartphone.sh: SHELLY - ping_SP.sh started for phoneIP: '192.168.12.62' / 'GreteS20'
Jan 9 11:27:16 srv4hai dhcpd[1107]: DHCPREQUEST for 192.168.12.62 (192.168.12.252) from f0:39:65:25:8d:c7 via enp2s0
Jan 9 11:27:16 srv4hai dhcpd[1107]: DHCPACK on 192.168.12.62 to f0:39:65:25:8d:c7 via enp2s0
Jan 9 11:27:16 srv4hai named[14530]: client @0x7fc328100b10 192.168.12.252#51393/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: GreteS20.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Jan 9 11:27:16 srv4hai ping_SP.sh: SHELLY ping_SP.sh started
Jan 9 11:27:16 srv4hai named[14530]: client @0x7fc32811da30 192.168.12.252#54337/key rndc-key: signer "rndc-key" approved
Jan 9 11:27:16 srv4hai named[14530]: client @0x7fc32811da30 192.168.12.252#54337/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'GreteS20.viehai.at' TXT
Jan 9 11:27:16 srv4hai named[14530]: client @0x7fc32811da30 192.168.12.252#54337/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'GreteS20.viehai.at' TXT "318a9f974c0d8a2868f64397b15c3ed3a0"
Jan 9 11:27:16 srv4hai named[14530]: client @0x7fc32811da30 192.168.12.252#54337/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'GreteS20.viehai.at' A
Jan 9 11:27:16 srv4hai named[14530]: client @0x7fc32811da30 192.168.12.252#54337/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'GreteS20.viehai.at' A 192.168.12.62
Jan 9 11:27:16 srv4hai dhcpd[1107]: Added new forward map from GreteS20.viehai.at to 192.168.12.62
Jan 9 11:27:16 srv4hai named[14530]: client @0x7fc328157870 192.168.12.252#36939/key rndc-key: signer "rndc-key" approved
Jan 9 11:27:16 srv4hai named[14530]: client @0x7fc328157870 192.168.12.252#36939/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '62.12.168.192.in-addr.arpa' PTR
Jan 9 11:27:16 srv4hai named[14530]: client @0x7fc328157870 192.168.12.252#36939/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.
Jan 9 11:27:16 srv4hai dhcpd[1107]: Added reverse map from 62.12.168.192.in-addr.arpa. to GreteS20.viehai.at
Jan 9 11:27:16 srv4hai ping_SP.sh: SHELLY - new process: '' - '/srv/www/viehai/shelly//meter/ping_SP_1' @~1704796036
Jan 9 11:27:16 srv4hai check_presence.sh: SHELLY (1) what: 'coming' - persPresent: 'false/no IP, scanBT/no ping, false/no IP,' - anyPresent: 'true'
Jan 9 11:27:16 srv4hai check_presence.sh: SHELLY (1)-coming: activate_scenario.shtml: test_41_ON
finished