activate_scenario.sh
Thu Jan 4 18:15:58 CET 2024
scenario: test_41_ON
phoneName: 0
'night' <--> 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/04/scene_test_41_ON_20240104_181558_0.log)
vorher: 18:15:58.919
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: 18:15:58.951
Dauer: 32 msec
phName: '0'
scenario: 'test 41 EIN' - 'test_41_ON' - 'test_41_on'
urls : 1
vorher : 18:15:58.894
nachher: 18:15:58.958
Dauer : 64 msec
outfile: '/srv/www/viehai/shelly/log_scenarios/2024/01/04/scene_test_41_ON_20240104_181558.txt'
syslog
Jan 4 18:12:01 srv4hai CRON[17019]: (root) CMD (/etc/linetest/linetest.sh)
Jan 4 18:12:21 srv4hai dhcpd[1107]: DHCPDISCOVER from 00:22:61:a1:fb:40 via enp2s0
Jan 4 18:12:21 srv4hai dhcpd[1107]: DHCPOFFER on 192.168.12.17 to 00:22:61:a1:fb:40 via enp2s0
Jan 4 18:12:35 srv4hai named[14530]: resolver priming query complete
Jan 4 18:12:50 srv4hai fetchmail[1651]: 7 messages (7 seen) for rozmig at mail.akis.at (1753652 octets).
Jan 4 18:12:51 srv4hai fetchmail[1651]: 34907 messages (34907 seen) for tmiglinci at mail.akis.at (-1370515044 octets).
Jan 4 18:12:52 srv4hai fetchmail[1651]: 9726 messages (9726 seen) for srozum at mail.akis.at (1177204483 octets).
Jan 4 18:12:52 srv4hai fetchmail[1651]: 20900 messages (20900 seen) for mrozum at mail.akis.at (-785636482 octets).
Jan 4 18:12:53 srv4hai fetchmail[1651]: 45 messages (45 seen) for admin@bastelstube.wien at pop3.world4you.com (12035480 octets).
Jan 4 18:12:55 srv4hai named[14530]: resolver priming query complete
Jan 4 18:13:01 srv4hai CRON[17028]: (root) CMD (/etc/linetest/linetest.sh)
Jan 4 18:13:14 srv4hai nmbd[30864]: [2024/01/04 18:13:14.321653, 0] ../source3/nmbd/nmbd_browsesync.c:354(find_domain_master_name_query_fail)
Jan 4 18:13:14 srv4hai nmbd[30864]: find_domain_master_name_query_fail:
Jan 4 18:13:14 srv4hai nmbd[30864]: Unable to find the Domain Master Browser name WORKGROUP<1b> for the workgroup WORKGROUP.
Jan 4 18:13:14 srv4hai nmbd[30864]: Unable to sync browse lists in this workgroup.
Jan 4 18:13:24 srv4hai named[14530]: resolver priming query complete
Jan 4 18:13:25 srv4hai dhcpd[1107]: DHCPDISCOVER from 00:22:61:a1:fb:40 via enp2s0
Jan 4 18:13:25 srv4hai dhcpd[1107]: DHCPOFFER on 192.168.12.17 to 00:22:61:a1:fb:40 via enp2s0
Jan 4 18:13:55 srv4hai named[14530]: resolver priming query complete
Jan 4 18:13:57 srv4hai named[14530]: resolver priming query complete
Jan 4 18:14:01 srv4hai CRON[17041]: (root) CMD (/etc/linetest/linetest.sh)
Jan 4 18:14:29 srv4hai dhcpd[1107]: DHCPDISCOVER from 00:22:61:a1:fb:40 via enp2s0
Jan 4 18:14:29 srv4hai dhcpd[1107]: DHCPOFFER on 192.168.12.17 to 00:22:61:a1:fb:40 via enp2s0
Jan 4 18:14:55 srv4hai named[14530]: resolver priming query complete
Jan 4 18:15:01 srv4hai CRON[17052]: (root) CMD (/etc/linetest/linetest.sh)
Jan 4 18:15:03 srv4hai named[14530]: resolver priming query complete
Jan 4 18:15:12 srv4hai bt_scan.sh: SHELLY - phoneName: 'FP4_Thomas' / '0'
Jan 4 18:15:12 srv4hai bt_scan.sh: SHELLY - ping_SP.sh started for BTmac: '0'
Jan 4 18:15:12 srv4hai ping_SP.sh: SHELLY ping_SP.sh started
Jan 4 18:15:12 srv4hai ping_SP.sh: SHELLY - new process: '' - '/srv/www/viehai/shelly//meter/ping_SP_0' @~1704388512
Jan 4 18:15:12 srv4hai bt_scan.sh: SHELLY - phoneName: 'S20_Grete' / '1'
Jan 4 18:15:12 srv4hai check_presence.sh: SHELLY (0) what: 'coming' - persPresent: 'scanBT/no ping, false/no IP, false/no IP,' - anyPresent: 'true'
Jan 4 18:15:12 srv4hai check_presence.sh: SHELLY (0)-coming: activate_scenario.shtml: test_41_ON
Jan 4 18:15:12 srv4hai bt_scan.sh: SHELLY - ping_SP.sh started for BTmac: '1'
Jan 4 18:15:12 srv4hai ping_SP.sh: SHELLY ping_SP.sh started
Jan 4 18:15:12 srv4hai ping_SP.sh: SHELLY - new process: '' - '/srv/www/viehai/shelly//meter/ping_SP_1' @~1704388512
Jan 4 18:15:12 srv4hai check_presence.sh: SHELLY (1) what: 'coming' - persPresent: 'scanBT/no ping, scanBT/no ping, false/no IP,' - anyPresent: 'true'
Jan 4 18:15:12 srv4hai check_presence.sh: SHELLY (1)-coming: activate_scenario.shtml: test_41_ON
Jan 4 18:15:12 srv4hai activate_scenario.sh: SHELLY scenario: 'test_41_ON(test_41_on)' TO BE EXECUTED
Jan 4 18:15:12 srv4hai activate_scenario.sh: SHELLY scenario: 'test_41_ON(test_41_on)' TO BE EXECUTED
Jan 4 18:15:12 srv4hai postfix/pickup[11848]: 3E6BF60245: uid=33 from=
Jan 4 18:15:12 srv4hai postfix/cleanup[17231]: 3E6BF60245: message-id=<20240104171512.3E6BF60245@mail.viehai.at>
Jan 4 18:15:12 srv4hai postfix/qmgr[28610]: 3E6BF60245: from=, size=4245, nrcpt=1 (queue active)
Jan 4 18:15:12 srv4hai postfix/pickup[11848]: 41B1E60119: uid=33 from=
Jan 4 18:15:12 srv4hai postfix/cleanup[17231]: 41B1E60119: message-id=<20240104171512.41B1E60119@mail.viehai.at>
Jan 4 18:15:12 srv4hai postfix/qmgr[28610]: 41B1E60119: from=, size=4349, nrcpt=1 (queue active)
Jan 4 18:15:12 srv4hai activate_scenario.sh: SHELLY: 'shelly-41'-'41-TBD-Dimmer' --> '0?turn=on&brightness=25'
Jan 4 18:15:12 srv4hai /srv/www/viehai/shelly/proc_url.sh: SHELLY: OF:'/srv/www/viehai/shelly/log_scenarios/2024/01/04/scene_test_41_ON_20240104_181512_0.log' - shellyUsage:'41-TBD-Dimmer' - scUrl:'http://192.168.12.141/light/0?turn=on&brightness=25' - phName:'0'
Jan 4 18:15:12 srv4hai activate_scenario.sh: SHELLY: 'shelly-41'-'41-TBD-Dimmer' --> '0?turn=on&brightness=25'
Jan 4 18:15:12 srv4hai /srv/www/viehai/shelly/proc_url.sh: SHELLY: OF:'/srv/www/viehai/shelly/log_scenarios/2024/01/04/scene_test_41_ON_20240104_181512_0.log' - shellyUsage:'41-TBD-Dimmer' - scUrl:'http://192.168.12.141/light/0?turn=on&brightness=25' - phName:'1'
Jan 4 18:15:12 srv4hai postfix/local[17257]: 3E6BF60245: to=, orig_to=, relay=local, delay=0.05, delays=0.03/0/0/0.01, dsn=5.1.1, status=bounced (unknown user: "null")
Jan 4 18:15:12 srv4hai postfix/local[17258]: 41B1E60119: to=, orig_to=, relay=local, delay=0.04, delays=0.03/0.01/0/0.01, dsn=5.1.1, status=bounced (unknown user: "null")
Jan 4 18:15:12 srv4hai postfix/cleanup[17231]: 46F0560249: message-id=<20240104171512.46F0560249@mail.viehai.at>
Jan 4 18:15:12 srv4hai postfix/bounce[17260]: 3E6BF60245: sender non-delivery notification: 46F0560249
Jan 4 18:15:12 srv4hai postfix/qmgr[28610]: 46F0560249: from=<>, size=6124, nrcpt=1 (queue active)
Jan 4 18:15:12 srv4hai postfix/qmgr[28610]: 3E6BF60245: removed
Jan 4 18:15:12 srv4hai postfix/cleanup[17231]: 47B9E6024A: message-id=<20240104171512.47B9E6024A@mail.viehai.at>
Jan 4 18:15:12 srv4hai postfix/bounce[17269]: 41B1E60119: sender non-delivery notification: 47B9E6024A
Jan 4 18:15:12 srv4hai postfix/qmgr[28610]: 47B9E6024A: from=<>, size=6228, nrcpt=1 (queue active)
Jan 4 18:15:12 srv4hai postfix/qmgr[28610]: 41B1E60119: removed
Jan 4 18:15:12 srv4hai postfix/local[17257]: 46F0560249: to=, relay=local, delay=0.01, delays=0/0/0/0.01, dsn=2.0.0, status=sent (delivered to command: /bin/procmail)
Jan 4 18:15:12 srv4hai postfix/qmgr[28610]: 46F0560249: removed
Jan 4 18:15:12 srv4hai postfix/local[17258]: 47B9E6024A: to=, relay=local, delay=0.01, delays=0.01/0/0/0.01, dsn=2.0.0, status=sent (delivered to command: /bin/procmail)
Jan 4 18:15:12 srv4hai postfix/qmgr[28610]: 47B9E6024A: removed
Jan 4 18:15:12 srv4hai activate_scenario.sh: SHELLY scenario: 'test_41_ON(test_41_on)' took 59 msec
Jan 4 18:15:12 srv4hai activate_scenario.sh: SHELLY scenario: 'test_41_ON(test_41_on)' took 65 msec
Jan 4 18:15:12 srv4hai /srv/www/viehai/shelly/proc_url.sh: SHELLY: grab status via - 'http://192.168.12.141/status'
Jan 4 18:15:12 srv4hai /srv/www/viehai/shelly/proc_url.sh: SHELLY: status: '{"wifi_sta":{"connected":true,"ssid":"viehai","ip":"192.168.12.141","rssi":-45},"cloud":{"enabled":false,"connected":false},"mqtt":{"connected":true},"time":"18:15","unixtime":1704388513,"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":11.17,"overpower":0.00,"is_valid":true, "timestamp":1704392113,"counters":[11.051, 11.064, 11.031],"total":7274}],"inputs":[{"input":0,"event":"","event_cnt":0},{"input":0,"event":"","event_cnt":0}],"tmp":{"tC":50.76,"tF":123.37, "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":"2023091
Jan 4 18:15:12 srv4hai /srv/www/viehai/shelly/proc_url.sh: SHELLY: grab status via - 'http://192.168.12.141/status'
Jan 4 18:15:12 srv4hai /srv/www/viehai/shelly/proc_url.sh: SHELLY: status: '{"wifi_sta":{"connected":true,"ssid":"viehai","ip":"192.168.12.141","rssi":-45},"cloud":{"enabled":false,"connected":false},"mqtt":{"connected":true},"time":"18:15","unixtime":1704388513,"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":11.17,"overpower":0.00,"is_valid":true, "timestamp":1704392113,"counters":[11.051, 11.064, 11.031],"total":7274}],"inputs":[{"input":0,"event":"","event_cnt":0},{"input":0,"event":"","event_cnt":0}],"tmp":{"tC":50.76,"tF":123.37, "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":"2023091
Jan 4 18:15:14 srv4hai activate_scenario.sh: SHELLY scenario: 'LOG_kommen(log_kommen)' TO BE EXECUTED
Jan 4 18:15:14 srv4hai postfix/pickup[11848]: B67CA60244: uid=33 from=
Jan 4 18:15:14 srv4hai postfix/cleanup[17277]: B67CA60244: message-id=<20240104171514.B67CA60244@mail.viehai.at>
Jan 4 18:15:14 srv4hai postfix/qmgr[28610]: B67CA60244: from=, size=10157, nrcpt=1 (queue active)
Jan 4 18:15:14 srv4hai activate_scenario.sh: SHELLY scenario: 'LOG_kommen(log_kommen)' took 20 msec
Jan 4 18:15:14 srv4hai named[14530]: resolver priming query complete
Jan 4 18:15:15 srv4hai ping_SP.sh: SHELLY (1)- pinging 192.168.12.62 0 not answered (1) @~1704388515 - '/srv/www/viehai/shelly//meter/ping_SP_1'
Jan 4 18:15:15 srv4hai ping_SP.sh: SHELLY (0)- pinging 192.168.12.61 0 not answered (1) @~1704388515 - '/srv/www/viehai/shelly//meter/ping_SP_0'
Jan 4 18:15:15 srv4hai postfix/relay/smtp[17376]: B67CA60244: to=, relay=mx04.nic.name[209.131.160.34]:25, delay=0.9, delays=0.01/0.02/0.63/0.25, dsn=2.0.0, status=sent (250 2.0.0 Ok: queued as 6FE0B80213)
Jan 4 18:15:15 srv4hai postfix/qmgr[28610]: B67CA60244: removed
Jan 4 18:15:28 srv4hai ping_SP.sh: SHELLY (0)- pinging 192.168.12.61 1 not answered (1) @~1704388528 - '/srv/www/viehai/shelly//meter/ping_SP_0'
Jan 4 18:15:28 srv4hai ping_SP.sh: SHELLY (1)- pinging 192.168.12.62 1 not answered (1) @~1704388528 - '/srv/www/viehai/shelly//meter/ping_SP_1'
Jan 4 18:15:33 srv4hai dhcpd[1107]: DHCPDISCOVER from 00:22:61:a1:fb:40 via enp2s0
Jan 4 18:15:33 srv4hai dhcpd[1107]: DHCPOFFER on 192.168.12.17 to 00:22:61:a1:fb:40 via enp2s0
Jan 4 18:15:41 srv4hai ping_SP.sh: SHELLY (1)- pinging 192.168.12.62 2 not answered (1) @~1704388541 - '/srv/www/viehai/shelly//meter/ping_SP_1'
Jan 4 18:15:41 srv4hai ping_SP.sh: SHELLY (0)- pinging 192.168.12.61 2 not answered (1) @~1704388541 - '/srv/www/viehai/shelly//meter/ping_SP_0'
Jan 4 18:15:41 srv4hai ping_SP.sh: SHELLY (1)- pinging 192.168.12.62 3 not answered - aborting (17114) @~1704388541 - rm '/srv/www/viehai/shelly//meter/ping_SP_1'
Jan 4 18:15:41 srv4hai ping_SP.sh: SHELLY (0)- pinging 192.168.12.61 3 not answered - aborting (17084) @~1704388541 - rm '/srv/www/viehai/shelly//meter/ping_SP_0'
Jan 4 18:15:41 srv4hai check_presence.sh: SHELLY (1) what: 'presence' - persPresent: 'scanBT/no IP, scanBT/no IP, false/no IP,' - anyPresent: 'true'
Jan 4 18:15:41 srv4hai check_presence.sh: SHELLY (0) what: 'presence' - persPresent: 'scanBT/no IP, scanBT/no IP, false/no IP,' - anyPresent: 'true'
Jan 4 18:15:41 srv4hai check_presence.sh: SHELLY (0)-presence: NOT activate_scenario.shtml: test_41_OFF
Jan 4 18:15:41 srv4hai check_presence.sh: SHELLY (1)-presence: NOT activate_scenario.shtml: test_41_OFF
Jan 4 18:15:43 srv4hai dhcpd[1107]: Wrote 0 deleted host decls to leases file.
Jan 4 18:15:43 srv4hai dhcpd[1107]: Wrote 0 new dynamic host decls to leases file.
Jan 4 18:15:43 srv4hai dhcpd[1107]: Wrote 2 leases to leases file.
Jan 4 18:15:55 srv4hai named[14530]: resolver priming query complete
Jan 4 18:15:58 srv4hai dhcpd[1107]: DHCPDISCOVER from e8:78:29:c0:c2:4e via enp2s0
Jan 4 18:15:58 srv4hai dhcpd[1107]: DHCPOFFER on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0
Jan 4 18:15:58 srv4hai dhcpd[1107]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Jan 4 18:15:58 srv4hai dhcpd[1107]: execute_statement argv[1] = 192.168.12.61
Jan 4 18:15:58 srv4hai dhcpd[1107]: execute_statement argv[2] = fpTom
Jan 4 18:15:58 srv4hai smartphone.sh: SHELLY - ping_SP.sh started for phoneIP: '192.168.12.61' / 'fpTom'
Jan 4 18:15:58 srv4hai dhcpd[1107]: DHCPREQUEST for 192.168.12.61 (192.168.12.252) from e8:78:29:c0:c2:4e via enp2s0
Jan 4 18:15:58 srv4hai dhcpd[1107]: DHCPACK on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0
Jan 4 18:15:58 srv4hai named[14530]: client @0x7fc3200ad570 192.168.12.252#55989/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: fpTom.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Jan 4 18:15:58 srv4hai ping_SP.sh: SHELLY ping_SP.sh started
Jan 4 18:15:58 srv4hai named[14530]: client @0x7fc31405af30 192.168.12.252#58491/key rndc-key: signer "rndc-key" approved
Jan 4 18:15:58 srv4hai named[14530]: client @0x7fc31405af30 192.168.12.252#58491/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'fpTom.viehai.at' TXT
Jan 4 18:15:58 srv4hai named[14530]: client @0x7fc31405af30 192.168.12.252#58491/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'fpTom.viehai.at' TXT "310ebd0eafccd9c2e311facf8a4b0e4ad2"
Jan 4 18:15:58 srv4hai named[14530]: client @0x7fc31405af30 192.168.12.252#58491/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'fpTom.viehai.at' A
Jan 4 18:15:58 srv4hai named[14530]: client @0x7fc31405af30 192.168.12.252#58491/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'fpTom.viehai.at' A 192.168.12.61
Jan 4 18:15:58 srv4hai dhcpd[1107]: Added new forward map from fpTom.viehai.at to 192.168.12.61
Jan 4 18:15:58 srv4hai named[14530]: client @0x7fc31c026bf0 192.168.12.252#38651/key rndc-key: signer "rndc-key" approved
Jan 4 18:15:58 srv4hai named[14530]: client @0x7fc31c026bf0 192.168.12.252#38651/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '61.12.168.192.in-addr.arpa' PTR
Jan 4 18:15:58 srv4hai named[14530]: client @0x7fc31c026bf0 192.168.12.252#38651/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': adding an RR at '61.12.168.192.in-addr.arpa' PTR fpTom.viehai.at.
Jan 4 18:15:58 srv4hai dhcpd[1107]: Added reverse map from 61.12.168.192.in-addr.arpa. to fpTom.viehai.at
Jan 4 18:15:58 srv4hai ping_SP.sh: SHELLY - new process: '' - '/srv/www/viehai/shelly//meter/ping_SP_0' @~1704388558
Jan 4 18:15:58 srv4hai check_presence.sh: SHELLY (0) what: 'coming' - persPresent: 'scanBT/no ping, scanBT/no IP, false/no IP,' - anyPresent: 'true'
Jan 4 18:15:58 srv4hai check_presence.sh: SHELLY (0)-coming: activate_scenario.shtml: test_41_ON
finished