activate_scenario.sh
Sat Apr 20 16:37:59 CEST 2024
scenario: vorraumOn
phoneName: 2

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

Licht Vorzimmer (/srv/www/viehai/shelly/log_scenarios/2024/04/20/_0.log)

vorher: 16:37:59.942

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

nachher: 16:38:00.064
Dauer: 122 msec phName: 'phoneName:'2''

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

urls : 1
vorher : 16:37:59.918
nachher: 16:38:00.070
Dauer : 151 msec
outfile: '/srv/www/viehai/shelly/log_scenarios/2024/04/20//scene_vorraumOn_20240420_163759.txt'

syslog

Apr 20 16:34:01 srv4hai CRON[25858]: (root) CMD (/etc/linetest/linetest.sh)
Apr 20 16:34:04 srv4hai smartphone.sh: SHELLY - ping_SP.sh already started for phoneIP: '192.168.12.63' / 'iPhoneSophie' / '2'
Apr 20 16:34:04 srv4hai dhcpd[22856]: DHCPREQUEST for 192.168.12.63 from 22:1e:8c:a4:80:db via enp2s0
Apr 20 16:34:04 srv4hai dhcpd[22856]: DHCPACK on 192.168.12.63 to 22:1e:8c:a4:80:db via enp2s0
Apr 20 16:34:04 srv4hai dhcpd[22856]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Apr 20 16:34:04 srv4hai dhcpd[22856]: execute_statement argv[1] = 192.168.12.63
Apr 20 16:34:04 srv4hai dhcpd[22856]: execute_statement argv[2] = iPhoneSophie
Apr 20 16:34:04 srv4hai common[25875]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/smartphone.sh
Apr 20 16:34:11 srv4hai ping_SP[30102]: SHELLY (0) - ping 192.168.12.61 1 sucessfully  @~1713623651 - 'ping_SP_0'
Apr 20 16:34:11 srv4hai ping_SP[30102]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Apr 20 16:34:14 srv4hai smartphone.sh: SHELLY - ping_SP.sh already started for phoneIP: '192.168.12.63' / 'iPhoneSophie' / '2'
Apr 20 16:34:14 srv4hai dhcpd[22856]: DHCPREQUEST for 192.168.12.63 from 22:1e:8c:a4:80:db via enp2s0
Apr 20 16:34:14 srv4hai dhcpd[22856]: DHCPACK on 192.168.12.63 to 22:1e:8c:a4:80:db via enp2s0
Apr 20 16:34:14 srv4hai dhcpd[22856]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Apr 20 16:34:14 srv4hai dhcpd[22856]: execute_statement argv[1] = 192.168.12.63
Apr 20 16:34:14 srv4hai dhcpd[22856]: execute_statement argv[2] = iPhoneSophie
Apr 20 16:34:14 srv4hai named[15509]: client @0x7f930c069dc0 192.168.12.252#43453/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: iPhoneSophie.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Apr 20 16:34:14 srv4hai common[25911]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/smartphone.sh
Apr 20 16:34:17 srv4hai common[25924]: SHELLY common.sh inkludiert von ./bt_scan.sh
Apr 20 16:34:24 srv4hai smartphone.sh: SHELLY - ping_SP.sh already started for phoneIP: '192.168.12.63' / 'iPhoneSophie' / '2'
Apr 20 16:34:24 srv4hai dhcpd[22856]: DHCPREQUEST for 192.168.12.63 from 22:1e:8c:a4:80:db via enp2s0
Apr 20 16:34:24 srv4hai dhcpd[22856]: DHCPACK on 192.168.12.63 to 22:1e:8c:a4:80:db via enp2s0
Apr 20 16:34:24 srv4hai dhcpd[22856]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Apr 20 16:34:24 srv4hai dhcpd[22856]: execute_statement argv[1] = 192.168.12.63
Apr 20 16:34:24 srv4hai dhcpd[22856]: execute_statement argv[2] = iPhoneSophie
Apr 20 16:34:24 srv4hai named[15509]: client @0x7f93100017f0 192.168.12.252#35377/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: iPhoneSophie.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Apr 20 16:34:24 srv4hai named[15509]: client @0x7f930c069dc0 192.168.12.252#43453/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: iPhoneSophie.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Apr 20 16:34:24 srv4hai common[25948]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/smartphone.sh
Apr 20 16:34:27 srv4hai bt_scan[25924]: SHELLY - who: 'Sophie' / phoneName: 'iP_Sophie' / '2' / shellyIP: '192.168.12.63' - found: 'No'
Apr 20 16:34:27 srv4hai bt_scan[25924]: SHELLY - lost BT-connection to BTmac: '2' found: 'No' - check presence
Apr 20 16:34:27 srv4hai common[25978]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//check_presence.sh
Apr 20 16:34:28 srv4hai common.sh: SHELLY: BTstatus[0]:'On' dweetAge:'10'=1713623668-1713623658
Apr 20 16:34:28 srv4hai check_presence[25978]: SHELLY (0) found /srv/www/viehai/shelly//meter/ping_SP_0.LIVE and On//srv/www/viehai/shelly//meter/BTscan_BTid_0.Yes
Apr 20 16:34:28 srv4hai check_presence[25978]: SHELLY (bt_scan)-(2) found '/srv/www/viehai/shelly//meter/ping_SP_0.LIVE' for what: 'presence'
Apr 20 16:34:28 srv4hai common.sh: SHELLY: BTstatus[1]:'Off' dweetAge:'10'=1713623668-1713623658
Apr 20 16:34:28 srv4hai check_presence[25978]: SHELLY (1) found /srv/www/viehai/shelly//meter/ping_SP_1.DEAD and Off//srv/www/viehai/shelly//meter/BTscan_BTid_1.No
Apr 20 16:34:28 srv4hai common.sh: SHELLY: BTstatus[2]:'Off' dweetAge:'10'=1713623668-1713623658
Apr 20 16:34:28 srv4hai check_presence[25978]: SHELLY (2) found /srv/www/viehai/shelly//meter/ping_SP_2.DEAD and Off//srv/www/viehai/shelly//meter/BTscan_BTid_2.No
Apr 20 16:34:28 srv4hai check_presence[25978]: SHELLY (bt_scan)-(2) what: 'presence' - persPresent: '(BT/LIVE) (__/__) (__/__)' - anyPresent: 'true' - otherPresent:'true'
Apr 20 16:34:28 srv4hai check_presence[25978]: SHELLY (bt_scan)-(2)-presence: NOT activate_scenario.shtml: offAll
Apr 20 16:34:34 srv4hai smartphone.sh: SHELLY - ping_SP.sh started for phoneIP: '192.168.12.63' / 'iPhoneSophie' / '2'
Apr 20 16:34:34 srv4hai common[26101]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//ping_SP.sh
Apr 20 16:34:34 srv4hai dhcpd[22856]: DHCPREQUEST for 192.168.12.63 from 22:1e:8c:a4:80:db via enp2s0
Apr 20 16:34:34 srv4hai dhcpd[22856]: DHCPACK on 192.168.12.63 to 22:1e:8c:a4:80:db via enp2s0
Apr 20 16:34:34 srv4hai named[15509]: client @0x7f930c069dc0 192.168.12.252#43453/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: iPhoneSophie.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Apr 20 16:34:34 srv4hai dhcpd[22856]: DHCPDISCOVER from 22:1e:8c:a4:80:db via enp2s0
Apr 20 16:34:34 srv4hai dhcpd[22856]: DHCPOFFER on 192.168.12.63 to 22:1e:8c:a4:80:db via enp2s0
Apr 20 16:34:34 srv4hai named[15509]: client @0x7f930c069dc0 192.168.12.252#43453/key rndc-key: signer "rndc-key" approved
Apr 20 16:34:34 srv4hai named[15509]: client @0x7f930c069dc0 192.168.12.252#43453/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'iPhoneSophie.viehai.at' TXT
Apr 20 16:34:34 srv4hai named[15509]: client @0x7f930c069dc0 192.168.12.252#43453/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'iPhoneSophie.viehai.at' TXT "31bf0fb452f28d5b707b5b66ceeaf6c2a6"
Apr 20 16:34:34 srv4hai dhcpd[22856]: DHCPDISCOVER from 22:1e:8c:a4:80:db via enp2s0
Apr 20 16:34:34 srv4hai named[15509]: client @0x7f930c069dc0 192.168.12.252#43453/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'iPhoneSophie.viehai.at' A
Apr 20 16:34:34 srv4hai dhcpd[22856]: DHCPOFFER on 192.168.12.63 to 22:1e:8c:a4:80:db via enp2s0
Apr 20 16:34:34 srv4hai named[15509]: client @0x7f930c069dc0 192.168.12.252#43453/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'iPhoneSophie.viehai.at' A 192.168.12.63
Apr 20 16:34:34 srv4hai named[15509]: client @0x7f930c069dc0 192.168.12.252#43453/key rndc-key: signer "rndc-key" approved
Apr 20 16:34:34 srv4hai named[15509]: client @0x7f930c069dc0 192.168.12.252#43453/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'iPhoneSophie.viehai.at' TXT
Apr 20 16:34:34 srv4hai named[15509]: client @0x7f930c069dc0 192.168.12.252#43453/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'iPhoneSophie.viehai.at' TXT "31bf0fb452f28d5b707b5b66ceeaf6c2a6"
Apr 20 16:34:34 srv4hai named[15509]: client @0x7f930c069dc0 192.168.12.252#43453/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'iPhoneSophie.viehai.at' A
Apr 20 16:34:34 srv4hai named[15509]: client @0x7f930c069dc0 192.168.12.252#43453/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'iPhoneSophie.viehai.at' A 192.168.12.63
Apr 20 16:34:34 srv4hai dhcpd[22856]: Added new forward map from iPhoneSophie.viehai.at to 192.168.12.63
Apr 20 16:34:34 srv4hai named[15509]: client @0x7f930c069dc0 192.168.12.252#43453/key rndc-key: signer "rndc-key" approved
Apr 20 16:34:34 srv4hai named[15509]: client @0x7f930c069dc0 192.168.12.252#43453/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'iPhoneSophie.viehai.at' TXT
Apr 20 16:34:34 srv4hai named[15509]: client @0x7f930c069dc0 192.168.12.252#43453/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'iPhoneSophie.viehai.at' TXT "31bf0fb452f28d5b707b5b66ceeaf6c2a6"
Apr 20 16:34:34 srv4hai named[15509]: client @0x7f930c069dc0 192.168.12.252#43453/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'iPhoneSophie.viehai.at' A
Apr 20 16:34:34 srv4hai dhcpd[22856]: Added new forward map from iPhoneSophie.viehai.at to 192.168.12.63
Apr 20 16:34:34 srv4hai named[15509]: client @0x7f930c069dc0 192.168.12.252#43453/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'iPhoneSophie.viehai.at' A 192.168.12.63
Apr 20 16:34:34 srv4hai dhcpd[22856]: Added new forward map from iPhoneSophie.viehai.at to 192.168.12.63
Apr 20 16:34:34 srv4hai named[15509]: client @0x7f930c069dc0 192.168.12.252#43453/key rndc-key: signer "rndc-key" approved
Apr 20 16:34:34 srv4hai named[15509]: client @0x7f930c069dc0 192.168.12.252#43453/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'iPhoneSophie.viehai.at' TXT
Apr 20 16:34:34 srv4hai named[15509]: client @0x7f930c069dc0 192.168.12.252#43453/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'iPhoneSophie.viehai.at' TXT "31bf0fb452f28d5b707b5b66ceeaf6c2a6"
Apr 20 16:34:34 srv4hai named[15509]: client @0x7f930c069dc0 192.168.12.252#43453/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'iPhoneSophie.viehai.at' A
Apr 20 16:34:34 srv4hai named[15509]: client @0x7f930c069dc0 192.168.12.252#43453/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'iPhoneSophie.viehai.at' A 192.168.12.63
Apr 20 16:34:34 srv4hai dhcpd[22856]: Added new forward map from iPhoneSophie.viehai.at to 192.168.12.63
Apr 20 16:34:34 srv4hai named[15509]: client @0x7f930c069dc0 192.168.12.252#43453/key rndc-key: signer "rndc-key" approved
Apr 20 16:34:34 srv4hai named[15509]: client @0x7f930c069dc0 192.168.12.252#43453/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '63.12.168.192.in-addr.arpa' PTR
Apr 20 16:34:34 srv4hai named[15509]: client @0x7f930c069dc0 192.168.12.252#43453/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': adding an RR at '63.12.168.192.in-addr.arpa' PTR iPhoneSophie.viehai.at.
Apr 20 16:34:34 srv4hai dhcpd[22856]: Added reverse map from 63.12.168.192.in-addr.arpa. to iPhoneSophie.viehai.at
Apr 20 16:34:34 srv4hai named[15509]: client @0x7f930c069dc0 192.168.12.252#43453/key rndc-key: signer "rndc-key" approved
Apr 20 16:34:34 srv4hai named[15509]: client @0x7f930c069dc0 192.168.12.252#43453/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '63.12.168.192.in-addr.arpa' PTR
Apr 20 16:34:34 srv4hai named[15509]: client @0x7f930c069dc0 192.168.12.252#43453/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': adding an RR at '63.12.168.192.in-addr.arpa' PTR iPhoneSophie.viehai.at.
Apr 20 16:34:34 srv4hai named[15509]: client @0x7f930c069dc0 192.168.12.252#43453/key rndc-key: signer "rndc-key" approved
Apr 20 16:34:34 srv4hai dhcpd[22856]: Added reverse map from 63.12.168.192.in-addr.arpa. to iPhoneSophie.viehai.at
Apr 20 16:34:34 srv4hai named[15509]: client @0x7f930c069dc0 192.168.12.252#43453/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '63.12.168.192.in-addr.arpa' PTR
Apr 20 16:34:34 srv4hai named[15509]: client @0x7f930c069dc0 192.168.12.252#43453/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': adding an RR at '63.12.168.192.in-addr.arpa' PTR iPhoneSophie.viehai.at.
Apr 20 16:34:34 srv4hai named[15509]: client @0x7f930c069dc0 192.168.12.252#43453/key rndc-key: signer "rndc-key" approved
Apr 20 16:34:34 srv4hai named[15509]: client @0x7f930c069dc0 192.168.12.252#43453/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '63.12.168.192.in-addr.arpa' PTR
Apr 20 16:34:34 srv4hai named[15509]: client @0x7f930c069dc0 192.168.12.252#43453/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': adding an RR at '63.12.168.192.in-addr.arpa' PTR iPhoneSophie.viehai.at.
Apr 20 16:34:34 srv4hai ping_SP[26101]: SHELLY ping_SP.sh started from smartphone, user:'root'
Apr 20 16:34:34 srv4hai dhcpd[22856]: Added reverse map from 63.12.168.192.in-addr.arpa. to iPhoneSophie.viehai.at
Apr 20 16:34:34 srv4hai dhcpd[22856]: Added reverse map from 63.12.168.192.in-addr.arpa. to iPhoneSophie.viehai.at
Apr 20 16:34:42 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=26135, secured, session=
Apr 20 16:34:42 srv4hai dovecot: imap(miglincit)<26135>: Logged out in=520 out=1290 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Apr 20 16:34:44 srv4hai common[26147]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//check_presence.sh
Apr 20 16:34:45 srv4hai common.sh: SHELLY: BTstatus[0]:'Off' dweetAge:'5'=1713623685-1713623680
Apr 20 16:34:45 srv4hai check_presence[26147]: SHELLY (0) found /srv/www/viehai/shelly//meter/ping_SP_0.LIVE and Off//srv/www/viehai/shelly//meter/BTscan_BTid_0.Yes
Apr 20 16:34:45 srv4hai check_presence[26147]: SHELLY (ping_SP__smartphone)-(2) found '/srv/www/viehai/shelly//meter/ping_SP_0.LIVE' for what: 'coming'
Apr 20 16:34:45 srv4hai common.sh: SHELLY: BTstatus[1]:'Off' dweetAge:'5'=1713623685-1713623680
Apr 20 16:34:45 srv4hai check_presence[26147]: SHELLY (1) found /srv/www/viehai/shelly//meter/ping_SP_1.DEAD and Off//srv/www/viehai/shelly//meter/BTscan_BTid_1.No
Apr 20 16:34:45 srv4hai common.sh: SHELLY: BTstatus[2]:'Off' dweetAge:'5'=1713623685-1713623680
Apr 20 16:34:45 srv4hai check_presence[26147]: SHELLY (2) found /srv/www/viehai/shelly//meter/ping_SP_2.LIVE and Off//srv/www/viehai/shelly//meter/BTscan_BTid_2.No
Apr 20 16:34:45 srv4hai check_presence[26147]: SHELLY (ping_SP__smartphone)-(2) found '/srv/www/viehai/shelly//meter/ping_SP_2.LIVE' for what: 'coming'
Apr 20 16:34:45 srv4hai check_presence[26147]: SHELLY (ping_SP__smartphone)-(2) what: 'coming' - persPresent: '(__/LIVE) (__/__) (__/LIVE)' - anyPresent: 'true' - otherPresent:'true'
Apr 20 16:34:45 srv4hai check_presence[26147]: SHELLY (ping_SP__smartphone)-(2)-coming: activate_scenario.shtml: vorraumOn
Apr 20 16:34:45 srv4hai activate_scenario[26269]: SHELLY scenario: 'vorraumOn(vorraumon)' started
Apr 20 16:34:45 srv4hai activate_scenario[26269]: SHELLY dayState='day' <--> DayTime=checked NightTime=checked - phoneName:'2'
Apr 20 16:34:45 srv4hai activate_scenario[26269]: SHELLY scenario: 'vorraumOn(vorraumon)' TO BE EXECUTED, phoneName:'2'
Apr 20 16:34:45 srv4hai postfix/pickup[19050]: 932FD6022F: uid=33 from=
Apr 20 16:34:45 srv4hai postfix/cleanup[26319]: 932FD6022F: message-id=<20240420143445.932FD6022F@mail.viehai.at>
Apr 20 16:34:45 srv4hai postfix/qmgr[15291]: 932FD6022F: from=, size=44750, nrcpt=1 (queue active)
Apr 20 16:34:45 srv4hai activate_scenario[26269]: SHELLY: scenario: 'vorraumOn(vorraumon)'-'shelly-03'-'Licht Vorzimmer' --> '0?turn=on'
Apr 20 16:34:45 srv4hai proc_url[26336]: SHELLY: OF:'/srv/www/viehai/shelly/log_scenarios/2024/04/20/_0.log' - shellyUsage:'Licht Vorzimmer' - scUrl:'http://192.168.12.103/relay/0?turn=on' - phName:'phoneName:'2''
Apr 20 16:34:45 srv4hai activate_scenario[26269]: SHELLY scenario: 'vorraumOn(vorraumon)' took 110 msec, phoneName:'2'
Apr 20 16:34:45 srv4hai ping_SP[26101]: SHELLY check_presence.sh coming 2 ping_SP__smartphone
Apr 20 16:34:47 srv4hai postfix/relay/smtp[26332]: 932FD6022F: to=, relay=mx03.nic.name[209.131.161.54]:25, delay=1.6, delays=0.03/0.01/0.85/0.72, dsn=2.0.0, status=sent (250 2.0.0 Ok: queued as 8BB988001B)
Apr 20 16:34:47 srv4hai postfix/qmgr[15291]: 932FD6022F: removed
Apr 20 16:34:47 srv4hai ping_SP[26101]: SHELLY - new process for '192.168.12.63' - '/srv/www/viehai/shelly//meter/ping_SP_2' @~1713623687
Apr 20 16:35:01 srv4hai CRON[26385]: (root) CMD (/etc/linetest/linetest.sh)
Apr 20 16:35:03 srv4hai ping_SP[26101]: SHELLY (2)- ping 192.168.12.63 0 not answered (1) @~1713623703 - 'ping_SP_2'
Apr 20 16:35:24 srv4hai ping_SP[30102]: SHELLY (0) - ping 192.168.12.61 1 sucessfully  @~1713623724 - 'ping_SP_0'
Apr 20 16:35:24 srv4hai ping_SP[30102]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Apr 20 16:35:27 srv4hai ping_SP[26101]: SHELLY (2)- ping 192.168.12.63 1 not answered (1) @~1713623727 - 'ping_SP_2'
Apr 20 16:35:53 srv4hai ping_SP[26101]: SHELLY (2)- ping 192.168.12.63 2 not answered (1) @~1713623753 - 'ping_SP_2'
Apr 20 16:36:01 srv4hai CRON[26482]: (root) CMD (/etc/linetest/linetest.sh)
Apr 20 16:36:16 srv4hai ping_SP[26101]: SHELLY (2)- ping 192.168.12.63 3 not answered (1) @~1713623776 - 'ping_SP_2'
Apr 20 16:36:18 srv4hai fetchmail[1651]: 7 messages (7 seen) for rozmig at mail.akis.at (1753652 octets).
Apr 20 16:36:19 srv4hai fetchmail[1651]: 42533 messages (42532 seen) for tmiglinci at mail.akis.at (-752498641 octets).
Apr 20 16:36:19 srv4hai postfix/smtpd[26509]: connect from localhost[127.0.0.1]
Apr 20 16:36:19 srv4hai postfix/smtpd[26509]: 89ACC60119: client=localhost[127.0.0.1]
Apr 20 16:36:19 srv4hai postfix/cleanup[26319]: 89ACC60119: message-id=<20240420143445.932FD6022F@mail.viehai.at>
Apr 20 16:36:19 srv4hai fetchmail[1651]: reading message tmiglinci@mail.akis.at:42533 of 42533 (45628 octets) not flushed
Apr 20 16:36:19 srv4hai postfix/qmgr[15291]: 89ACC60119: from=, size=46024, nrcpt=1 (queue active)
Apr 20 16:36:19 srv4hai postfix/smtpd[26509]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1 data=1 quit=1 commands=5
Apr 20 16:36:19 srv4hai postfix/local[26511]: 89ACC60119: to=, relay=local, delay=0.1, delays=0.08/0.01/0/0.01, dsn=2.0.0, status=sent (delivered to command: /bin/procmail)
Apr 20 16:36:19 srv4hai postfix/qmgr[15291]: 89ACC60119: removed
Apr 20 16:36:20 srv4hai fetchmail[1651]: 10156 messages (10156 seen) for srozum at mail.akis.at (1226487762 octets).
Apr 20 16:36:21 srv4hai fetchmail[1651]: 22989 messages (22989 seen) for mrozum at mail.akis.at (-311105689 octets).
Apr 20 16:36:21 srv4hai fetchmail[1651]: 46 messages (46 seen) for admin@bastelstube.wien at pop3.world4you.com (12059834 octets).
Apr 20 16:36:37 srv4hai ping_SP[30102]: SHELLY (0) - ping 192.168.12.61 1 sucessfully  @~1713623797 - 'ping_SP_0'
Apr 20 16:36:37 srv4hai ping_SP[30102]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Apr 20 16:36:37 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=26550, secured, session=
Apr 20 16:36:37 srv4hai dovecot: imap(miglincit)<26550>: Logged out in=735 out=42692 deleted=0 expunged=0 trashed=0 hdr_count=50 hdr_bytes=18501 body_count=0 body_bytes=0
Apr 20 16:36:39 srv4hai ping_SP[26101]: SHELLY (2)- ping 192.168.12.63 4 not answered (1) @~1713623799 - 'ping_SP_2'
Apr 20 16:36:52 srv4hai postfix/anvil[25825]: statistics: max connection rate 1/60s for (smtp:193.222.96.12) at Apr 20 16:33:32
Apr 20 16:36:52 srv4hai postfix/anvil[25825]: statistics: max connection count 1 for (smtp:193.222.96.12) at Apr 20 16:33:32
Apr 20 16:36:52 srv4hai postfix/anvil[25825]: statistics: max cache size 1 at Apr 20 16:33:32
Apr 20 16:37:01 srv4hai CRON[26579]: (root) CMD (/etc/linetest/linetest.sh)
Apr 20 16:37:05 srv4hai ping_SP[26101]: SHELLY (2)- ping 192.168.12.63 5 not answered (1) @~1713623825 - 'ping_SP_2'
Apr 20 16:37:05 srv4hai ping_SP[26101]: SHELLY (2)- ping 192.168.12.63 5 not answered - abort (26101) @~1713623825 - rm 'ping_SP_2'
Apr 20 16:37:05 srv4hai common[26598]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//check_presence.sh
Apr 20 16:37:06 srv4hai common.sh: SHELLY: BTstatus[0]:'Off' dweetAge:'5'=1713623826-1713623821
Apr 20 16:37:06 srv4hai check_presence[26598]: SHELLY (0) found /srv/www/viehai/shelly//meter/ping_SP_0.LIVE and Off//srv/www/viehai/shelly//meter/BTscan_BTid_0.Yes
Apr 20 16:37:06 srv4hai check_presence[26598]: SHELLY (ping_SP__NO_ping)-(2) found '/srv/www/viehai/shelly//meter/ping_SP_0.LIVE' for what: 'presence'
Apr 20 16:37:06 srv4hai common.sh: SHELLY: BTstatus[1]:'Off' dweetAge:'5'=1713623826-1713623821
Apr 20 16:37:06 srv4hai check_presence[26598]: SHELLY (1) found /srv/www/viehai/shelly//meter/ping_SP_1.DEAD and Off//srv/www/viehai/shelly//meter/BTscan_BTid_1.No
Apr 20 16:37:06 srv4hai common.sh: SHELLY: BTstatus[2]:'Off' dweetAge:'5'=1713623826-1713623821
Apr 20 16:37:06 srv4hai check_presence[26598]: SHELLY (2) found /srv/www/viehai/shelly//meter/ping_SP_2.DEAD and Off//srv/www/viehai/shelly//meter/BTscan_BTid_2.No
Apr 20 16:37:06 srv4hai check_presence[26598]: SHELLY (ping_SP__NO_ping)-(2) what: 'presence' - persPresent: '(__/LIVE) (__/__) (__/__)' - anyPresent: 'true' - otherPresent:'true'
Apr 20 16:37:06 srv4hai check_presence[26598]: SHELLY (ping_SP__NO_ping)-(2)-presence: NOT activate_scenario.shtml: offAll
Apr 20 16:37:28 srv4hai dhcpd[22856]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Apr 20 16:37:28 srv4hai dhcpd[22856]: execute_statement argv[1] = 192.168.12.63
Apr 20 16:37:28 srv4hai dhcpd[22856]: execute_statement argv[2] = iPhoneSophie
Apr 20 16:37:28 srv4hai common[26732]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/smartphone.sh
Apr 20 16:37:38 srv4hai smartphone.sh: SHELLY - ping_SP.sh started for phoneIP: '192.168.12.63' / 'iPhoneSophie' / '2'
Apr 20 16:37:38 srv4hai common[26748]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//ping_SP.sh
Apr 20 16:37:38 srv4hai dhcpd[22856]: DHCPREQUEST for 192.168.12.63 from 22:1e:8c:a4:80:db via enp2s0
Apr 20 16:37:38 srv4hai dhcpd[22856]: DHCPACK on 192.168.12.63 to 22:1e:8c:a4:80:db via enp2s0
Apr 20 16:37:38 srv4hai dhcpd[22856]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Apr 20 16:37:38 srv4hai dhcpd[22856]: execute_statement argv[1] = 192.168.12.63
Apr 20 16:37:38 srv4hai dhcpd[22856]: execute_statement argv[2] = iPhoneSophie
Apr 20 16:37:38 srv4hai common[26761]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/smartphone.sh
Apr 20 16:37:38 srv4hai ping_SP[26748]: SHELLY ping_SP.sh started from smartphone, user:'root'
Apr 20 16:37:42 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=26786, secured, session=
Apr 20 16:37:42 srv4hai dovecot: imap(miglincit)<26786>: Logged out in=735 out=42700 deleted=0 expunged=0 trashed=0 hdr_count=50 hdr_bytes=18501 body_count=0 body_bytes=0
Apr 20 16:37:49 srv4hai smartphone.sh: SHELLY - ping_SP.sh started for phoneIP: '192.168.12.63' / 'iPhoneSophie' / '2'
Apr 20 16:37:49 srv4hai common[26799]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//ping_SP.sh
Apr 20 16:37:49 srv4hai common[26808]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//check_presence.sh
Apr 20 16:37:49 srv4hai dhcpd[22856]: DHCPREQUEST for 192.168.12.63 from 22:1e:8c:a4:80:db via enp2s0
Apr 20 16:37:49 srv4hai dhcpd[22856]: DHCPACK on 192.168.12.63 to 22:1e:8c:a4:80:db via enp2s0
Apr 20 16:37:49 srv4hai dhcpd[22856]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Apr 20 16:37:49 srv4hai dhcpd[22856]: execute_statement argv[1] = 192.168.12.63
Apr 20 16:37:49 srv4hai dhcpd[22856]: execute_statement argv[2] = iPhoneSophie
Apr 20 16:37:49 srv4hai named[15509]: client @0x7f931c688d80 192.168.12.252#51653/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: iPhoneSophie.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Apr 20 16:37:49 srv4hai common[26821]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/smartphone.sh
Apr 20 16:37:49 srv4hai ping_SP[26799]: SHELLY ping_SP.sh started from smartphone, user:'root'
Apr 20 16:37:49 srv4hai common.sh: SHELLY: BTstatus[0]:'On' dweetAge:'5'=1713623869-1713623864
Apr 20 16:37:49 srv4hai check_presence[26808]: SHELLY (0) found /srv/www/viehai/shelly//meter/ping_SP_0.LIVE and On//srv/www/viehai/shelly//meter/BTscan_BTid_0.Yes
Apr 20 16:37:49 srv4hai check_presence[26808]: SHELLY (ping_SP__smartphone)-(2) found '/srv/www/viehai/shelly//meter/ping_SP_0.LIVE' for what: 'coming'
Apr 20 16:37:49 srv4hai common.sh: SHELLY: BTstatus[1]:'Off' dweetAge:'5'=1713623869-1713623864
Apr 20 16:37:49 srv4hai check_presence[26808]: SHELLY (1) found /srv/www/viehai/shelly//meter/ping_SP_1.DEAD and Off//srv/www/viehai/shelly//meter/BTscan_BTid_1.No
Apr 20 16:37:49 srv4hai common.sh: SHELLY: BTstatus[2]:'Off' dweetAge:'5'=1713623869-1713623864
Apr 20 16:37:49 srv4hai check_presence[26808]: SHELLY (2) found /srv/www/viehai/shelly//meter/ping_SP_2.LIVE and Off//srv/www/viehai/shelly//meter/BTscan_BTid_2.No
Apr 20 16:37:49 srv4hai check_presence[26808]: SHELLY (ping_SP__smartphone)-(2) found '/srv/www/viehai/shelly//meter/ping_SP_2.LIVE' for what: 'coming'
Apr 20 16:37:49 srv4hai check_presence[26808]: SHELLY (ping_SP__smartphone)-(2) what: 'coming' - persPresent: '(BT/LIVE) (__/__) (__/LIVE)' - anyPresent: 'true' - otherPresent:'true'
Apr 20 16:37:49 srv4hai check_presence[26808]: SHELLY (ping_SP__smartphone)-(2)-coming: activate_scenario.shtml: vorraumOn
Apr 20 16:37:49 srv4hai activate_scenario[26961]: SHELLY scenario: 'vorraumOn(vorraumon)' started
Apr 20 16:37:49 srv4hai activate_scenario[26961]: SHELLY dayState='day' <--> DayTime=checked NightTime=checked - phoneName:'2'
Apr 20 16:37:49 srv4hai activate_scenario[26961]: SHELLY scenario: 'vorraumOn(vorraumon)' TO BE EXECUTED, phoneName:'2'
Apr 20 16:37:49 srv4hai postfix/pickup[19050]: C15216022F: uid=33 from=
Apr 20 16:37:49 srv4hai postfix/cleanup[26319]: C15216022F: message-id=<20240420143749.C15216022F@mail.viehai.at>
Apr 20 16:37:49 srv4hai postfix/qmgr[15291]: C15216022F: from=, size=26235, nrcpt=1 (queue active)
Apr 20 16:37:49 srv4hai activate_scenario[26961]: SHELLY: scenario: 'vorraumOn(vorraumon)'-'shelly-03'-'Licht Vorzimmer' --> '0?turn=on'
Apr 20 16:37:49 srv4hai proc_url[27026]: SHELLY: OF:'/srv/www/viehai/shelly/log_scenarios/2024/04/20/_0.log' - shellyUsage:'Licht Vorzimmer' - scUrl:'http://192.168.12.103/relay/0?turn=on' - phName:'phoneName:'2''
Apr 20 16:37:49 srv4hai activate_scenario[26961]: SHELLY scenario: 'vorraumOn(vorraumon)' took 136 msec, phoneName:'2'
Apr 20 16:37:49 srv4hai ping_SP[26748]: SHELLY check_presence.sh coming 2 ping_SP__smartphone
Apr 20 16:37:50 srv4hai ping_SP[30102]: SHELLY (0) - ping 192.168.12.61 1 sucessfully  @~1713623870 - 'ping_SP_0'
Apr 20 16:37:50 srv4hai ping_SP[30102]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Apr 20 16:37:52 srv4hai ping_SP[26748]: SHELLY - running process-id(26748): '26748 /bin/bash /srv/www/viehai/shelly//ping_SP.sh 192.168.12.63 smartphone#01226799 /bin/bash /srv/www/viehai/shelly//ping_SP.sh 192.168.12.63 smartphone#01226821 /bin/bash /srv/www/viehai/shelly/smartphone.sh 192.168.12.63 iPhoneSophie' - 'ping_SP_2' - exiting ==============
Apr 20 16:37:53 srv4hai postfix/relay/smtp[27016]: C15216022F: to=, relay=mx01.nic.name[209.131.159.47]:25, delay=3.3, delays=0.01/0.01/0.77/2.5, dsn=2.0.0, status=sent (250 2.0.0 Ok: queued as 1CFA720406)
Apr 20 16:37:53 srv4hai postfix/qmgr[15291]: C15216022F: removed
Apr 20 16:37:59 srv4hai smartphone.sh: SHELLY - ping_SP.sh started for phoneIP: '192.168.12.63' / 'iPhoneSophie' / '2'
Apr 20 16:37:59 srv4hai common[27085]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//ping_SP.sh
Apr 20 16:37:59 srv4hai dhcpd[22856]: DHCPREQUEST for 192.168.12.63 from 22:1e:8c:a4:80:db via enp2s0
Apr 20 16:37:59 srv4hai dhcpd[22856]: DHCPACK on 192.168.12.63 to 22:1e:8c:a4:80:db via enp2s0
Apr 20 16:37:59 srv4hai dhcpd[22856]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Apr 20 16:37:59 srv4hai dhcpd[22856]: execute_statement argv[1] = 192.168.12.63
Apr 20 16:37:59 srv4hai dhcpd[22856]: execute_statement argv[2] = iPhoneSophie
Apr 20 16:37:59 srv4hai named[15509]: client @0x7f931006ead0 192.168.12.252#55243/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: iPhoneSophie.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Apr 20 16:37:59 srv4hai named[15509]: client @0x7f931c688d80 192.168.12.252#51653/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: iPhoneSophie.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Apr 20 16:37:59 srv4hai common[27103]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//check_presence.sh
Apr 20 16:37:59 srv4hai common[27106]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/smartphone.sh
Apr 20 16:37:59 srv4hai ping_SP[27085]: SHELLY ping_SP.sh started from smartphone, user:'root'
Apr 20 16:37:59 srv4hai common.sh: SHELLY: BTstatus[0]:'On' dweetAge:'15'=1713623879-1713623864
Apr 20 16:37:59 srv4hai check_presence[27103]: SHELLY (0) found /srv/www/viehai/shelly//meter/ping_SP_0.LIVE and On//srv/www/viehai/shelly//meter/BTscan_BTid_0.Yes
Apr 20 16:37:59 srv4hai check_presence[27103]: SHELLY (ping_SP__smartphone)-(2) found '/srv/www/viehai/shelly//meter/ping_SP_0.LIVE' for what: 'coming'
Apr 20 16:37:59 srv4hai common.sh: SHELLY: BTstatus[1]:'Off' dweetAge:'15'=1713623879-1713623864
Apr 20 16:37:59 srv4hai check_presence[27103]: SHELLY (1) found /srv/www/viehai/shelly//meter/ping_SP_1.DEAD and Off//srv/www/viehai/shelly//meter/BTscan_BTid_1.No
Apr 20 16:37:59 srv4hai common.sh: SHELLY: BTstatus[2]:'Off' dweetAge:'15'=1713623879-1713623864
Apr 20 16:37:59 srv4hai check_presence[27103]: SHELLY (2) found /srv/www/viehai/shelly//meter/ping_SP_2.LIVE and Off//srv/www/viehai/shelly//meter/BTscan_BTid_2.No
Apr 20 16:37:59 srv4hai check_presence[27103]: SHELLY (ping_SP__smartphone)-(2) found '/srv/www/viehai/shelly//meter/ping_SP_2.LIVE' for what: 'coming'
Apr 20 16:37:59 srv4hai check_presence[27103]: SHELLY (ping_SP__smartphone)-(2) what: 'coming' - persPresent: '(BT/LIVE) (__/__) (__/LIVE)' - anyPresent: 'true' - otherPresent:'true'
Apr 20 16:37:59 srv4hai check_presence[27103]: SHELLY (ping_SP__smartphone)-(2)-coming: activate_scenario.shtml: vorraumOn
Apr 20 16:37:59 srv4hai activate_scenario[27248]: SHELLY scenario: 'vorraumOn(vorraumon)' started
Apr 20 16:37:59 srv4hai activate_scenario[27248]: SHELLY dayState='day' <--> DayTime=checked NightTime=checked - phoneName:'2'

finished