activate_scenario.sh
Sat Feb 15 09:14:15 CET 2025
scenario: vorraumOn
phoneName: 0

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

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

VZ Licht (/srv/www/viehai/shelly/log_scenarios/2025/02/15/_0.log)

vorher: 09:14:15.632

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: 09:14:15.662
Dauer: 30 msec phName: 'phoneName:'0''


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

urls : 1
vorher : 09:14:15.608
nachher: 09:14:15.668
Dauer : 61 msec
outfile: '/srv/www/viehai/shelly/log_scenarios/2025/02/15//scene_vorraumOn_20250215_091415.txt'


syslog

Feb 15 09:11:00 srv4hai common[19443]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb 15 09:11:00 srv4hai common[19467]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb 15 09:11:01 srv4hai CRON[19492]: (root) CMD (/etc/linetest/linetest.sh)
Feb 15 09:11:06 srv4hai named[30406]: resolver priming query complete
Feb 15 09:11:13 srv4hai dhcpd[4995]: DHCPDISCOVER from c0:49:ef:8a:c9:d8 via enp2s0
Feb 15 09:11:13 srv4hai dhcpd[4995]: DHCPOFFER on 192.168.12.139 to c0:49:ef:8a:c9:d8 via enp2s0
Feb 15 09:11:13 srv4hai dhcpd[4995]: execute_statement argv[0] = /srv/www/viehai/shelly/json.sh
Feb 15 09:11:13 srv4hai dhcpd[4995]: execute_statement argv[1] = 192.168.12.139
Feb 15 09:11:13 srv4hai dhcpd[4995]: execute_statement argv[2] = shelly-39
Feb 15 09:11:13 srv4hai dhcpd[4995]: DHCPREQUEST for 192.168.12.139 (192.168.12.252) from c0:49:ef:8a:c9:d8 via enp2s0
Feb 15 09:11:13 srv4hai dhcpd[4995]: DHCPACK on 192.168.12.139 to c0:49:ef:8a:c9:d8 via enp2s0
Feb 15 09:11:13 srv4hai named[30406]: client @0x7f9338088040 192.168.12.252#47441/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: shelly-39.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Feb 15 09:11:13 srv4hai named[30406]: client @0x7f932c097490 192.168.12.252#49857/key rndc-key: signer "rndc-key" approved
Feb 15 09:11:13 srv4hai named[30406]: client @0x7f932c097490 192.168.12.252#49857/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'shelly-39.viehai.at' TXT
Feb 15 09:11:13 srv4hai named[30406]: client @0x7f932c097490 192.168.12.252#49857/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'shelly-39.viehai.at' TXT "314be662bb43914e78b269d3f3bc9f38c7"
Feb 15 09:11:13 srv4hai named[30406]: client @0x7f932c097490 192.168.12.252#49857/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'shelly-39.viehai.at' A
Feb 15 09:11:13 srv4hai named[30406]: client @0x7f932c097490 192.168.12.252#49857/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'shelly-39.viehai.at' A 192.168.12.139
Feb 15 09:11:13 srv4hai dhcpd[4995]: Added new forward map from shelly-39.viehai.at to 192.168.12.139
Feb 15 09:11:13 srv4hai named[30406]: client @0x7f93100dcb70 192.168.12.252#38171/key rndc-key: signer "rndc-key" approved
Feb 15 09:11:13 srv4hai named[30406]: client @0x7f93100dcb70 192.168.12.252#38171/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '139.12.168.192.in-addr.arpa' PTR
Feb 15 09:11:13 srv4hai named[30406]: client @0x7f93100dcb70 192.168.12.252#38171/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': adding an RR at '139.12.168.192.in-addr.arpa' PTR shelly-39.viehai.at.
Feb 15 09:11:13 srv4hai dhcpd[4995]: Added reverse map from 139.12.168.192.in-addr.arpa. to shelly-39.viehai.at
Feb 15 09:11:13 srv4hai common[19504]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/grab_shelly.sh
Feb 15 09:11:13 srv4hai grab_shelly.sh: SHELLY shellyName/1:'shelly-39' - shIP/2:'' perform:'conf'
Feb 15 09:11:13 srv4hai conf_shelly.sh: SHELLY: PARAMS  shName/1:'shelly-39' - shIP/2:'192.168.12.139'
Feb 15 09:11:13 srv4hai common[19522]: SHELLY common.sh inkludiert von ./ht_shelly.sh
Feb 15 09:11:13 srv4hai ht_shelly.sh: SHELLY - QUERY_STRING: gen=2&temp=1.800000&hum=58.400000&id=C049EF8AC9D8&batt=90&volt=5.810000
Feb 15 09:11:13 srv4hai ht_shelly.sh: SHELLY - storing into '/srv/www/viehai/shelly//wetter/2025/02/shelly-39_20250215.csv'
Feb 15 09:11:13 srv4hai conf_shelly.sh: SHELLY: PARAMS  shName/1:'shelly-39' - shIP/2:'192.168.12.139'
Feb 15 09:11:13 srv4hai conf_shelly.sh: SHELLY: usage/name 'Wetterwerte Balkon' already set for '192.168.12.139'
Feb 15 09:11:13 srv4hai conf_shelly.sh: SHELLY:'shelly-39' 'conf(0)' - 'settings/actions?index=0&name=report_url&enabled=true&urls[]=http://www.viehai.at/shelly/ht_shelly.shtml?gen=2&temp=${ev.tC}&hum=${status["humidity:0"].rH}&id=${config.sys.device.mac}'
Feb 15 09:11:13 srv4hai conf_shelly.sh: SHELLY: usage/name 'Wetterwerte Balkon' already set for '192.168.12.139'
Feb 15 09:11:13 srv4hai conf_shelly.sh: SHELLY:'shelly-39' 'conf(0)' - 'settings/actions?index=0&name=report_url&enabled=true&urls[]=http://www.viehai.at/shelly/ht_shelly.shtml?gen=2&temp=${ev.tC}&hum=${status["humidity:0"].rH}&id=${config.sys.device.mac}'
Feb 15 09:11:13 srv4hai conf_shelly.sh: SHELLY:'shelly-39' conf(0)-cmd: 'curl --connect-timeout 3 --silent -POST -d 'settings/actions?index=0&name=report_url&enabled=true&urls[]=http://www.viehai.at/shelly/ht_shelly.shtml?gen=2&temp=${ev.tC}&hum=${status["humidity:0"].rH}&id=${config.sys.device.mac}' http://192.168.12.139/rpc | jq -r '.''
Feb 15 09:11:13 srv4hai conf_shelly.sh: SHELLY:'shelly-39' conf(0)-ret: ''
Feb 15 09:11:13 srv4hai conf_shelly.sh: SHELLY:'shelly-39' conf(0)-cmd: 'curl --connect-timeout 3 --silent -POST -d 'settings/actions?index=0&name=report_url&enabled=true&urls[]=http://www.viehai.at/shelly/ht_shelly.shtml?gen=2&temp=${ev.tC}&hum=${status["humidity:0"].rH}&id=${config.sys.device.mac}' http://192.168.12.139/rpc | jq -r '.''
Feb 15 09:11:13 srv4hai conf_shelly.sh: SHELLY:'shelly-39' conf(0)-ret: ''
Feb 15 09:11:13 srv4hai ht_shelly.sh: SHELLY C049EF8AC9D8 / shelly-39 / Wetterwerte Balkon: 1.8° - 58.4%
Feb 15 09:11:13 srv4hai ht_shelly.sh: SHELLY C049EF8AC9D8 / shelly-39 / Wetterwerte Balkon: 1.8°/0.8° - 58.4%/60.2% - SAVED
Feb 15 09:11:13 srv4hai ht_shelly.sh: SHELLY C049EF8AC9D8 / shelly-39 / Wetterwerte Balkon: batt='90'°
Feb 15 09:11:13 srv4hai named[30406]: resolver priming query complete
Feb 15 09:11:14 srv4hai ht_shelly.sh: SHELLY C049EF8AC9D8 / shelly-39 / Wetterwerte Balkon: dweetIO='{#012  "this": "succeeded",#012  "by": "dweeting",#012  "the": "dweet",#012  "with": {#012    "thing": "viehai-39",#012    "created": "2025-02-15T08:11:14.141Z",#012    "content": {#012      "temp": 1.8,#012      "hum": 58.4,#012      "batt": 90,#012      "csvF": "shelly-39_20250215.csv"#012    },#012    "transaction": "39596ea7-4b5d-45c6-97fc-860646dffdb5"#012  }#012}'°
Feb 15 09:11:21 srv4hai common[19662]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb 15 09:11:21 srv4hai common[19686]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb 15 09:11:29 srv4hai ping_SP[20743]: SHELLY (1) - ping 192.168.12.62 1 sucessfully  @~1739607089 - 'ping_SP_1'
Feb 15 09:11:29 srv4hai ping_SP[20743]: SHELLY (1) - ping 192.168.12.62 pCnt:'1'
Feb 15 09:11:48 srv4hai postfix/smtpd[19736]: connect from unknown[45.144.212.102]
Feb 15 09:11:48 srv4hai postfix/smtpd[19736]: warning: unknown[45.144.212.102]: SASL LOGIN authentication failed: Invalid authentication mechanism
Feb 15 09:11:48 srv4hai postfix/smtpd[19736]: disconnect from unknown[45.144.212.102] ehlo=1 auth=0/1 rset=1 quit=1 commands=3/4
Feb 15 09:11:57 srv4hai named[30406]: resolver priming query complete
Feb 15 09:11:58 srv4hai common[19740]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb 15 09:11:58 srv4hai common[19767]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb 15 09:11:58 srv4hai common[19794]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb 15 09:11:58 srv4hai common[19824]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb 15 09:11:59 srv4hai named[30406]: resolver priming query complete
Feb 15 09:12:00 srv4hai common[19851]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb 15 09:12:00 srv4hai common[19875]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb 15 09:12:00 srv4hai common[19899]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb 15 09:12:01 srv4hai CRON[19924]: (root) CMD (/etc/linetest/linetest.sh)
Feb 15 09:12:02 srv4hai common[19928]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb 15 09:12:03 srv4hai common[19956]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb 15 09:12:04 srv4hai common[19993]: SHELLY common.sh inkludiert von ./switch.sh
Feb 15 09:12:04 srv4hai common[19992]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb 15 09:12:04 srv4hai switch.sh: SHELLY   QUERY_STRING: 'shelly=shelly-23&generation=1&id=0&postAction=html&action=toggle'
Feb 15 09:12:04 srv4hai switch.sh: SHELLY   scUrl: 'http://192.168.12.123/relay/0?turn=toggle'
Feb 15 09:12:04 srv4hai switch.sh: SHELLY   aJson: '{"ison":true,"has_timer":false,"timer_started":0,"timer_duration":0,"timer_remaining":0,"source":"http"}'
Feb 15 09:12:06 srv4hai named[30406]: resolver priming query complete
Feb 15 09:12:08 srv4hai mosquitto[29419]: Saving in-memory database to /var/lib/mosquitto/mosquitto.db.
Feb 15 09:12:19 srv4hai named[30406]: resolver priming query complete
Feb 15 09:12:21 srv4hai common[20061]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb 15 09:12:21 srv4hai common[20085]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb 15 09:12:32 srv4hai ping_SP[20743]: SHELLY (1) - ping 192.168.12.62 1 sucessfully  @~1739607152 - 'ping_SP_1'
Feb 15 09:12:32 srv4hai ping_SP[20743]: SHELLY (1) - ping 192.168.12.62 pCnt:'1'
Feb 15 09:12:59 srv4hai common[20137]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb 15 09:12:59 srv4hai common[20161]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb 15 09:13:00 srv4hai common[20185]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb 15 09:13:00 srv4hai fetchmail[1651]: 7 messages (7 seen) for rozmig at mail.akis.at (1753652 octets).
Feb 15 09:13:01 srv4hai CRON[20210]: (root) CMD (/etc/linetest/linetest.sh)
Feb 15 09:13:01 srv4hai fetchmail[1651]: 63676 messages (63676 seen) for tmiglinci at mail.akis.at (926211905 octets).
Feb 15 09:13:01 srv4hai fetchmail[1651]: 11542 messages (11542 seen) for srozum at mail.akis.at (1369975716 octets).
Feb 15 09:13:02 srv4hai fetchmail[1651]: 28969 messages (28968 seen) for mrozum at mail.akis.at (673576870 octets).
Feb 15 09:13:02 srv4hai postfix/smtpd[19736]: connect from localhost[127.0.0.1]
Feb 15 09:13:02 srv4hai postfix/smtpd[19736]: 6011A6004E: client=localhost[127.0.0.1]
Feb 15 09:13:02 srv4hai postfix/cleanup[20216]: 6011A6004E: message-id=<4BJIDV6PEPU4.LIIBACG8D7YH2@5b64708c62ec>
Feb 15 09:13:02 srv4hai fetchmail[1651]: reading message mrozum@mail.akis.at:28969 of 28969 (84135 octets) not flushed
Feb 15 09:13:02 srv4hai postfix/qmgr[8566]: 6011A6004E: from=, size=84414, nrcpt=1 (queue active)
Feb 15 09:13:02 srv4hai postfix/smtpd[19736]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1 data=1 quit=1 commands=5
Feb 15 09:13:02 srv4hai postfix/local[20217]: 6011A6004E: to=, relay=local, delay=0.07, delays=0.06/0.01/0/0.01, dsn=2.0.0, status=sent (delivered to command: /bin/procmail)
Feb 15 09:13:02 srv4hai postfix/qmgr[8566]: 6011A6004E: removed
Feb 15 09:13:02 srv4hai fetchmail[1651]: 1163 messages (1163 seen) for admin@bastelstube.wien at pop3.world4you.com (52216311 octets).
Feb 15 09:13:08 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=20221, secured, session=<63+C3CkuOuYAAAAAAAAAAAAAAAAAAAAB>
Feb 15 09:13:08 srv4hai dovecot: imap(miglincit)<20221><63+C3CkuOuYAAAAAAAAAAAAAAAAAAAAB>: Logged out in=1374 out=1285 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Feb 15 09:13:10 srv4hai named[30406]: resolver priming query complete
Feb 15 09:13:19 srv4hai named[30406]: resolver priming query complete
Feb 15 09:13:20 srv4hai named[30406]: resolver priming query complete
Feb 15 09:13:21 srv4hai common[20226]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb 15 09:13:21 srv4hai common[20250]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb 15 09:13:23 srv4hai postfix/smtpd[19736]: connect from unknown[92.118.39.65]
Feb 15 09:13:27 srv4hai postfix/smtpd[19736]: warning: unknown[92.118.39.65]: SASL PLAIN authentication failed: 
Feb 15 09:13:27 srv4hai postfix/smtpd[19736]: disconnect from unknown[92.118.39.65] ehlo=1 auth=0/1 quit=1 commands=2/3
Feb 15 09:13:31 srv4hai named[30406]: resolver priming query complete
Feb 15 09:13:36 srv4hai ping_SP[20743]: SHELLY (1) - ping 192.168.12.62 1 sucessfully  @~1739607216 - 'ping_SP_1'
Feb 15 09:13:36 srv4hai ping_SP[20743]: SHELLY (1) - ping 192.168.12.62 pCnt:'1'
Feb 15 09:13:45 srv4hai nmbd[2172]: [2025/02/15 09:13:45.994042,  0] ../source3/nmbd/nmbd_browsesync.c:354(find_domain_master_name_query_fail)
Feb 15 09:13:45 srv4hai nmbd[2172]:   find_domain_master_name_query_fail:
Feb 15 09:13:45 srv4hai nmbd[2172]:   Unable to find the Domain Master Browser name WORKGROUP<1b> for the workgroup WORKGROUP.
Feb 15 09:13:45 srv4hai nmbd[2172]:   Unable to sync browse lists in this workgroup.
Feb 15 09:14:00 srv4hai common[20302]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb 15 09:14:00 srv4hai common[20326]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb 15 09:14:00 srv4hai common[20350]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb 15 09:14:01 srv4hai CRON[20377]: (root) CMD (/etc/linetest/linetest.sh)
Feb 15 09:14:14 srv4hai common[20386]: SHELLY common.sh inkludiert von ./bt_scan.sh
Feb 15 09:14:14 srv4hai bt_scan[20386]: SHELLY - who: 'Thomas' / phoneName: 'FP4_Thomas' / '0' / shellyIP: '192.168.12.61' - found: 'Yes'
Feb 15 09:14:14 srv4hai bt_scan[20386]: SHELLY - ping_SP.sh started for BTmac: '0' - IPv4: '192.168.12.61'
Feb 15 09:14:14 srv4hai common[20417]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//ping_SP.sh
Feb 15 09:14:14 srv4hai ping_SP[20417]: SHELLY ping_SP.sh started from bt_scan, user:'www-data'
Feb 15 09:14:14 srv4hai common[20438]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//check_presence.sh
Feb 15 09:14:15 srv4hai common.sh: SHELLY: BTstatus[0]:'BTdweet_not_set' dweetAge:'1'=1739607255-1739607254
Feb 15 09:14:15 srv4hai check_presence[20438]: 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
Feb 15 09:14:15 srv4hai common.sh: SHELLY: BTstatus[1]:'BTdweet_not_set' dweetAge:'1'=1739607255-1739607254
Feb 15 09:14:15 srv4hai check_presence[20438]: SHELLY (1) found /srv/www/viehai/shelly//meter/ping_SP_1.LIVE and BTdweet_not_set//srv/www/viehai/shelly//meter/BTscan_BTid_1.Yes
Feb 15 09:14:15 srv4hai check_presence[20438]: SHELLY (ping_SP__bt_scan)-(0) found '/srv/www/viehai/shelly//meter/ping_SP_1.LIVE' for what: 'coming'
Feb 15 09:14:15 srv4hai check_presence[20438]: SHELLY (ping_SP__bt_scan)-(0) what: 'coming' - persPresent: '(__/__) (__/LIVE)' - anyPresent: 'true' - otherPresent:'true'
Feb 15 09:14:15 srv4hai check_presence[20438]: SHELLY - check 'coming': anyPresent(true) - lScene(scene_offAll) - sceneONsmall(vorraumOn)
Feb 15 09:14:15 srv4hai check_presence[20438]: SHELLY (ping_SP__bt_scan)-(0)-coming: activate_scenario.shtml: vorraumOn
Feb 15 09:14:15 srv4hai activate_scenario[20539]: SHELLY scenario: 'vorraumOn(vorraumon)' started
Feb 15 09:14:15 srv4hai activate_scenario[20539]: SHELLY dayState='day' <--> DayTime=checked NightTime=checked - phoneName:'0'

finished