activate_scenario.sh
Fri Aug 23 16:39:16 CEST 2024
scenario: vorraumOn
phoneName: 0

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

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

vorher: 16:39:16.314

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

nachher: 16:39:16.439
Dauer: 124 msec phName: 'phoneName:'0''

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

urls : 1
vorher : 16:39:16.273
nachher: 16:39:16.446
Dauer : 175 msec
outfile: '/srv/www/viehai/shelly/log_scenarios/2024/08/23//scene_vorraumOn_20240823_163916.txt'

syslog

Aug 23 16:36:01 srv4hai CRON[29471]: (root) CMD (/etc/linetest/linetest.sh)
Aug 23 16:36:08 srv4hai postfix/smtpd[29481]: connect from unknown[185.208.158.123]
Aug 23 16:36:08 srv4hai postfix/smtpd[29481]: warning: unknown[185.208.158.123]: SASL LOGIN authentication failed: Invalid authentication mechanism
Aug 23 16:36:08 srv4hai postfix/smtpd[29481]: disconnect from unknown[185.208.158.123] ehlo=1 auth=0/1 quit=1 commands=2/3
Aug 23 16:36:11 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=29487, secured, session=
Aug 23 16:36:11 srv4hai dovecot: imap(miglincit)<29487>: Logged out in=265 out=1723 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Aug 23 16:36:47 srv4hai ping_SP[29184]: SHELLY (0) - ping 192.168.12.61 1 sucessfully  @~1724423807 - 'ping_SP_0'
Aug 23 16:36:47 srv4hai ping_SP[29184]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Aug 23 16:37:01 srv4hai CRON[29531]: (root) CMD (/etc/linetest/linetest.sh)
Aug 23 16:37:32 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=29552, secured, session=
Aug 23 16:37:32 srv4hai dovecot: imap(miglincit)<29552>: Logged out in=604 out=1231 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Aug 23 16:37:42 srv4hai fetchmail[1651]: 7 messages (7 seen) for rozmig at mail.akis.at (1753652 octets).
Aug 23 16:37:43 srv4hai fetchmail[1651]: 50651 messages (50650 seen) for tmiglinci at mail.akis.at (-100800648 octets).
Aug 23 16:37:43 srv4hai postfix/smtpd[29481]: connect from localhost[127.0.0.1]
Aug 23 16:37:43 srv4hai postfix/smtpd[29481]: 909446000F: client=localhost[127.0.0.1]
Aug 23 16:37:43 srv4hai postfix/cleanup[29561]: 909446000F: message-id=<20240823143519.746B863460@mail.viehai.at>
Aug 23 16:37:43 srv4hai fetchmail[1651]: reading message tmiglinci@mail.akis.at:50651 of 50651 (9202 octets) not flushed
Aug 23 16:37:43 srv4hai postfix/qmgr[8566]: 909446000F: from=, size=9505, nrcpt=1 (queue active)
Aug 23 16:37:43 srv4hai postfix/smtpd[29481]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1 data=1 quit=1 commands=5
Aug 23 16:37:43 srv4hai postfix/local[29562]: 909446000F: to=, relay=local, delay=0.07, delays=0.05/0.01/0/0.01, dsn=2.0.0, status=sent (delivered to command: /bin/procmail)
Aug 23 16:37:43 srv4hai postfix/qmgr[8566]: 909446000F: removed
Aug 23 16:37:44 srv4hai fetchmail[1651]: 10614 messages (10614 seen) for srozum at mail.akis.at (1280169721 octets).
Aug 23 16:37:44 srv4hai fetchmail[1651]: 25476 messages (25476 seen) for mrozum at mail.akis.at (151000348 octets).
Aug 23 16:37:45 srv4hai fetchmail[1651]: 1141 messages (1141 seen) for admin@bastelstube.wien at pop3.world4you.com (51598561 octets).
Aug 23 16:38:01 srv4hai dhcpd[30731]: execute_statement argv[0] = /srv/www/viehai/shelly/dhcpcommit.sh
Aug 23 16:38:01 srv4hai dhcpd[30731]: execute_statement argv[1] = 192.168.12.27
Aug 23 16:38:01 srv4hai dhcpd[30731]: execute_statement argv[2] = tvsz
Aug 23 16:38:01 srv4hai dhcpd[30731]: DHCPREQUEST for 192.168.12.27 from 70:b1:3d:9f:36:ea via enp2s0
Aug 23 16:38:01 srv4hai dhcpd[30731]: DHCPACK on 192.168.12.27 to 70:b1:3d:9f:36:ea via enp2s0
Aug 23 16:38:01 srv4hai named[30406]: client @0x7f93480bd120 192.168.12.252#48895/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: tvsz.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Aug 23 16:38:01 srv4hai named[30406]: client @0x7f932c0b5090 192.168.12.252#36817/key rndc-key: signer "rndc-key" approved
Aug 23 16:38:01 srv4hai named[30406]: client @0x7f932c0b5090 192.168.12.252#36817/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'tvsz.viehai.at' TXT
Aug 23 16:38:01 srv4hai named[30406]: client @0x7f932c0b5090 192.168.12.252#36817/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'tvsz.viehai.at' TXT "3189601b07a6e5c1515a2fd330306e89ab"
Aug 23 16:38:01 srv4hai named[30406]: client @0x7f932c0b5090 192.168.12.252#36817/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'tvsz.viehai.at' A
Aug 23 16:38:01 srv4hai named[30406]: client @0x7f932c0b5090 192.168.12.252#36817/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'tvsz.viehai.at' A 192.168.12.27
Aug 23 16:38:01 srv4hai dhcpd[30731]: Added new forward map from tvsz.viehai.at to 192.168.12.27
Aug 23 16:38:01 srv4hai dhcpcommit.sh: SHELLY ip:'192.168.12.27'(27) device 'tvsz'
Aug 23 16:38:01 srv4hai named[30406]: client @0x7f93100e1310 192.168.12.252#60263/key rndc-key: signer "rndc-key" approved
Aug 23 16:38:01 srv4hai named[30406]: client @0x7f93100e1310 192.168.12.252#60263/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '27.12.168.192.in-addr.arpa' PTR
Aug 23 16:38:01 srv4hai named[30406]: client @0x7f93100e1310 192.168.12.252#60263/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': adding an RR at '27.12.168.192.in-addr.arpa' PTR tvsz.viehai.at.
Aug 23 16:38:01 srv4hai dhcpd[30731]: Added reverse map from 27.12.168.192.in-addr.arpa. to tvsz.viehai.at
Aug 23 16:38:01 srv4hai dhcpcommit.sh: SHELLY device 'tvsz' known and accepted
Aug 23 16:38:01 srv4hai CRON[29585]: (root) CMD (/etc/linetest/linetest.sh)
Aug 23 16:38:11 srv4hai ping_SP[29184]: SHELLY (0)- ping 192.168.12.61 1 not answered (1) @~1724423891 - 'ping_SP_0'
Aug 23 16:38:37 srv4hai ping_SP[29184]: SHELLY (0)- ping 192.168.12.61 2 not answered (1) @~1724423917 - 'ping_SP_0'
Aug 23 16:38:45 srv4hai dhcpd[30731]: DHCPDISCOVER from e8:78:29:c0:c2:4e via enp2s0
Aug 23 16:38:45 srv4hai dhcpd[30731]: DHCPOFFER on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0
Aug 23 16:38:45 srv4hai dhcpd[30731]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Aug 23 16:38:45 srv4hai dhcpd[30731]: execute_statement argv[1] = 192.168.12.61
Aug 23 16:38:45 srv4hai dhcpd[30731]: execute_statement argv[2] = fpThomas
Aug 23 16:38:45 srv4hai common[29643]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/smartphone.sh
Aug 23 16:38:55 srv4hai smartphone.sh: SHELLY - ping_SP.sh started for phoneIP: '192.168.12.61' / 'fpThomas' / '0'
Aug 23 16:38:55 srv4hai common[29667]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//ping_SP.sh
Aug 23 16:38:55 srv4hai dhcpd[30731]: DHCPREQUEST for 192.168.12.61 (192.168.12.252) from e8:78:29:c0:c2:4e via enp2s0
Aug 23 16:38:55 srv4hai dhcpd[30731]: DHCPACK on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0
Aug 23 16:38:55 srv4hai dhcpd[30731]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Aug 23 16:38:55 srv4hai dhcpd[30731]: execute_statement argv[1] = 192.168.12.61
Aug 23 16:38:55 srv4hai dhcpd[30731]: execute_statement argv[2] = fpThomas
Aug 23 16:38:55 srv4hai common[29680]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/smartphone.sh
Aug 23 16:38:55 srv4hai ping_SP[29667]: SHELLY ping_SP.sh started from smartphone, user:'root'
Aug 23 16:39:00 srv4hai systemd[1]: Starting Clean php session files...
Aug 23 16:39:00 srv4hai systemd[1]: phpsessionclean.service: Succeeded.
Aug 23 16:39:00 srv4hai systemd[1]: Started Clean php session files.
Aug 23 16:39:01 srv4hai CRON[29806]: (root) CMD (/etc/linetest/linetest.sh)
Aug 23 16:39:01 srv4hai CRON[29807]: (root) CMD (  [ -x /usr/lib/php/sessionclean ] && if [ ! -d /run/systemd/system ]; then /usr/lib/php/sessionclean; fi)
Aug 23 16:39:03 srv4hai ping_SP[29184]: SHELLY (0)- ping 192.168.12.61 3 not answered (1) @~1724423943 - 'ping_SP_0'
Aug 23 16:39:05 srv4hai smartphone.sh: SHELLY - ping_SP.sh started for phoneIP: '192.168.12.61' / 'fpThomas' / '0'
Aug 23 16:39:05 srv4hai common[29830]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//ping_SP.sh
Aug 23 16:39:05 srv4hai dhcpd[30731]: DHCPREQUEST for 192.168.12.61 (192.168.12.252) from e8:78:29:c0:c2:4e via enp2s0
Aug 23 16:39:05 srv4hai dhcpd[30731]: DHCPACK on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0
Aug 23 16:39:05 srv4hai dhcpd[30731]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Aug 23 16:39:05 srv4hai dhcpd[30731]: execute_statement argv[1] = 192.168.12.61
Aug 23 16:39:05 srv4hai dhcpd[30731]: execute_statement argv[2] = fpThomas
Aug 23 16:39:05 srv4hai named[30406]: client @0x7f93506d04d0 192.168.12.252#50273/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: fpThomas.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Aug 23 16:39:05 srv4hai common[29848]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//check_presence.sh
Aug 23 16:39:05 srv4hai common[29851]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/smartphone.sh
Aug 23 16:39:05 srv4hai ping_SP[29830]: SHELLY ping_SP.sh started from smartphone, user:'root'
Aug 23 16:39:06 srv4hai common.sh: SHELLY: BTstatus[0]:'NO_BTdweet' dweetAge:''=-
Aug 23 16:39:06 srv4hai check_presence[29848]: SHELLY (0) found /srv/www/viehai/shelly//meter/ping_SP_0.LIVE and NO_BTdweet//srv/www/viehai/shelly//meter/BTscan_BTid_0.No
Aug 23 16:39:06 srv4hai check_presence[29848]: SHELLY (ping_SP__smartphone)-(0) found '/srv/www/viehai/shelly//meter/ping_SP_0.LIVE' for what: 'coming'
Aug 23 16:39:06 srv4hai common.sh: SHELLY: BTstatus[1]:'NO_BTdweet' dweetAge:''=-
Aug 23 16:39:06 srv4hai check_presence[29848]: SHELLY (1) found /srv/www/viehai/shelly//meter/ping_SP_1.DEAD and NO_BTdweet//srv/www/viehai/shelly//meter/BTscan_BTid_1.Yes
Aug 23 16:39:06 srv4hai common.sh: SHELLY: BTstatus[2]:'NO_BTdweet' dweetAge:''=-
Aug 23 16:39:06 srv4hai check_presence[29848]: SHELLY (2) found /srv/www/viehai/shelly//meter/ping_SP_2.DEAD and NO_BTdweet//srv/www/viehai/shelly//meter/BTscan_BTid_2.No
Aug 23 16:39:06 srv4hai check_presence[29848]: SHELLY (ping_SP__smartphone)-(0) what: 'coming' - persPresent: '(__/LIVE) (__/__) (__/__)' - anyPresent: 'true' - otherPresent:'false'
Aug 23 16:39:06 srv4hai check_presence[29848]: SHELLY - check 'coming': anyPresent(true) - lScene(scene_vorraumOn) - sceneONsmall(vorraumOn)
Aug 23 16:39:06 srv4hai check_presence[29848]: SHELLY (ping_SP__smartphone)-(0)-coming: activate_scenario.shtml: vorraumOn
Aug 23 16:39:06 srv4hai activate_scenario[29976]: SHELLY scenario: 'vorraumOn(vorraumon)' started
Aug 23 16:39:06 srv4hai activate_scenario[29976]: SHELLY dayState='day' <--> DayTime=checked NightTime=checked - phoneName:'0'
Aug 23 16:39:06 srv4hai activate_scenario[29976]: SHELLY scenario: 'vorraumOn(vorraumon)' TO BE EXECUTED, phoneName:'0'
Aug 23 16:39:06 srv4hai postfix/pickup[27948]: 9FF1A63460: uid=33 from=
Aug 23 16:39:06 srv4hai postfix/cleanup[29561]: 9FF1A63460: message-id=<20240823143906.9FF1A63460@mail.viehai.at>
Aug 23 16:39:06 srv4hai postfix/qmgr[8566]: 9FF1A63460: from=, size=11297, nrcpt=1 (queue active)
Aug 23 16:39:06 srv4hai activate_scenario[29976]: SHELLY: scenario: 'vorraumOn(vorraumon)'-'shelly-03'-'Licht Vorzimmer' --> '0?turn=on'
Aug 23 16:39:06 srv4hai proc_url[30041]: SHELLY: OF:'/srv/www/viehai/shelly/log_scenarios/2024/08/23/_0.log' - shellyUsage:'Licht Vorzimmer' - scUrl:'http://192.168.12.103/relay/0?turn=on' - phName:'phoneName:'0''
Aug 23 16:39:06 srv4hai activate_scenario[29976]: SHELLY scenario: 'vorraumOn(vorraumon)' took 50 msec, phoneName:'0'
Aug 23 16:39:06 srv4hai ping_SP[29667]: SHELLY check_presence.sh coming 0 ping_SP__smartphone
Aug 23 16:39:08 srv4hai ping_SP[29667]: SHELLY - running process-id(29667): '29184 /bin/bash /srv/www/viehai/shelly//ping_SP.sh 192.168.12.61 smartphone#01229667 /bin/bash /srv/www/viehai/shelly//ping_SP.sh 192.168.12.61 smartphone#01229830 /bin/bash /srv/www/viehai/shelly//ping_SP.sh 192.168.12.61 smartphone#01229851 /bin/bash /srv/www/viehai/shelly/smartphone.sh 192.168.12.61 fpThomas' - 'ping_SP_0' - exiting ==============
Aug 23 16:39:11 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=30079, secured, session=
Aug 23 16:39:11 srv4hai dovecot: imap(miglincit)<30079>: Logged out in=265 out=1715 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Aug 23 16:39:15 srv4hai smartphone.sh: SHELLY - ping_SP.sh started for phoneIP: '192.168.12.61' / 'fpThomas' / '0'
Aug 23 16:39:15 srv4hai common[30090]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//ping_SP.sh
Aug 23 16:39:15 srv4hai dhcpd[30731]: DHCPREQUEST for 192.168.12.61 (192.168.12.252) from e8:78:29:c0:c2:4e via enp2s0
Aug 23 16:39:15 srv4hai dhcpd[30731]: DHCPACK on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0
Aug 23 16:39:15 srv4hai dhcpd[30731]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Aug 23 16:39:15 srv4hai dhcpd[30731]: execute_statement argv[1] = 192.168.12.61
Aug 23 16:39:15 srv4hai dhcpd[30731]: execute_statement argv[2] = fpThomas
Aug 23 16:39:15 srv4hai named[30406]: client @0x7f93506d04d0 192.168.12.252#50273/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: fpThomas.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Aug 23 16:39:15 srv4hai named[30406]: client @0x7f9348081920 192.168.12.252#44009/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: fpThomas.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Aug 23 16:39:15 srv4hai common[30105]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//check_presence.sh
Aug 23 16:39:15 srv4hai common[30110]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/smartphone.sh
Aug 23 16:39:15 srv4hai ping_SP[30090]: SHELLY ping_SP.sh started from smartphone, user:'root'
Aug 23 16:39:16 srv4hai common.sh: SHELLY: BTstatus[0]:'NO_BTdweet' dweetAge:''=-
Aug 23 16:39:16 srv4hai check_presence[30105]: SHELLY (0) found /srv/www/viehai/shelly//meter/ping_SP_0.LIVE and NO_BTdweet//srv/www/viehai/shelly//meter/BTscan_BTid_0.No
Aug 23 16:39:16 srv4hai check_presence[30105]: SHELLY (ping_SP__smartphone)-(0) found '/srv/www/viehai/shelly//meter/ping_SP_0.LIVE' for what: 'coming'
Aug 23 16:39:16 srv4hai common.sh: SHELLY: BTstatus[1]:'NO_BTdweet' dweetAge:''=-
Aug 23 16:39:16 srv4hai check_presence[30105]: SHELLY (1) found /srv/www/viehai/shelly//meter/ping_SP_1.DEAD and NO_BTdweet//srv/www/viehai/shelly//meter/BTscan_BTid_1.Yes
Aug 23 16:39:16 srv4hai common.sh: SHELLY: BTstatus[2]:'NO_BTdweet' dweetAge:''=-
Aug 23 16:39:16 srv4hai check_presence[30105]: SHELLY (2) found /srv/www/viehai/shelly//meter/ping_SP_2.DEAD and NO_BTdweet//srv/www/viehai/shelly//meter/BTscan_BTid_2.No
Aug 23 16:39:16 srv4hai check_presence[30105]: SHELLY (ping_SP__smartphone)-(0) what: 'coming' - persPresent: '(__/LIVE) (__/__) (__/__)' - anyPresent: 'true' - otherPresent:'false'
Aug 23 16:39:16 srv4hai check_presence[30105]: SHELLY - check 'coming': anyPresent(true) - lScene(scene_vorraumOn) - sceneONsmall(vorraumOn)
Aug 23 16:39:16 srv4hai check_presence[30105]: SHELLY (ping_SP__smartphone)-(0)-coming: activate_scenario.shtml: vorraumOn
Aug 23 16:39:16 srv4hai activate_scenario[30234]: SHELLY scenario: 'vorraumOn(vorraumon)' started
Aug 23 16:39:16 srv4hai activate_scenario[30234]: SHELLY dayState='day' <--> DayTime=checked NightTime=checked - phoneName:'0'

finished