activate_scenario.sh
Thu Mar 28 17:43:46 CET 2024
scenario: LOG_gehen
source: 192.168.12.103
'day' <--> DayTime=checked , NightTime=checked
-> execution of scenario
-> sending email(s) to ''
scenario: 'LOG_AUS' - 'LOG_gehen' - 'log_gehen'
scenario: 'LOG_AUS' - 'LOG_gehen' - 'log_gehen'
urls : 0
vorher : 17:43:46.165
nachher: 17:43:46.172
Dauer : 6 msec
outfile: '/srv/www/viehai/shelly/log_scenarios/2024/03/28//scene_LOG_gehen_20240328_174346.txt'
syslog
Mar 28 17:40:01 srv4hai CRON[22067]: (root) CMD (/etc/linetest/linetest.sh)
Mar 28 17:40:01 srv4hai CRON[22068]: (www-data) CMD (/srv/www/viehai/shelly/meter.sh)
Mar 28 17:40:01 srv4hai meter.sh: SHELLY meter.sh started
Mar 28 17:40:07 srv4hai meter.sh: SHELLY 'shelly-13/Thomas Taster': OFFLINE, resp. not answering within connect-timeout 2.500000s
Mar 28 17:40:10 srv4hai meter.sh: SHELLY 'shelly-14/AUS-Schalter': OFFLINE, resp. not answering within connect-timeout 2.500000s
Mar 28 17:40:12 srv4hai meter.sh: SHELLY 'shelly-37/Klima Büro': OFFLINE, resp. not answering within connect-timeout 2.500000s
Mar 28 17:40:15 srv4hai meter.sh: SHELLY 'shelly-38/Wetterwerte Innen': OFFLINE, resp. not answering within connect-timeout 2.500000s
Mar 28 17:40:17 srv4hai meter.sh: SHELLY 'shelly-39/Wetterwerte Balkon': OFFLINE, resp. not answering within connect-timeout 2.500000s
Mar 28 17:40:20 srv4hai meter.sh: SHELLY 'shelly-58/Werkstatt Licht': OFFLINE, resp. not answering within connect-timeout 2.500000s
Mar 28 17:40:23 srv4hai meter.sh: SHELLY 'shelly-18/18-Test Farbige LED': OFFLINE, resp. not answering within connect-timeout 2.500000s
Mar 28 17:40:25 srv4hai meter.sh: SHELLY 'shelly-15/15-Test Weiss': OFFLINE, resp. not answering within connect-timeout 2.500000s
Mar 28 17:40:28 srv4hai meter.sh: SHELLY 'shelly-30/30-TBD': OFFLINE, resp. not answering within connect-timeout 2.500000s
Mar 28 17:40:30 srv4hai meter.sh: SHELLY 'shelly-31/31-Test i3': OFFLINE, resp. not answering within connect-timeout 2.500000s
Mar 28 17:40:33 srv4hai meter.sh: SHELLY 'shelly-32/32-TBD': OFFLINE, resp. not answering within connect-timeout 2.500000s
Mar 28 17:40:35 srv4hai meter.sh: SHELLY 'shelly-33/33-TBD': OFFLINE, resp. not answering within connect-timeout 2.500000s
Mar 28 17:40:38 srv4hai meter.sh: SHELLY 'shelly-36/36-TBD': OFFLINE, resp. not answering within connect-timeout 2.500000s
Mar 28 17:40:40 srv4hai meter.sh: SHELLY 'shelly-47/47-plus1-Test': OFFLINE, resp. not answering within connect-timeout 2.500000s
Mar 28 17:40:44 srv4hai meter.sh: SHELLY 'shelly-26/20230404 - kein WLAN': OFFLINE, resp. not answering within connect-timeout 2.500000s
Mar 28 17:40:46 srv4hai meter.sh: SHELLY 'shelly-27/Schalter Herdzeile - Defekt WLAN 20230417': OFFLINE, resp. not answering within connect-timeout 2.500000s
Mar 28 17:40:49 srv4hai meter.sh: SHELLY 'shelly-21/Badezimmer - 20230720 - WLAN Problem': OFFLINE, resp. not answering within connect-timeout 2.500000s
Mar 28 17:40:51 srv4hai meter.sh: SHELLY 'shelly-11/Ausgangstransistor defekt 20230722': OFFLINE, resp. not answering within connect-timeout 2.500000s
Mar 28 17:40:54 srv4hai meter.sh: SHELLY 'shelly-43/43-TBD': OFFLINE, resp. not answering within connect-timeout 2.500000s
Mar 28 17:40:55 srv4hai dhcpd[5037]: DHCPDISCOVER from e8:78:29:c0:c2:4e via enp2s0
Mar 28 17:40:55 srv4hai dhcpd[5037]: DHCPOFFER on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0
Mar 28 17:40:55 srv4hai dhcpd[5037]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Mar 28 17:40:55 srv4hai dhcpd[5037]: execute_statement argv[1] = 192.168.12.61
Mar 28 17:40:55 srv4hai dhcpd[5037]: execute_statement argv[2] = fpThomas
Mar 28 17:40:55 srv4hai common[22592]: SHELLY common.sh inkludiert
Mar 28 17:40:55 srv4hai smartphone.sh: SHELLY - ping_SP.sh started for phoneIP: '192.168.12.61' / 'fpThomas' / '0'
Mar 28 17:40:55 srv4hai common[22604]: SHELLY common.sh inkludiert
Mar 28 17:40:55 srv4hai dhcpd[5037]: DHCPREQUEST for 192.168.12.61 (192.168.12.252) from e8:78:29:c0:c2:4e via enp2s0
Mar 28 17:40:55 srv4hai dhcpd[5037]: DHCPACK on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0
Mar 28 17:40:55 srv4hai named[15509]: client @0x7f93180334d0 192.168.12.252#57767/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: fpThomas.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Mar 28 17:40:55 srv4hai named[15509]: client @0x7f931c697a10 192.168.12.252#43483/key rndc-key: signer "rndc-key" approved
Mar 28 17:40:55 srv4hai named[15509]: client @0x7f931c697a10 192.168.12.252#43483/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'fpThomas.viehai.at' TXT
Mar 28 17:40:55 srv4hai named[15509]: client @0x7f931c697a10 192.168.12.252#43483/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'fpThomas.viehai.at' TXT "310ebd0eafccd9c2e311facf8a4b0e4ad2"
Mar 28 17:40:55 srv4hai named[15509]: client @0x7f931c697a10 192.168.12.252#43483/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'fpThomas.viehai.at' A
Mar 28 17:40:55 srv4hai named[15509]: client @0x7f931c697a10 192.168.12.252#43483/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'fpThomas.viehai.at' A 192.168.12.61
Mar 28 17:40:55 srv4hai dhcpd[5037]: Added new forward map from fpThomas.viehai.at to 192.168.12.61
Mar 28 17:40:55 srv4hai named[15509]: client @0x7f9314033170 192.168.12.252#55917/key rndc-key: signer "rndc-key" approved
Mar 28 17:40:55 srv4hai named[15509]: client @0x7f9314033170 192.168.12.252#55917/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '61.12.168.192.in-addr.arpa' PTR
Mar 28 17:40:55 srv4hai named[15509]: client @0x7f9314033170 192.168.12.252#55917/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': adding an RR at '61.12.168.192.in-addr.arpa' PTR fpThomas.viehai.at.
Mar 28 17:40:55 srv4hai dhcpd[5037]: Added reverse map from 61.12.168.192.in-addr.arpa. to fpThomas.viehai.at
Mar 28 17:40:55 srv4hai ping_SP[22604]: SHELLY ping_SP.sh started from smartphone
Mar 28 17:40:56 srv4hai activate_scenario[22624]: SHELLY scenario: 'LOG_kommen(log_kommen)' started
Mar 28 17:40:56 srv4hai activate_scenario[22624]: SHELLY dayState='day' <--> DayTime=checked NightTime=checked - shelly-03:'Licht Vorzimmer'
Mar 28 17:40:56 srv4hai activate_scenario[22624]: SHELLY scenario: 'LOG_kommen(log_kommen)' TO BE EXECUTED, shelly-03:'Licht Vorzimmer'
Mar 28 17:40:56 srv4hai activate_scenario[22624]: SHELLY scenario: 'LOG_kommen(log_kommen)' took 5 msec, shelly-03:'Licht Vorzimmer'
Mar 28 17:40:56 srv4hai meter.sh: SHELLY 'shelly-44/44-TBD': OFFLINE, resp. not answering within connect-timeout 2.500000s
Mar 28 17:40:59 srv4hai meter.sh: SHELLY 'shelly-45/45-TBD': OFFLINE, resp. not answering within connect-timeout 2.500000s
Mar 28 17:40:59 srv4hai meter.sh: SHELLY meter.sh ended
Mar 28 17:40:59 srv4hai postfix/pickup[13281]: 4528F6027D: uid=33 from=
Mar 28 17:40:59 srv4hai postfix/cleanup[22039]: 4528F6027D: message-id=<20240328164059.4528F6027D@mail.viehai.at>
Mar 28 17:40:59 srv4hai postfix/qmgr[15291]: 4528F6027D: from=, size=654, nrcpt=1 (queue active)
Mar 28 17:40:59 srv4hai postfix/local[22040]: 4528F6027D: to=, orig_to=, relay=local, delay=0.02, delays=0.01/0/0/0.01, dsn=2.0.0, status=sent (delivered to command: /bin/procmail)
Mar 28 17:40:59 srv4hai postfix/qmgr[15291]: 4528F6027D: removed
Mar 28 17:41:01 srv4hai CRON[22711]: (root) CMD (/etc/linetest/linetest.sh)
Mar 28 17:41:02 srv4hai common[22717]: SHELLY common.sh inkludiert
Mar 28 17:41:05 srv4hai common[22737]: SHELLY common.sh inkludiert
Mar 28 17:41:06 srv4hai check_presence[22737]: SHELLY (0) found /srv/www/viehai/shelly//meter/ping_SP_0.LIVE and Off//srv/www/viehai/shelly//meter/BTscan_BTid_0.No
Mar 28 17:41:06 srv4hai check_presence[22737]: SHELLY (0) found /srv/www/viehai/shelly//meter/ping_SP_1.DEAD and Off//srv/www/viehai/shelly//meter/BTscan_BTid_1.No
Mar 28 17:41:06 srv4hai check_presence[22737]: SHELLY (0) found /srv/www/viehai/shelly//meter/ping_SP_2.DEAD and Off//srv/www/viehai/shelly//meter/BTscan_BTid_2.No
Mar 28 17:41:06 srv4hai check_presence[22737]: SHELLY (ping_SP__smartphone)-(0) what: 'coming' - persPresent: '(__/IP) (__/__) (__/__)' - anyPresent: 'true' - otherPresent:'false'
Mar 28 17:41:06 srv4hai check_presence[22737]: SHELLY (ping_SP__smartphone)-(0)-coming: activate_scenario.shtml: vorraumOn
Mar 28 17:41:06 srv4hai activate_scenario[22810]: SHELLY scenario: 'vorraumOn(vorraumon)' started
Mar 28 17:41:06 srv4hai activate_scenario[22810]: SHELLY dayState='day' <--> DayTime=checked NightTime=checked - phoneName:'0'
Mar 28 17:41:06 srv4hai activate_scenario[22810]: SHELLY scenario: 'vorraumOn(vorraumon)' TO BE EXECUTED, phoneName:'0'
Mar 28 17:41:06 srv4hai postfix/pickup[13281]: 1FBF46027D: uid=33 from=
Mar 28 17:41:06 srv4hai postfix/cleanup[22039]: 1FBF46027D: message-id=<20240328164106.1FBF46027D@mail.viehai.at>
Mar 28 17:41:06 srv4hai postfix/qmgr[15291]: 1FBF46027D: from=, size=13339, nrcpt=1 (queue active)
Mar 28 17:41:06 srv4hai activate_scenario[22810]: SHELLY: scenario: 'vorraumOn(vorraumon)'-'shelly-03'-'Licht Vorzimmer' --> '0?turn=on'
Mar 28 17:41:06 srv4hai proc_url[22875]: SHELLY: OF:'/srv/www/viehai/shelly/log_scenarios/2024/03/28/_0.log' - shellyUsage:'Licht Vorzimmer' - scUrl:'http://192.168.12.103/relay/0?turn=on' - phName:'phoneName:'0''
Mar 28 17:41:06 srv4hai activate_scenario[22810]: SHELLY scenario: 'vorraumOn(vorraumon)' took 62 msec, phoneName:'0'
Mar 28 17:41:06 srv4hai ping_SP[22604]: SHELLY check_presence.sh coming 0 ping_SP__smartphone
Mar 28 17:41:06 srv4hai named[15509]: _default: sending trust-anchor-telemetry query '_ta-4f66/NULL'
Mar 28 17:41:07 srv4hai postfix/relay/smtp[22865]: 1FBF46027D: to=, relay=mx04.nic.name[209.131.160.34]:25, delay=0.96, delays=0.01/0.01/0.63/0.32, dsn=2.0.0, status=sent (250 2.0.0 Ok: queued as CB66680189)
Mar 28 17:41:07 srv4hai postfix/qmgr[15291]: 1FBF46027D: removed
Mar 28 17:41:08 srv4hai ping_SP[22604]: SHELLY - new process for '192.168.12.61' - '/srv/www/viehai/shelly//meter/ping_SP_0' @~1711644068
Mar 28 17:41:08 srv4hai named[15509]: managed-keys-zone: Key 20326 for zone . acceptance timer complete: key now trusted
Mar 28 17:41:12 srv4hai bt_scan[22717]: SHELLY - who: 'Thomas' / phoneName: 'FP4_Thomas' / '0' / fixAddr: '192.168.12.61;' - found: 'Yes'
Mar 28 17:41:12 srv4hai bt_scan[22717]: SHELLY - ping_SP.sh already running for BTmac: '0' - IPv4: '192.168.12.61'
Mar 28 17:41:21 srv4hai ping_SP[22604]: SHELLY (0) - ping 192.168.12.61 0 sucessfully @~1711644081 - 'ping_SP_0'
Mar 28 17:41:21 srv4hai ping_SP[22604]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Mar 28 17:41:47 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=22973, secured, session=
Mar 28 17:41:48 srv4hai dovecot: imap(miglincit)<22973>: Logged out in=672 out=40280 deleted=0 expunged=0 trashed=0 hdr_count=50 hdr_bytes=16334 body_count=0 body_bytes=0
Mar 28 17:41:54 srv4hai dhcpd[5037]: DHCPDISCOVER from c0:49:ef:8a:c9:d8 via enp2s0
Mar 28 17:41:54 srv4hai dhcpd[5037]: DHCPOFFER on 192.168.12.139 to c0:49:ef:8a:c9:d8 via enp2s0
Mar 28 17:41:54 srv4hai dhcpd[5037]: execute_statement argv[0] = /srv/www/viehai/shelly/json.sh
Mar 28 17:41:54 srv4hai dhcpd[5037]: execute_statement argv[1] = 192.168.12.139
Mar 28 17:41:54 srv4hai dhcpd[5037]: execute_statement argv[2] = shelly-39
Mar 28 17:41:54 srv4hai dhcpd[5037]: DHCPREQUEST for 192.168.12.139 (192.168.12.252) from c0:49:ef:8a:c9:d8 via enp2s0
Mar 28 17:41:54 srv4hai dhcpd[5037]: DHCPACK on 192.168.12.139 to c0:49:ef:8a:c9:d8 via enp2s0
Mar 28 17:41:54 srv4hai named[15509]: client @0x7f930807c330 192.168.12.252#55499/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: shelly-39.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Mar 28 17:41:54 srv4hai named[15509]: client @0x7f931c6302d0 192.168.12.252#43909/key rndc-key: signer "rndc-key" approved
Mar 28 17:41:54 srv4hai named[15509]: client @0x7f931c6302d0 192.168.12.252#43909/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'shelly-39.viehai.at' TXT
Mar 28 17:41:54 srv4hai named[15509]: client @0x7f931c6302d0 192.168.12.252#43909/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'shelly-39.viehai.at' TXT "314be662bb43914e78b269d3f3bc9f38c7"
Mar 28 17:41:54 srv4hai named[15509]: client @0x7f931c6302d0 192.168.12.252#43909/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'shelly-39.viehai.at' A
Mar 28 17:41:54 srv4hai named[15509]: client @0x7f931c6302d0 192.168.12.252#43909/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'shelly-39.viehai.at' A 192.168.12.139
Mar 28 17:41:54 srv4hai dhcpd[5037]: Added new forward map from shelly-39.viehai.at to 192.168.12.139
Mar 28 17:41:54 srv4hai named[15509]: client @0x7f931c6b5500 192.168.12.252#35059/key rndc-key: signer "rndc-key" approved
Mar 28 17:41:54 srv4hai named[15509]: client @0x7f931c6b5500 192.168.12.252#35059/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '139.12.168.192.in-addr.arpa' PTR
Mar 28 17:41:54 srv4hai named[15509]: client @0x7f931c6b5500 192.168.12.252#35059/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': adding an RR at '139.12.168.192.in-addr.arpa' PTR shelly-39.viehai.at.
Mar 28 17:41:54 srv4hai dhcpd[5037]: Added reverse map from 139.12.168.192.in-addr.arpa. to shelly-39.viehai.at
Mar 28 17:41:54 srv4hai grab_shelly.sh: SHELLY shellyName/1:'shelly-39' - shIP/2:'' workdir:'/srv/www/viehai/shelly' perform:'conf'
Mar 28 17:41:56 srv4hai conf_shelly.sh: SHELLY: PARAMS shName/1:'shelly-39' - shIP/2:'192.168.12.139'
Mar 28 17:41:56 srv4hai conf_shelly.sh: SHELLY: usage/name 'Wetterwerte Balkon' already set for '192.168.12.139'
Mar 28 17:41:56 srv4hai conf_shelly.sh: SHELLY:'shelly-39' 'conf(0)' - 'settings/actions?index=0&name=report_url&enabled=true&urls[]=http://www.viehai.at/shelly/ht_shelly.shtml?gen=2&temp=${ev.tC}&hum=${status["humidity:0"].rH}&id=${config.sys.device.mac}'
Mar 28 17:41:56 srv4hai conf_shelly.sh: SHELLY:'shelly-39' conf(0)-cmd: 'curl --connect-timeout 3 --silent -X -POST -d 'settings/actions?index=0&name=report_url&enabled=true&urls[]=http://www.viehai.at/shelly/ht_shelly.shtml?gen=2&temp=${ev.tC}&hum=${status["humidity:0"].rH}&id=${config.sys.device.mac}' http://192.168.12.139/rpc | jq -r '.''
Mar 28 17:41:56 srv4hai conf_shelly.sh: SHELLY:'shelly-39' conf(0)-ret: ''
Mar 28 17:41:56 srv4hai conf_shelly.sh: SHELLY:'shelly-39' no WebHook
Mar 28 17:41:59 srv4hai ht_shelly.sh: SHELLY - QUERY_STRING: gen=2&temp=16.200000&hum=44.200000&id=C049EF8AC9D8&batt=100&volt=6.740000
Mar 28 17:41:59 srv4hai ht_shelly.sh: SHELLY - storing into '/srv/www/viehai/shelly/wetter/2024/03/shelly-39_20240328.csv'
Mar 28 17:41:59 srv4hai conf_shelly.sh: SHELLY: PARAMS shName/1:'shelly-39' - shIP/2:'192.168.12.139'
Mar 28 17:41:59 srv4hai conf_shelly.sh: SHELLY: usage/name 'Wetterwerte Balkon' already set for '192.168.12.139'
Mar 28 17:41:59 srv4hai conf_shelly.sh: SHELLY:'shelly-39' 'conf(0)' - 'settings/actions?index=0&name=report_url&enabled=true&urls[]=http://www.viehai.at/shelly/ht_shelly.shtml?gen=2&temp=${ev.tC}&hum=${status["humidity:0"].rH}&id=${config.sys.device.mac}'
Mar 28 17:41:59 srv4hai conf_shelly.sh: SHELLY:'shelly-39' conf(0)-cmd: 'curl --connect-timeout 3 --silent -X -POST -d 'settings/actions?index=0&name=report_url&enabled=true&urls[]=http://www.viehai.at/shelly/ht_shelly.shtml?gen=2&temp=${ev.tC}&hum=${status["humidity:0"].rH}&id=${config.sys.device.mac}' http://192.168.12.139/rpc | jq -r '.''
Mar 28 17:41:59 srv4hai conf_shelly.sh: SHELLY:'shelly-39' conf(0)-ret: ''
Mar 28 17:41:59 srv4hai conf_shelly.sh: SHELLY:'shelly-39' no WebHook
Mar 28 17:41:59 srv4hai ht_shelly.sh: SHELLY C049EF8AC9D8 / shelly-39 / Wetterwerte Balkon: 16.2° - 44.2%
Mar 28 17:41:59 srv4hai ht_shelly.sh: SHELLY C049EF8AC9D8 / shelly-39 / Wetterwerte Balkon: 16.2°/16.8° - 44.2%/41.1% - SAVED
Mar 28 17:41:59 srv4hai ht_shelly.sh: SHELLY C049EF8AC9D8 / shelly-39 / Wetterwerte Balkon: batt='100'°
Mar 28 17:41:59 srv4hai ht_shelly.sh: SHELLY C049EF8AC9D8 / shelly-39 / Wetterwerte Balkon: dweetIO='{#012 "this": "succeeded",#012 "by": "dweeting",#012 "the": "dweet",#012 "with": {#012 "thing": "viehai-39",#012 "created": "2024-03-28T16:41:59.748Z",#012 "content": {#012 "temp": 16.2,#012 "hum": 44.2,#012 "batt": 100#012 },#012 "transaction": "29f9c964-8e79-44fc-a97e-51629f169152"#012 }#012}'°
Mar 28 17:42:01 srv4hai CRON[23134]: (root) CMD (/etc/linetest/linetest.sh)
Mar 28 17:42:34 srv4hai ping_SP[22604]: SHELLY (0) - ping 192.168.12.61 1 sucessfully @~1711644154 - 'ping_SP_0'
Mar 28 17:42:34 srv4hai ping_SP[22604]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Mar 28 17:43:00 srv4hai postfix/anvil[22052]: statistics: max connection rate 1/60s for (smtp:185.173.176.67) at Mar 28 17:39:40
Mar 28 17:43:00 srv4hai postfix/anvil[22052]: statistics: max connection count 1 for (smtp:185.173.176.67) at Mar 28 17:39:40
Mar 28 17:43:00 srv4hai postfix/anvil[22052]: statistics: max cache size 1 at Mar 28 17:39:40
Mar 28 17:43:01 srv4hai CRON[23192]: (root) CMD (/etc/linetest/linetest.sh)
Mar 28 17:43:46 srv4hai activate_scenario[23225]: SHELLY scenario: 'LOG_gehen(log_gehen)' started
Mar 28 17:43:46 srv4hai activate_scenario[23225]: SHELLY dayState='day' <--> DayTime=checked NightTime=checked - shelly-03:'Licht Vorzimmer'
finished