activate_scenario.sh
Mon May 6 11:27:44 CEST 2024
scenario: sekr_UM
source: 192.168.12.101

'day' <--> DayTime=checked , NightTime=checked
-> execution of scenario
-> sending email(s) to 'null'

scenario: 'Sekretär Licht UM-schalten' - 'sekr_UM' - 'sekr_um'

Licht Sekretär (/srv/www/viehai/shelly/log_scenarios/2024/05/06/_0.log)

vorher: 11:27:44.779

http://192.168.12.123/relay/0?turn=toggle
  "ison": true,
  "has_timer": false,
  "timer_started": 0,
  "timer_duration": 0,
  "timer_remaining": 0,
  "source": "http"

nachher: 11:27:44.912
Dauer: 132 msec phName: 'shelly-01:'Wandlicht beim Esstisch''


scenario: 'Sekretär Licht UM-schalten' - 'sekr_UM' - 'sekr_um'

urls : 1
vorher : 11:27:44.759
nachher: 11:27:44.918
Dauer : 159 msec
outfile: '/srv/www/viehai/shelly/log_scenarios/2024/05/06//scene_sekr_UM_20240506_112744.txt'


syslog

May  6 11:24:01 srv4hai CRON[18847]: (root) CMD (/etc/linetest/linetest.sh)
May  6 11:24:36 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=18857, secured, session=
May  6 11:24:36 srv4hai dovecot: imap(miglincit)<18857>: Logged out in=707 out=40178 deleted=0 expunged=0 trashed=0 hdr_count=50 hdr_bytes=15838 body_count=0 body_bytes=0
May  6 11:25:01 srv4hai CRON[18862]: (root) CMD (/etc/linetest/linetest.sh)
May  6 11:25:11 srv4hai postfix/anvil[27694]: statistics: max connection rate 1/60s for (smtp:80.76.49.171) at May  6 11:16:19
May  6 11:25:11 srv4hai postfix/anvil[27694]: statistics: max connection count 1 for (smtp:80.76.49.171) at May  6 11:16:19
May  6 11:25:11 srv4hai postfix/anvil[27694]: statistics: max cache size 1 at May  6 11:16:19
May  6 11:25:31 srv4hai nmbd[2172]: [2024/05/06 11:25:31.307443,  0] ../source3/nmbd/nmbd_browsesync.c:354(find_domain_master_name_query_fail)
May  6 11:25:31 srv4hai nmbd[2172]:   find_domain_master_name_query_fail:
May  6 11:25:31 srv4hai nmbd[2172]:   Unable to find the Domain Master Browser name WORKGROUP<1b> for the workgroup WORKGROUP.
May  6 11:25:31 srv4hai nmbd[2172]:   Unable to sync browse lists in this workgroup.
May  6 11:26:01 srv4hai CRON[18873]: (root) CMD (/etc/linetest/linetest.sh)
May  6 11:26:35 srv4hai dhcpd[22856]: DHCPDISCOVER from c0:49:ef:8a:c9:d8 via enp2s0
May  6 11:26:35 srv4hai dhcpd[22856]: DHCPOFFER on 192.168.12.139 to c0:49:ef:8a:c9:d8 via enp2s0
May  6 11:26:35 srv4hai dhcpd[22856]: execute_statement argv[0] = /srv/www/viehai/shelly/json.sh
May  6 11:26:35 srv4hai dhcpd[22856]: execute_statement argv[1] = 192.168.12.139
May  6 11:26:35 srv4hai dhcpd[22856]: execute_statement argv[2] = shelly-39
May  6 11:26:35 srv4hai dhcpd[22856]: DHCPREQUEST for 192.168.12.139 (192.168.12.252) from c0:49:ef:8a:c9:d8 via enp2s0
May  6 11:26:35 srv4hai dhcpd[22856]: DHCPACK on 192.168.12.139 to c0:49:ef:8a:c9:d8 via enp2s0
May  6 11:26:35 srv4hai named[15509]: client @0x7f930801c730 192.168.12.252#55459/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: shelly-39.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
May  6 11:26:35 srv4hai named[15509]: client @0x7f930c069dc0 192.168.12.252#42617/key rndc-key: signer "rndc-key" approved
May  6 11:26:35 srv4hai named[15509]: client @0x7f930c069dc0 192.168.12.252#42617/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'shelly-39.viehai.at' TXT
May  6 11:26:35 srv4hai named[15509]: client @0x7f930c069dc0 192.168.12.252#42617/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'shelly-39.viehai.at' TXT "314be662bb43914e78b269d3f3bc9f38c7"
May  6 11:26:35 srv4hai named[15509]: client @0x7f930c069dc0 192.168.12.252#42617/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'shelly-39.viehai.at' A
May  6 11:26:35 srv4hai named[15509]: client @0x7f930c069dc0 192.168.12.252#42617/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'shelly-39.viehai.at' A 192.168.12.139
May  6 11:26:35 srv4hai dhcpd[22856]: Added new forward map from shelly-39.viehai.at to 192.168.12.139
May  6 11:26:35 srv4hai named[15509]: client @0x7f92fc030df0 192.168.12.252#56255/key rndc-key: signer "rndc-key" approved
May  6 11:26:35 srv4hai named[15509]: client @0x7f92fc030df0 192.168.12.252#56255/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '139.12.168.192.in-addr.arpa' PTR
May  6 11:26:35 srv4hai named[15509]: client @0x7f92fc030df0 192.168.12.252#56255/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.
May  6 11:26:35 srv4hai dhcpd[22856]: Added reverse map from 139.12.168.192.in-addr.arpa. to shelly-39.viehai.at
May  6 11:26:35 srv4hai common[18883]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/grab_shelly.sh
May  6 11:26:35 srv4hai grab_shelly.sh: SHELLY shellyName/1:'shelly-39' - shIP/2:'' perform:'conf'
May  6 11:26:36 srv4hai dhcpd[22856]: DHCPDISCOVER from e8:78:29:c0:c2:4e via enp2s0
May  6 11:26:36 srv4hai dhcpd[22856]: DHCPOFFER on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0
May  6 11:26:36 srv4hai dhcpd[22856]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
May  6 11:26:36 srv4hai dhcpd[22856]: execute_statement argv[1] = 192.168.12.61
May  6 11:26:36 srv4hai dhcpd[22856]: execute_statement argv[2] = fpThomas
May  6 11:26:36 srv4hai common[18895]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/smartphone.sh
May  6 11:26:36 srv4hai smartphone.sh: SHELLY - ping_SP.sh started for phoneIP: '192.168.12.61' / 'fpThomas' / '0'
May  6 11:26:36 srv4hai common[18909]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//ping_SP.sh
May  6 11:26:36 srv4hai dhcpd[22856]: DHCPREQUEST for 192.168.12.61 (192.168.12.252) from e8:78:29:c0:c2:4e via enp2s0
May  6 11:26:36 srv4hai dhcpd[22856]: DHCPACK on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0
May  6 11:26:36 srv4hai named[15509]: client @0x7f931c66c140 192.168.12.252#48141/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: fpThomas.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
May  6 11:26:36 srv4hai named[15509]: client @0x7f9314033170 192.168.12.252#52769/key rndc-key: signer "rndc-key" approved
May  6 11:26:36 srv4hai named[15509]: client @0x7f9314033170 192.168.12.252#52769/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'fpThomas.viehai.at' TXT
May  6 11:26:36 srv4hai named[15509]: client @0x7f9314033170 192.168.12.252#52769/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'fpThomas.viehai.at' TXT "310ebd0eafccd9c2e311facf8a4b0e4ad2"
May  6 11:26:36 srv4hai named[15509]: client @0x7f9314033170 192.168.12.252#52769/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'fpThomas.viehai.at' A
May  6 11:26:36 srv4hai named[15509]: client @0x7f9314033170 192.168.12.252#52769/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'fpThomas.viehai.at' A 192.168.12.61
May  6 11:26:36 srv4hai dhcpd[22856]: Added new forward map from fpThomas.viehai.at to 192.168.12.61
May  6 11:26:36 srv4hai named[15509]: client @0x7f931c67a760 192.168.12.252#42489/key rndc-key: signer "rndc-key" approved
May  6 11:26:36 srv4hai named[15509]: client @0x7f931c67a760 192.168.12.252#42489/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '61.12.168.192.in-addr.arpa' PTR
May  6 11:26:36 srv4hai named[15509]: client @0x7f931c67a760 192.168.12.252#42489/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.
May  6 11:26:36 srv4hai dhcpd[22856]: Added reverse map from 61.12.168.192.in-addr.arpa. to fpThomas.viehai.at
May  6 11:26:36 srv4hai ping_SP[18909]: SHELLY ping_SP.sh started from smartphone, user:'root'
May  6 11:26:36 srv4hai conf_shelly.sh: SHELLY: PARAMS  shName/1:'shelly-39' - shIP/2:'192.168.12.139'
May  6 11:26:36 srv4hai conf_shelly.sh: SHELLY: usage/name 'Wetterwerte Balkon' already set for '192.168.12.139'
May  6 11:26:36 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}'
May  6 11:26:37 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 '.''
May  6 11:26:37 srv4hai conf_shelly.sh: SHELLY:'shelly-39' conf(0)-ret: ''
May  6 11:26:38 srv4hai conf_shelly.sh: SHELLY:'shelly-39' no WebHook
May  6 11:26:38 srv4hai common[18969]: SHELLY common.sh inkludiert von ./ht_shelly.sh
May  6 11:26:38 srv4hai ht_shelly.sh: SHELLY - QUERY_STRING: gen=2&temp=23.200000&hum=45.800000&id=C049EF8AC9D8&batt=100&volt=6.770000
May  6 11:26:38 srv4hai ht_shelly.sh: SHELLY - storing into '/srv/www/viehai/shelly//wetter/2024/05/shelly-39_20240506.csv'
May  6 11:26:38 srv4hai ht_shelly.sh: SHELLY - 'shelly-39_20240506.csv' modifed 552s ago - EXIT
May  6 11:26:40 srv4hai common[18997]: SHELLY common.sh inkludiert von ./bt_scan.sh
May  6 11:26:44 srv4hai postfix/smtpd[19026]: connect from unknown[195.3.220.108]
May  6 11:26:44 srv4hai postfix/smtpd[19026]: warning: unknown[195.3.220.108]: SASL LOGIN authentication failed: Invalid authentication mechanism
May  6 11:26:44 srv4hai postfix/smtpd[19026]: lost connection after AUTH from unknown[195.3.220.108]
May  6 11:26:44 srv4hai postfix/smtpd[19026]: disconnect from unknown[195.3.220.108] ehlo=1 auth=0/1 commands=1/2
May  6 11:26:46 srv4hai common[19037]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//check_presence.sh
May  6 11:26:46 srv4hai postfix/smtpd[19026]: connect from unknown[80.76.49.171]
May  6 11:26:46 srv4hai postfix/smtpd[19026]: warning: unknown[80.76.49.171]: SASL LOGIN authentication failed: Invalid authentication mechanism
May  6 11:26:47 srv4hai postfix/smtpd[19026]: disconnect from unknown[80.76.49.171] ehlo=1 auth=0/1 quit=1 commands=2/3
May  6 11:26:47 srv4hai common.sh: SHELLY: BTstatus[0]:'On' dweetAge:'7'=1714987607-1714987600
May  6 11:26:47 srv4hai check_presence[19037]: SHELLY (0) found /srv/www/viehai/shelly//meter/ping_SP_0.LIVE and On//srv/www/viehai/shelly//meter/BTscan_BTid_0.No
May  6 11:26:47 srv4hai check_presence[19037]: SHELLY (ping_SP__smartphone)-(0) found '/srv/www/viehai/shelly//meter/ping_SP_0.LIVE' for what: 'coming'
May  6 11:26:47 srv4hai common.sh: SHELLY: BTstatus[1]:'Off' dweetAge:'7'=1714987607-1714987600
May  6 11:26:47 srv4hai check_presence[19037]: SHELLY (1) found /srv/www/viehai/shelly//meter/ping_SP_1.DEAD and Off//srv/www/viehai/shelly//meter/BTscan_BTid_1.No
May  6 11:26:47 srv4hai common.sh: SHELLY: BTstatus[2]:'Off' dweetAge:'7'=1714987607-1714987600
May  6 11:26:47 srv4hai check_presence[19037]: SHELLY (2) found /srv/www/viehai/shelly//meter/ping_SP_2.DEAD and Off//srv/www/viehai/shelly//meter/BTscan_BTid_2.No
May  6 11:26:47 srv4hai check_presence[19037]: SHELLY (ping_SP__smartphone)-(0) what: 'coming' - persPresent: '(BT/LIVE) (__/__) (__/__)' - anyPresent: 'true' - otherPresent:'false'
May  6 11:26:47 srv4hai check_presence[19037]: SHELLY - check 'coming': anyPresent(true) - lScene(scene_offAll) - sceneONsmall(vorraumOn)
May  6 11:26:47 srv4hai check_presence[19037]: SHELLY (ping_SP__smartphone)-(0)-coming: activate_scenario.shtml: vorraumOn
May  6 11:26:47 srv4hai activate_scenario[19189]: SHELLY scenario: 'vorraumOn(vorraumon)' started
May  6 11:26:47 srv4hai activate_scenario[19189]: SHELLY dayState='day' <--> DayTime=checked NightTime=checked - phoneName:'0'
May  6 11:26:47 srv4hai activate_scenario[19189]: SHELLY scenario: 'vorraumOn(vorraumon)' TO BE EXECUTED, phoneName:'0'
May  6 11:26:47 srv4hai postfix/pickup[17819]: 95CFC631A0: uid=33 from=
May  6 11:26:47 srv4hai postfix/cleanup[19239]: 95CFC631A0: message-id=<20240506092647.95CFC631A0@mail.viehai.at>
May  6 11:26:47 srv4hai postfix/qmgr[15291]: 95CFC631A0: from=, size=14546, nrcpt=1 (queue active)
May  6 11:26:47 srv4hai activate_scenario[19189]: SHELLY: scenario: 'vorraumOn(vorraumon)'-'shelly-03'-'Licht Vorzimmer' --> '0?turn=on'
May  6 11:26:47 srv4hai proc_url[19256]: SHELLY: OF:'/srv/www/viehai/shelly/log_scenarios/2024/05/06/_0.log' - shellyUsage:'Licht Vorzimmer' - scUrl:'http://192.168.12.103/relay/0?turn=on' - phName:'phoneName:'0''
May  6 11:26:47 srv4hai activate_scenario[19189]: SHELLY scenario: 'vorraumOn(vorraumon)' took 63 msec, phoneName:'0'
May  6 11:26:47 srv4hai ping_SP[18909]: SHELLY check_presence.sh coming 0 ping_SP__smartphone
May  6 11:26:48 srv4hai postfix/relay/smtp[19252]: 95CFC631A0: to=, relay=mx03.nic.name[209.131.161.54]:25, delay=1.1, delays=0.02/0.01/0.69/0.36, dsn=2.0.0, status=sent (250 2.0.0 Ok: queued as 5D2F2801CA)
May  6 11:26:48 srv4hai postfix/qmgr[15291]: 95CFC631A0: removed
May  6 11:26:49 srv4hai ping_SP[18909]: SHELLY - new process for '192.168.12.61' - '/srv/www/viehai/shelly//meter/ping_SP_0' @~1714987609
May  6 11:26:50 srv4hai bt_scan[18997]: SHELLY - who: 'Thomas' / phoneName: 'FP4_Thomas' / '0' / shellyIP: '192.168.12.61' - found: 'Yes'
May  6 11:26:50 srv4hai bt_scan[18997]: SHELLY - ping_SP.sh already running for BTmac: '0' - IPv4: '192.168.12.61'
May  6 11:27:01 srv4hai CRON[19320]: (root) CMD (/etc/linetest/linetest.sh)
May  6 11:27:02 srv4hai ping_SP[18909]: SHELLY (0) - ping 192.168.12.61 0 sucessfully  @~1714987622 - 'ping_SP_0'
May  6 11:27:02 srv4hai ping_SP[18909]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
May  6 11:27:36 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=19342, secured, session=<8AsSrsUXLL0AAAAAAAAAAAAAAAAAAAAB>
May  6 11:27:36 srv4hai dovecot: imap(miglincit)<19342><8AsSrsUXLL0AAAAAAAAAAAAAAAAAAAAB>: Logged out in=485 out=1357 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
May  6 11:27:44 srv4hai activate_scenario[19346]: SHELLY scenario: 'sekr_UM(sekr_um)' started
May  6 11:27:44 srv4hai activate_scenario[19346]: SHELLY dayState='day' <--> DayTime=checked NightTime=checked - shelly-01:'Wandlicht beim Esstisch'

finished