activate_scenario.sh
Tue Apr 9 19:48:40 CEST 2024
scenario: sekr_UM
source: 192.168.12.101

'night' <--> DayTime=checked , NightTime=checked
-> execution of scenario
-> sending email(s) to 'null'

scenario: 'Sekretär Licht UM-schalten' - 'sekr_UM' - 'sekr_um'

Licht Sekretär (/srv/www/viehai/shelly/log_scenarios/2024/04/09/_0.log)

vorher: 19:48:40.675

http://192.168.12.123/relay/0?turn=toggle
  "ison": true,
  "has_timer": false,
  "timer_started": 0,
  "timer_duration": 0,
  "timer_remaining": 0,
  "source": "http"

nachher: 19:48:40.706
Dauer: 31 msec phName: 'shelly-01:'Wandlicht beim Esstisch''


scenario: 'Sekretär Licht UM-schalten' - 'sekr_UM' - 'sekr_um'

urls : 1
vorher : 19:48:40.648
nachher: 19:48:40.712
Dauer : 64 msec
outfile: '/srv/www/viehai/shelly/log_scenarios/2024/04/09//scene_sekr_UM_20240409_194840.txt'


syslog

Apr  9 19:45:01 srv4hai CRON[10851]: (root) CMD (/etc/linetest/linetest.sh)
Apr  9 19:45:12 srv4hai activate_scenario[10857]: SHELLY scenario: 'LOG_kommen(log_kommen)' started
Apr  9 19:45:12 srv4hai activate_scenario[10857]: SHELLY dayState='night' <--> DayTime=checked NightTime=checked - shelly-03:'Licht Vorzimmer'
Apr  9 19:45:12 srv4hai activate_scenario[10857]: SHELLY scenario: 'LOG_kommen(log_kommen)' TO BE EXECUTED, shelly-03:'Licht Vorzimmer'
Apr  9 19:45:12 srv4hai activate_scenario[10857]: SHELLY scenario: 'LOG_kommen(log_kommen)' took 10 msec, shelly-03:'Licht Vorzimmer'
Apr  9 19:45:13 srv4hai common[10924]: SHELLY common.sh inkludiert von ./bt_scan.sh
Apr  9 19:45:13 srv4hai bt_scan[10924]: SHELLY - who: 'Thomas' / phoneName: 'FP4_Thomas' / '0' / fixAddr: '192.168.12.61;' - found: 'Yes'
Apr  9 19:45:13 srv4hai bt_scan[10924]: SHELLY - ping_SP.sh started for BTmac: '0' - IPv4: '192.168.12.61'
Apr  9 19:45:13 srv4hai common[10954]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//ping_SP.sh
Apr  9 19:45:13 srv4hai ping_SP[10954]: SHELLY ping_SP.sh started from bt_scan
Apr  9 19:45:13 srv4hai common[10965]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//check_presence.sh
Apr  9 19:45:13 srv4hai common.sh: SHELLY: BTstatus[0]:'BTdweet_not_set' dweetAge:'0'=1712684713-1712684713
Apr  9 19:45:13 srv4hai check_presence[10965]: SHELLY (0) found /srv/www/viehai/shelly//meter/ping_SP_0.DEAD and BTdweet_not_set//srv/www/viehai/shelly//meter/BTscan_BTid_0.Yes
Apr  9 19:45:13 srv4hai common.sh: SHELLY: BTstatus[1]:'BTdweet_not_set' dweetAge:'0'=1712684713-1712684713
Apr  9 19:45:13 srv4hai check_presence[10965]: 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
Apr  9 19:45:13 srv4hai common.sh: SHELLY: BTstatus[2]:'BTdweet_not_set' dweetAge:'0'=1712684713-1712684713
Apr  9 19:45:13 srv4hai check_presence[10965]: 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
Apr  9 19:45:13 srv4hai check_presence[10965]: SHELLY (ping_SP__bt_scan)-(0) what: 'coming' - persPresent: '(__/__) (__/__) (__/__)' - anyPresent: 'false' - otherPresent:'false'
Apr  9 19:45:13 srv4hai postfix/pickup[28856]: C71C660245: uid=33 from=
Apr  9 19:45:13 srv4hai postfix/cleanup[10674]: C71C660245: message-id=<20240409174513.C71C660245@mail.viehai.at>
Apr  9 19:45:13 srv4hai check_presence[10965]: SHELLY (ping_SP__bt_scan)-(0)-coming: NOT activate_scenario.shtml: welcomeLight
Apr  9 19:45:13 srv4hai postfix/qmgr[15291]: C71C660245: from=, size=13321, nrcpt=1 (queue active)
Apr  9 19:45:13 srv4hai ping_SP[10954]: SHELLY check_presence.sh coming 0 ping_SP__bt_scan
Apr  9 19:45:13 srv4hai ping_SP[10954]: SHELLY - new process for '192.168.12.61' - '/srv/www/viehai/shelly//meter/ping_SP_0' @~1712684713
Apr  9 19:45:13 srv4hai named[15509]: resolver priming query complete
Apr  9 19:45:14 srv4hai named[15509]: resolver priming query complete
Apr  9 19:45:14 srv4hai postfix/relay/smtp[11062]: C71C660245: to=, relay=mx04.nic.name[209.131.160.34]:25, delay=1.1, delays=0.02/0.02/0.74/0.32, dsn=2.0.0, status=sent (250 2.0.0 Ok: queued as AC79D8004B)
Apr  9 19:45:14 srv4hai postfix/qmgr[15291]: C71C660245: removed
Apr  9 19:45:26 srv4hai postfix/smtpd[10671]: connect from unknown[45.128.96.187]
Apr  9 19:45:26 srv4hai postfix/smtpd[10671]: warning: unknown[45.128.96.187]: SASL LOGIN authentication failed: Invalid authentication mechanism
Apr  9 19:45:26 srv4hai postfix/smtpd[10671]: disconnect from unknown[45.128.96.187] ehlo=1 auth=0/1 quit=1 commands=2/3
Apr  9 19:45:30 srv4hai ping_SP[10954]: SHELLY (0)- ping 192.168.12.61 0 not answered (1) @~1712684730 - 'ping_SP_0'
Apr  9 19:45:35 srv4hai dhcpd[28085]: DHCPDISCOVER from e8:78:29:c0:c2:4e via enp2s0
Apr  9 19:45:35 srv4hai dhcpd[28085]: DHCPOFFER on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0
Apr  9 19:45:35 srv4hai dhcpd[28085]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Apr  9 19:45:35 srv4hai dhcpd[28085]: execute_statement argv[1] = 192.168.12.61
Apr  9 19:45:35 srv4hai dhcpd[28085]: execute_statement argv[2] = fpThomas
Apr  9 19:45:35 srv4hai common[11090]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/smartphone.sh
Apr  9 19:45:45 srv4hai smartphone.sh: SHELLY - ping_SP.sh already started for phoneIP: '192.168.12.61' / 'fpThomas' / '0'
Apr  9 19:45:45 srv4hai dhcpd[28085]: DHCPREQUEST for 192.168.12.61 (192.168.12.252) from e8:78:29:c0:c2:4e via enp2s0
Apr  9 19:45:45 srv4hai dhcpd[28085]: DHCPACK on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0
Apr  9 19:45:45 srv4hai dhcpd[28085]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Apr  9 19:45:45 srv4hai dhcpd[28085]: execute_statement argv[1] = 192.168.12.61
Apr  9 19:45:45 srv4hai dhcpd[28085]: execute_statement argv[2] = fpThomas
Apr  9 19:45:45 srv4hai common[11113]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/smartphone.sh
Apr  9 19:45:45 srv4hai named[15509]: resolver priming query complete
Apr  9 19:45:46 srv4hai named[15509]: resolver priming query complete
Apr  9 19:45:47 srv4hai named[15509]: resolver priming query complete
Apr  9 19:45:48 srv4hai named[15509]: resolver priming query complete
Apr  9 19:45:54 srv4hai named[15509]: resolver priming query complete
Apr  9 19:45:55 srv4hai smartphone.sh: SHELLY - ping_SP.sh already started for phoneIP: '192.168.12.61' / 'fpThomas' / '0'
Apr  9 19:45:55 srv4hai dhcpd[28085]: DHCPREQUEST for 192.168.12.61 (192.168.12.252) from e8:78:29:c0:c2:4e via enp2s0
Apr  9 19:45:55 srv4hai dhcpd[28085]: DHCPACK on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0
Apr  9 19:45:55 srv4hai dhcpd[28085]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Apr  9 19:45:55 srv4hai dhcpd[28085]: execute_statement argv[1] = 192.168.12.61
Apr  9 19:45:55 srv4hai dhcpd[28085]: execute_statement argv[2] = fpThomas
Apr  9 19:45:55 srv4hai named[15509]: client @0x7f931c157870 192.168.12.252#56347/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: fpThomas.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Apr  9 19:45:55 srv4hai common[11135]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/smartphone.sh
Apr  9 19:45:55 srv4hai ping_SP[10954]: SHELLY (0) - ping 192.168.12.61 1 sucessfully  @~1712684755 - 'ping_SP_0'
Apr  9 19:45:55 srv4hai ping_SP[10954]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Apr  9 19:45:59 srv4hai named[15509]: resolver priming query complete
Apr  9 19:46:01 srv4hai CRON[11164]: (root) CMD (/etc/linetest/linetest.sh)
Apr  9 19:46:03 srv4hai named[15509]: resolver priming query complete
Apr  9 19:46:05 srv4hai smartphone.sh: SHELLY - ping_SP.sh already started for phoneIP: '192.168.12.61' / 'fpThomas' / '0'
Apr  9 19:46:05 srv4hai dhcpd[28085]: DHCPREQUEST for 192.168.12.61 (192.168.12.252) from e8:78:29:c0:c2:4e via enp2s0
Apr  9 19:46:05 srv4hai dhcpd[28085]: DHCPACK on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0
Apr  9 19:46:05 srv4hai dhcpd[28085]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Apr  9 19:46:05 srv4hai dhcpd[28085]: execute_statement argv[1] = 192.168.12.61
Apr  9 19:46:05 srv4hai dhcpd[28085]: execute_statement argv[2] = fpThomas
Apr  9 19:46:05 srv4hai named[15509]: client @0x7f9310045f00 192.168.12.252#59961/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: fpThomas.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Apr  9 19:46:05 srv4hai named[15509]: client @0x7f931c157870 192.168.12.252#56347/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: fpThomas.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Apr  9 19:46:05 srv4hai common[11177]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/smartphone.sh
Apr  9 19:46:15 srv4hai smartphone.sh: SHELLY - ping_SP.sh already started for phoneIP: '192.168.12.61' / 'fpThomas' / '0'
Apr  9 19:46:15 srv4hai dhcpd[28085]: DHCPREQUEST for 192.168.12.61 (192.168.12.252) from e8:78:29:c0:c2:4e via enp2s0
Apr  9 19:46:15 srv4hai dhcpd[28085]: DHCPACK on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0
Apr  9 19:46:15 srv4hai named[15509]: client @0x7f931c157870 192.168.12.252#56347/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: fpThomas.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Apr  9 19:46:15 srv4hai named[15509]: client @0x7f931c157870 192.168.12.252#56347/key rndc-key: signer "rndc-key" approved
Apr  9 19:46:15 srv4hai named[15509]: client @0x7f931c157870 192.168.12.252#56347/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'fpThomas.viehai.at' TXT
Apr  9 19:46:15 srv4hai named[15509]: client @0x7f931c157870 192.168.12.252#56347/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'fpThomas.viehai.at' TXT "310ebd0eafccd9c2e311facf8a4b0e4ad2"
Apr  9 19:46:15 srv4hai named[15509]: client @0x7f931c157870 192.168.12.252#56347/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'fpThomas.viehai.at' A
Apr  9 19:46:15 srv4hai named[15509]: client @0x7f931c157870 192.168.12.252#56347/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'fpThomas.viehai.at' A 192.168.12.61
Apr  9 19:46:15 srv4hai named[15509]: client @0x7f931c157870 192.168.12.252#56347/key rndc-key: signer "rndc-key" approved
Apr  9 19:46:15 srv4hai dhcpd[28085]: Added new forward map from fpThomas.viehai.at to 192.168.12.61
Apr  9 19:46:15 srv4hai named[15509]: client @0x7f931c157870 192.168.12.252#56347/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'fpThomas.viehai.at' TXT
Apr  9 19:46:15 srv4hai named[15509]: client @0x7f931c157870 192.168.12.252#56347/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'fpThomas.viehai.at' TXT "310ebd0eafccd9c2e311facf8a4b0e4ad2"
Apr  9 19:46:15 srv4hai named[15509]: client @0x7f931c157870 192.168.12.252#56347/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'fpThomas.viehai.at' A
Apr  9 19:46:15 srv4hai named[15509]: client @0x7f931c157870 192.168.12.252#56347/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'fpThomas.viehai.at' A 192.168.12.61
Apr  9 19:46:15 srv4hai named[15509]: client @0x7f931c157870 192.168.12.252#56347/key rndc-key: signer "rndc-key" approved
Apr  9 19:46:15 srv4hai named[15509]: client @0x7f931c157870 192.168.12.252#56347/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'fpThomas.viehai.at' TXT
Apr  9 19:46:15 srv4hai dhcpd[28085]: Added new forward map from fpThomas.viehai.at to 192.168.12.61
Apr  9 19:46:15 srv4hai named[15509]: client @0x7f931c157870 192.168.12.252#56347/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'fpThomas.viehai.at' TXT "310ebd0eafccd9c2e311facf8a4b0e4ad2"
Apr  9 19:46:15 srv4hai named[15509]: client @0x7f931c157870 192.168.12.252#56347/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'fpThomas.viehai.at' A
Apr  9 19:46:15 srv4hai named[15509]: client @0x7f931c157870 192.168.12.252#56347/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'fpThomas.viehai.at' A 192.168.12.61
Apr  9 19:46:15 srv4hai dhcpd[28085]: Added new forward map from fpThomas.viehai.at to 192.168.12.61
Apr  9 19:46:15 srv4hai named[15509]: client @0x7f931c157870 192.168.12.252#56347/key rndc-key: signer "rndc-key" approved
Apr  9 19:46:15 srv4hai named[15509]: client @0x7f931c157870 192.168.12.252#56347/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'fpThomas.viehai.at' TXT
Apr  9 19:46:15 srv4hai named[15509]: client @0x7f931c157870 192.168.12.252#56347/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'fpThomas.viehai.at' TXT "310ebd0eafccd9c2e311facf8a4b0e4ad2"
Apr  9 19:46:15 srv4hai named[15509]: client @0x7f931c157870 192.168.12.252#56347/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'fpThomas.viehai.at' A
Apr  9 19:46:15 srv4hai named[15509]: client @0x7f931c157870 192.168.12.252#56347/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'fpThomas.viehai.at' A 192.168.12.61
Apr  9 19:46:15 srv4hai dhcpd[28085]: Added new forward map from fpThomas.viehai.at to 192.168.12.61
Apr  9 19:46:15 srv4hai named[15509]: client @0x7f931c157870 192.168.12.252#56347/key rndc-key: signer "rndc-key" approved
Apr  9 19:46:15 srv4hai named[15509]: client @0x7f931c157870 192.168.12.252#56347/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '61.12.168.192.in-addr.arpa' PTR
Apr  9 19:46:15 srv4hai named[15509]: client @0x7f931c157870 192.168.12.252#56347/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': adding an RR at '61.12.168.192.in-addr.arpa' PTR fpThomas.viehai.at.
Apr  9 19:46:15 srv4hai named[15509]: client @0x7f931c157870 192.168.12.252#56347/key rndc-key: signer "rndc-key" approved
Apr  9 19:46:15 srv4hai dhcpd[28085]: Added reverse map from 61.12.168.192.in-addr.arpa. to fpThomas.viehai.at
Apr  9 19:46:15 srv4hai named[15509]: client @0x7f931c157870 192.168.12.252#56347/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '61.12.168.192.in-addr.arpa' PTR
Apr  9 19:46:15 srv4hai named[15509]: client @0x7f931c157870 192.168.12.252#56347/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': adding an RR at '61.12.168.192.in-addr.arpa' PTR fpThomas.viehai.at.
Apr  9 19:46:15 srv4hai dhcpd[28085]: Added reverse map from 61.12.168.192.in-addr.arpa. to fpThomas.viehai.at
Apr  9 19:46:15 srv4hai named[15509]: client @0x7f931c157870 192.168.12.252#56347/key rndc-key: signer "rndc-key" approved
Apr  9 19:46:15 srv4hai named[15509]: client @0x7f931c157870 192.168.12.252#56347/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '61.12.168.192.in-addr.arpa' PTR
Apr  9 19:46:15 srv4hai named[15509]: client @0x7f931c157870 192.168.12.252#56347/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': adding an RR at '61.12.168.192.in-addr.arpa' PTR fpThomas.viehai.at.
Apr  9 19:46:15 srv4hai named[15509]: client @0x7f931c157870 192.168.12.252#56347/key rndc-key: signer "rndc-key" approved
Apr  9 19:46:15 srv4hai named[15509]: client @0x7f931c157870 192.168.12.252#56347/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '61.12.168.192.in-addr.arpa' PTR
Apr  9 19:46:15 srv4hai named[15509]: client @0x7f931c157870 192.168.12.252#56347/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': adding an RR at '61.12.168.192.in-addr.arpa' PTR fpThomas.viehai.at.
Apr  9 19:46:15 srv4hai dhcpd[28085]: Added reverse map from 61.12.168.192.in-addr.arpa. to fpThomas.viehai.at
Apr  9 19:46:15 srv4hai dhcpd[28085]: Added reverse map from 61.12.168.192.in-addr.arpa. to fpThomas.viehai.at
Apr  9 19:46:15 srv4hai named[15509]: resolver priming query complete
Apr  9 19:46:17 srv4hai named[15509]: resolver priming query complete
Apr  9 19:46:19 srv4hai named[15509]: resolver priming query complete
Apr  9 19:46:20 srv4hai named[15509]: resolver priming query complete
Apr  9 19:46:32 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=11203, secured, session=
Apr  9 19:46:32 srv4hai dovecot: imap(miglincit)<11203>: Logged out in=693 out=42420 deleted=0 expunged=0 trashed=0 hdr_count=50 hdr_bytes=18328 body_count=0 body_bytes=0
Apr  9 19:46:49 srv4hai named[15509]: resolver priming query complete
Apr  9 19:46:59 srv4hai named[15509]: resolver priming query complete
Apr  9 19:47:01 srv4hai CRON[11217]: (root) CMD (/etc/linetest/linetest.sh)
Apr  9 19:47:08 srv4hai ping_SP[10954]: SHELLY (0) - ping 192.168.12.61 1 sucessfully  @~1712684828 - 'ping_SP_0'
Apr  9 19:47:08 srv4hai ping_SP[10954]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Apr  9 19:47:53 srv4hai named[15509]: resolver priming query complete
Apr  9 19:47:59 srv4hai named[15509]: resolver priming query complete
Apr  9 19:48:01 srv4hai CRON[11243]: (root) CMD (/etc/linetest/linetest.sh)
Apr  9 19:48:21 srv4hai ping_SP[10954]: SHELLY (0) - ping 192.168.12.61 1 sucessfully  @~1712684901 - 'ping_SP_0'
Apr  9 19:48:21 srv4hai ping_SP[10954]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Apr  9 19:48:40 srv4hai activate_scenario[11271]: SHELLY scenario: 'sekr_UM(sekr_um)' started
Apr  9 19:48:40 srv4hai activate_scenario[11271]: SHELLY dayState='night' <--> DayTime=checked NightTime=checked - shelly-01:'Wandlicht beim Esstisch'

finished