activate_scenario.sh
Mon Jan 8 20:22:54 CET 2024
scenario: LOG_gehen
'night' <--> DayTime=checked , NightTime=checked
-> execution of scenario
-> sending email(s) to 'thomas@miglinci.name'
scenario: 'LOG_aus' - 'LOG_gehen' - 'log_gehen'
scenario: 'LOG_aus' - 'LOG_gehen' - 'log_gehen'
urls : 0
vorher : 20:22:54.413
nachher: 20:22:54.429
Dauer : 17 msec
outfile: '/srv/www/viehai/shelly/log_scenarios/2024/01/08//scene_LOG_gehen_20240108_202254.txt'
syslog
Jan 8 20:20:01 srv4hai CRON[25119]: (root) CMD (/etc/linetest/linetest.sh)
Jan 8 20:20:01 srv4hai CRON[25120]: (www-data) CMD (/srv/www/viehai/shelly/meter.sh)
Jan 8 20:20:01 srv4hai meter.sh: SHELLY meter.sh started
Jan 8 20:20:05 srv4hai bt_scan.sh: SHELLY - who: 'Thomas' / phoneName: 'FP4_Thomas' / '0'
Jan 8 20:20:09 srv4hai dhcpd[1107]: DHCPDISCOVER from f0:39:65:25:8d:c7 via enp2s0
Jan 8 20:20:09 srv4hai dhcpd[1107]: DHCPOFFER on 192.168.12.62 to f0:39:65:25:8d:c7 via enp2s0
Jan 8 20:20:09 srv4hai dhcpd[1107]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Jan 8 20:20:09 srv4hai dhcpd[1107]: execute_statement argv[1] = 192.168.12.62
Jan 8 20:20:09 srv4hai dhcpd[1107]: execute_statement argv[2] = GreteS20
Jan 8 20:20:09 srv4hai smartphone.sh: SHELLY - ping_SP.sh started for phoneIP: '192.168.12.62' / 'GreteS20'
Jan 8 20:20:09 srv4hai dhcpd[1107]: DHCPREQUEST for 192.168.12.62 (192.168.12.252) from f0:39:65:25:8d:c7 via enp2s0
Jan 8 20:20:09 srv4hai dhcpd[1107]: DHCPACK on 192.168.12.62 to f0:39:65:25:8d:c7 via enp2s0
Jan 8 20:20:09 srv4hai named[14530]: client @0x7fc32403b700 192.168.12.252#50367/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: GreteS20.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Jan 8 20:20:09 srv4hai named[14530]: client @0x7fc3281490e0 192.168.12.252#51877/key rndc-key: signer "rndc-key" approved
Jan 8 20:20:09 srv4hai named[14530]: client @0x7fc3281490e0 192.168.12.252#51877/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'GreteS20.viehai.at' TXT
Jan 8 20:20:09 srv4hai named[14530]: client @0x7fc3281490e0 192.168.12.252#51877/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'GreteS20.viehai.at' TXT "318a9f974c0d8a2868f64397b15c3ed3a0"
Jan 8 20:20:09 srv4hai named[14530]: client @0x7fc3281490e0 192.168.12.252#51877/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'GreteS20.viehai.at' A
Jan 8 20:20:09 srv4hai named[14530]: client @0x7fc3281490e0 192.168.12.252#51877/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'GreteS20.viehai.at' A 192.168.12.62
Jan 8 20:20:09 srv4hai ping_SP.sh: SHELLY ping_SP.sh started
Jan 8 20:20:09 srv4hai dhcpd[1107]: Added new forward map from GreteS20.viehai.at to 192.168.12.62
Jan 8 20:20:09 srv4hai named[14530]: client @0x7fc308070fe0 192.168.12.252#56227/key rndc-key: signer "rndc-key" approved
Jan 8 20:20:09 srv4hai named[14530]: client @0x7fc308070fe0 192.168.12.252#56227/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '62.12.168.192.in-addr.arpa' PTR
Jan 8 20:20:09 srv4hai named[14530]: client @0x7fc308070fe0 192.168.12.252#56227/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 8 20:20:09 srv4hai dhcpd[1107]: Added reverse map from 62.12.168.192.in-addr.arpa. to GreteS20.viehai.at
Jan 8 20:20:09 srv4hai ping_SP.sh: SHELLY - new process: '' - '/srv/www/viehai/shelly//meter/ping_SP_1' @~1704741609
Jan 8 20:20:09 srv4hai check_presence.sh: SHELLY (1) found './meter/ping_SP_0' more than one (1) line for what: 'coming'
Jan 8 20:20:09 srv4hai check_presence.sh: SHELLY (1) set what to 'presence'
Jan 8 20:20:09 srv4hai check_presence.sh: SHELLY (1) what: 'presence' - persPresent: 'false/ping, false/no ping, false/no IP,' - anyPresent: 'true'
Jan 8 20:20:09 srv4hai check_presence.sh: SHELLY (1)-presence: NOT activate_scenario.shtml: test_41_OFF
Jan 8 20:20:10 srv4hai ping_SP.sh: SHELLY (1) - pinging 192.168.12.62 0 sucessfully @~1704741610 - '/srv/www/viehai/shelly//meter/ping_SP_1'
Jan 8 20:20:16 srv4hai dhcpd[1107]: DHCPDISCOVER from 00:22:61:a1:fb:40 via enp2s0
Jan 8 20:20:16 srv4hai dhcpd[1107]: DHCPOFFER on 192.168.12.17 to 00:22:61:a1:fb:40 via enp2s0
Jan 8 20:20:17 srv4hai ping_SP.sh: SHELLY (0) - pinging 192.168.12.61 0 sucessfully @~1704741617 - '/srv/www/viehai/shelly//meter/ping_SP_0'
Jan 8 20:20:30 srv4hai named[14530]: resolver priming query complete
Jan 8 20:20:31 srv4hai named[14530]: resolver priming query complete
Jan 8 20:20:32 srv4hai named[14530]: resolver priming query complete
Jan 8 20:20:35 srv4hai named[14530]: resolver priming query complete
Jan 8 20:20:40 srv4hai ping_SP.sh: SHELLY (1) - pinging 192.168.12.62 0 sucessfully @~1704741640 - '/srv/www/viehai/shelly//meter/ping_SP_1'
Jan 8 20:20:41 srv4hai bt_scan.sh: SHELLY - who: 'Thomas' / phoneName: 'FP4_Thomas' / '0'
Jan 8 20:20:41 srv4hai bt_scan.sh: SHELLY - who: 'Thomas' / fixAddr: ' invalid length of '0'!
Jan 8 20:20:47 srv4hai named[14530]: resolver priming query complete
Jan 8 20:20:47 srv4hai ping_SP.sh: SHELLY (0) - pinging 192.168.12.61 0 sucessfully @~1704741647 - '/srv/www/viehai/shelly//meter/ping_SP_0'
Jan 8 20:20:51 srv4hai named[14530]: resolver priming query complete
Jan 8 20:20:56 srv4hai named[14530]: resolver priming query complete
Jan 8 20:21:01 srv4hai CRON[25554]: (root) CMD (/etc/linetest/linetest.sh)
Jan 8 20:21:01 srv4hai named[14530]: resolver priming query complete
Jan 8 20:21:02 srv4hai named[14530]: resolver priming query complete
Jan 8 20:21:05 srv4hai named[14530]: resolver priming query complete
Jan 8 20:21:08 srv4hai meter.sh: SHELLY meter.sh ended
Jan 8 20:21:10 srv4hai ping_SP.sh: SHELLY (1) - pinging 192.168.12.62 0 sucessfully @~1704741670 - '/srv/www/viehai/shelly//meter/ping_SP_1'
Jan 8 20:21:16 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=25586, secured, session=<4TsJGnQOtpkAAAAAAAAAAAAAAAAAAAAB>
Jan 8 20:21:16 srv4hai dovecot: imap(miglincit)<25586><4TsJGnQOtpkAAAAAAAAAAAAAAAAAAAAB>: Logged out in=137 out=1088 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Jan 8 20:21:17 srv4hai ping_SP.sh: SHELLY (0) - pinging 192.168.12.61 0 sucessfully @~1704741677 - '/srv/www/viehai/shelly//meter/ping_SP_0'
Jan 8 20:21:20 srv4hai dhcpd[1107]: DHCPDISCOVER from 00:22:61:a1:fb:40 via enp2s0
Jan 8 20:21:20 srv4hai dhcpd[1107]: DHCPOFFER on 192.168.12.17 to 00:22:61:a1:fb:40 via enp2s0
Jan 8 20:21:30 srv4hai activate_scenario.sh: SHELLY scenario: 'sekr_UM(sekr_um)' TO BE EXECUTED
Jan 8 20:21:30 srv4hai postfix/pickup[21019]: D0F8660244: uid=33 from=
Jan 8 20:21:30 srv4hai postfix/cleanup[25638]: D0F8660244: message-id=<20240108192130.D0F8660244@mail.viehai.at>
Jan 8 20:21:30 srv4hai postfix/qmgr[28610]: D0F8660244: from=, size=7456, nrcpt=1 (queue active)
Jan 8 20:21:30 srv4hai activate_scenario.sh: SHELLY: 'shelly-23'-'Licht Sekretär' --> '0?turn=toggle'
Jan 8 20:21:30 srv4hai proc_url.sh: SHELLY: OF:'/srv/www/viehai/shelly/log_scenarios/2024/01/08/_0.log' - shellyUsage:'Licht Sekretär' - scUrl:'http://192.168.12.123/relay/0?turn=toggle' - phName:'noHandy'
Jan 8 20:21:30 srv4hai postfix/local[25652]: D0F8660244: to=, orig_to=, relay=local, delay=0.04, delays=0.02/0/0/0.02, dsn=5.1.1, status=bounced (unknown user: "null")
Jan 8 20:21:30 srv4hai postfix/cleanup[25638]: D8A0D60245: message-id=<20240108192130.D8A0D60245@mail.viehai.at>
Jan 8 20:21:30 srv4hai postfix/bounce[25654]: D0F8660244: sender non-delivery notification: D8A0D60245
Jan 8 20:21:30 srv4hai postfix/qmgr[28610]: D8A0D60245: from=<>, size=9335, nrcpt=1 (queue active)
Jan 8 20:21:30 srv4hai postfix/qmgr[28610]: D0F8660244: removed
Jan 8 20:21:30 srv4hai postfix/local[25652]: D8A0D60245: to=, relay=local, delay=0.01, delays=0/0/0/0, dsn=2.0.0, status=sent (delivered to command: /bin/procmail)
Jan 8 20:21:30 srv4hai postfix/qmgr[28610]: D8A0D60245: removed
Jan 8 20:21:30 srv4hai activate_scenario.sh: SHELLY scenario: 'sekr_UM(sekr_um)' took 74 msec
Jan 8 20:21:30 srv4hai proc_url.sh: SHELLY: grab status via - 'http://192.168.12.123/status'
Jan 8 20:21:30 srv4hai proc_url.sh: SHELLY: status: '{"wifi_sta":{"connected":true,"ssid":"viehai","ip":"192.168.12.123","rssi":-70},"cloud":{"enabled":false,"connected":false},"mqtt":{"connected":false},"time":"20:21","unixtime":1704741691,"serial":1,"has_update":false,"mac":"3083988C059D","cfg_changed_cnt":0,"actions_stats":{"skipped":0},"relays":[{"ison":true,"has_timer":false,"timer_started":0,"timer_duration":0,"timer_remaining":0,"source":"http"}],"meters":[{"power":0.00,"is_valid":true}],"inputs":[{"input":0,"event":"","event_cnt":42}],"ext_sensors":{},"ext_temperature":{},"ext_humidity":{},"update":{"status":"idle","has_update":false,"new_version":"20230913-112003/v1.14.0-gcb84623","old_version":"20230913-112003/v1.14.0-gcb84623","beta_version":"20231107-162940/v1.14.1-rc1-g0617c15"},"ram_total":51688,"ram_free":40088,"fs_size":233681,"fs_free":150098,"uptime":1141645}'
Jan 8 20:21:33 srv4hai named[14530]: resolver priming query complete
Jan 8 20:21:34 srv4hai bt_scan.sh: SHELLY - who: 'Grete' / phoneName: 'S20_Grete' / '1'
Jan 8 20:21:34 srv4hai bt_scan.sh: SHELLY - ping_SP.sh started for BTmac: '1' - IPv4: '192.168.12.62'
Jan 8 20:21:34 srv4hai ping_SP.sh: SHELLY ping_SP.sh started
Jan 8 20:21:34 srv4hai check_presence.sh: SHELLY (1) found './meter/ping_SP_0' more than one (1) line for what: 'coming'
Jan 8 20:21:34 srv4hai check_presence.sh: SHELLY (1) set what to 'presence'
Jan 8 20:21:34 srv4hai check_presence.sh: SHELLY (1) found './meter/ping_SP_1' more than one (1) line for what: 'presence'
Jan 8 20:21:34 srv4hai check_presence.sh: SHELLY (1) what: 'presence' - persPresent: 'scanBT/ping, scanBT/ping, false/no IP,' - anyPresent: 'true'
Jan 8 20:21:34 srv4hai check_presence.sh: SHELLY (1)-presence: NOT activate_scenario.shtml: test_41_OFF
Jan 8 20:21:34 srv4hai ping_SP.sh: SHELLY - running process: '25337' - '/srv/www/viehai/shelly//meter/ping_SP_1' - exiting
Jan 8 20:21:40 srv4hai ping_SP.sh: SHELLY (1) - pinging 192.168.12.62 0 sucessfully @~1704741700 - '/srv/www/viehai/shelly//meter/ping_SP_1'
Jan 8 20:21:47 srv4hai ping_SP.sh: SHELLY (0) - pinging 192.168.12.61 0 sucessfully @~1704741707 - '/srv/www/viehai/shelly//meter/ping_SP_0'
Jan 8 20:22:01 srv4hai CRON[25751]: (root) CMD (/etc/linetest/linetest.sh)
Jan 8 20:22:10 srv4hai named[14530]: resolver priming query complete
Jan 8 20:22:10 srv4hai ping_SP.sh: SHELLY (1) - pinging 192.168.12.62 0 sucessfully @~1704741730 - '/srv/www/viehai/shelly//meter/ping_SP_1'
Jan 8 20:22:14 srv4hai named[14530]: resolver priming query complete
Jan 8 20:22:16 srv4hai named[14530]: resolver priming query complete
Jan 8 20:22:17 srv4hai named[14530]: resolver priming query complete
Jan 8 20:22:18 srv4hai ping_SP.sh: SHELLY (0) - pinging 192.168.12.61 0 sucessfully @~1704741738 - '/srv/www/viehai/shelly//meter/ping_SP_0'
Jan 8 20:22:18 srv4hai named[14530]: resolver priming query complete
Jan 8 20:22:24 srv4hai dhcpd[1107]: DHCPDISCOVER from 00:22:61:a1:fb:40 via enp2s0
Jan 8 20:22:24 srv4hai dhcpd[1107]: DHCPOFFER on 192.168.12.17 to 00:22:61:a1:fb:40 via enp2s0
Jan 8 20:22:25 srv4hai fetchmail[1651]: 7 messages (7 seen) for rozmig at mail.akis.at (1753652 octets).
Jan 8 20:22:26 srv4hai fetchmail[1651]: 35123 messages (35122 seen) for tmiglinci at mail.akis.at (-1347892672 octets).
Jan 8 20:22:26 srv4hai postfix/smtpd[25772]: connect from localhost[127.0.0.1]
Jan 8 20:22:26 srv4hai postfix/smtpd[25772]: 63A8E6011E: client=localhost[127.0.0.1]
Jan 8 20:22:26 srv4hai postfix/cleanup[25638]: 63A8E6011E: message-id=<20240108191751.5939560244@mail.viehai.at>
Jan 8 20:22:26 srv4hai fetchmail[1651]: reading message tmiglinci@mail.akis.at:35123 of 35123 (6792 octets) not flushed
Jan 8 20:22:26 srv4hai postfix/qmgr[28610]: 63A8E6011E: from=, size=7084, nrcpt=1 (queue active)
Jan 8 20:22:26 srv4hai postfix/local[25652]: 63A8E6011E: 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 8 20:22:26 srv4hai postfix/qmgr[28610]: 63A8E6011E: removed
Jan 8 20:22:26 srv4hai postfix/smtpd[25772]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1 data=1 quit=1 commands=5
Jan 8 20:22:26 srv4hai fetchmail[1651]: 9743 messages (9743 seen) for srozum at mail.akis.at (1178332714 octets).
Jan 8 20:22:27 srv4hai fetchmail[1651]: 20959 messages (20959 seen) for mrozum at mail.akis.at (-746227464 octets).
Jan 8 20:22:27 srv4hai fetchmail[1651]: 45 messages (45 seen) for admin@bastelstube.wien at pop3.world4you.com (12035480 octets).
Jan 8 20:22:40 srv4hai ping_SP.sh: SHELLY (1) - pinging 192.168.12.62 0 sucessfully @~1704741760 - '/srv/www/viehai/shelly//meter/ping_SP_1'
Jan 8 20:22:48 srv4hai ping_SP.sh: SHELLY (0) - pinging 192.168.12.61 0 sucessfully @~1704741768 - '/srv/www/viehai/shelly//meter/ping_SP_0'
Jan 8 20:22:50 srv4hai named[14530]: resolver priming query complete
finished