activate_scenario.sh
Mon Nov 25 20:38:40 CET 2024
scenario: vorraumOn
phoneName: 1

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

VZ Licht (/srv/www/viehai/shelly/log_scenarios/2024/11/25/_0.log)

vorher: 20:38:40.516

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

nachher: 20:38:40.620
Dauer: 104 msec phName: 'phoneName:'1''

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

urls : 1
vorher : 20:38:40.492
nachher: 20:38:40.628
Dauer : 135 msec
outfile: '/srv/www/viehai/shelly/log_scenarios/2024/11/25//scene_vorraumOn_20241125_203840.txt'

syslog

Nov 25 20:35:01 srv4hai CRON[1253]: (root) CMD (/etc/linetest/linetest.sh)
Nov 25 20:35:19 srv4hai postfix/anvil[30375]: statistics: max connection rate 1/60s for (smtp:80.94.95.239) at Nov 25 20:28:05
Nov 25 20:35:19 srv4hai postfix/anvil[30375]: statistics: max connection count 1 for (smtp:80.94.95.239) at Nov 25 20:28:05
Nov 25 20:35:19 srv4hai postfix/anvil[30375]: statistics: max cache size 1 at Nov 25 20:28:05
Nov 25 20:35:45 srv4hai postfix/smtpd[1291]: connect from unknown[80.94.95.239]
Nov 25 20:35:48 srv4hai postfix/smtpd[1291]: warning: unknown[80.94.95.239]: SASL LOGIN authentication failed: Invalid authentication mechanism
Nov 25 20:35:48 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=1295, secured, session=<27Tz1sEnCrgAAAAAAAAAAAAAAAAAAAAB>
Nov 25 20:35:48 srv4hai dovecot: imap(miglincit)<1295><27Tz1sEnCrgAAAAAAAAAAAAAAAAAAAAB>: Logged out in=137 out=1088 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Nov 25 20:35:49 srv4hai postfix/smtpd[1291]: disconnect from unknown[80.94.95.239] ehlo=1 auth=0/1 rset=1 quit=1 commands=3/4
Nov 25 20:35:54 srv4hai mosquitto[18551]: Client shellyplus1-441793a58ae4 has exceeded timeout, disconnecting.
Nov 25 20:35:54 srv4hai mosquitto[18551]: Socket error on client shellyplus1-441793a58ae4, disconnecting.
Nov 25 20:35:54 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=1302, secured, session=
Nov 25 20:35:54 srv4hai dovecot: imap(rozumm)<1302>: Logged out in=92 out=1063 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Nov 25 20:36:01 srv4hai CRON[1311]: (root) CMD (/etc/linetest/linetest.sh)
Nov 25 20:36:06 srv4hai ping_SP[23057]: SHELLY (0) - ping 192.168.12.61 1 sucessfully  @~1732563366 - 'ping_SP_0'
Nov 25 20:36:06 srv4hai ping_SP[23057]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Nov 25 20:36:30 srv4hai fetchmail[1651]: 7 messages (7 seen) for rozmig at mail.akis.at (1753652 octets).
Nov 25 20:36:34 srv4hai fetchmail[1651]: 57690 messages (57690 seen) for tmiglinci at mail.akis.at (442281284 octets).
Nov 25 20:36:35 srv4hai fetchmail[1651]: 11106 messages (11106 seen) for srozum at mail.akis.at (1338079262 octets).
Nov 25 20:36:40 srv4hai fetchmail[1651]: 27449 messages (27449 seen) for mrozum at mail.akis.at (437667784 octets).
Nov 25 20:36:40 srv4hai postfix/smtpd[1291]: connect from unknown[194.186.69.166]
Nov 25 20:36:40 srv4hai fetchmail[1651]: 1157 messages (1157 seen) for admin@bastelstube.wien at pop3.world4you.com (52170273 octets).
Nov 25 20:36:43 srv4hai postfix/smtpd[1291]: lost connection after EHLO from unknown[194.186.69.166]
Nov 25 20:36:43 srv4hai postfix/smtpd[1291]: disconnect from unknown[194.186.69.166] ehlo=2 starttls=1 commands=3
Nov 25 20:36:55 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=1365, secured, session=
Nov 25 20:36:55 srv4hai dovecot: imap(rozumm)<1365>: Logged out in=92 out=1063 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Nov 25 20:37:01 srv4hai CRON[1369]: (root) CMD (/etc/linetest/linetest.sh)
Nov 25 20:37:15 srv4hai dhcpd[21239]: DHCPDISCOVER from 44:17:93:a5:8a:e4 via enp2s0
Nov 25 20:37:15 srv4hai dhcpd[21239]: DHCPOFFER on 192.168.12.147 to 44:17:93:a5:8a:e4 via enp2s0
Nov 25 20:37:15 srv4hai dhcpd[21239]: execute_statement argv[0] = /srv/www/viehai/shelly/json.sh
Nov 25 20:37:15 srv4hai dhcpd[21239]: execute_statement argv[1] = 192.168.12.147
Nov 25 20:37:15 srv4hai dhcpd[21239]: execute_statement argv[2] = shelly-47
Nov 25 20:37:15 srv4hai dhcpd[21239]: DHCPREQUEST for 192.168.12.147 (192.168.12.252) from 44:17:93:a5:8a:e4 via enp2s0
Nov 25 20:37:15 srv4hai dhcpd[21239]: DHCPACK on 192.168.12.147 to 44:17:93:a5:8a:e4 via enp2s0
Nov 25 20:37:15 srv4hai named[30406]: client @0x7f932c0cbfc0 192.168.12.252#60585/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: shelly-47.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Nov 25 20:37:15 srv4hai named[30406]: client @0x7f933011a820 192.168.12.252#44461/key rndc-key: signer "rndc-key" approved
Nov 25 20:37:15 srv4hai named[30406]: client @0x7f933011a820 192.168.12.252#44461/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'shelly-47.viehai.at' TXT
Nov 25 20:37:15 srv4hai named[30406]: client @0x7f933011a820 192.168.12.252#44461/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'shelly-47.viehai.at' TXT "31f97534415d36e3a13ec57b0310c0867f"
Nov 25 20:37:15 srv4hai named[30406]: client @0x7f933011a820 192.168.12.252#44461/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'shelly-47.viehai.at' A
Nov 25 20:37:15 srv4hai named[30406]: client @0x7f933011a820 192.168.12.252#44461/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'shelly-47.viehai.at' A 192.168.12.147
Nov 25 20:37:15 srv4hai dhcpd[21239]: Added new forward map from shelly-47.viehai.at to 192.168.12.147
Nov 25 20:37:15 srv4hai named[30406]: client @0x7f933000d2b0 192.168.12.252#37925/key rndc-key: signer "rndc-key" approved
Nov 25 20:37:15 srv4hai named[30406]: client @0x7f933000d2b0 192.168.12.252#37925/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '147.12.168.192.in-addr.arpa' PTR
Nov 25 20:37:15 srv4hai named[30406]: client @0x7f933000d2b0 192.168.12.252#37925/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': adding an RR at '147.12.168.192.in-addr.arpa' PTR shelly-47.viehai.at.
Nov 25 20:37:15 srv4hai dhcpd[21239]: Added reverse map from 147.12.168.192.in-addr.arpa. to shelly-47.viehai.at
Nov 25 20:37:15 srv4hai common[1393]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/grab_shelly.sh
Nov 25 20:37:15 srv4hai grab_shelly.sh: SHELLY shellyName/1:'shelly-47' - shIP/2:'' perform:'conf'
Nov 25 20:37:16 srv4hai conf_shelly.sh: SHELLY: PARAMS  shName/1:'shelly-47' - shIP/2:'192.168.12.147'
Nov 25 20:37:16 srv4hai conf_shelly.sh: SHELLY: usage/name 'GB Schreibtischlampe links' already set for '192.168.12.147'
Nov 25 20:37:16 srv4hai mosquitto[18551]: New connection from 192.168.12.147 on port 1883.
Nov 25 20:37:16 srv4hai mosquitto[18551]: New client connected from 192.168.12.147 as shellyplus1-441793a58ae4 (c1, k60, u'shmqtt').
Nov 25 20:37:16 srv4hai conf_shelly.sh: SHELLY:'shelly-47' 'conf(0)' - allready transmitted - skipping
Nov 25 20:37:19 srv4hai ping_SP[23057]: SHELLY (0) - ping 192.168.12.61 1 sucessfully  @~1732563439 - 'ping_SP_0'
Nov 25 20:37:19 srv4hai ping_SP[23057]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Nov 25 20:37:55 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=1495, secured, session=
Nov 25 20:37:55 srv4hai dovecot: imap(rozumm)<1495>: Logged out in=92 out=1063 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Nov 25 20:38:01 srv4hai CRON[1505]: (root) CMD (/etc/linetest/linetest.sh)
Nov 25 20:38:09 srv4hai dhcpd[21239]: DHCPDISCOVER from f0:39:65:25:8d:c7 via enp2s0
Nov 25 20:38:09 srv4hai dhcpd[21239]: DHCPOFFER on 192.168.12.62 to f0:39:65:25:8d:c7 via enp2s0
Nov 25 20:38:09 srv4hai dhcpd[21239]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Nov 25 20:38:09 srv4hai dhcpd[21239]: execute_statement argv[1] = 192.168.12.62
Nov 25 20:38:09 srv4hai dhcpd[21239]: execute_statement argv[2] = GreteS20
Nov 25 20:38:09 srv4hai common[1518]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/smartphone.sh
Nov 25 20:38:19 srv4hai smartphone.sh: SHELLY - ping_SP.sh started for phoneIP: '192.168.12.62' / 'GreteS20' / '1'
Nov 25 20:38:19 srv4hai common[1542]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//ping_SP.sh
Nov 25 20:38:19 srv4hai dhcpd[21239]: DHCPREQUEST for 192.168.12.62 (192.168.12.252) from f0:39:65:25:8d:c7 via enp2s0
Nov 25 20:38:19 srv4hai dhcpd[21239]: DHCPACK on 192.168.12.62 to f0:39:65:25:8d:c7 via enp2s0
Nov 25 20:38:19 srv4hai dhcpd[21239]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Nov 25 20:38:19 srv4hai dhcpd[21239]: execute_statement argv[1] = 192.168.12.62
Nov 25 20:38:19 srv4hai dhcpd[21239]: execute_statement argv[2] = GreteS20
Nov 25 20:38:19 srv4hai common[1555]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/smartphone.sh
Nov 25 20:38:19 srv4hai ping_SP[1542]: SHELLY ping_SP.sh started from smartphone, user:'root'
Nov 25 20:38:29 srv4hai smartphone.sh: SHELLY - ping_SP.sh started for phoneIP: '192.168.12.62' / 'GreteS20' / '1'
Nov 25 20:38:29 srv4hai common[1594]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//ping_SP.sh
Nov 25 20:38:29 srv4hai common[1602]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//check_presence.sh
Nov 25 20:38:29 srv4hai dhcpd[21239]: DHCPREQUEST for 192.168.12.62 (192.168.12.252) from f0:39:65:25:8d:c7 via enp2s0
Nov 25 20:38:29 srv4hai dhcpd[21239]: DHCPACK on 192.168.12.62 to f0:39:65:25:8d:c7 via enp2s0
Nov 25 20:38:29 srv4hai dhcpd[21239]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Nov 25 20:38:29 srv4hai dhcpd[21239]: execute_statement argv[1] = 192.168.12.62
Nov 25 20:38:29 srv4hai dhcpd[21239]: execute_statement argv[2] = GreteS20
Nov 25 20:38:29 srv4hai named[30406]: client @0x7f9338088040 192.168.12.252#58269/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: GreteS20.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Nov 25 20:38:29 srv4hai common[1614]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/smartphone.sh
Nov 25 20:38:29 srv4hai ping_SP[1594]: SHELLY ping_SP.sh started from smartphone, user:'root'
Nov 25 20:38:30 srv4hai common.sh: SHELLY: BTstatus[0]:'On' dweetAge:'35'=1732563510-1732563475
Nov 25 20:38:30 srv4hai check_presence[1602]: SHELLY (0) found /srv/www/viehai/shelly//meter/ping_SP_0.LIVE and On//srv/www/viehai/shelly//meter/BTscan_BTid_0.No
Nov 25 20:38:30 srv4hai check_presence[1602]: SHELLY (ping_SP__smartphone)-(1) found '/srv/www/viehai/shelly//meter/ping_SP_0.LIVE' for what: 'coming'
Nov 25 20:38:30 srv4hai common.sh: SHELLY: BTstatus[1]:'On' dweetAge:'35'=1732563510-1732563475
Nov 25 20:38:30 srv4hai check_presence[1602]: SHELLY (1) found /srv/www/viehai/shelly//meter/ping_SP_1.LIVE and On//srv/www/viehai/shelly//meter/BTscan_BTid_1.No
Nov 25 20:38:30 srv4hai check_presence[1602]: SHELLY (ping_SP__smartphone)-(1) found '/srv/www/viehai/shelly//meter/ping_SP_1.LIVE' for what: 'coming'
Nov 25 20:38:30 srv4hai common.sh: SHELLY: BTstatus[2]:'null' dweetAge:'35'=1732563510-1732563475
Nov 25 20:38:30 srv4hai check_presence[1602]: SHELLY (2) found /srv/www/viehai/shelly//meter/ping_SP_2.DEAD and null//srv/www/viehai/shelly//meter/BTscan_BTid_2.No
Nov 25 20:38:30 srv4hai check_presence[1602]: SHELLY (ping_SP__smartphone)-(1) what: 'coming' - persPresent: '(BT/LIVE) (BT/LIVE) (__/__)' - anyPresent: 'true' - otherPresent:'true'
Nov 25 20:38:30 srv4hai check_presence[1602]: SHELLY - check 'coming': anyPresent(true) - lScene(scene_buero_UM) - sceneONsmall(vorraumOn)
Nov 25 20:38:30 srv4hai check_presence[1602]: SHELLY (ping_SP__smartphone)-(1)-coming: activate_scenario.shtml: vorraumOn
Nov 25 20:38:30 srv4hai activate_scenario[1781]: SHELLY scenario: 'vorraumOn(vorraumon)' started
Nov 25 20:38:30 srv4hai activate_scenario[1781]: SHELLY dayState='night' <--> DayTime=checked NightTime=checked - phoneName:'1'
Nov 25 20:38:30 srv4hai activate_scenario[1781]: SHELLY scenario: 'vorraumOn(vorraumon)' TO BE EXECUTED, phoneName:'1'
Nov 25 20:38:30 srv4hai postfix/pickup[18069]: 7A02063463: uid=33 from=
Nov 25 20:38:30 srv4hai postfix/cleanup[1831]: 7A02063463: message-id=<20241125193830.7A02063463@mail.viehai.at>
Nov 25 20:38:30 srv4hai postfix/qmgr[8566]: 7A02063463: from=, size=12393, nrcpt=1 (queue active)
Nov 25 20:38:30 srv4hai activate_scenario[1781]: SHELLY: scenario: 'vorraumOn(vorraumon)'-'shelly-03'-'VZ Licht' --> '0?turn=on'
Nov 25 20:38:30 srv4hai proc_url[1848]: SHELLY: OF:'/srv/www/viehai/shelly/log_scenarios/2024/11/25/_0.log' - shellyUsage:'VZ Licht' - scUrl:'http://192.168.12.103/relay/0?turn=on' - phName:'phoneName:'1''
Nov 25 20:38:30 srv4hai activate_scenario[1781]: SHELLY scenario: 'vorraumOn(vorraumon)' took 105 msec, phoneName:'1'
Nov 25 20:38:30 srv4hai ping_SP[1542]: SHELLY check_presence.sh coming 1 ping_SP__smartphone
Nov 25 20:38:31 srv4hai postfix/relay/smtp[1844]: 7A02063463: to=, relay=mx03.nic.name[209.131.161.54]:25, delay=0.81, delays=0.02/0.01/0.53/0.26, dsn=2.0.0, status=sent (250 2.0.0 Ok: queued as 1BCED6036D)
Nov 25 20:38:31 srv4hai postfix/qmgr[8566]: 7A02063463: removed
Nov 25 20:38:32 srv4hai ping_SP[23057]: SHELLY (0) - ping 192.168.12.61 1 sucessfully  @~1732563512 - 'ping_SP_0'
Nov 25 20:38:32 srv4hai ping_SP[23057]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Nov 25 20:38:32 srv4hai ping_SP[1542]: SHELLY - running process-id(1542): '1542 /bin/bash /srv/www/viehai/shelly//ping_SP.sh 192.168.12.62 smartphone#0121594 /bin/bash /srv/www/viehai/shelly//ping_SP.sh 192.168.12.62 smartphone#0121614 /bin/bash /srv/www/viehai/shelly/smartphone.sh 192.168.12.62 GreteS20' - 'ping_SP_1' - exiting ==============
Nov 25 20:38:39 srv4hai smartphone.sh: SHELLY - ping_SP.sh started for phoneIP: '192.168.12.62' / 'GreteS20' / '1'
Nov 25 20:38:39 srv4hai common[1912]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//ping_SP.sh
Nov 25 20:38:39 srv4hai common[1921]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//check_presence.sh
Nov 25 20:38:39 srv4hai dhcpd[21239]: DHCPREQUEST for 192.168.12.62 (192.168.12.252) from f0:39:65:25:8d:c7 via enp2s0
Nov 25 20:38:39 srv4hai dhcpd[21239]: DHCPACK on 192.168.12.62 to f0:39:65:25:8d:c7 via enp2s0
Nov 25 20:38:39 srv4hai dhcpd[21239]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Nov 25 20:38:39 srv4hai dhcpd[21239]: execute_statement argv[1] = 192.168.12.62
Nov 25 20:38:39 srv4hai dhcpd[21239]: execute_statement argv[2] = GreteS20
Nov 25 20:38:39 srv4hai named[30406]: client @0x7f9350685ed0 192.168.12.252#48121/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: GreteS20.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Nov 25 20:38:39 srv4hai named[30406]: client @0x7f9338088040 192.168.12.252#58269/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: GreteS20.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Nov 25 20:38:39 srv4hai common[1934]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/smartphone.sh
Nov 25 20:38:39 srv4hai ping_SP[1912]: SHELLY ping_SP.sh started from smartphone, user:'root'
Nov 25 20:38:40 srv4hai common.sh: SHELLY: BTstatus[0]:'On' dweetAge:'45'=1732563520-1732563475
Nov 25 20:38:40 srv4hai check_presence[1921]: SHELLY (0) found /srv/www/viehai/shelly//meter/ping_SP_0.LIVE and On//srv/www/viehai/shelly//meter/BTscan_BTid_0.No
Nov 25 20:38:40 srv4hai check_presence[1921]: SHELLY (ping_SP__smartphone)-(1) found '/srv/www/viehai/shelly//meter/ping_SP_0.LIVE' for what: 'coming'
Nov 25 20:38:40 srv4hai common.sh: SHELLY: BTstatus[1]:'On' dweetAge:'45'=1732563520-1732563475
Nov 25 20:38:40 srv4hai check_presence[1921]: SHELLY (1) found /srv/www/viehai/shelly//meter/ping_SP_1.LIVE and On//srv/www/viehai/shelly//meter/BTscan_BTid_1.No
Nov 25 20:38:40 srv4hai check_presence[1921]: SHELLY (ping_SP__smartphone)-(1) found '/srv/www/viehai/shelly//meter/ping_SP_1.LIVE' for what: 'coming'
Nov 25 20:38:40 srv4hai common.sh: SHELLY: BTstatus[2]:'null' dweetAge:'45'=1732563520-1732563475
Nov 25 20:38:40 srv4hai check_presence[1921]: SHELLY (2) found /srv/www/viehai/shelly//meter/ping_SP_2.DEAD and null//srv/www/viehai/shelly//meter/BTscan_BTid_2.No
Nov 25 20:38:40 srv4hai check_presence[1921]: SHELLY (ping_SP__smartphone)-(1) what: 'coming' - persPresent: '(BT/LIVE) (BT/LIVE) (__/__)' - anyPresent: 'true' - otherPresent:'true'
Nov 25 20:38:40 srv4hai check_presence[1921]: SHELLY - check 'coming': anyPresent(true) - lScene(scene_vorraumOn) - sceneONsmall(vorraumOn)
Nov 25 20:38:40 srv4hai check_presence[1921]: SHELLY (ping_SP__smartphone)-(1)-coming: activate_scenario.shtml: vorraumOn
Nov 25 20:38:40 srv4hai activate_scenario[2107]: SHELLY scenario: 'vorraumOn(vorraumon)' started
Nov 25 20:38:40 srv4hai activate_scenario[2107]: SHELLY dayState='night' <--> DayTime=checked NightTime=checked - phoneName:'1'

finished