activate_scenario.sh
Sun Jun 16 18:19:29 CEST 2024
scenario: vorraumOn
phoneName: 0

'day' <--> DayTime=checked , NightTime=checked
-> execution of scenario
-> sending email(s) to 'thomas@miglinci.name'

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

Licht Vorzimmer (/srv/www/viehai/shelly/log_scenarios/2024/06/16/_0.log)

vorher: 18:19:29.872

http://192.168.12.103/relay/0?turn=on
  "ison": true,
  "has_timer": false,
  "timer_started": 0,
  "timer_duration": 0,
  "timer_remaining": 0,
  "overpower": false,
  "source": "input"

nachher: 18:19:29.909
Dauer: 37 msec phName: 'phoneName:'0''


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

urls : 1
vorher : 18:19:29.852
nachher: 18:19:29.915
Dauer : 63 msec
outfile: '/srv/www/viehai/shelly/log_scenarios/2024/06/16//scene_vorraumOn_20240616_181929.txt'


syslog

Jun 16 18:16:01 srv4hai CRON[2538]: (root) CMD (/etc/linetest/linetest.sh)
Jun 16 18:16:11 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=2548, secured, session=<1Xu+KgQbVtgAAAAAAAAAAAAAAAAAAAAB>
Jun 16 18:16:11 srv4hai dovecot: imap(rozumm)<2548><1Xu+KgQbVtgAAAAAAAAAAAAAAAAAAAAB>: Logged out in=92 out=1059 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Jun 16 18:16:21 srv4hai named[30406]: validating at-w-comdiepres-relay.iocnt.net/CNAME: no valid signature found
Jun 16 18:16:21 srv4hai named[30406]: validating at-w-comdiepres-relay.iocnt.net/CNAME: no valid signature found
Jun 16 18:16:21 srv4hai named[30406]: validating relay-client-c01.iocnt.net/A: no valid signature found
Jun 16 18:16:28 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=2557, secured, session=
Jun 16 18:16:29 srv4hai dovecot: imap(miglincit)<2557>: Logged out in=276 out=1684 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Jun 16 18:16:43 srv4hai postfix/smtpd[2563]: warning: database /etc/aliases.db is older than source file /etc/aliases
Jun 16 18:16:43 srv4hai postfix/smtpd[2563]: connect from unknown[193.32.162.89]
Jun 16 18:16:43 srv4hai postfix/smtpd[2563]: warning: unknown[193.32.162.89]: SASL LOGIN authentication failed: Invalid authentication mechanism
Jun 16 18:16:43 srv4hai postfix/smtpd[2563]: lost connection after AUTH from unknown[193.32.162.89]
Jun 16 18:16:43 srv4hai postfix/smtpd[2563]: disconnect from unknown[193.32.162.89] ehlo=1 auth=0/1 commands=1/2
Jun 16 18:16:52 srv4hai ping_SP[23084]: SHELLY (1) - ping 192.168.12.62 1 sucessfully  @~1718554612 - 'ping_SP_1'
Jun 16 18:16:52 srv4hai ping_SP[23084]: SHELLY (1) - ping 192.168.12.62 pCnt:'1'
Jun 16 18:17:01 srv4hai CRON[2593]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)
Jun 16 18:17:01 srv4hai CRON[2594]: (root) CMD (/etc/linetest/linetest.sh)
Jun 16 18:17:11 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=2601, secured, session=
Jun 16 18:17:11 srv4hai dovecot: imap(rozumm)<2601>: Logged out in=92 out=1059 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Jun 16 18:17:54 srv4hai dhcpd[30731]: DHCPINFORM from 192.168.12.20 via enp2s0
Jun 16 18:17:54 srv4hai dhcpd[30731]: DHCPACK to 192.168.12.20 (08:92:04:5f:12:67) via enp2s0
Jun 16 18:17:55 srv4hai ping_SP[23084]: SHELLY (1) - ping 192.168.12.62 1 sucessfully  @~1718554675 - 'ping_SP_1'
Jun 16 18:17:55 srv4hai ping_SP[23084]: SHELLY (1) - ping 192.168.12.62 pCnt:'1'
Jun 16 18:18:01 srv4hai CRON[2637]: (root) CMD (/etc/linetest/linetest.sh)
Jun 16 18:18:11 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=2643, secured, session=
Jun 16 18:18:11 srv4hai dovecot: imap(rozumm)<2643>: Logged out in=92 out=1059 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Jun 16 18:18:58 srv4hai ping_SP[23084]: SHELLY (1) - ping 192.168.12.62 1 sucessfully  @~1718554738 - 'ping_SP_1'
Jun 16 18:18:58 srv4hai ping_SP[23084]: SHELLY (1) - ping 192.168.12.62 pCnt:'1'
Jun 16 18:19:01 srv4hai CRON[2679]: (root) CMD (/etc/linetest/linetest.sh)
Jun 16 18:19:11 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=2688, secured, session=
Jun 16 18:19:11 srv4hai dovecot: imap(rozumm)<2688>: Logged out in=92 out=1059 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Jun 16 18:19:22 srv4hai dhcpd[30731]: DHCPDISCOVER from 48:55:19:00:b3:58 via enp2s0
Jun 16 18:19:22 srv4hai dhcpd[30731]: DHCPOFFER on 192.168.12.137 to 48:55:19:00:b3:58 via enp2s0
Jun 16 18:19:22 srv4hai dhcpd[30731]: execute_statement argv[0] = /srv/www/viehai/shelly/json.sh
Jun 16 18:19:22 srv4hai dhcpd[30731]: execute_statement argv[1] = 192.168.12.137
Jun 16 18:19:22 srv4hai dhcpd[30731]: execute_statement argv[2] = shelly-37
Jun 16 18:19:22 srv4hai dhcpd[30731]: DHCPREQUEST for 192.168.12.137 (192.168.12.252) from 48:55:19:00:b3:58 via enp2s0
Jun 16 18:19:22 srv4hai dhcpd[30731]: DHCPACK on 192.168.12.137 to 48:55:19:00:b3:58 via enp2s0
Jun 16 18:19:22 srv4hai named[30406]: client @0x7f93506df2d0 192.168.12.252#41367/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: shelly-37.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Jun 16 18:19:22 srv4hai named[30406]: client @0x7f933011a820 192.168.12.252#56811/key rndc-key: signer "rndc-key" approved
Jun 16 18:19:22 srv4hai named[30406]: client @0x7f933011a820 192.168.12.252#56811/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'shelly-37.viehai.at' TXT
Jun 16 18:19:22 srv4hai named[30406]: client @0x7f933011a820 192.168.12.252#56811/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'shelly-37.viehai.at' TXT "001bc21ebc20088cb6a311248591ab7101"
Jun 16 18:19:22 srv4hai named[30406]: client @0x7f933011a820 192.168.12.252#56811/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'shelly-37.viehai.at' A
Jun 16 18:19:22 srv4hai named[30406]: client @0x7f933011a820 192.168.12.252#56811/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'shelly-37.viehai.at' A 192.168.12.137
Jun 16 18:19:22 srv4hai dhcpd[30731]: Added new forward map from shelly-37.viehai.at to 192.168.12.137
Jun 16 18:19:22 srv4hai named[30406]: client @0x7f931c094600 192.168.12.252#57969/key rndc-key: signer "rndc-key" approved
Jun 16 18:19:22 srv4hai named[30406]: client @0x7f931c094600 192.168.12.252#57969/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '137.12.168.192.in-addr.arpa' PTR
Jun 16 18:19:22 srv4hai named[30406]: client @0x7f931c094600 192.168.12.252#57969/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': adding an RR at '137.12.168.192.in-addr.arpa' PTR shelly-37.viehai.at.
Jun 16 18:19:22 srv4hai dhcpd[30731]: Added reverse map from 137.12.168.192.in-addr.arpa. to shelly-37.viehai.at
Jun 16 18:19:22 srv4hai common[2693]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/grab_shelly.sh
Jun 16 18:19:22 srv4hai grab_shelly.sh: SHELLY shellyName/1:'shelly-37' - shIP/2:'' perform:'conf'
Jun 16 18:19:23 srv4hai mosquitto[18551]: New connection from 192.168.12.137 on port 1883.
Jun 16 18:19:23 srv4hai mosquitto[18551]: New client connected from 192.168.12.137 as shellyht-00B358 (c1, k60, u'shmqtt').
Jun 16 18:19:23 srv4hai conf_shelly.sh: SHELLY: PARAMS  shName/1:'shelly-37' - shIP/2:'192.168.12.137'
Jun 16 18:19:23 srv4hai common[2717]: SHELLY common.sh inkludiert von ./ht_shelly.sh
Jun 16 18:19:23 srv4hai ht_shelly.sh: SHELLY - QUERY_STRING: hum=46&temp=25.88&id=shellyht-00B358
Jun 16 18:19:23 srv4hai conf_shelly.sh: SHELLY: usage/name 'Klima Büro' already set for '192.168.12.137'
Jun 16 18:19:23 srv4hai ht_shelly.sh: SHELLY - storing into '/srv/www/viehai/shelly//wetter/2024/06/shelly-37_20240616.csv'
Jun 16 18:19:23 srv4hai ht_shelly.sh: SHELLY - 'shelly-37_20240616.csv' modifed 496s ago - EXIT
Jun 16 18:19:23 srv4hai conf_shelly.sh: SHELLY:'shelly-37' 'conf(0)' - allready transmitted - skipping
Jun 16 18:19:23 srv4hai conf_shelly.sh: SHELLY:'shelly-37' 'conf(1)' - allready transmitted - skipping
Jun 16 18:19:23 srv4hai conf_shelly.sh: SHELLY:'shelly-37' 'conf(2)' - allready transmitted - skipping
Jun 16 18:19:23 srv4hai conf_shelly.sh: SHELLY:'shelly-37' 'conf(3)' - ''
Jun 16 18:19:23 srv4hai conf_shelly.sh: SHELLY:'shelly-37' conf(3)-cmd: 'curl --connect-timeout 3 --silent 'http://192.168.12.137/' | jq -r '.''
Jun 16 18:19:23 srv4hai conf_shelly.sh: SHELLY:'shelly-37' conf(3)-ret: ''
Jun 16 18:19:26 srv4hai dhcpd[30731]: DHCPDISCOVER from 08:3a:8d:d0:46:5a via enp2s0
Jun 16 18:19:26 srv4hai dhcpd[30731]: DHCPOFFER on 192.168.12.5 to 08:3a:8d:d0:46:5a via enp2s0
Jun 16 18:19:27 srv4hai dhcpd[30731]: DHCPDISCOVER from 08:3a:8d:d0:46:5a via enp2s0
Jun 16 18:19:27 srv4hai dhcpd[30731]: DHCPOFFER on 192.168.12.5 to 08:3a:8d:d0:46:5a via enp2s0
Jun 16 18:19:27 srv4hai dhcpd[30731]: execute_statement argv[0] = /srv/www/viehai/shelly/dhcpcommit.sh
Jun 16 18:19:27 srv4hai dhcpd[30731]: execute_statement argv[1] = 192.168.12.5
Jun 16 18:19:27 srv4hai dhcpd[30731]: execute_statement argv[2] = btscan1
Jun 16 18:19:27 srv4hai dhcpd[30731]: DHCPREQUEST for 192.168.12.5 (192.168.12.252) from 08:3a:8d:d0:46:5a via enp2s0
Jun 16 18:19:27 srv4hai dhcpd[30731]: DHCPACK on 192.168.12.5 to 08:3a:8d:d0:46:5a via enp2s0
Jun 16 18:19:27 srv4hai named[30406]: client @0x7f9338022410 192.168.12.252#33813/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: btscan1.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Jun 16 18:19:27 srv4hai named[30406]: client @0x7f9350685ed0 192.168.12.252#58423/key rndc-key: signer "rndc-key" approved
Jun 16 18:19:27 srv4hai named[30406]: client @0x7f9350685ed0 192.168.12.252#58423/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'btscan1.viehai.at' TXT
Jun 16 18:19:27 srv4hai named[30406]: client @0x7f9350685ed0 192.168.12.252#58423/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'btscan1.viehai.at' TXT "000118916176265854cff71f762ffe0635"
Jun 16 18:19:27 srv4hai named[30406]: client @0x7f9350685ed0 192.168.12.252#58423/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'btscan1.viehai.at' A
Jun 16 18:19:27 srv4hai named[30406]: client @0x7f9350685ed0 192.168.12.252#58423/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'btscan1.viehai.at' A 192.168.12.5
Jun 16 18:19:27 srv4hai dhcpd[30731]: Added new forward map from btscan1.viehai.at to 192.168.12.5
Jun 16 18:19:27 srv4hai dhcpcommit.sh: SHELLY ip:'192.168.12.5'(5) device 'btscan1'
Jun 16 18:19:27 srv4hai named[30406]: client @0x7f934c0b2c60 192.168.12.252#35285/key rndc-key: signer "rndc-key" approved
Jun 16 18:19:27 srv4hai named[30406]: client @0x7f934c0b2c60 192.168.12.252#35285/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '5.12.168.192.in-addr.arpa' PTR
Jun 16 18:19:27 srv4hai named[30406]: client @0x7f934c0b2c60 192.168.12.252#35285/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': adding an RR at '5.12.168.192.in-addr.arpa' PTR btscan1.viehai.at.
Jun 16 18:19:27 srv4hai dhcpd[30731]: Added reverse map from 5.12.168.192.in-addr.arpa. to btscan1.viehai.at
Jun 16 18:19:28 srv4hai dhcpcommit.sh: SHELLY device 'btscan1' known and accepted
Jun 16 18:19:28 srv4hai common[2837]: SHELLY common.sh inkludiert von ./ht_shelly.sh
Jun 16 18:19:28 srv4hai ht_shelly.sh: SHELLY - QUERY_STRING: hum=46&temp=25.88&id=shellyht-00B358
Jun 16 18:19:28 srv4hai ht_shelly.sh: SHELLY - storing into '/srv/www/viehai/shelly//wetter/2024/06/shelly-37_20240616.csv'
Jun 16 18:19:28 srv4hai ht_shelly.sh: SHELLY - 'shelly-37_20240616.csv' modifed 501s ago - EXIT
Jun 16 18:19:28 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=2862, secured, session=
Jun 16 18:19:28 srv4hai dovecot: imap(miglincit)<2862>: Logged out in=276 out=1684 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Jun 16 18:19:29 srv4hai common[2864]: SHELLY common.sh inkludiert von ./bt_scan.sh
Jun 16 18:19:29 srv4hai bt_scan[2864]: SHELLY - who: 'Thomas' / phoneName: 'FP4_Thomas' / '0' / shellyIP: '192.168.12.61' - found: 'Yes'
Jun 16 18:19:29 srv4hai common[2885]: SHELLY common.sh inkludiert von ./bt_scan.sh
Jun 16 18:19:29 srv4hai bt_scan[2864]: SHELLY - ping_SP.sh started for BTmac: '0' - IPv4: '192.168.12.61'
Jun 16 18:19:29 srv4hai common[2911]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//ping_SP.sh
Jun 16 18:19:29 srv4hai ping_SP[2911]: SHELLY ping_SP.sh started from bt_scan, user:'www-data'
Jun 16 18:19:29 srv4hai common[2932]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//check_presence.sh
Jun 16 18:19:29 srv4hai common.sh: SHELLY: BTstatus[0]:'BTdweet_not_set' dweetAge:'0'=1718554769-1718554769
Jun 16 18:19:29 srv4hai check_presence[2932]: 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
Jun 16 18:19:29 srv4hai common.sh: SHELLY: BTstatus[1]:'BTdweet_not_set' dweetAge:'0'=1718554769-1718554769
Jun 16 18:19:29 srv4hai check_presence[2932]: SHELLY (1) found /srv/www/viehai/shelly//meter/ping_SP_1.LIVE and BTdweet_not_set//srv/www/viehai/shelly//meter/BTscan_BTid_1.No
Jun 16 18:19:29 srv4hai check_presence[2932]: SHELLY (ping_SP__bt_scan)-(0) found '/srv/www/viehai/shelly//meter/ping_SP_1.LIVE' for what: 'coming'
Jun 16 18:19:29 srv4hai common.sh: SHELLY: BTstatus[2]:'BTdweet_not_set' dweetAge:'0'=1718554769-1718554769
Jun 16 18:19:29 srv4hai check_presence[2932]: 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
Jun 16 18:19:29 srv4hai check_presence[2932]: SHELLY (ping_SP__bt_scan)-(0) what: 'coming' - persPresent: '(__/__) (__/LIVE) (__/__)' - anyPresent: 'true' - otherPresent:'true'
Jun 16 18:19:29 srv4hai check_presence[2932]: SHELLY - check 'coming': anyPresent(true) - lScene(scene_vorraumOn) - sceneONsmall(vorraumOn)
Jun 16 18:19:29 srv4hai check_presence[2932]: SHELLY (ping_SP__bt_scan)-(0)-coming: activate_scenario.shtml: vorraumOn
Jun 16 18:19:29 srv4hai activate_scenario[3061]: SHELLY scenario: 'vorraumOn(vorraumon)' started
Jun 16 18:19:29 srv4hai activate_scenario[3061]: SHELLY dayState='day' <--> DayTime=checked NightTime=checked - phoneName:'0'

finished