activate_scenario.sh
Thu Jun 27 18:59:55 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/27/_0.log)

vorher: 18:59:56.040

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": "http"

nachher: 18:59:56.071
Dauer: 32 msec phName: 'phoneName:'0''


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

urls : 1
vorher : 18:59:56.017
nachher: 18:59:56.078
Dauer : 60 msec
outfile: '/srv/www/viehai/shelly/log_scenarios/2024/06/27//scene_vorraumOn_20240627_185955.txt'


syslog

Jun 27 18:56:01 srv4hai CRON[23416]: (root) CMD (/etc/linetest/linetest.sh)
Jun 27 18:56:03 srv4hai common[23422]: SHELLY common.sh inkludiert von ./ht_shelly.sh
Jun 27 18:56:03 srv4hai ht_shelly.sh: SHELLY - QUERY_STRING: hum=52&temp=27.50&id=shellyht-00B358
Jun 27 18:56:03 srv4hai ht_shelly.sh: SHELLY - storing into '/srv/www/viehai/shelly//wetter/2024/06/shelly-37_20240627.csv'
Jun 27 18:56:03 srv4hai ht_shelly.sh: SHELLY - 'shelly-37_20240627.csv' modifed 168s ago - EXIT
Jun 27 18:56:04 srv4hai postfix/smtp[23187]: 1A66C63AD7: to=, relay=etb-1.mail.tiscali.it[213.205.33.64]:25, delay=8819, delays=8779/0.11/40/0, dsn=4.0.0, status=deferred (host etb-1.mail.tiscali.it[213.205.33.64] refused to talk to me: 554 cmgw-1.mail.tiscali.it  ggw42C00b27tw0X01 IP: 95.129.204.98, You are not allowed to send mail. Please see https://www.senderscore.org/blocklistlookup You are listed in Returnpath RBL)
Jun 27 18:56:04 srv4hai postfix/smtp[23180]: ACDCB63BC0: host etb-1.mail.tiscali.it[213.205.33.61] refused to talk to me: 554 cmgw-1.mail.tiscali.it  ggw42C00o27tw0X01 IP: 95.129.204.98, You are not allowed to send mail. Please see https://www.senderscore.org/blocklistlookup You are listed in Returnpath RBL
Jun 27 18:56:05 srv4hai postfix/smtp[23180]: connect to etb-4.mail.tiscali.it[213.205.33.62]:25: No route to host
Jun 27 18:56:05 srv4hai postfix/smtp[23180]: ACDCB63BC0: to=, relay=none, delay=6904, delays=6862/0.08/42/0, dsn=4.4.1, status=deferred (connect to etb-4.mail.tiscali.it[213.205.33.62]:25: No route to host)
Jun 27 18:56:06 srv4hai nmbd[2172]: [2024/06/27 18:56:06.873794,  0] ../source3/nmbd/nmbd_browsesync.c:354(find_domain_master_name_query_fail)
Jun 27 18:56:06 srv4hai nmbd[2172]:   find_domain_master_name_query_fail:
Jun 27 18:56:06 srv4hai nmbd[2172]:   Unable to find the Domain Master Browser name WORKGROUP<1b> for the workgroup WORKGROUP.
Jun 27 18:56:06 srv4hai nmbd[2172]:   Unable to sync browse lists in this workgroup.
Jun 27 18:56:08 srv4hai common[23449]: SHELLY common.sh inkludiert von ./ht_shelly.sh
Jun 27 18:56:08 srv4hai ht_shelly.sh: SHELLY - QUERY_STRING: hum=52&temp=27.50&id=shellyht-00B358
Jun 27 18:56:08 srv4hai ht_shelly.sh: SHELLY - storing into '/srv/www/viehai/shelly//wetter/2024/06/shelly-37_20240627.csv'
Jun 27 18:56:08 srv4hai ht_shelly.sh: SHELLY - 'shelly-37_20240627.csv' modifed 173s ago - EXIT
Jun 27 18:56:14 srv4hai common[23476]: SHELLY common.sh inkludiert von ./ht_shelly.sh
Jun 27 18:56:14 srv4hai ht_shelly.sh: SHELLY - QUERY_STRING: hum=52&temp=27.50&id=shellyht-00B358
Jun 27 18:56:14 srv4hai ht_shelly.sh: SHELLY - storing into '/srv/www/viehai/shelly//wetter/2024/06/shelly-37_20240627.csv'
Jun 27 18:56:14 srv4hai ht_shelly.sh: SHELLY - 'shelly-37_20240627.csv' modifed 179s ago - EXIT
Jun 27 18:56:15 srv4hai ping_SP[14673]: SHELLY (1) - ping 192.168.12.62 1 sucessfully  @~1719507375 - 'ping_SP_1'
Jun 27 18:56:15 srv4hai ping_SP[14673]: SHELLY (1) - ping 192.168.12.62 pCnt:'1'
Jun 27 18:56:16 srv4hai dhcpd[30731]: uid lease 192.168.12.40 for client ec:da:3b:c6:1d:3c is duplicate on 192.168.12.0/24
Jun 27 18:56:16 srv4hai dhcpd[30731]: DHCPDISCOVER from ec:da:3b:c6:1d:3c via enp2s0
Jun 27 18:56:16 srv4hai dhcpd[30731]: DHCPOFFER on 192.168.12.150 to ec:da:3b:c6:1d:3c via enp2s0
Jun 27 18:56:16 srv4hai dhcpd[30731]: uid lease 192.168.12.40 for client ec:da:3b:c6:1d:3c is duplicate on 192.168.12.0/24
Jun 27 18:56:16 srv4hai dhcpd[30731]: execute_statement argv[0] = /srv/www/viehai/shelly/json.sh
Jun 27 18:56:16 srv4hai dhcpd[30731]: execute_statement argv[1] = 192.168.12.150
Jun 27 18:56:16 srv4hai dhcpd[30731]: execute_statement argv[2] = shelly-50
Jun 27 18:56:16 srv4hai dhcpd[30731]: DHCPREQUEST for 192.168.12.150 (192.168.12.252) from ec:da:3b:c6:1d:3c via enp2s0
Jun 27 18:56:16 srv4hai dhcpd[30731]: DHCPACK on 192.168.12.150 to ec:da:3b:c6:1d:3c via enp2s0
Jun 27 18:56:16 srv4hai named[30406]: client @0x7f9350100b10 192.168.12.252#49949/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: shelly-50.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Jun 27 18:56:16 srv4hai named[30406]: client @0x7f9350157870 192.168.12.252#36705/key rndc-key: signer "rndc-key" approved
Jun 27 18:56:16 srv4hai named[30406]: client @0x7f9350157870 192.168.12.252#36705/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'shelly-50.viehai.at' TXT
Jun 27 18:56:16 srv4hai named[30406]: client @0x7f9350157870 192.168.12.252#36705/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'shelly-50.viehai.at' TXT "31ddfcf6e79cbbdf4e55e03247020075f9"
Jun 27 18:56:16 srv4hai named[30406]: client @0x7f9350157870 192.168.12.252#36705/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'shelly-50.viehai.at' A
Jun 27 18:56:16 srv4hai named[30406]: client @0x7f9350157870 192.168.12.252#36705/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'shelly-50.viehai.at' A 192.168.12.150
Jun 27 18:56:16 srv4hai dhcpd[30731]: Added new forward map from shelly-50.viehai.at to 192.168.12.150
Jun 27 18:56:16 srv4hai named[30406]: client @0x7f932c0c3e90 192.168.12.252#52681/key rndc-key: signer "rndc-key" approved
Jun 27 18:56:16 srv4hai named[30406]: client @0x7f932c0c3e90 192.168.12.252#52681/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '150.12.168.192.in-addr.arpa' PTR
Jun 27 18:56:16 srv4hai named[30406]: client @0x7f932c0c3e90 192.168.12.252#52681/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': adding an RR at '150.12.168.192.in-addr.arpa' PTR shelly-50.viehai.at.
Jun 27 18:56:16 srv4hai dhcpd[30731]: Added reverse map from 150.12.168.192.in-addr.arpa. to shelly-50.viehai.at
Jun 27 18:56:16 srv4hai common[23519]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/grab_shelly.sh
Jun 27 18:56:16 srv4hai grab_shelly.sh: SHELLY shellyName/1:'shelly-50' - shIP/2:'' perform:'conf'
Jun 27 18:56:17 srv4hai conf_shelly.sh: SHELLY: PARAMS  shName/1:'shelly-50' - shIP/2:'192.168.12.150'
Jun 27 18:56:17 srv4hai mosquitto[18551]: New connection from 192.168.12.150 on port 1883.
Jun 27 18:56:17 srv4hai mosquitto[18551]: Client shellypmminig3-ecda3bc61d3c already connected, closing old connection.
Jun 27 18:56:17 srv4hai mosquitto[18551]: Socket error on client shellypmminig3-ecda3bc61d3c, disconnecting.
Jun 27 18:56:17 srv4hai mosquitto[18551]: New client connected from 192.168.12.150 as shellypmminig3-ecda3bc61d3c (c1, k60, u'shmqtt').
Jun 27 18:56:17 srv4hai conf_shelly.sh: SHELLY: usage/name '50-TBD' already set for '192.168.12.150'
Jun 27 18:56:17 srv4hai conf_shelly.sh: SHELLY:'shelly-50' no WebHook
Jun 27 18:56:19 srv4hai common[23568]: SHELLY common.sh inkludiert von ./ht_shelly.sh
Jun 27 18:56:19 srv4hai ht_shelly.sh: SHELLY - QUERY_STRING: hum=52&temp=27.50&id=shellyht-00B358
Jun 27 18:56:19 srv4hai ht_shelly.sh: SHELLY - storing into '/srv/www/viehai/shelly//wetter/2024/06/shelly-37_20240627.csv'
Jun 27 18:56:19 srv4hai ht_shelly.sh: SHELLY - 'shelly-37_20240627.csv' modifed 184s ago - EXIT
Jun 27 18:56:45 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=23606, secured, session=
Jun 27 18:56:45 srv4hai dovecot: imap(rozumm)<23606>: 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 27 18:57:01 srv4hai CRON[23618]: (root) CMD (/etc/linetest/linetest.sh)
Jun 27 18:57:12 srv4hai mosquitto[18551]: Client shellyht-00B358 has exceeded timeout, disconnecting.
Jun 27 18:57:12 srv4hai mosquitto[18551]: Socket error on client shellyht-00B358, disconnecting.
Jun 27 18:57:18 srv4hai ping_SP[14673]: SHELLY (1) - ping 192.168.12.62 1 sucessfully  @~1719507438 - 'ping_SP_1'
Jun 27 18:57:18 srv4hai ping_SP[14673]: SHELLY (1) - ping 192.168.12.62 pCnt:'1'
Jun 27 18:57:26 srv4hai fetchmail[1651]: 7 messages (7 seen) for rozmig at mail.akis.at (1753652 octets).
Jun 27 18:57:26 srv4hai fetchmail[1651]: 47455 messages (47454 seen) for tmiglinci at mail.akis.at (-373380994 octets).
Jun 27 18:57:26 srv4hai postfix/smtpd[23647]: warning: database /etc/aliases.db is older than source file /etc/aliases
Jun 27 18:57:26 srv4hai postfix/smtpd[23647]: connect from localhost[127.0.0.1]
Jun 27 18:57:26 srv4hai postfix/smtpd[23647]: BE34D639A0: client=localhost[127.0.0.1]
Jun 27 18:57:26 srv4hai postfix/cleanup[23650]: BE34D639A0: message-id=
Jun 27 18:57:26 srv4hai fetchmail[1651]: reading message tmiglinci@mail.akis.at:47455 of 47455 (10794 octets) not flushed
Jun 27 18:57:26 srv4hai postfix/qmgr[31004]: BE34D639A0: from=, size=11078, nrcpt=1 (queue active)
Jun 27 18:57:26 srv4hai postfix/local[23651]: warning: database /etc/aliases.db is older than source file /etc/aliases
Jun 27 18:57:26 srv4hai postfix/smtpd[23647]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1 data=1 quit=1 commands=5
Jun 27 18:57:26 srv4hai postfix/local[23651]: BE34D639A0: to=, relay=local, delay=0.1, delays=0.06/0.01/0/0.03, dsn=2.0.0, status=sent (delivered to command: /bin/procmail)
Jun 27 18:57:26 srv4hai postfix/qmgr[31004]: BE34D639A0: removed
Jun 27 18:57:27 srv4hai fetchmail[1651]: 10371 messages (10371 seen) for srozum at mail.akis.at (1247642577 octets).
Jun 27 18:57:27 srv4hai fetchmail[1651]: 24399 messages (24399 seen) for mrozum at mail.akis.at (-50335433 octets).
Jun 27 18:57:28 srv4hai fetchmail[1651]: 1138 messages (1138 seen) for admin@bastelstube.wien at pop3.world4you.com (51557211 octets).
Jun 27 18:57:35 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=23659, secured, session=
Jun 27 18:57:35 srv4hai dovecot: imap(pilsc)<23659>: Logged out in=93 out=1020 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Jun 27 18:57:45 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=23664, secured, session=
Jun 27 18:57:45 srv4hai dovecot: imap(rozumm)<23664>: 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 27 18:57:50 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=23668, secured, session=
Jun 27 18:57:50 srv4hai dovecot: imap(miglincit)<23668>: Logged out in=660 out=57230 deleted=0 expunged=0 trashed=0 hdr_count=65 hdr_bytes=27751 body_count=0 body_bytes=0
Jun 27 18:58:01 srv4hai CRON[23677]: (root) CMD (/etc/linetest/linetest.sh)
Jun 27 18:58:21 srv4hai ping_SP[14673]: SHELLY (1) - ping 192.168.12.62 1 sucessfully  @~1719507501 - 'ping_SP_1'
Jun 27 18:58:21 srv4hai ping_SP[14673]: SHELLY (1) - ping 192.168.12.62 pCnt:'1'
Jun 27 18:58:29 srv4hai named[30406]: connection refused resolving '78.46.96.156.in-addr.arpa/PTR/IN': 192.34.59.231#53
Jun 27 18:58:37 srv4hai postfix/smtpd[23647]: connect from unknown[156.96.46.78]
Jun 27 18:58:38 srv4hai postfix/smtpd[23647]: warning: unknown[156.96.46.78]: SASL LOGIN authentication failed: Invalid authentication mechanism
Jun 27 18:58:38 srv4hai postfix/smtpd[23647]: disconnect from unknown[156.96.46.78] ehlo=1 auth=0/1 quit=1 commands=2/3
Jun 27 18:58:51 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=23726, secured, session=
Jun 27 18:58:51 srv4hai dovecot: imap(rozumm)<23726>: 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 27 18:59:01 srv4hai CRON[23732]: (root) CMD (/etc/linetest/linetest.sh)
Jun 27 18:59:25 srv4hai ping_SP[14673]: SHELLY (1) - ping 192.168.12.62 1 sucessfully  @~1719507565 - 'ping_SP_1'
Jun 27 18:59:25 srv4hai ping_SP[14673]: SHELLY (1) - ping 192.168.12.62 pCnt:'1'
Jun 27 18:59:26 srv4hai postfix/scache[23214]: statistics: start interval Jun 27 18:55:25
Jun 27 18:59:26 srv4hai postfix/scache[23214]: statistics: domain lookup hits=0 miss=133 success=0%
Jun 27 18:59:26 srv4hai postfix/scache[23214]: statistics: address lookup hits=0 miss=2688 success=0%
Jun 27 18:59:51 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=23776, secured, session=
Jun 27 18:59:51 srv4hai dovecot: imap(rozumm)<23776>: 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 27 18:59:55 srv4hai common[23779]: SHELLY common.sh inkludiert von ./bt_scan.sh
Jun 27 18:59:55 srv4hai bt_scan[23779]: SHELLY - who: 'Thomas' / phoneName: 'FP4_Thomas' / '0' / shellyIP: '192.168.12.61' - found: 'Yes'
Jun 27 18:59:55 srv4hai bt_scan[23779]: SHELLY - ping_SP.sh started for BTmac: '0' - IPv4: '192.168.12.61'
Jun 27 18:59:55 srv4hai common[23810]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//ping_SP.sh
Jun 27 18:59:55 srv4hai ping_SP[23810]: SHELLY ping_SP.sh started from bt_scan, user:'www-data'
Jun 27 18:59:55 srv4hai common[23831]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//check_presence.sh
Jun 27 18:59:55 srv4hai common.sh: SHELLY: BTstatus[0]:'BTdweet_not_set' dweetAge:'0'=1719507595-1719507595
Jun 27 18:59:55 srv4hai check_presence[23831]: 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 27 18:59:55 srv4hai common.sh: SHELLY: BTstatus[1]:'BTdweet_not_set' dweetAge:'0'=1719507595-1719507595
Jun 27 18:59:55 srv4hai check_presence[23831]: 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 27 18:59:55 srv4hai check_presence[23831]: SHELLY (ping_SP__bt_scan)-(0) found '/srv/www/viehai/shelly//meter/ping_SP_1.LIVE' for what: 'coming'
Jun 27 18:59:55 srv4hai common.sh: SHELLY: BTstatus[2]:'BTdweet_not_set' dweetAge:'0'=1719507595-1719507595
Jun 27 18:59:55 srv4hai check_presence[23831]: 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 27 18:59:55 srv4hai check_presence[23831]: SHELLY (ping_SP__bt_scan)-(0) what: 'coming' - persPresent: '(__/__) (__/LIVE) (__/__)' - anyPresent: 'true' - otherPresent:'true'
Jun 27 18:59:55 srv4hai check_presence[23831]: SHELLY - check 'coming': anyPresent(true) - lScene(scene_herdzeile_UM) - sceneONsmall(vorraumOn)
Jun 27 18:59:55 srv4hai check_presence[23831]: SHELLY (ping_SP__bt_scan)-(0)-coming: activate_scenario.shtml: vorraumOn
Jun 27 18:59:55 srv4hai activate_scenario[23960]: SHELLY scenario: 'vorraumOn(vorraumon)' started
Jun 27 18:59:55 srv4hai activate_scenario[23960]: SHELLY dayState='day' <--> DayTime=checked NightTime=checked - phoneName:'0'

finished