activate_scenario.sh
Tue Jan 16 19:35:36 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/16/_0.log)
vorher: 19:35:36.982
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:35:37.009
Dauer: 28 msec
scenario: 'Schreibtisch Thomas EIN-schalten' - 'pcON' - 'pcon'
urls : 1
vorher : 19:35:36.964
nachher: 19:35:37.016
Dauer : 52 msec
outfile: '/srv/www/viehai/shelly/log_scenarios/2024/01/16//scene_pcON_20240116_193536.txt'
syslog
Jan 16 19:32:01 srv4hai CRON[3467]: (root) CMD (/etc/linetest/linetest.sh)
Jan 16 19:32:04 srv4hai named[14530]: resolver priming query complete
Jan 16 19:32:12 srv4hai ping_SP.sh: SHELLY (0) - ping 192.168.12.61 0 sucessfully @~1705429932 - 'ping_SP_0'
Jan 16 19:32:13 srv4hai named[14530]: resolver priming query complete
Jan 16 19:32:36 srv4hai dhcpd[8095]: DHCPDISCOVER from 00:22:61:a1:fb:40 via enp2s0
Jan 16 19:32:36 srv4hai dhcpd[8095]: DHCPOFFER on 192.168.12.17 to 00:22:61:a1:fb:40 via enp2s0
Jan 16 19:32:42 srv4hai ping_SP.sh: SHELLY (0) - ping 192.168.12.61 0 sucessfully @~1705429962 - 'ping_SP_0'
Jan 16 19:33:01 srv4hai CRON[3506]: (root) CMD (/etc/linetest/linetest.sh)
Jan 16 19:33:04 srv4hai named[14530]: resolver priming query complete
Jan 16 19:33:12 srv4hai ping_SP.sh: SHELLY (0) - ping 192.168.12.61 0 sucessfully @~1705429992 - 'ping_SP_0'
Jan 16 19:33:12 srv4hai named[14530]: resolver priming query complete
Jan 16 19:33:23 srv4hai named[14530]: resolver priming query complete
Jan 16 19:33:25 srv4hai activate_scenario.sh: SHELLY scenario: 'buero_UM(buero_um)' TO BE EXECUTED
Jan 16 19:33:25 srv4hai postfix/pickup[30217]: D404D60244: uid=33 from=
Jan 16 19:33:25 srv4hai postfix/cleanup[3573]: D404D60244: message-id=<20240116183325.D404D60244@mail.viehai.at>
Jan 16 19:33:25 srv4hai postfix/qmgr[28610]: D404D60244: from=, size=41592, nrcpt=1 (queue active)
Jan 16 19:33:25 srv4hai activate_scenario.sh: SHELLY: 'shelly-08'-'Thomas Büro Regallicht' --> '0?turn=toggle'
Jan 16 19:33:25 srv4hai proc_url.sh: SHELLY: OF:'/srv/www/viehai/shelly/log_scenarios/2024/01/16/_0.log' - shellyUsage:'Thomas Büro Regallicht' - scUrl:'http://192.168.12.108/relay/0?turn=toggle' - phName:'noHandy'
Jan 16 19:33:25 srv4hai postfix/local[3587]: D404D60244: to=, orig_to=, relay=local, delay=0.05, delays=0.03/0.01/0/0.01, dsn=5.1.1, status=bounced (unknown user: "null")
Jan 16 19:33:25 srv4hai postfix/cleanup[3573]: DBF5660245: message-id=<20240116183325.DBF5660245@mail.viehai.at>
Jan 16 19:33:25 srv4hai activate_scenario.sh: SHELLY: 'shelly-40'-'Thomas Büro Licht' --> '0?turn=toggle'
Jan 16 19:33:25 srv4hai proc_url.sh: SHELLY: OF:'/srv/www/viehai/shelly/log_scenarios/2024/01/16/_1.log' - shellyUsage:'Thomas Büro Licht' - scUrl:'http://192.168.12.140/relay/0?turn=toggle' - phName:'noHandy'
Jan 16 19:33:25 srv4hai postfix/bounce[3598]: D404D60244: sender non-delivery notification: DBF5660245
Jan 16 19:33:25 srv4hai postfix/qmgr[28610]: DBF5660245: from=<>, size=43471, nrcpt=1 (queue active)
Jan 16 19:33:25 srv4hai postfix/qmgr[28610]: D404D60244: removed
Jan 16 19:33:25 srv4hai postfix/local[3587]: DBF5660245: to=, relay=local, delay=0.02, delays=0.01/0/0/0.01, dsn=2.0.0, status=sent (delivered to command: /bin/procmail)
Jan 16 19:33:25 srv4hai postfix/qmgr[28610]: DBF5660245: removed
Jan 16 19:33:25 srv4hai activate_scenario.sh: SHELLY scenario: 'buero_UM(buero_um)' took 81 msec
Jan 16 19:33:25 srv4hai proc_url.sh: SHELLY: grab status via - 'http://192.168.12.108/status'
Jan 16 19:33:25 srv4hai proc_url.sh: SHELLY: status: '{"wifi_sta":{"connected":true,"ssid":"viehai","ip":"192.168.12.108","rssi":-64},"cloud":{"enabled":false,"connected":false},"mqtt":{"connected":false},"time":"19:33","unixtime":1705430006,"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":6498195}'
Jan 16 19:33:26 srv4hai proc_url.sh: SHELLY: grab status via - 'http://192.168.12.140/status'
Jan 16 19:33:26 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:33","unixtime":1705430006,"serial":757,"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":1705433606,"counters":[0.000, 0.000, 0.000],"total":0}],"inputs":[{"input":0,"event":"S","event_cnt":308},{"input":0,"event":"","event_cnt":0}],"temperature":44.99,"overtemperature":false,"tmp":{"tC":44.99,"tF":112.98,"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":36776,"fs_size":233681,"fs_free":162648,"uptime":6498140}'
Jan 16 19:33:32 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=3651, secured, session=
Jan 16 19:33:32 srv4hai dovecot: imap(miglincit)<3651>: 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 16 19:33:34 srv4hai dhcpd[8095]: DHCPDISCOVER from 34:94:54:71:bc:cf via enp2s0
Jan 16 19:33:34 srv4hai dhcpd[8095]: DHCPOFFER on 192.168.12.142 to 34:94:54:71:bc:cf via enp2s0
Jan 16 19:33:34 srv4hai dhcpd[8095]: execute_statement argv[0] = /srv/www/viehai/shelly/json.sh
Jan 16 19:33:34 srv4hai dhcpd[8095]: execute_statement argv[1] = 192.168.12.142
Jan 16 19:33:34 srv4hai dhcpd[8095]: execute_statement argv[2] = shelly-42
Jan 16 19:33:34 srv4hai dhcpd[8095]: DHCPREQUEST for 192.168.12.142 (192.168.12.252) from 34:94:54:71:bc:cf via enp2s0
Jan 16 19:33:34 srv4hai dhcpd[8095]: DHCPACK on 192.168.12.142 to 34:94:54:71:bc:cf via enp2s0
Jan 16 19:33:34 srv4hai named[14530]: client @0x7fc31c026bf0 192.168.12.252#38415/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: shelly-42.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Jan 16 19:33:34 srv4hai named[14530]: client @0x7fc3240894a0 192.168.12.252#59005/key rndc-key: signer "rndc-key" approved
Jan 16 19:33:34 srv4hai named[14530]: client @0x7fc3240894a0 192.168.12.252#59005/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'shelly-42.viehai.at' TXT
Jan 16 19:33:34 srv4hai named[14530]: client @0x7fc3240894a0 192.168.12.252#59005/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'shelly-42.viehai.at' TXT "009725012a308142e411b9d6a121917f19"
Jan 16 19:33:34 srv4hai named[14530]: client @0x7fc3240894a0 192.168.12.252#59005/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'shelly-42.viehai.at' A
Jan 16 19:33:34 srv4hai named[14530]: client @0x7fc3240894a0 192.168.12.252#59005/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'shelly-42.viehai.at' A 192.168.12.142
Jan 16 19:33:34 srv4hai dhcpd[8095]: Added new forward map from shelly-42.viehai.at to 192.168.12.142
Jan 16 19:33:34 srv4hai named[14530]: client @0x7fc3180965b0 192.168.12.252#43309/key rndc-key: signer "rndc-key" approved
Jan 16 19:33:34 srv4hai named[14530]: client @0x7fc3180965b0 192.168.12.252#43309/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '142.12.168.192.in-addr.arpa' PTR
Jan 16 19:33:34 srv4hai named[14530]: client @0x7fc3180965b0 192.168.12.252#43309/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': adding an RR at '142.12.168.192.in-addr.arpa' PTR shelly-42.viehai.at.
Jan 16 19:33:34 srv4hai dhcpd[8095]: Added reverse map from 142.12.168.192.in-addr.arpa. to shelly-42.viehai.at
Jan 16 19:33:34 srv4hai grab_shelly.sh: SHELLY shellyName/1:'shelly-42' - shIP/2:'' workdir:'/srv/www/viehai/shelly' perform:'conf'
Jan 16 19:33:35 srv4hai conf_shelly.sh: SHELLY: PARAMS shName/1:'shelly-42' - shIP/2:'192.168.12.142'
Jan 16 19:33:35 srv4hai conf_shelly.sh: SHELLY: usage/name 'Stehlampe dimmbar' already set for '192.168.12.142'
Jan 16 19:33:35 srv4hai conf_shelly.sh: SHELLY:'shelly-42' 'conf(0)' - allready transmitted - skipping
Jan 16 19:33:35 srv4hai conf_shelly.sh: SHELLY:'shelly-42' 'conf(1)' - allready transmitted - skipping
Jan 16 19:33:35 srv4hai conf_shelly.sh: SHELLY:'shelly-42' 'conf(2)' - allready transmitted - skipping
Jan 16 19:33:35 srv4hai conf_shelly.sh: SHELLY:'shelly-42' out:0 ON : MISSING
Jan 16 19:33:35 srv4hai conf_shelly.sh: SHELLY:'shelly-42' out:0 OFF: MISSING
Jan 16 19:33:40 srv4hai dhcpd[8095]: DHCPDISCOVER from 00:22:61:a1:fb:40 via enp2s0
Jan 16 19:33:40 srv4hai dhcpd[8095]: DHCPOFFER on 192.168.12.17 to 00:22:61:a1:fb:40 via enp2s0
Jan 16 19:33:42 srv4hai ping_SP.sh: SHELLY (0) - ping 192.168.12.61 0 sucessfully @~1705430022 - 'ping_SP_0'
Jan 16 19:33:42 srv4hai dhcpd[8095]: DHCPDISCOVER from 00:22:61:a1:fb:40 via enp2s0
Jan 16 19:33:42 srv4hai dhcpd[8095]: DHCPOFFER on 192.168.12.17 to 00:22:61:a1:fb:40 via enp2s0
Jan 16 19:33:46 srv4hai dhcpd[8095]: DHCPDISCOVER from 00:22:61:a1:fb:40 via enp2s0
Jan 16 19:33:46 srv4hai dhcpd[8095]: DHCPOFFER on 192.168.12.17 to 00:22:61:a1:fb:40 via enp2s0
Jan 16 19:33:54 srv4hai dhcpd[8095]: DHCPDISCOVER from 00:22:61:a1:fb:40 via enp2s0
Jan 16 19:33:54 srv4hai dhcpd[8095]: DHCPOFFER on 192.168.12.17 to 00:22:61:a1:fb:40 via enp2s0
Jan 16 19:34:01 srv4hai CRON[3764]: (root) CMD (/etc/linetest/linetest.sh)
Jan 16 19:34:04 srv4hai named[14530]: resolver priming query complete
Jan 16 19:34:10 srv4hai dhcpd[8095]: DHCPDISCOVER from 00:22:61:a1:fb:40 via enp2s0
Jan 16 19:34:10 srv4hai dhcpd[8095]: DHCPOFFER on 192.168.12.17 to 00:22:61:a1:fb:40 via enp2s0
Jan 16 19:34:12 srv4hai ping_SP.sh: SHELLY (0) - ping 192.168.12.61 0 sucessfully @~1705430052 - 'ping_SP_0'
Jan 16 19:34:29 srv4hai named[14530]: resolver priming query complete
Jan 16 19:34:42 srv4hai dhcpd[8095]: DHCPDISCOVER from 00:22:61:a1:fb:40 via enp2s0
Jan 16 19:34:42 srv4hai dhcpd[8095]: DHCPOFFER on 192.168.12.17 to 00:22:61:a1:fb:40 via enp2s0
Jan 16 19:34:42 srv4hai ping_SP.sh: SHELLY (0) - ping 192.168.12.61 0 sucessfully @~1705430082 - 'ping_SP_0'
Jan 16 19:35:01 srv4hai CRON[3807]: (root) CMD (/etc/linetest/linetest.sh)
Jan 16 19:35:03 srv4hai fetchmail[1651]: 7 messages (7 seen) for rozmig at mail.akis.at (1753652 octets).
Jan 16 19:35:04 srv4hai fetchmail[1651]: 35730 messages (35727 seen) for tmiglinci at mail.akis.at (-1311123453 octets).
Jan 16 19:35:04 srv4hai postfix/smtpd[3814]: connect from localhost[127.0.0.1]
Jan 16 19:35:04 srv4hai postfix/smtpd[3814]: 12B4860119: client=localhost[127.0.0.1]
Jan 16 19:35:04 srv4hai postfix/cleanup[3573]: 12B4860119: message-id=<20240116183010.013DF60245@mail.viehai.at>
Jan 16 19:35:04 srv4hai fetchmail[1651]: reading message tmiglinci@mail.akis.at:35728 of 35730 (9039 octets) not flushed
Jan 16 19:35:04 srv4hai postfix/qmgr[28610]: 12B4860119: from=, size=9331, nrcpt=1 (queue active)
Jan 16 19:35:04 srv4hai postfix/local[3587]: 12B4860119: 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 16 19:35:04 srv4hai postfix/qmgr[28610]: 12B4860119: removed
Jan 16 19:35:04 srv4hai postfix/smtpd[3814]: 24C5E60119: client=localhost[127.0.0.1]
Jan 16 19:35:04 srv4hai postfix/cleanup[3573]: 24C5E60119: message-id=<20240116183009.D0A9160244@mail.viehai.at>
Jan 16 19:35:04 srv4hai fetchmail[1651]: reading message tmiglinci@mail.akis.at:35729 of 35730 (5798 octets) not flushed
Jan 16 19:35:04 srv4hai postfix/qmgr[28610]: 24C5E60119: from=, size=6084, nrcpt=1 (queue active)
Jan 16 19:35:04 srv4hai postfix/local[3587]: 24C5E60119: 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 16 19:35:04 srv4hai postfix/qmgr[28610]: 24C5E60119: removed
Jan 16 19:35:04 srv4hai postfix/smtpd[3814]: 360ED60119: client=localhost[127.0.0.1]
Jan 16 19:35:04 srv4hai postfix/cleanup[3573]: 360ED60119: message-id=<20240116183051.BF8B960244@mail.viehai.at>
Jan 16 19:35:04 srv4hai fetchmail[1651]: reading message tmiglinci@mail.akis.at:35730 of 35730 (18725 octets) not flushed
Jan 16 19:35:04 srv4hai postfix/qmgr[28610]: 360ED60119: from=, size=19031, nrcpt=1 (queue active)
Jan 16 19:35:04 srv4hai postfix/local[3587]: 360ED60119: to=, relay=local, delay=0.07, delays=0.05/0/0/0.01, dsn=2.0.0, status=sent (delivered to command: /bin/procmail)
Jan 16 19:35:04 srv4hai postfix/qmgr[28610]: 360ED60119: removed
Jan 16 19:35:04 srv4hai postfix/smtpd[3814]: disconnect from localhost[127.0.0.1] ehlo=1 mail=3 rcpt=3 data=3 quit=1 commands=11
Jan 16 19:35:04 srv4hai named[14530]: resolver priming query complete
Jan 16 19:35:04 srv4hai fetchmail[1651]: 9770 messages (9770 seen) for srozum at mail.akis.at (1180605929 octets).
Jan 16 19:35:05 srv4hai fetchmail[1651]: 21105 messages (21105 seen) for mrozum at mail.akis.at (-726907240 octets).
Jan 16 19:35:05 srv4hai fetchmail[1651]: 45 messages (45 seen) for admin@bastelstube.wien at pop3.world4you.com (12035480 octets).
Jan 16 19:35:12 srv4hai ping_SP.sh: SHELLY (0) - ping 192.168.12.61 0 sucessfully @~1705430112 - 'ping_SP_0'
Jan 16 19:35:13 srv4hai bt_scan.sh: SHELLY - who: 'Thomas' / phoneName: 'FP4_Thomas' / '0' found: 'No'
Jan 16 19:35:13 srv4hai bt_scan.sh: SHELLY - lost BT-connection to BTmac: '0' found: 'No' - check presence
Jan 16 19:35:13 srv4hai check_presence.sh: SHELLY () found './meter/ping_SP_0' more than one (1) line for what: 'presence'
Jan 16 19:35:13 srv4hai check_presence.sh: SHELLY () what: 'presence' - persPresent: 'false/ping, false/no IP, false/no IP,' - anyPresent: 'true'
Jan 16 19:35:13 srv4hai check_presence.sh: SHELLY ()-presence: NOT activate_scenario.shtml: offAll
Jan 16 19:35:15 srv4hai named[14530]: resolver priming query complete
Jan 16 19:35:18 srv4hai named[14530]: resolver priming query complete
Jan 16 19:35:19 srv4hai named[14530]: resolver priming query complete
Jan 16 19:35:20 srv4hai named[14530]: resolver priming query complete
Jan 16 19:35:22 srv4hai named[14530]: resolver priming query complete
Jan 16 19:35:23 srv4hai named[14530]: resolver priming query complete
Jan 16 19:35:35 srv4hai named[14530]: resolver priming query complete
Jan 16 19:35:36 srv4hai dhcpd[8095]: execute_statement argv[0] = /srv/www/viehai/shelly/dhcpcommit.sh
Jan 16 19:35:36 srv4hai dhcpd[8095]: execute_statement argv[1] = 192.168.12.10
Jan 16 19:35:36 srv4hai dhcpd[8095]: execute_statement argv[2] = pchai7x
Jan 16 19:35:36 srv4hai dhcpd[8095]: DHCPREQUEST for 192.168.12.10 from 70:54:d2:7b:fe:a7 via enp2s0
Jan 16 19:35:36 srv4hai dhcpd[8095]: DHCPACK on 192.168.12.10 to 70:54:d2:7b:fe:a7 via enp2s0
Jan 16 19:35:36 srv4hai named[14530]: client @0x7fc3200cc820 192.168.12.252#44959/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: pchai7x.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Jan 16 19:35:36 srv4hai named[14530]: client @0x7fc3180a5240 192.168.12.252#40709/key rndc-key: signer "rndc-key" approved
Jan 16 19:35:36 srv4hai named[14530]: client @0x7fc3180a5240 192.168.12.252#40709/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'pchai7x.viehai.at' TXT
Jan 16 19:35:36 srv4hai named[14530]: client @0x7fc3180a5240 192.168.12.252#40709/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'pchai7x.viehai.at' TXT "001e3ad86ca86e10b8e2aaf2e2ade54f52"
Jan 16 19:35:36 srv4hai named[14530]: client @0x7fc3180a5240 192.168.12.252#40709/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'pchai7x.viehai.at' A
Jan 16 19:35:36 srv4hai named[14530]: client @0x7fc3180a5240 192.168.12.252#40709/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'pchai7x.viehai.at' A 192.168.12.10
Jan 16 19:35:36 srv4hai dhcpd[8095]: Added new forward map from pchai7x.viehai.at to 192.168.12.10
Jan 16 19:35:36 srv4hai dhcpcommit.sh: SHELLY ip:'192.168.12.10'(10) device 'pchai7x'
Jan 16 19:35:36 srv4hai named[14530]: client @0x7fc31c0640a0 192.168.12.252#33705/key rndc-key: signer "rndc-key" approved
Jan 16 19:35:36 srv4hai named[14530]: client @0x7fc31c0640a0 192.168.12.252#33705/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '10.12.168.192.in-addr.arpa' PTR
Jan 16 19:35:36 srv4hai named[14530]: client @0x7fc31c0640a0 192.168.12.252#33705/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 16 19:35:36 srv4hai dhcpd[8095]: Added reverse map from 10.12.168.192.in-addr.arpa. to pchai7x.viehai.at
finished