activate_scenario.sh
Sun May 19 19:43:40 CEST 2024
scenario: vorraumOn
phoneName: 1

'day' <--> DayTime=checked , NightTime=checked
-> execution of scenario
-> sending email(s) to 'thomas@miglinci.name'

scenario: 'Vorzimmer EIN' - 'vorraumOn' - 'vorraumon'

Licht Vorzimmer (/srv/www/viehai/shelly/log_scenarios/2024/05/19/_0.log)

vorher: 19:43:40.648

http://192.168.12.103/relay/0?turn=on
  "ison": true,
  "has_timer": false,
  "timer_started": 0,
  "timer_duration": 0,
  "timer_remaining": 0,
  "overpower": false,
  "source": "input"

nachher: 19:43:40.677
Dauer: 28 msec phName: 'phoneName:'1''


scenario: 'Vorzimmer EIN' - 'vorraumOn' - 'vorraumon'

urls : 1
vorher : 19:43:40.597
nachher: 19:43:40.684
Dauer : 88 msec
outfile: '/srv/www/viehai/shelly/log_scenarios/2024/05/19//scene_vorraumOn_20240519_194340.txt'


syslog

May 19 19:40:01 srv4hai CRON[12162]: (www-data) CMD (/srv/www/viehai/shelly/meter.sh)
May 19 19:40:01 srv4hai CRON[12163]: (root) CMD (/etc/linetest/linetest.sh)
May 19 19:40:01 srv4hai common[12164]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/meter.sh
May 19 19:40:01 srv4hai meter.sh: SHELLY meter.sh started
May 19 19:40:01 srv4hai meter.sh: SHELLY 'shelly-29/Schalter Herdzeile' - type: 'shelly-i3' ignoring
May 19 19:40:02 srv4hai meter.sh: SHELLY 'shelly-28/Schalter Nasszeile' - type: 'shelly-i3' ignoring
May 19 19:40:08 srv4hai meter.sh: SHELLY 'shelly-42/Stehlampe dimmbar':  OFFLINE, resp. not answering within connect-timeout 2.500000s
May 19 19:40:09 srv4hai meter.sh: SHELLY 'shelly-13/Thomas Taster' - type: 'shelly-BTN' ignoring
May 19 19:40:09 srv4hai meter.sh: SHELLY 'shelly-14/AUS-Schalter' - type: 'shelly-BTN' ignoring
May 19 19:40:09 srv4hai meter.sh: SHELLY 'shelly-37/Klima Büro' - type: 'shelly-ht' ignoring
May 19 19:40:09 srv4hai meter.sh: SHELLY 'shelly-38/Wetterwerte Innen' - status: 'OOS' ignoring
May 19 19:40:09 srv4hai meter.sh: SHELLY 'shelly-39/Wetterwerte Balkon' - type: 'shelly-ht-plus' ignoring
May 19 19:40:12 srv4hai meter.sh: SHELLY 'shelly-18/18-Test Farbige LED':  OFFLINE, resp. not answering within connect-timeout 2.500000s
May 19 19:40:14 srv4hai meter.sh: SHELLY 'shelly-15/15-Test Weiss':  OFFLINE, resp. not answering within connect-timeout 2.500000s
May 19 19:40:14 srv4hai meter.sh: SHELLY 'shelly-30/30-TBD' - status: 'OOS' ignoring
May 19 19:40:14 srv4hai meter.sh: SHELLY 'shelly-31/31-Test i3' - status: 'OOS' ignoring
May 19 19:40:14 srv4hai meter.sh: SHELLY 'shelly-32/32-TBD' - status: 'OOS' ignoring
May 19 19:40:14 srv4hai meter.sh: SHELLY 'shelly-33/33-TBD' - status: 'OOS' ignoring
May 19 19:40:14 srv4hai meter.sh: SHELLY 'shelly-36/36-TBD' - status: 'OOS' ignoring
May 19 19:40:17 srv4hai meter.sh: SHELLY 'shelly-47/47-plus1-Test':  OFFLINE, resp. not answering within connect-timeout 2.500000s
May 19 19:40:17 srv4hai meter.sh: SHELLY 'shelly-09a/Inbetriebnahme - 20210720 - Defekt' - status: 'defect' ignoring
May 19 19:40:17 srv4hai meter.sh: SHELLY 'shelly-09b/Sophie Licht - 20230404 - Defekt' - status: 'defect' ignoring
May 19 19:40:17 srv4hai meter.sh: SHELLY 'shelly-26/20230404 - kein WLAN' - status: 'defect' ignoring
May 19 19:40:17 srv4hai meter.sh: SHELLY 'shelly-27/Schalter Herdzeile - Defekt WLAN 20230417' - status: 'defect' ignoring
May 19 19:40:17 srv4hai meter.sh: SHELLY 'shelly-21/Badezimmer - 20230720 - WLAN Problem' - status: 'defect' ignoring
May 19 19:40:17 srv4hai meter.sh: SHELLY 'shelly-11/Ausgangstransistor defekt 20230722' - status: 'defect' ignoring
May 19 19:40:17 srv4hai meter.sh: SHELLY 'shelly-43/43-TBD' - status: 'OOS' ignoring
May 19 19:40:17 srv4hai meter.sh: SHELLY 'shelly-44/44-TBD' - status: 'OOS' ignoring
May 19 19:40:17 srv4hai meter.sh: SHELLY 'shelly-45/45-TBD' - status: 'OOS' ignoring
May 19 19:40:17 srv4hai meter.sh: SHELLY 'shelly-46/46-TBD' - status: 'OOS' ignoring
May 19 19:40:20 srv4hai meter.sh: SHELLY 'shelly-58/Werkstatt Licht':  OFFLINE, resp. not answering within connect-timeout 2.500000s
May 19 19:40:20 srv4hai meter.sh: SHELLY 'shelly-59/Werkstatt Schalter Kellertür' - type: 'shelly-i3' ignoring
May 19 19:40:20 srv4hai common[13045]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//check_shelly.sh
May 19 19:40:20 srv4hai check_shelly.sh: SHELLY last scenery executed: 'offAll'
May 19 19:40:20 srv4hai check_shelly.sh: SHELLY found '0' active shelly outputs
May 19 19:40:20 srv4hai meter.sh: SHELLY meter.sh ended
May 19 19:40:40 srv4hai nmbd[2172]: [2024/05/19 19:40:40.657944,  0] ../source3/nmbd/nmbd_browsesync.c:354(find_domain_master_name_query_fail)
May 19 19:40:40 srv4hai nmbd[2172]:   find_domain_master_name_query_fail:
May 19 19:40:40 srv4hai nmbd[2172]:   Unable to find the Domain Master Browser name WORKGROUP<1b> for the workgroup WORKGROUP.
May 19 19:40:40 srv4hai nmbd[2172]:   Unable to sync browse lists in this workgroup.
May 19 19:40:57 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=13104, secured, session=
May 19 19:40:57 srv4hai dovecot: imap(miglincit)<13104>: Logged out in=137 out=1088 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
May 19 19:41:01 srv4hai CRON[13106]: (root) CMD (/etc/linetest/linetest.sh)
May 19 19:41:27 srv4hai postfix/scache[11973]: statistics: start interval May 19 19:37:38
May 19 19:41:27 srv4hai postfix/scache[11973]: statistics: domain lookup hits=0 miss=27 success=0%
May 19 19:41:27 srv4hai postfix/scache[11973]: statistics: address lookup hits=0 miss=648 success=0%
May 19 19:41:57 srv4hai mosquitto[18551]: Saving in-memory database to /var/lib/mosquitto/mosquitto.db.
May 19 19:42:01 srv4hai CRON[13143]: (root) CMD (/etc/linetest/linetest.sh)
May 19 19:42:37 srv4hai postfix/qmgr[15291]: A47EB634A6: from=, size=1521, nrcpt=1 (queue active)
May 19 19:42:37 srv4hai postfix/qmgr[15291]: 1452C63635: from=, size=21739, nrcpt=1 (queue active)
May 19 19:42:37 srv4hai postfix/smtp[13158]: 1452C63635: lost connection with mail.saltechsystems.com[162.247.129.55] while receiving the initial server greeting
May 19 19:42:38 srv4hai postfix/smtp[13158]: 1452C63635: to=, relay=mail.saltechsystems.com[162.247.129.54]:25, delay=193566, delays=193566/0.01/0.87/0, dsn=4.4.2, status=deferred (lost connection with mail.saltechsystems.com[162.247.129.54] while receiving the initial server greeting)
May 19 19:42:44 srv4hai dhcpd[22856]: DHCPDISCOVER from c0:49:ef:8a:c9:d8 via enp2s0
May 19 19:42:44 srv4hai dhcpd[22856]: DHCPOFFER on 192.168.12.139 to c0:49:ef:8a:c9:d8 via enp2s0
May 19 19:42:44 srv4hai dhcpd[22856]: execute_statement argv[0] = /srv/www/viehai/shelly/json.sh
May 19 19:42:44 srv4hai dhcpd[22856]: execute_statement argv[1] = 192.168.12.139
May 19 19:42:44 srv4hai dhcpd[22856]: execute_statement argv[2] = shelly-39
May 19 19:42:44 srv4hai dhcpd[22856]: DHCPREQUEST for 192.168.12.139 (192.168.12.252) from c0:49:ef:8a:c9:d8 via enp2s0
May 19 19:42:44 srv4hai dhcpd[22856]: DHCPACK on 192.168.12.139 to c0:49:ef:8a:c9:d8 via enp2s0
May 19 19:42:44 srv4hai named[15509]: client @0x7f93080d8970 192.168.12.252#35941/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: shelly-39.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
May 19 19:42:44 srv4hai named[15509]: client @0x7f931406e6c0 192.168.12.252#49149/key rndc-key: signer "rndc-key" approved
May 19 19:42:44 srv4hai named[15509]: client @0x7f931406e6c0 192.168.12.252#49149/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'shelly-39.viehai.at' TXT
May 19 19:42:44 srv4hai named[15509]: client @0x7f931406e6c0 192.168.12.252#49149/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'shelly-39.viehai.at' TXT "314be662bb43914e78b269d3f3bc9f38c7"
May 19 19:42:44 srv4hai named[15509]: client @0x7f931406e6c0 192.168.12.252#49149/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'shelly-39.viehai.at' A
May 19 19:42:44 srv4hai named[15509]: client @0x7f931406e6c0 192.168.12.252#49149/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'shelly-39.viehai.at' A 192.168.12.139
May 19 19:42:44 srv4hai dhcpd[22856]: Added new forward map from shelly-39.viehai.at to 192.168.12.139
May 19 19:42:44 srv4hai named[15509]: client @0x7f93080fc360 192.168.12.252#51031/key rndc-key: signer "rndc-key" approved
May 19 19:42:44 srv4hai named[15509]: client @0x7f93080fc360 192.168.12.252#51031/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '139.12.168.192.in-addr.arpa' PTR
May 19 19:42:44 srv4hai named[15509]: client @0x7f93080fc360 192.168.12.252#51031/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 19 19:42:44 srv4hai dhcpd[22856]: Added reverse map from 139.12.168.192.in-addr.arpa. to shelly-39.viehai.at
May 19 19:42:44 srv4hai common[13165]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/grab_shelly.sh
May 19 19:42:44 srv4hai grab_shelly.sh: SHELLY shellyName/1:'shelly-39' - shIP/2:'' perform:'conf'
May 19 19:43:01 srv4hai CRON[13187]: (root) CMD (/etc/linetest/linetest.sh)
May 19 19:43:07 srv4hai postfix/smtp[13157]: connect to mx.insight.synacor.com[208.47.185.21]:25: Connection timed out
May 19 19:43:07 srv4hai postfix/smtp[13157]: A47EB634A6: to=, relay=none, delay=399369, delays=399339/0.01/30/0, dsn=4.4.1, status=deferred (connect to mx.insight.synacor.com[208.47.185.21]:25: Connection timed out)
May 19 19:43:22 srv4hai dhcpd[22856]: DHCPDISCOVER from e8:78:29:c0:c2:4e via enp2s0
May 19 19:43:22 srv4hai dhcpd[22856]: DHCPOFFER on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0
May 19 19:43:22 srv4hai dhcpd[22856]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
May 19 19:43:22 srv4hai dhcpd[22856]: execute_statement argv[1] = 192.168.12.61
May 19 19:43:22 srv4hai dhcpd[22856]: execute_statement argv[2] = fpThomas
May 19 19:43:22 srv4hai common[13201]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/smartphone.sh
May 19 19:43:22 srv4hai smartphone.sh: SHELLY - ping_SP.sh started for phoneIP: '192.168.12.61' / 'fpThomas' / '0'
May 19 19:43:22 srv4hai common[13215]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//ping_SP.sh
May 19 19:43:22 srv4hai dhcpd[22856]: DHCPREQUEST for 192.168.12.61 (192.168.12.252) from e8:78:29:c0:c2:4e via enp2s0
May 19 19:43:22 srv4hai dhcpd[22856]: DHCPACK on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0
May 19 19:43:22 srv4hai named[15509]: client @0x7f930808a950 192.168.12.252#32849/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: fpThomas.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
May 19 19:43:22 srv4hai named[15509]: client @0x7f931c709dd0 192.168.12.252#52833/key rndc-key: signer "rndc-key" approved
May 19 19:43:22 srv4hai named[15509]: client @0x7f931c709dd0 192.168.12.252#52833/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'fpThomas.viehai.at' TXT
May 19 19:43:22 srv4hai named[15509]: client @0x7f931c709dd0 192.168.12.252#52833/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'fpThomas.viehai.at' TXT "310ebd0eafccd9c2e311facf8a4b0e4ad2"
May 19 19:43:22 srv4hai named[15509]: client @0x7f931c709dd0 192.168.12.252#52833/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'fpThomas.viehai.at' A
May 19 19:43:22 srv4hai named[15509]: client @0x7f931c709dd0 192.168.12.252#52833/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'fpThomas.viehai.at' A 192.168.12.61
May 19 19:43:22 srv4hai dhcpd[22856]: Added new forward map from fpThomas.viehai.at to 192.168.12.61
May 19 19:43:22 srv4hai named[15509]: client @0x7f930805ddf0 192.168.12.252#39107/key rndc-key: signer "rndc-key" approved
May 19 19:43:22 srv4hai named[15509]: client @0x7f930805ddf0 192.168.12.252#39107/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '61.12.168.192.in-addr.arpa' PTR
May 19 19:43:22 srv4hai named[15509]: client @0x7f930805ddf0 192.168.12.252#39107/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 19 19:43:22 srv4hai dhcpd[22856]: Added reverse map from 61.12.168.192.in-addr.arpa. to fpThomas.viehai.at
May 19 19:43:22 srv4hai ping_SP[13215]: SHELLY ping_SP.sh started from smartphone, user:'root'
May 19 19:43:29 srv4hai common[13239]: SHELLY common.sh inkludiert von ./bt_scan.sh
May 19 19:43:29 srv4hai common[13252]: SHELLY common.sh inkludiert von ./bt_scan.sh
May 19 19:43:32 srv4hai common[13275]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//check_presence.sh
May 19 19:43:32 srv4hai common.sh: SHELLY: BTstatus[0]:'On' dweetAge:'3'=1716140612-1716140609
May 19 19:43:32 srv4hai check_presence[13275]: SHELLY (0) found /srv/www/viehai/shelly//meter/ping_SP_0.LIVE and On//srv/www/viehai/shelly//meter/BTscan_BTid_0.No
May 19 19:43:32 srv4hai check_presence[13275]: SHELLY (ping_SP__smartphone)-(0) found '/srv/www/viehai/shelly//meter/ping_SP_0.LIVE' for what: 'coming'
May 19 19:43:32 srv4hai common.sh: SHELLY: BTstatus[1]:'On' dweetAge:'3'=1716140612-1716140609
May 19 19:43:32 srv4hai check_presence[13275]: SHELLY (1) found /srv/www/viehai/shelly//meter/ping_SP_1.DEAD and On//srv/www/viehai/shelly//meter/BTscan_BTid_1.No
May 19 19:43:32 srv4hai common.sh: SHELLY: BTstatus[2]:'Off' dweetAge:'3'=1716140612-1716140609
May 19 19:43:32 srv4hai check_presence[13275]: SHELLY (2) found /srv/www/viehai/shelly//meter/ping_SP_2.DEAD and Off//srv/www/viehai/shelly//meter/BTscan_BTid_2.No
May 19 19:43:32 srv4hai check_presence[13275]: SHELLY (ping_SP__smartphone)-(0) what: 'coming' - persPresent: '(BT/LIVE) (BT/__) (__/__)' - anyPresent: 'true' - otherPresent:'true'
May 19 19:43:33 srv4hai check_presence[13275]: SHELLY - check 'coming': anyPresent(true) - lScene(scene_offAll) - sceneONsmall(vorraumOn)
May 19 19:43:33 srv4hai check_presence[13275]: SHELLY (ping_SP__smartphone)-(0)-coming: activate_scenario.shtml: vorraumOn
May 19 19:43:33 srv4hai activate_scenario[13426]: SHELLY scenario: 'vorraumOn(vorraumon)' started
May 19 19:43:33 srv4hai activate_scenario[13426]: SHELLY dayState='day' <--> DayTime=checked NightTime=checked - phoneName:'0'
May 19 19:43:33 srv4hai activate_scenario[13426]: SHELLY scenario: 'vorraumOn(vorraumon)' TO BE EXECUTED, phoneName:'0'
May 19 19:43:33 srv4hai postfix/pickup[32084]: 2273D63452: uid=33 from=
May 19 19:43:33 srv4hai postfix/cleanup[13476]: 2273D63452: message-id=<20240519174333.2273D63452@mail.viehai.at>
May 19 19:43:33 srv4hai postfix/qmgr[15291]: 2273D63452: from=, size=14358, nrcpt=1 (queue active)
May 19 19:43:33 srv4hai activate_scenario[13426]: SHELLY: scenario: 'vorraumOn(vorraumon)'-'shelly-03'-'Licht Vorzimmer' --> '0?turn=on'
May 19 19:43:33 srv4hai proc_url[13492]: SHELLY: OF:'/srv/www/viehai/shelly/log_scenarios/2024/05/19/_0.log' - shellyUsage:'Licht Vorzimmer' - scUrl:'http://192.168.12.103/relay/0?turn=on' - phName:'phoneName:'0''
May 19 19:43:33 srv4hai activate_scenario[13426]: SHELLY scenario: 'vorraumOn(vorraumon)' took 103 msec, phoneName:'0'
May 19 19:43:33 srv4hai ping_SP[13215]: SHELLY check_presence.sh coming 0 ping_SP__smartphone
May 19 19:43:34 srv4hai postfix/relay/smtp[13482]: 2273D63452: to=, relay=mx02.nic.name[209.131.158.56]:25, delay=1.2, delays=0.03/0.02/0.71/0.4, dsn=2.0.0, status=sent (250 2.0.0 Ok: queued as E5930200AF)
May 19 19:43:34 srv4hai postfix/qmgr[15291]: 2273D63452: removed
May 19 19:43:35 srv4hai ping_SP[13215]: SHELLY - new process for '192.168.12.61' - '/srv/www/viehai/shelly//meter/ping_SP_0' @~1716140615
May 19 19:43:39 srv4hai bt_scan[13239]: SHELLY - who: 'Thomas' / phoneName: 'FP4_Thomas' / '0' / shellyIP: '192.168.12.61' - found: 'Yes'
May 19 19:43:39 srv4hai bt_scan[13239]: SHELLY - ping_SP.sh already running for BTmac: '0' - IPv4: '192.168.12.61'
May 19 19:43:39 srv4hai bt_scan[13252]: SHELLY - who: 'Grete' / phoneName: 'S20_Grete' / '1' / shellyIP: '192.168.12.62' - found: 'Yes'
May 19 19:43:39 srv4hai bt_scan[13252]: SHELLY - ping_SP.sh started for BTmac: '1' - IPv4: '192.168.12.62'
May 19 19:43:39 srv4hai common[13574]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//ping_SP.sh
May 19 19:43:39 srv4hai ping_SP[13574]: SHELLY ping_SP.sh started from bt_scan, user:'www-data'
May 19 19:43:39 srv4hai common[13595]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//check_presence.sh
May 19 19:43:40 srv4hai common.sh: SHELLY: BTstatus[0]:'On' dweetAge:'3'=1716140620-1716140617
May 19 19:43:40 srv4hai check_presence[13595]: SHELLY (0) found /srv/www/viehai/shelly//meter/ping_SP_0.LIVE and On//srv/www/viehai/shelly//meter/BTscan_BTid_0.Yes
May 19 19:43:40 srv4hai check_presence[13595]: SHELLY (ping_SP__bt_scan)-(1) found '/srv/www/viehai/shelly//meter/ping_SP_0.LIVE' for what: 'coming'
May 19 19:43:40 srv4hai common.sh: SHELLY: BTstatus[1]:'On' dweetAge:'3'=1716140620-1716140617
May 19 19:43:40 srv4hai check_presence[13595]: SHELLY (1) found /srv/www/viehai/shelly//meter/ping_SP_1.TRY} and On//srv/www/viehai/shelly//meter/BTscan_BTid_1.Yes
May 19 19:43:40 srv4hai common.sh: SHELLY: BTstatus[2]:'Off' dweetAge:'3'=1716140620-1716140617
May 19 19:43:40 srv4hai check_presence[13595]: SHELLY (2) found /srv/www/viehai/shelly//meter/ping_SP_2.DEAD and Off//srv/www/viehai/shelly//meter/BTscan_BTid_2.No
May 19 19:43:40 srv4hai check_presence[13595]: SHELLY (ping_SP__bt_scan)-(1) what: 'coming' - persPresent: '(BT/LIVE) (BT/__) (__/__)' - anyPresent: 'true' - otherPresent:'true'
May 19 19:43:40 srv4hai check_presence[13595]: SHELLY - check 'coming': anyPresent(true) - lScene(scene_vorraumOn) - sceneONsmall(vorraumOn)
May 19 19:43:40 srv4hai check_presence[13595]: SHELLY (ping_SP__bt_scan)-(1)-coming: activate_scenario.shtml: vorraumOn
May 19 19:43:40 srv4hai activate_scenario[13746]: SHELLY scenario: 'vorraumOn(vorraumon)' started
May 19 19:43:40 srv4hai activate_scenario[13746]: SHELLY dayState='day' <--> DayTime=checked NightTime=checked - phoneName:'1'

finished