
    <p>
      <strong><code>activate_scenario.sh</code></strong><br />
      Sun Dec 29 15:19:33 CET 2024<br />
      scenario: <code>vorraumOn</code><br />
      phoneName: <code>1</code><br />
    </p>
   'day' <--> DayTime=checked , NightTime=checked<br />
    -> execution of scenario<br />
    -> sending email(s) to 'thomas@miglinci.name' <br />
    <h3>scenario: 'Vorzimmer EIN' - 'vorraumOn' - 'vorraumon'</h3>
    <h4>VZ Licht (/srv/www/viehai/shelly/log_scenarios/2024/12/29/_0.log)</h4>
    <p>vorher: 15:19:33.950</p>
      <table class='myTable'>
        <tr><th style='text-align:left;'><code>http://192.168.12.103/relay/0?turn=on</code></th></tr>
        <tr><td style='text-align:left;'>
          <pre style='margin:1px;'>  "ison": true,
  "has_timer": false,
  "timer_started": 0,
  "timer_duration": 0,
  "timer_remaining": 0,
  "overpower": false,
  "source": "http"</pre>
        </td></tr>
      </table>
    <p>
      nachher: 15:19:34.090<br/>
      Dauer: 139 msec
      phName: 'phoneName:'1''
    </p>
    <hr/>
    <h3>scenario: 'Vorzimmer EIN' - 'vorraumOn' - 'vorraumon'</h3>
    <p>
      urls   : 1<br/>
      vorher : 15:19:33.932<br/>
      nachher: 15:19:34.096<br/>
      Dauer  : 163 msec<br/>
      outfile: '/srv/www/viehai/shelly/log_scenarios/2024/12/29//scene_vorraumOn_20241229_151933.txt'<br/>
    </p>
    <hr/>
<h3>syslog</h3>
<pre>Dec 29 15:16:01 srv4hai CRON[31049]: (root) CMD (/etc/linetest/linetest.sh)
Dec 29 15:16:21 srv4hai dhcpd[4995]: DHCPDISCOVER from 98:cd:ac:2e:1c:da via enp2s0
Dec 29 15:16:21 srv4hai dhcpd[4995]: DHCPOFFER on 192.168.12.122 to 98:cd:ac:2e:1c:da via enp2s0
Dec 29 15:16:21 srv4hai dhcpd[4995]: execute_statement argv[0] = /srv/www/viehai/shelly/json.sh
Dec 29 15:16:21 srv4hai dhcpd[4995]: execute_statement argv[1] = 192.168.12.122
Dec 29 15:16:21 srv4hai dhcpd[4995]: execute_statement argv[2] = shelly-22
Dec 29 15:16:21 srv4hai dhcpd[4995]: DHCPREQUEST for 192.168.12.122 (192.168.12.252) from 98:cd:ac:2e:1c:da via enp2s0
Dec 29 15:16:21 srv4hai dhcpd[4995]: DHCPACK on 192.168.12.122 to 98:cd:ac:2e:1c:da via enp2s0
Dec 29 15:16:21 srv4hai named[30406]: client @0x7f93300c1420 192.168.12.252#33895/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: shelly-22.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Dec 29 15:16:21 srv4hai named[30406]: client @0x7f932c0c3e90 192.168.12.252#45913/key rndc-key: signer "rndc-key" approved
Dec 29 15:16:21 srv4hai named[30406]: client @0x7f932c0c3e90 192.168.12.252#45913/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'shelly-22.viehai.at' TXT
Dec 29 15:16:21 srv4hai named[30406]: client @0x7f932c0c3e90 192.168.12.252#45913/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'shelly-22.viehai.at' TXT "0051fce7ef7edfb3d29643aefa7bc5b7f2"
Dec 29 15:16:21 srv4hai named[30406]: client @0x7f932c0c3e90 192.168.12.252#45913/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'shelly-22.viehai.at' A
Dec 29 15:16:21 srv4hai named[30406]: client @0x7f932c0c3e90 192.168.12.252#45913/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'shelly-22.viehai.at' A 192.168.12.122
Dec 29 15:16:21 srv4hai dhcpd[4995]: Added new forward map from shelly-22.viehai.at to 192.168.12.122
Dec 29 15:16:21 srv4hai named[30406]: client @0x7f93480ae320 192.168.12.252#42717/key rndc-key: signer "rndc-key" approved
Dec 29 15:16:21 srv4hai named[30406]: client @0x7f93480ae320 192.168.12.252#42717/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '122.12.168.192.in-addr.arpa' PTR
Dec 29 15:16:21 srv4hai named[30406]: client @0x7f93480ae320 192.168.12.252#42717/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': adding an RR at '122.12.168.192.in-addr.arpa' PTR shelly-22.viehai.at.
Dec 29 15:16:21 srv4hai dhcpd[4995]: Added reverse map from 122.12.168.192.in-addr.arpa. to shelly-22.viehai.at
Dec 29 15:16:21 srv4hai common[31066]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/grab_shelly.sh
Dec 29 15:16:21 srv4hai grab_shelly.sh: SHELLY shellyName/1:'shelly-22' - shIP/2:'' perform:'conf'
Dec 29 15:16:22 srv4hai mosquitto[29419]: New connection from 192.168.12.122 on port 1883.
Dec 29 15:16:22 srv4hai mosquitto[29419]: Client shelly1-98CDAC2E1CDA already connected, closing old connection.
Dec 29 15:16:22 srv4hai mosquitto[29419]: Socket error on client shelly1-98CDAC2E1CDA, disconnecting.
Dec 29 15:16:22 srv4hai mosquitto[29419]: New client connected from 192.168.12.122 as shelly1-98CDAC2E1CDA (c1, k60, u'shmqtt').
Dec 29 15:16:23 srv4hai conf_shelly.sh: SHELLY: PARAMS  shName/1:'shelly-22' - shIP/2:'192.168.12.122'
Dec 29 15:16:23 srv4hai conf_shelly.sh: SHELLY: usage/name 'WC Licht' already set for '192.168.12.122'
Dec 29 15:16:23 srv4hai conf_shelly.sh: SHELLY:'shelly-22' 'conf(0)' - allready transmitted - skipping
Dec 29 15:16:23 srv4hai conf_shelly.sh: SHELLY:'shelly-22' 'conf(1)' - allready transmitted - skipping
Dec 29 15:16:23 srv4hai conf_shelly.sh: SHELLY:'shelly-22' 'conf(2)' - allready transmitted - skipping
Dec 29 15:16:23 srv4hai conf_shelly.sh: SHELLY:'shelly-22' 'conf(3)' - allready transmitted - skipping
Dec 29 15:16:23 srv4hai conf_shelly.sh: SHELLY:'shelly-22' 'conf(4)' - allready transmitted - skipping
Dec 29 15:16:24 srv4hai fetchmail[1651]: 7 messages (7 seen) for rozmig at mail.akis.at (1753652 octets).
Dec 29 15:16:25 srv4hai fetchmail[1651]: 60348 messages (60348 seen) for tmiglinci at mail.akis.at (662828153 octets).
Dec 29 15:16:25 srv4hai fetchmail[1651]: 11304 messages (11304 seen) for srozum at mail.akis.at (1353375925 octets).
Dec 29 15:16:26 srv4hai fetchmail[1651]: 28090 messages (28090 seen) for mrozum at mail.akis.at (526278035 octets).
Dec 29 15:16:26 srv4hai fetchmail[1651]: 1162 messages (1162 seen) for admin@bastelstube.wien at pop3.world4you.com (52206525 octets).
Dec 29 15:16:48 srv4hai postfix/smtpd[31186]: connect from unknown[80.94.95.239]
Dec 29 15:16:50 srv4hai postfix/smtpd[31186]: warning: unknown[80.94.95.239]: SASL LOGIN authentication failed: Invalid authentication mechanism
Dec 29 15:16:51 srv4hai postfix/smtpd[31186]: disconnect from unknown[80.94.95.239] ehlo=1 auth=0/1 rset=1 quit=1 commands=3/4
Dec 29 15:16:55 srv4hai dhcpd[4995]: DHCPDISCOVER from 98:cd:ac:2e:1c:da via enp2s0
Dec 29 15:16:55 srv4hai dhcpd[4995]: DHCPOFFER on 192.168.12.122 to 98:cd:ac:2e:1c:da via enp2s0
Dec 29 15:16:55 srv4hai dhcpd[4995]: execute_statement argv[0] = /srv/www/viehai/shelly/json.sh
Dec 29 15:16:55 srv4hai dhcpd[4995]: execute_statement argv[1] = 192.168.12.122
Dec 29 15:16:55 srv4hai dhcpd[4995]: execute_statement argv[2] = shelly-22
Dec 29 15:16:55 srv4hai dhcpd[4995]: DHCPREQUEST for 192.168.12.122 (192.168.12.252) from 98:cd:ac:2e:1c:da via enp2s0
Dec 29 15:16:55 srv4hai dhcpd[4995]: DHCPACK on 192.168.12.122 to 98:cd:ac:2e:1c:da via enp2s0
Dec 29 15:16:55 srv4hai named[30406]: client @0x7f933004a8d0 192.168.12.252#36691/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: shelly-22.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Dec 29 15:16:55 srv4hai named[30406]: client @0x7f9348081920 192.168.12.252#59399/key rndc-key: signer "rndc-key" approved
Dec 29 15:16:55 srv4hai named[30406]: client @0x7f9348081920 192.168.12.252#59399/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'shelly-22.viehai.at' TXT
Dec 29 15:16:55 srv4hai named[30406]: client @0x7f9348081920 192.168.12.252#59399/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'shelly-22.viehai.at' TXT "0051fce7ef7edfb3d29643aefa7bc5b7f2"
Dec 29 15:16:55 srv4hai named[30406]: client @0x7f9348081920 192.168.12.252#59399/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'shelly-22.viehai.at' A
Dec 29 15:16:55 srv4hai named[30406]: client @0x7f9348081920 192.168.12.252#59399/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'shelly-22.viehai.at' A 192.168.12.122
Dec 29 15:16:55 srv4hai dhcpd[4995]: Added new forward map from shelly-22.viehai.at to 192.168.12.122
Dec 29 15:16:55 srv4hai named[30406]: client @0x7f93100d6a50 192.168.12.252#40471/key rndc-key: signer "rndc-key" approved
Dec 29 15:16:55 srv4hai named[30406]: client @0x7f93100d6a50 192.168.12.252#40471/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '122.12.168.192.in-addr.arpa' PTR
Dec 29 15:16:55 srv4hai named[30406]: client @0x7f93100d6a50 192.168.12.252#40471/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': adding an RR at '122.12.168.192.in-addr.arpa' PTR shelly-22.viehai.at.
Dec 29 15:16:55 srv4hai dhcpd[4995]: Added reverse map from 122.12.168.192.in-addr.arpa. to shelly-22.viehai.at
Dec 29 15:16:55 srv4hai common[31188]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/grab_shelly.sh
Dec 29 15:16:55 srv4hai grab_shelly.sh: SHELLY shellyName/1:'shelly-22' - shIP/2:'' perform:'conf'
Dec 29 15:16:56 srv4hai mosquitto[29419]: New connection from 192.168.12.122 on port 1883.
Dec 29 15:16:56 srv4hai mosquitto[29419]: Client shelly1-98CDAC2E1CDA already connected, closing old connection.
Dec 29 15:16:56 srv4hai mosquitto[29419]: Socket error on client shelly1-98CDAC2E1CDA, disconnecting.
Dec 29 15:16:56 srv4hai mosquitto[29419]: New client connected from 192.168.12.122 as shelly1-98CDAC2E1CDA (c1, k60, u'shmqtt').
Dec 29 15:16:56 srv4hai conf_shelly.sh: SHELLY: PARAMS  shName/1:'shelly-22' - shIP/2:'192.168.12.122'
Dec 29 15:16:56 srv4hai conf_shelly.sh: SHELLY: usage/name 'WC Licht' already set for '192.168.12.122'
Dec 29 15:16:56 srv4hai conf_shelly.sh: SHELLY:'shelly-22' 'conf(0)' - allready transmitted - skipping
Dec 29 15:16:56 srv4hai conf_shelly.sh: SHELLY:'shelly-22' 'conf(1)' - allready transmitted - skipping
Dec 29 15:16:56 srv4hai conf_shelly.sh: SHELLY:'shelly-22' 'conf(2)' - allready transmitted - skipping
Dec 29 15:16:56 srv4hai conf_shelly.sh: SHELLY:'shelly-22' 'conf(3)' - allready transmitted - skipping
Dec 29 15:16:56 srv4hai conf_shelly.sh: SHELLY:'shelly-22' 'conf(4)' - allready transmitted - skipping
Dec 29 15:17:01 srv4hai CRON[31300]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)
Dec 29 15:17:01 srv4hai CRON[31301]: (root) CMD (/etc/linetest/linetest.sh)
Dec 29 15:18:01 srv4hai CRON[31326]: (root) CMD (/etc/linetest/linetest.sh)
Dec 29 15:19:01 srv4hai CRON[31348]: (root) CMD (/etc/linetest/linetest.sh)
Dec 29 15:19:23 srv4hai common[31359]: SHELLY common.sh inkludiert von ./bt_scan.sh
Dec 29 15:19:23 srv4hai common[31374]: SHELLY common.sh inkludiert von ./bt_scan.sh
Dec 29 15:19:23 srv4hai bt_scan[31359]: SHELLY - who: 'Thomas' / phoneName: 'FP4_Thomas' / '0' / shellyIP: '192.168.12.61' - found: 'Yes'
Dec 29 15:19:23 srv4hai bt_scan[31359]: SHELLY - ping_SP.sh started for BTmac: '0' - IPv4: '192.168.12.61'
Dec 29 15:19:23 srv4hai common[31404]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//ping_SP.sh
Dec 29 15:19:23 srv4hai ping_SP[31404]: SHELLY ping_SP.sh started from bt_scan, user:'www-data'
Dec 29 15:19:23 srv4hai common[31425]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//check_presence.sh
Dec 29 15:19:23 srv4hai common.sh: SHELLY: BTstatus[0]:'BTdweet_not_set' dweetAge:'0'=1735481963-1735481963
Dec 29 15:19:23 srv4hai check_presence[31425]: SHELLY (0) found /srv/www/viehai/shelly//meter/ping_SP_0.TRY} and BTdweet_not_set//srv/www/viehai/shelly//meter/BTscan_BTid_0.Yes
Dec 29 15:19:23 srv4hai common.sh: SHELLY: BTstatus[1]:'BTdweet_not_set' dweetAge:'0'=1735481963-1735481963
Dec 29 15:19:23 srv4hai check_presence[31425]: SHELLY (1) found /srv/www/viehai/shelly//meter/ping_SP_1.DEAD and BTdweet_not_set//srv/www/viehai/shelly//meter/BTscan_BTid_1.No
Dec 29 15:19:23 srv4hai check_presence[31425]: SHELLY (ping_SP__bt_scan)-(0) what: 'coming' - persPresent: '(__/__) (__/__)' - anyPresent: 'false' - otherPresent:'false'
Dec 29 15:19:23 srv4hai postfix/pickup[23732]: B180B63463: uid=33 from=<www-data>
Dec 29 15:19:23 srv4hai postfix/cleanup[31501]: B180B63463: message-id=<20241229141923.B180B63463@mail.viehai.at>
Dec 29 15:19:23 srv4hai postfix/qmgr[8566]: B180B63463: from=<www-data@mail.viehai.at>, size=11110, nrcpt=1 (queue active)
Dec 29 15:19:23 srv4hai check_presence[31425]: SHELLY - check 'coming': anyPresent(false) - lScene(scene_offAll) - sceneONsmall(vorraumOn)
Dec 29 15:19:23 srv4hai check_presence[31425]: SHELLY (ping_SP__bt_scan)-(0)-coming: NOT activate_scenario.shtml: vorraumOn
Dec 29 15:19:23 srv4hai ping_SP[31404]: SHELLY check_presence.sh coming 0 ping_SP__bt_scan
Dec 29 15:19:23 srv4hai ping_SP[31404]: SHELLY - new process for '192.168.12.61' - '/srv/www/viehai/shelly//meter/ping_SP_0' @~1735481963
Dec 29 15:19:25 srv4hai postfix/relay/smtp[31508]: B180B63463: to=<thomas@miglinci.name>, relay=mx02.nic.name[209.131.158.56]:25, delay=1.7, delays=0.03/0.01/0.52/1.1, dsn=2.0.0, status=sent (250 2.0.0 Ok: queued as 5297180033)
Dec 29 15:19:25 srv4hai postfix/qmgr[8566]: B180B63463: removed
Dec 29 15:19:33 srv4hai bt_scan[31374]: SHELLY - who: 'Grete' / phoneName: 'S20_Grete' / '1' / shellyIP: '192.168.12.62' - found: 'Yes'
Dec 29 15:19:33 srv4hai bt_scan[31374]: SHELLY - ping_SP.sh started for BTmac: '1' - IPv4: '192.168.12.62'
Dec 29 15:19:33 srv4hai common[31563]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//ping_SP.sh
Dec 29 15:19:33 srv4hai ping_SP[31563]: SHELLY ping_SP.sh started from bt_scan, user:'www-data'
Dec 29 15:19:33 srv4hai common[31584]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//check_presence.sh
Dec 29 15:19:33 srv4hai common.sh: SHELLY: BTstatus[0]:'On' dweetAge:'4'=1735481973-1735481969
Dec 29 15:19:33 srv4hai check_presence[31584]: SHELLY (0) found /srv/www/viehai/shelly//meter/ping_SP_0.TRY} and On//srv/www/viehai/shelly//meter/BTscan_BTid_0.Yes
Dec 29 15:19:33 srv4hai common.sh: SHELLY: BTstatus[1]:'On' dweetAge:'4'=1735481973-1735481969
Dec 29 15:19:33 srv4hai check_presence[31584]: SHELLY (1) found /srv/www/viehai/shelly//meter/ping_SP_1.TRY} and On//srv/www/viehai/shelly//meter/BTscan_BTid_1.Yes
Dec 29 15:19:33 srv4hai check_presence[31584]: SHELLY (ping_SP__bt_scan)-(1) what: 'coming' - persPresent: '(BT/__) (BT/__)' - anyPresent: 'true' - otherPresent:'true'
Dec 29 15:19:33 srv4hai check_presence[31584]: SHELLY - check 'coming': anyPresent(true) - lScene(scene_offAll) - sceneONsmall(vorraumOn)
Dec 29 15:19:33 srv4hai check_presence[31584]: SHELLY (ping_SP__bt_scan)-(1)-coming: activate_scenario.shtml: vorraumOn
Dec 29 15:19:33 srv4hai activate_scenario[31697]: SHELLY last scenario: 'offAll' @ '093059' from 'scene_offAll_20241229_093059.txt' - delta=26
Dec 29 15:19:33 srv4hai activate_scenario[31697]:        dateutils.ddiff -i '%Y%m%d%H%M%s' 20241229151933 20241229093059  -f '%S'
Dec 29 15:19:33 srv4hai activate_scenario[31697]: SHELLY  new scenario: 'vorraumOn' different - GO ON
Dec 29 15:19:33 srv4hai activate_scenario[31697]: SHELLY scenario: 'vorraumOn(vorraumon)' started
Dec 29 15:19:33 srv4hai activate_scenario[31697]: SHELLY dayState='day' <--> DayTime=checked NightTime=checked - phoneName:'1'</pre><br/>
<p>finished</p>