activate_scenario.sh
Mon Jan 15 19:42:59 CET 2024
scenario: pcON
'night' <--> DayTime=checked , NightTime=checked
-> execution of scenario
-> sending email(s) to 'null'
scenario: 'Schreibtisch Thomas EIN-schalten' - 'pcON' - 'pcon'
Thomas Schreibtischlampe (/srv/www/viehai/shelly/log_scenarios/2024/01/15/_0.log)
vorher: 19:42:59.515
http://192.168.12.120/relay/0?turn=on |
|---|
"ison": true,
"has_timer": false,
"timer_started": 0,
"timer_duration": 0,
"timer_remaining": 0,
"source": "http"
|
nachher: 19:42:59.554
Dauer: 38 msec
scenario: 'Schreibtisch Thomas EIN-schalten' - 'pcON' - 'pcon'
urls : 1
vorher : 19:42:59.497
nachher: 19:42:59.560
Dauer : 63 msec
outfile: '/srv/www/viehai/shelly/log_scenarios/2024/01/15//scene_pcON_20240115_194259.txt'
syslog
Jan 15 19:39:01 srv4hai CRON[1624]: (root) CMD (/etc/linetest/linetest.sh)
Jan 15 19:39:01 srv4hai CRON[1625]: (root) CMD ( [ -x /usr/lib/php/sessionclean ] && if [ ! -d /run/systemd/system ]; then /usr/lib/php/sessionclean; fi)
Jan 15 19:39:01 srv4hai systemd[1]: Starting Clean php session files...
Jan 15 19:39:01 srv4hai systemd[1]: phpsessionclean.service: Succeeded.
Jan 15 19:39:01 srv4hai systemd[1]: Started Clean php session files.
Jan 15 19:39:06 srv4hai named[14530]: resolver priming query complete
Jan 15 19:39:09 srv4hai named[14530]: resolver priming query complete
Jan 15 19:39:12 srv4hai dhcpd[1107]: DHCPDISCOVER from e8:78:29:c0:c2:4e via enp2s0
Jan 15 19:39:12 srv4hai dhcpd[1107]: DHCPOFFER on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0
Jan 15 19:39:12 srv4hai dhcpd[1107]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Jan 15 19:39:12 srv4hai dhcpd[1107]: execute_statement argv[1] = 192.168.12.61
Jan 15 19:39:12 srv4hai dhcpd[1107]: execute_statement argv[2] = fpTom
Jan 15 19:39:12 srv4hai smartphone.sh: SHELLY - ping_SP.sh started for phoneIP: '192.168.12.61' / 'fpTom'
Jan 15 19:39:12 srv4hai dhcpd[1107]: DHCPREQUEST for 192.168.12.61 (192.168.12.252) from e8:78:29:c0:c2:4e via enp2s0
Jan 15 19:39:12 srv4hai dhcpd[1107]: DHCPACK on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0
Jan 15 19:39:12 srv4hai named[14530]: client @0x7fc310083bf0 192.168.12.252#38921/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: fpTom.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Jan 15 19:39:12 srv4hai ping_SP.sh: SHELLY ping_SP.sh started
Jan 15 19:39:12 srv4hai named[14530]: client @0x7fc310074f60 192.168.12.252#41077/key rndc-key: signer "rndc-key" approved
Jan 15 19:39:12 srv4hai named[14530]: client @0x7fc310074f60 192.168.12.252#41077/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'fpTom.viehai.at' TXT
Jan 15 19:39:12 srv4hai named[14530]: client @0x7fc310074f60 192.168.12.252#41077/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'fpTom.viehai.at' TXT "310ebd0eafccd9c2e311facf8a4b0e4ad2"
Jan 15 19:39:12 srv4hai named[14530]: client @0x7fc310074f60 192.168.12.252#41077/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'fpTom.viehai.at' A
Jan 15 19:39:12 srv4hai named[14530]: client @0x7fc310074f60 192.168.12.252#41077/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'fpTom.viehai.at' A 192.168.12.61
Jan 15 19:39:12 srv4hai dhcpd[1107]: Added new forward map from fpTom.viehai.at to 192.168.12.61
Jan 15 19:39:12 srv4hai named[14530]: client @0x7fc3200bd520 192.168.12.252#59595/key rndc-key: signer "rndc-key" approved
Jan 15 19:39:12 srv4hai named[14530]: client @0x7fc3200bd520 192.168.12.252#59595/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '61.12.168.192.in-addr.arpa' PTR
Jan 15 19:39:12 srv4hai named[14530]: client @0x7fc3200bd520 192.168.12.252#59595/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 15 19:39:12 srv4hai dhcpd[1107]: Added reverse map from 61.12.168.192.in-addr.arpa. to fpTom.viehai.at
Jan 15 19:39:12 srv4hai check_presence.sh: SHELLY (0) what: 'coming' - persPresent: 'false/no IP, false/no IP, false/no IP,' - anyPresent: 'false'
Jan 15 19:39:12 srv4hai ping_SP.sh: SHELLY - new process: '' - '/srv/www/viehai/shelly//meter/ping_SP_0' @~1705343952
Jan 15 19:39:12 srv4hai check_presence.sh: SHELLY (0)-coming: NOT activate_scenario.shtml: welcomeLight
Jan 15 19:39:12 srv4hai postfix/pickup[25054]: DE6F560244: uid=0 from=
Jan 15 19:39:12 srv4hai postfix/cleanup[1749]: DE6F560244: message-id=<20240115183912.DE6F560244@mail.viehai.at>
Jan 15 19:39:12 srv4hai postfix/qmgr[28610]: DE6F560244: from=, size=497, nrcpt=1 (queue active)
Jan 15 19:39:12 srv4hai named[14530]: resolver priming query complete
Jan 15 19:39:13 srv4hai named[14530]: resolver priming query complete
Jan 15 19:39:13 srv4hai postfix/relay/smtp[1752]: DE6F560244: to=, relay=mx02.nic.name[209.131.158.56]:25, delay=1, delays=0.02/0.02/0.77/0.24, dsn=2.0.0, status=sent (250 2.0.0 Ok: queued as BE59C200EC)
Jan 15 19:39:13 srv4hai postfix/qmgr[28610]: DE6F560244: removed
Jan 15 19:39:14 srv4hai named[14530]: resolver priming query complete
Jan 15 19:39:15 srv4hai named[14530]: resolver priming query complete
Jan 15 19:39:21 srv4hai bt_scan.sh: SHELLY - who: 'Thomas' / phoneName: 'FP4_Thomas' / '0'
Jan 15 19:39:21 srv4hai bt_scan.sh: SHELLY - who: 'Thomas' / fixAddr: ' invalid length of '0'!
Jan 15 19:39:22 srv4hai ping_SP.sh: SHELLY (0)- ping 192.168.12.61 0 not answered (1) @~1705343962 - 'ping_SP_0'
Jan 15 19:39:28 srv4hai named[14530]: resolver priming query complete
Jan 15 19:39:32 srv4hai ping_SP.sh: SHELLY (0) - ping 192.168.12.61 1 sucessfully @~1705343972 - 'ping_SP_0'
Jan 15 19:39:38 srv4hai dhcpd[1107]: DHCPDISCOVER from 00:22:61:a1:fb:40 via enp2s0
Jan 15 19:39:38 srv4hai dhcpd[1107]: DHCPOFFER on 192.168.12.17 to 00:22:61:a1:fb:40 via enp2s0
Jan 15 19:39:46 srv4hai fetchmail[1651]: 7 messages (7 seen) for rozmig at mail.akis.at (1753652 octets).
Jan 15 19:39:47 srv4hai fetchmail[1651]: 35646 messages (35645 seen) for tmiglinci at mail.akis.at (-1316410209 octets).
Jan 15 19:39:48 srv4hai postfix/smtpd[1586]: connect from localhost[127.0.0.1]
Jan 15 19:39:48 srv4hai postfix/smtpd[1586]: 06CB96011E: client=localhost[127.0.0.1]
Jan 15 19:39:48 srv4hai postfix/cleanup[1749]: 06CB96011E: message-id=<20240115183912.DE6F560244@mail.viehai.at>
Jan 15 19:39:48 srv4hai fetchmail[1651]: reading message tmiglinci@mail.akis.at:35646 of 35646 (1475 octets) not flushed
Jan 15 19:39:48 srv4hai postfix/qmgr[28610]: 06CB96011E: from=, size=1759, nrcpt=1 (queue active)
Jan 15 19:39:48 srv4hai postfix/smtpd[1586]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1 data=1 quit=1 commands=5
Jan 15 19:39:48 srv4hai postfix/local[1810]: 06CB96011E: to=, relay=local, delay=0.07, delays=0.05/0.02/0/0.01, dsn=2.0.0, status=sent (delivered to command: /bin/procmail)
Jan 15 19:39:48 srv4hai postfix/qmgr[28610]: 06CB96011E: removed
Jan 15 19:39:48 srv4hai fetchmail[1651]: 9767 messages (9767 seen) for srozum at mail.akis.at (1180475634 octets).
Jan 15 19:39:49 srv4hai fetchmail[1651]: 21096 messages (21096 seen) for mrozum at mail.akis.at (-727769572 octets).
Jan 15 19:39:49 srv4hai fetchmail[1651]: 45 messages (45 seen) for admin@bastelstube.wien at pop3.world4you.com (12035480 octets).
Jan 15 19:39:56 srv4hai named[14530]: resolver priming query complete
Jan 15 19:40:01 srv4hai CRON[1821]: (root) CMD (/etc/linetest/linetest.sh)
Jan 15 19:40:01 srv4hai CRON[1822]: (www-data) CMD (/srv/www/viehai/shelly/meter.sh)
Jan 15 19:40:01 srv4hai meter.sh: SHELLY meter.sh started
Jan 15 19:40:03 srv4hai ping_SP.sh: SHELLY (0) - ping 192.168.12.61 0 sucessfully @~1705344003 - 'ping_SP_0'
Jan 15 19:40:13 srv4hai named[14530]: resolver priming query complete
Jan 15 19:40:33 srv4hai ping_SP.sh: SHELLY (0) - ping 192.168.12.61 0 sucessfully @~1705344033 - 'ping_SP_0'
Jan 15 19:40:36 srv4hai activate_scenario.sh: SHELLY scenario: 'buero_UM(buero_um)' TO BE EXECUTED
Jan 15 19:40:36 srv4hai postfix/pickup[25054]: B89FE60244: uid=33 from=
Jan 15 19:40:36 srv4hai postfix/cleanup[1749]: B89FE60244: message-id=<20240115184036.B89FE60244@mail.viehai.at>
Jan 15 19:40:36 srv4hai postfix/qmgr[28610]: B89FE60244: from=, size=17688, nrcpt=1 (queue active)
Jan 15 19:40:36 srv4hai postfix/local[1810]: B89FE60244: to=, orig_to=, relay=local, delay=0.03, delays=0.02/0/0/0.01, dsn=5.1.1, status=bounced (unknown user: "null")
Jan 15 19:40:36 srv4hai postfix/cleanup[1749]: BDB9E60245: message-id=<20240115184036.BDB9E60245@mail.viehai.at>
Jan 15 19:40:36 srv4hai postfix/bounce[2184]: B89FE60244: sender non-delivery notification: BDB9E60245
Jan 15 19:40:36 srv4hai postfix/qmgr[28610]: BDB9E60245: from=<>, size=19567, nrcpt=1 (queue active)
Jan 15 19:40:36 srv4hai postfix/qmgr[28610]: B89FE60244: removed
Jan 15 19:40:36 srv4hai activate_scenario.sh: SHELLY: 'shelly-08'-'Thomas Büro Regallicht' --> '0?turn=toggle'
Jan 15 19:40:36 srv4hai proc_url.sh: SHELLY: OF:'/srv/www/viehai/shelly/log_scenarios/2024/01/15/_0.log' - shellyUsage:'Thomas Büro Regallicht' - scUrl:'http://192.168.12.108/relay/0?turn=toggle' - phName:'noHandy'
Jan 15 19:40:36 srv4hai postfix/local[1810]: BDB9E60245: to=, relay=local, delay=0.02, delays=0/0/0/0.01, dsn=2.0.0, status=sent (delivered to command: /bin/procmail)
Jan 15 19:40:36 srv4hai postfix/qmgr[28610]: BDB9E60245: removed
Jan 15 19:40:36 srv4hai activate_scenario.sh: SHELLY: 'shelly-40'-'Thomas Büro Licht' --> '0?turn=toggle'
Jan 15 19:40:36 srv4hai proc_url.sh: SHELLY: OF:'/srv/www/viehai/shelly/log_scenarios/2024/01/15/_1.log' - shellyUsage:'Thomas Büro Licht' - scUrl:'http://192.168.12.140/relay/0?turn=toggle' - phName:'noHandy'
Jan 15 19:40:36 srv4hai activate_scenario.sh: SHELLY scenario: 'buero_UM(buero_um)' took 89 msec
Jan 15 19:40:36 srv4hai proc_url.sh: SHELLY: grab status via - 'http://192.168.12.108/status'
Jan 15 19:40:36 srv4hai proc_url.sh: SHELLY: status: '{"wifi_sta":{"connected":true,"ssid":"viehai","ip":"192.168.12.108","rssi":-66},"cloud":{"enabled":false,"connected":false},"mqtt":{"connected":false},"time":"19:40","unixtime":1705344037,"serial":1,"has_update":false,"mac":"8CAAB56203DF","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":1,"event":"","event_cnt":0}],"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":40364,"fs_size":233681,"fs_free":150600,"uptime":6412228}'
Jan 15 19:40:36 srv4hai proc_url.sh: SHELLY: grab status via - 'http://192.168.12.140/status'
Jan 15 19:40:36 srv4hai proc_url.sh: SHELLY: status: '{"wifi_sta":{"connected":true,"ssid":"viehai","ip":"192.168.12.140","rssi":-67},"cloud":{"enabled":false,"connected":false},"mqtt":{"connected":false},"time":"19:40","unixtime":1705344037,"serial":748,"has_update":false,"mac":"98CDAC307B4F","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":false,"timestamp":1705347637,"counters":[0.000, 0.000, 0.000],"total":0}],"inputs":[{"input":0,"event":"S","event_cnt":302},{"input":0,"event":"","event_cnt":0}],"temperature":45.30,"overtemperature":false,"tmp":{"tC":45.30,"tF":113.54,"is_valid":true},"update":{"status":"idle","has_update":false,"new_version":"20230913-112054/v1.14.0-gcb84623","old_version":"20230913-112054/v1.14.0-gcb84623","beta_version":"20231107-163031/v1.14.1-rc1-g0617c15"},"ram_total":51912,"ram_free":33852,"fs_size":233681,"fs_free":162648,"uptime":6412174}'
Jan 15 19:40:42 srv4hai dhcpd[1107]: DHCPDISCOVER from 00:22:61:a1:fb:40 via enp2s0
Jan 15 19:40:42 srv4hai dhcpd[1107]: DHCPOFFER on 192.168.12.17 to 00:22:61:a1:fb:40 via enp2s0
Jan 15 19:40:56 srv4hai named[14530]: resolver priming query complete
Jan 15 19:41:01 srv4hai CRON[2330]: (root) CMD (/etc/linetest/linetest.sh)
Jan 15 19:41:03 srv4hai ping_SP.sh: SHELLY (0) - ping 192.168.12.61 0 sucessfully @~1705344063 - 'ping_SP_0'
Jan 15 19:41:07 srv4hai meter.sh: SHELLY meter.sh ended
Jan 15 19:41:15 srv4hai named[14530]: resolver priming query complete
Jan 15 19:41:25 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=2379, secured, session=
Jan 15 19:41:25 srv4hai dovecot: imap(miglincit)<2379>: Logged out in=714 out=43717 deleted=0 expunged=0 trashed=0 hdr_count=50 hdr_bytes=20500 body_count=0 body_bytes=0
Jan 15 19:41:33 srv4hai ping_SP.sh: SHELLY (0) - ping 192.168.12.61 0 sucessfully @~1705344093 - 'ping_SP_0'
Jan 15 19:41:46 srv4hai dhcpd[1107]: DHCPDISCOVER from 00:22:61:a1:fb:40 via enp2s0
Jan 15 19:41:46 srv4hai dhcpd[1107]: DHCPOFFER on 192.168.12.17 to 00:22:61:a1:fb:40 via enp2s0
Jan 15 19:41:56 srv4hai named[14530]: resolver priming query complete
Jan 15 19:42:01 srv4hai CRON[2431]: (root) CMD (/etc/linetest/linetest.sh)
Jan 15 19:42:03 srv4hai ping_SP.sh: SHELLY (0) - ping 192.168.12.61 0 sucessfully @~1705344123 - 'ping_SP_0'
Jan 15 19:42:21 srv4hai bt_scan.sh: SHELLY - who: 'Thomas' / phoneName: 'FP4_Thomas' / '0'
Jan 15 19:42:21 srv4hai named[14530]: resolver priming query complete
Jan 15 19:42:33 srv4hai ping_SP.sh: SHELLY (0) - ping 192.168.12.61 0 sucessfully @~1705344153 - 'ping_SP_0'
Jan 15 19:42:45 srv4hai named[14530]: resolver priming query complete
Jan 15 19:42:49 srv4hai dhcpd[1107]: DHCPDISCOVER from 00:22:61:a1:fb:40 via enp2s0
Jan 15 19:42:49 srv4hai dhcpd[1107]: DHCPOFFER on 192.168.12.17 to 00:22:61:a1:fb:40 via enp2s0
Jan 15 19:42:54 srv4hai named[14530]: resolver priming query complete
Jan 15 19:42:56 srv4hai named[14530]: resolver priming query complete
Jan 15 19:42:59 srv4hai dhcpd[1107]: execute_statement argv[0] = /srv/www/viehai/shelly/dhcpcommit.sh
Jan 15 19:42:59 srv4hai dhcpd[1107]: execute_statement argv[1] = 192.168.12.10
Jan 15 19:42:59 srv4hai dhcpd[1107]: execute_statement argv[2] = pchai7x
Jan 15 19:42:59 srv4hai dhcpd[1107]: DHCPREQUEST for 192.168.12.10 from 70:54:d2:7b:fe:a7 via enp2s0
Jan 15 19:42:59 srv4hai dhcpd[1107]: DHCPACK on 192.168.12.10 to 70:54:d2:7b:fe:a7 via enp2s0
Jan 15 19:42:59 srv4hai named[14530]: client @0x7fc31803cbb0 192.168.12.252#57795/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: pchai7x.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Jan 15 19:42:59 srv4hai named[14530]: client @0x7fc3286ca970 192.168.12.252#56191/key rndc-key: signer "rndc-key" approved
Jan 15 19:42:59 srv4hai named[14530]: client @0x7fc3286ca970 192.168.12.252#56191/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'pchai7x.viehai.at' TXT
Jan 15 19:42:59 srv4hai named[14530]: client @0x7fc3286ca970 192.168.12.252#56191/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'pchai7x.viehai.at' TXT "001e3ad86ca86e10b8e2aaf2e2ade54f52"
Jan 15 19:42:59 srv4hai named[14530]: client @0x7fc3286ca970 192.168.12.252#56191/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'pchai7x.viehai.at' A
Jan 15 19:42:59 srv4hai named[14530]: client @0x7fc3286ca970 192.168.12.252#56191/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'pchai7x.viehai.at' A 192.168.12.10
Jan 15 19:42:59 srv4hai dhcpcommit.sh: SHELLY ip:'192.168.12.10'(10) device 'pchai7x'
Jan 15 19:42:59 srv4hai dhcpd[1107]: Added new forward map from pchai7x.viehai.at to 192.168.12.10
Jan 15 19:42:59 srv4hai named[14530]: client @0x7fc314083510 192.168.12.252#35701/key rndc-key: signer "rndc-key" approved
Jan 15 19:42:59 srv4hai named[14530]: client @0x7fc314083510 192.168.12.252#35701/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '10.12.168.192.in-addr.arpa' PTR
Jan 15 19:42:59 srv4hai named[14530]: client @0x7fc314083510 192.168.12.252#35701/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': adding an RR at '10.12.168.192.in-addr.arpa' PTR pchai7x.viehai.at.
Jan 15 19:42:59 srv4hai dhcpd[1107]: Added reverse map from 10.12.168.192.in-addr.arpa. to pchai7x.viehai.at
finished