activate_scenario.sh
Sat Aug 10 09:04:56 CEST 2024
scenario: vorraumOn
phoneName: 1

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

Licht Vorzimmer (/srv/www/viehai/shelly/log_scenarios/2024/08/10/_0.log)

vorher: 09:04:56.707

  "ison": true,
  "has_timer": false,
  "timer_started": 0,
  "timer_duration": 0,
  "timer_remaining": 0,
  "overpower": false,
  "source": "http"

nachher: 09:04:56.786
Dauer: 78 msec phName: 'phoneName:'1''

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

urls : 1
vorher : 09:04:56.687
nachher: 09:04:56.792
Dauer : 105 msec
outfile: '/srv/www/viehai/shelly/log_scenarios/2024/08/10//scene_vorraumOn_20240810_090456.txt'

syslog

Aug 10 09:01:01 srv4hai CRON[1933]: (root) CMD (/etc/linetest/linetest.sh)
Aug 10 09:01:23 srv4hai postfix/anvil[1011]: statistics: max connection rate 1/60s for (smtp:178.215.236.133) at Aug 10 08:58:03
Aug 10 09:01:23 srv4hai postfix/anvil[1011]: statistics: max connection count 1 for (smtp:178.215.236.133) at Aug 10 08:58:03
Aug 10 09:01:23 srv4hai postfix/anvil[1011]: statistics: max cache size 1 at Aug 10 08:58:03
Aug 10 09:02:01 srv4hai CRON[1947]: (root) CMD (/etc/linetest/linetest.sh)
Aug 10 09:02:14 srv4hai postfix/smtpd[1952]: connect from unknown[178.215.236.216]
Aug 10 09:02:15 srv4hai postfix/smtpd[1952]: warning: unknown[178.215.236.216]: SASL LOGIN authentication failed: Invalid authentication mechanism
Aug 10 09:02:15 srv4hai postfix/smtpd[1952]: disconnect from unknown[178.215.236.216] ehlo=1 auth=0/1 quit=1 commands=2/3
Aug 10 09:02:18 srv4hai postfix/smtpd[1952]: connect from starttls-virginia.securing-email.com[34.227.19.103]
Aug 10 09:02:19 srv4hai postfix/smtpd[1952]: disconnect from starttls-virginia.securing-email.com[34.227.19.103] ehlo=2 starttls=1 quit=1 commands=4
Aug 10 09:02:27 srv4hai postfix/smtpd[1952]: connect from unknown[178.215.236.133]
Aug 10 09:02:27 srv4hai postfix/smtpd[1952]: warning: unknown[178.215.236.133]: SASL LOGIN authentication failed: Invalid authentication mechanism
Aug 10 09:02:27 srv4hai postfix/smtpd[1952]: disconnect from unknown[178.215.236.133] ehlo=1 auth=0/1 quit=1 commands=2/3
Aug 10 09:03:01 srv4hai CRON[1958]: (root) CMD (/etc/linetest/linetest.sh)
Aug 10 09:03:04 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=1966, secured, session=
Aug 10 09:03:04 srv4hai dovecot: imap(miglincit)<1966>: Logged out in=137 out=1088 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Aug 10 09:04:01 srv4hai CRON[1975]: (root) CMD (/etc/linetest/linetest.sh)
Aug 10 09:04:18 srv4hai common[1982]: SHELLY common.sh inkludiert von ./bt_scan.sh
Aug 10 09:04:18 srv4hai bt_scan[1982]: SHELLY - who: 'Thomas' / phoneName: 'FP4_Thomas' / '0' / shellyIP: '192.168.12.61' - found: 'Yes'
Aug 10 09:04:18 srv4hai bt_scan[1982]: SHELLY - ping_SP.sh started for BTmac: '0' - IPv4: '192.168.12.61'
Aug 10 09:04:18 srv4hai common[2013]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//ping_SP.sh
Aug 10 09:04:18 srv4hai ping_SP[2013]: SHELLY ping_SP.sh started from bt_scan, user:'www-data'
Aug 10 09:04:18 srv4hai common[2034]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//check_presence.sh
Aug 10 09:04:19 srv4hai common.sh: SHELLY: BTstatus[0]:'BTdweet_not_set' dweetAge:'0'=1723273459-1723273459
Aug 10 09:04:19 srv4hai check_presence[2034]: 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
Aug 10 09:04:19 srv4hai common.sh: SHELLY: BTstatus[1]:'BTdweet_not_set' dweetAge:'0'=1723273459-1723273459
Aug 10 09:04:19 srv4hai check_presence[2034]: 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
Aug 10 09:04:19 srv4hai common.sh: SHELLY: BTstatus[2]:'BTdweet_not_set' dweetAge:'0'=1723273459-1723273459
Aug 10 09:04:19 srv4hai check_presence[2034]: SHELLY (2) found /srv/www/viehai/shelly//meter/ping_SP_2.DEAD and BTdweet_not_set//srv/www/viehai/shelly//meter/BTscan_BTid_2.No
Aug 10 09:04:19 srv4hai check_presence[2034]: SHELLY (ping_SP__bt_scan)-(0) what: 'coming' - persPresent: '(__/__) (__/__) (__/__)' - anyPresent: 'false' - otherPresent:'false'
Aug 10 09:04:19 srv4hai postfix/pickup[20551]: 836516345B: uid=33 from=
Aug 10 09:04:19 srv4hai postfix/cleanup[2142]: 836516345B: message-id=<20240810070419.836516345B@mail.viehai.at>
Aug 10 09:04:19 srv4hai postfix/qmgr[8566]: 836516345B: from=, size=4235, nrcpt=1 (queue active)
Aug 10 09:04:19 srv4hai check_presence[2034]: SHELLY - check 'coming': anyPresent(false) - lScene(scene_offAll) - sceneONsmall(vorraumOn)
Aug 10 09:04:19 srv4hai check_presence[2034]: SHELLY (ping_SP__bt_scan)-(0)-coming: NOT activate_scenario.shtml: vorraumOn
Aug 10 09:04:19 srv4hai ping_SP[2013]: SHELLY check_presence.sh coming 0 ping_SP__bt_scan
Aug 10 09:04:19 srv4hai ping_SP[2013]: SHELLY - new process for '192.168.12.61' - '/srv/www/viehai/shelly//meter/ping_SP_0' @~1723273459
Aug 10 09:04:20 srv4hai postfix/relay/smtp[2149]: 836516345B: to=, relay=mx03.nic.name[209.131.161.54]:25, delay=1, delays=0.04/0.01/0.7/0.25, dsn=2.0.0, status=sent (250 2.0.0 Ok: queued as 5108F800A0)
Aug 10 09:04:20 srv4hai postfix/qmgr[8566]: 836516345B: removed
Aug 10 09:04:25 srv4hai dhcpd[30731]: DHCPDISCOVER from f0:39:65:25:8d:c7 via enp2s0
Aug 10 09:04:25 srv4hai dhcpd[30731]: DHCPOFFER on 192.168.12.62 to f0:39:65:25:8d:c7 via enp2s0
Aug 10 09:04:25 srv4hai dhcpd[30731]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Aug 10 09:04:25 srv4hai dhcpd[30731]: execute_statement argv[1] = 192.168.12.62
Aug 10 09:04:25 srv4hai dhcpd[30731]: execute_statement argv[2] = GreteS20
Aug 10 09:04:25 srv4hai common[2183]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/smartphone.sh
Aug 10 09:04:28 srv4hai fetchmail[1651]: 7 messages (7 seen) for rozmig at mail.akis.at (1753652 octets).
Aug 10 09:04:29 srv4hai fetchmail[1651]: 49799 messages (49797 seen) for tmiglinci at mail.akis.at (-172378218 octets).
Aug 10 09:04:29 srv4hai postfix/smtpd[2195]: connect from localhost[127.0.0.1]
Aug 10 09:04:29 srv4hai postfix/smtpd[2195]: 0F5976004E: client=localhost[127.0.0.1]
Aug 10 09:04:29 srv4hai postfix/cleanup[2142]: 0F5976004E: message-id=
Aug 10 09:04:29 srv4hai fetchmail[1651]: reading message tmiglinci@mail.akis.at:49798 of 49799 (237787 octets) not flushed
Aug 10 09:04:29 srv4hai postfix/qmgr[8566]: 0F5976004E: from=, size=238062, nrcpt=1 (queue active)
Aug 10 09:04:29 srv4hai postfix/local[2197]: 0F5976004E: to=, relay=local, delay=0.15, delays=0.12/0/0/0.02, dsn=2.0.0, status=sent (delivered to command: /bin/procmail)
Aug 10 09:04:29 srv4hai postfix/qmgr[8566]: 0F5976004E: removed
Aug 10 09:04:29 srv4hai postfix/smtpd[2195]: 33C006004E: client=localhost[127.0.0.1]
Aug 10 09:04:29 srv4hai postfix/cleanup[2142]: 33C006004E: message-id=<20240810070419.836516345B@mail.viehai.at>
Aug 10 09:04:29 srv4hai fetchmail[1651]: reading message tmiglinci@mail.akis.at:49799 of 49799 (5216 octets) not flushed
Aug 10 09:04:29 srv4hai postfix/qmgr[8566]: 33C006004E: from=, size=5509, nrcpt=1 (queue active)
Aug 10 09:04:29 srv4hai postfix/smtpd[2195]: disconnect from localhost[127.0.0.1] ehlo=1 mail=2 rcpt=2 data=2 quit=1 commands=8
Aug 10 09:04:29 srv4hai postfix/local[2197]: 33C006004E: to=, relay=local, delay=0.07, delays=0.05/0/0/0.02, dsn=2.0.0, status=sent (delivered to command: /bin/procmail)
Aug 10 09:04:29 srv4hai postfix/qmgr[8566]: 33C006004E: removed
Aug 10 09:04:29 srv4hai fetchmail[1651]: 10566 messages (10566 seen) for srozum at mail.akis.at (1270289795 octets).
Aug 10 09:04:30 srv4hai fetchmail[1651]: 25235 messages (25234 seen) for mrozum at mail.akis.at (107597821 octets).
Aug 10 09:04:30 srv4hai postfix/smtpd[2195]: connect from localhost[127.0.0.1]
Aug 10 09:04:30 srv4hai postfix/smtpd[2195]: 4E75E6004E: client=localhost[127.0.0.1]
Aug 10 09:04:30 srv4hai postfix/cleanup[2142]: 4E75E6004E: message-id=
Aug 10 09:04:30 srv4hai fetchmail[1651]: reading message mrozum@mail.akis.at:25235 of 25235 (237992 octets) not flushed
Aug 10 09:04:30 srv4hai postfix/qmgr[8566]: 4E75E6004E: from=, size=238262, nrcpt=1 (queue active)
Aug 10 09:04:30 srv4hai postfix/smtpd[2195]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1 data=1 quit=1 commands=5
Aug 10 09:04:30 srv4hai postfix/local[2197]: 4E75E6004E: to=, relay=local, delay=0.14, delays=0.12/0/0/0.02, dsn=2.0.0, status=sent (delivered to command: /bin/procmail)
Aug 10 09:04:30 srv4hai postfix/qmgr[8566]: 4E75E6004E: removed
Aug 10 09:04:30 srv4hai fetchmail[1651]: 1139 messages (1139 seen) for admin@bastelstube.wien at pop3.world4you.com (51570784 octets).
Aug 10 09:04:35 srv4hai smartphone.sh: SHELLY - ping_SP.sh started for phoneIP: '192.168.12.62' / 'GreteS20' / '1'
Aug 10 09:04:35 srv4hai common[2206]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//ping_SP.sh
Aug 10 09:04:35 srv4hai dhcpd[30731]: DHCPREQUEST for 192.168.12.62 (192.168.12.252) from f0:39:65:25:8d:c7 via enp2s0
Aug 10 09:04:35 srv4hai dhcpd[30731]: DHCPACK on 192.168.12.62 to f0:39:65:25:8d:c7 via enp2s0
Aug 10 09:04:35 srv4hai dhcpd[30731]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Aug 10 09:04:35 srv4hai dhcpd[30731]: execute_statement argv[1] = 192.168.12.62
Aug 10 09:04:35 srv4hai dhcpd[30731]: execute_statement argv[2] = GreteS20
Aug 10 09:04:35 srv4hai common[2219]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/smartphone.sh
Aug 10 09:04:35 srv4hai ping_SP[2206]: SHELLY ping_SP.sh started from smartphone, user:'root'
Aug 10 09:04:42 srv4hai ping_SP[2013]: SHELLY (0)- ping 192.168.12.61 0 not answered (1) @~1723273482 - 'ping_SP_0'
Aug 10 09:04:45 srv4hai smartphone.sh: SHELLY - ping_SP.sh started for phoneIP: '192.168.12.62' / 'GreteS20' / '1'
Aug 10 09:04:45 srv4hai common[2259]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//ping_SP.sh
Aug 10 09:04:45 srv4hai dhcpd[30731]: DHCPREQUEST for 192.168.12.62 (192.168.12.252) from f0:39:65:25:8d:c7 via enp2s0
Aug 10 09:04:45 srv4hai dhcpd[30731]: DHCPACK on 192.168.12.62 to f0:39:65:25:8d:c7 via enp2s0
Aug 10 09:04:45 srv4hai dhcpd[30731]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Aug 10 09:04:45 srv4hai dhcpd[30731]: execute_statement argv[1] = 192.168.12.62
Aug 10 09:04:45 srv4hai dhcpd[30731]: execute_statement argv[2] = GreteS20
Aug 10 09:04:45 srv4hai named[30406]: client @0x7f93480bd120 192.168.12.252#32877/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: GreteS20.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Aug 10 09:04:45 srv4hai common[2276]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//check_presence.sh
Aug 10 09:04:45 srv4hai common[2279]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/smartphone.sh
Aug 10 09:04:45 srv4hai ping_SP[2259]: SHELLY ping_SP.sh started from smartphone, user:'root'
Aug 10 09:04:46 srv4hai common.sh: SHELLY: BTstatus[0]:'On' dweetAge:'4'=1723273486-1723273482
Aug 10 09:04:46 srv4hai check_presence[2276]: SHELLY (0) found /srv/www/viehai/shelly//meter/ping_SP_0.TRY and On//srv/www/viehai/shelly//meter/BTscan_BTid_0.Yes
Aug 10 09:04:46 srv4hai check_presence[2276]: SHELLY (ping_SP__smartphone)-(1) found '/srv/www/viehai/shelly//meter/ping_SP_0.TRY' for what: 'coming'
Aug 10 09:04:46 srv4hai common.sh: SHELLY: BTstatus[1]:'Off' dweetAge:'4'=1723273486-1723273482
Aug 10 09:04:46 srv4hai check_presence[2276]: SHELLY (1) found /srv/www/viehai/shelly//meter/ping_SP_1.LIVE and Off//srv/www/viehai/shelly//meter/BTscan_BTid_1.No
Aug 10 09:04:46 srv4hai check_presence[2276]: SHELLY (ping_SP__smartphone)-(1) found '/srv/www/viehai/shelly//meter/ping_SP_1.LIVE' for what: 'coming'
Aug 10 09:04:46 srv4hai common.sh: SHELLY: BTstatus[2]:'Off' dweetAge:'4'=1723273486-1723273482
Aug 10 09:04:46 srv4hai check_presence[2276]: SHELLY (2) found /srv/www/viehai/shelly//meter/ping_SP_2.DEAD and Off//srv/www/viehai/shelly//meter/BTscan_BTid_2.No
Aug 10 09:04:46 srv4hai check_presence[2276]: SHELLY (ping_SP__smartphone)-(1) what: 'coming' - persPresent: '(BT/TRY) (__/LIVE) (__/__)' - anyPresent: 'true' - otherPresent:'true'
Aug 10 09:04:46 srv4hai check_presence[2276]: SHELLY - check 'coming': anyPresent(true) - lScene(scene_offAll) - sceneONsmall(vorraumOn)
Aug 10 09:04:46 srv4hai check_presence[2276]: SHELLY (ping_SP__smartphone)-(1)-coming: activate_scenario.shtml: vorraumOn
Aug 10 09:04:46 srv4hai activate_scenario[2477]: SHELLY scenario: 'vorraumOn(vorraumon)' started
Aug 10 09:04:46 srv4hai activate_scenario[2477]: SHELLY dayState='day' <--> DayTime=checked NightTime=checked - phoneName:'1'
Aug 10 09:04:46 srv4hai activate_scenario[2477]: SHELLY scenario: 'vorraumOn(vorraumon)' TO BE EXECUTED, phoneName:'1'
Aug 10 09:04:46 srv4hai postfix/pickup[20551]: 97D296345B: uid=33 from=
Aug 10 09:04:46 srv4hai postfix/cleanup[2142]: 97D296345B: message-id=<20240810070446.97D296345B@mail.viehai.at>
Aug 10 09:04:46 srv4hai postfix/qmgr[8566]: 97D296345B: from=, size=13378, nrcpt=1 (queue active)
Aug 10 09:04:46 srv4hai activate_scenario[2477]: SHELLY: scenario: 'vorraumOn(vorraumon)'-'shelly-03'-'Licht Vorzimmer' --> '0?turn=on'
Aug 10 09:04:46 srv4hai proc_url[2550]: SHELLY: OF:'/srv/www/viehai/shelly/log_scenarios/2024/08/10/_0.log' - shellyUsage:'Licht Vorzimmer' - scUrl:'http://192.168.12.103/relay/0?turn=on' - phName:'phoneName:'1''
Aug 10 09:04:46 srv4hai activate_scenario[2477]: SHELLY scenario: 'vorraumOn(vorraumon)' took 68 msec, phoneName:'1'
Aug 10 09:04:46 srv4hai ping_SP[2206]: SHELLY check_presence.sh coming 1 ping_SP__smartphone
Aug 10 09:04:47 srv4hai postfix/relay/smtp[2149]: 97D296345B: to=, relay=mx01.nic.name[209.131.159.47]:25, delay=0.69, delays=0.01/0/0.42/0.26, dsn=2.0.0, status=sent (250 2.0.0 Ok: queued as 1D00960063)
Aug 10 09:04:47 srv4hai postfix/qmgr[8566]: 97D296345B: removed
Aug 10 09:04:48 srv4hai ping_SP[2206]: SHELLY - running process-id(2206): '2206 /bin/bash /srv/www/viehai/shelly//ping_SP.sh 192.168.12.62 smartphone#0122259 /bin/bash /srv/www/viehai/shelly//ping_SP.sh 192.168.12.62 smartphone#0122279 /bin/bash /srv/www/viehai/shelly/smartphone.sh 192.168.12.62 GreteS20' - 'ping_SP_1' - exiting ==============
Aug 10 09:04:55 srv4hai smartphone.sh: SHELLY - ping_SP.sh started for phoneIP: '192.168.12.62' / 'GreteS20' / '1'
Aug 10 09:04:55 srv4hai common[2606]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//ping_SP.sh
Aug 10 09:04:55 srv4hai common[2605]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//check_presence.sh
Aug 10 09:04:55 srv4hai dhcpd[30731]: DHCPREQUEST for 192.168.12.62 (192.168.12.252) from f0:39:65:25:8d:c7 via enp2s0
Aug 10 09:04:55 srv4hai dhcpd[30731]: DHCPACK on 192.168.12.62 to f0:39:65:25:8d:c7 via enp2s0
Aug 10 09:04:55 srv4hai dhcpd[30731]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Aug 10 09:04:55 srv4hai dhcpd[30731]: execute_statement argv[1] = 192.168.12.62
Aug 10 09:04:55 srv4hai dhcpd[30731]: execute_statement argv[2] = GreteS20
Aug 10 09:04:55 srv4hai named[30406]: client @0x7f93480bd120 192.168.12.252#32877/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: GreteS20.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Aug 10 09:04:55 srv4hai named[30406]: client @0x7f931001f430 192.168.12.252#49245/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: GreteS20.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Aug 10 09:04:55 srv4hai common[2625]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/smartphone.sh
Aug 10 09:04:55 srv4hai ping_SP[2606]: SHELLY ping_SP.sh started from smartphone, user:'root'
Aug 10 09:04:56 srv4hai common.sh: SHELLY: BTstatus[0]:'On' dweetAge:'2'=1723273496-1723273494
Aug 10 09:04:56 srv4hai check_presence[2605]: SHELLY (0) found /srv/www/viehai/shelly//meter/ping_SP_0.TRY and On//srv/www/viehai/shelly//meter/BTscan_BTid_0.Yes
Aug 10 09:04:56 srv4hai check_presence[2605]: SHELLY (ping_SP__smartphone)-(1) found '/srv/www/viehai/shelly//meter/ping_SP_0.TRY' for what: 'coming'
Aug 10 09:04:56 srv4hai common.sh: SHELLY: BTstatus[1]:'Off' dweetAge:'2'=1723273496-1723273494
Aug 10 09:04:56 srv4hai check_presence[2605]: SHELLY (1) found /srv/www/viehai/shelly//meter/ping_SP_1.LIVE and Off//srv/www/viehai/shelly//meter/BTscan_BTid_1.No
Aug 10 09:04:56 srv4hai check_presence[2605]: SHELLY (ping_SP__smartphone)-(1) found '/srv/www/viehai/shelly//meter/ping_SP_1.LIVE' for what: 'coming'
Aug 10 09:04:56 srv4hai common.sh: SHELLY: BTstatus[2]:'Off' dweetAge:'2'=1723273496-1723273494
Aug 10 09:04:56 srv4hai check_presence[2605]: SHELLY (2) found /srv/www/viehai/shelly//meter/ping_SP_2.DEAD and Off//srv/www/viehai/shelly//meter/BTscan_BTid_2.No
Aug 10 09:04:56 srv4hai check_presence[2605]: SHELLY (ping_SP__smartphone)-(1) what: 'coming' - persPresent: '(BT/TRY) (__/LIVE) (__/__)' - anyPresent: 'true' - otherPresent:'true'
Aug 10 09:04:56 srv4hai check_presence[2605]: SHELLY - check 'coming': anyPresent(true) - lScene(scene_vorraumOn) - sceneONsmall(vorraumOn)
Aug 10 09:04:56 srv4hai check_presence[2605]: SHELLY (ping_SP__smartphone)-(1)-coming: activate_scenario.shtml: vorraumOn
Aug 10 09:04:56 srv4hai activate_scenario[2786]: SHELLY scenario: 'vorraumOn(vorraumon)' started
Aug 10 09:04:56 srv4hai activate_scenario[2786]: SHELLY dayState='day' <--> DayTime=checked NightTime=checked - phoneName:'1'

finished