activate_scenario.sh
Tue Aug 27 20:07:47 CEST 2024
scenario: vorraumOn
phoneName: 1

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

Licht Vorzimmer (/srv/www/viehai/shelly/log_scenarios/2024/08/27/_0.log)

vorher: 20:07:47.856

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

nachher: 20:07:47.968
Dauer: 112 msec phName: 'phoneName:'1''

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

urls : 1
vorher : 20:07:47.838
nachher: 20:07:47.974
Dauer : 136 msec
outfile: '/srv/www/viehai/shelly/log_scenarios/2024/08/27//scene_vorraumOn_20240827_200747.txt'

syslog

Aug 27 20:04:01 srv4hai CRON[15908]: (root) CMD (/etc/linetest/linetest.sh)
Aug 27 20:04:35 srv4hai ping_SP[24780]: SHELLY (0) - ping 192.168.12.61 1 sucessfully  @~1724781875 - 'ping_SP_0'
Aug 27 20:04:35 srv4hai ping_SP[24780]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Aug 27 20:04:42 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=15940, secured, session=
Aug 27 20:04:42 srv4hai dovecot: imap(miglincit)<15940>: Logged out in=814 out=55884 deleted=0 expunged=0 trashed=0 hdr_count=66 hdr_bytes=25669 body_count=0 body_bytes=0
Aug 27 20:04:44 srv4hai fetchmail[1651]: 7 messages (7 seen) for rozmig at mail.akis.at (1753652 octets).
Aug 27 20:04:45 srv4hai fetchmail[1651]: 50947 messages (50947 seen) for tmiglinci at mail.akis.at (-83453553 octets).
Aug 27 20:04:45 srv4hai fetchmail[1651]: 10631 messages (10631 seen) for srozum at mail.akis.at (1281357981 octets).
Aug 27 20:04:46 srv4hai fetchmail[1651]: 25559 messages (25559 seen) for mrozum at mail.akis.at (158556532 octets).
Aug 27 20:04:46 srv4hai fetchmail[1651]: 1141 messages (1141 seen) for admin@bastelstube.wien at pop3.world4you.com (51598561 octets).
Aug 27 20:05:01 srv4hai CRON[15943]: (root) CMD (/etc/linetest/linetest.sh)
Aug 27 20:05:14 srv4hai postfix/anvil[15680]: statistics: max connection rate 1/60s for (smtp:179.43.172.41) at Aug 27 20:01:52
Aug 27 20:05:14 srv4hai postfix/anvil[15680]: statistics: max connection count 1 for (smtp:179.43.172.41) at Aug 27 20:01:52
Aug 27 20:05:14 srv4hai postfix/anvil[15680]: statistics: max cache size 1 at Aug 27 20:01:52
Aug 27 20:05:44 srv4hai dhcpd[30731]: DHCPDISCOVER from 8c:aa:b5:61:a9:20 via enp2s0
Aug 27 20:05:44 srv4hai dhcpd[30731]: DHCPOFFER on 192.168.12.107 to 8c:aa:b5:61:a9:20 via enp2s0
Aug 27 20:05:44 srv4hai dhcpd[30731]: execute_statement argv[0] = /srv/www/viehai/shelly/json.sh
Aug 27 20:05:44 srv4hai dhcpd[30731]: execute_statement argv[1] = 192.168.12.107
Aug 27 20:05:44 srv4hai dhcpd[30731]: execute_statement argv[2] = shelly-07
Aug 27 20:05:44 srv4hai dhcpd[30731]: DHCPREQUEST for 192.168.12.107 (192.168.12.252) from 8c:aa:b5:61:a9:20 via enp2s0
Aug 27 20:05:44 srv4hai dhcpd[30731]: DHCPACK on 192.168.12.107 to 8c:aa:b5:61:a9:20 via enp2s0
Aug 27 20:05:44 srv4hai named[30406]: client @0x7f93500576c0 192.168.12.252#53757/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: shelly-07.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Aug 27 20:05:44 srv4hai named[30406]: client @0x7f934c0821f0 192.168.12.252#56925/key rndc-key: signer "rndc-key" approved
Aug 27 20:05:44 srv4hai named[30406]: client @0x7f934c0821f0 192.168.12.252#56925/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'shelly-07.viehai.at' TXT
Aug 27 20:05:44 srv4hai named[30406]: client @0x7f934c0821f0 192.168.12.252#56925/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'shelly-07.viehai.at' TXT "00304107ff63db9ada5ee5a0bc2105bb05"
Aug 27 20:05:44 srv4hai named[30406]: client @0x7f934c0821f0 192.168.12.252#56925/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'shelly-07.viehai.at' A
Aug 27 20:05:44 srv4hai named[30406]: client @0x7f934c0821f0 192.168.12.252#56925/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'shelly-07.viehai.at' A 192.168.12.107
Aug 27 20:05:44 srv4hai dhcpd[30731]: Added new forward map from shelly-07.viehai.at to 192.168.12.107
Aug 27 20:05:44 srv4hai named[30406]: client @0x7f932c088690 192.168.12.252#38979/key rndc-key: signer "rndc-key" approved
Aug 27 20:05:44 srv4hai named[30406]: client @0x7f932c088690 192.168.12.252#38979/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '107.12.168.192.in-addr.arpa' PTR
Aug 27 20:05:44 srv4hai named[30406]: client @0x7f932c088690 192.168.12.252#38979/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': adding an RR at '107.12.168.192.in-addr.arpa' PTR shelly-07.viehai.at.
Aug 27 20:05:44 srv4hai dhcpd[30731]: Added reverse map from 107.12.168.192.in-addr.arpa. to shelly-07.viehai.at
Aug 27 20:05:44 srv4hai common[15960]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/grab_shelly.sh
Aug 27 20:05:44 srv4hai grab_shelly.sh: SHELLY shellyName/1:'shelly-07' - shIP/2:'' perform:'conf'
Aug 27 20:05:46 srv4hai conf_shelly.sh: SHELLY: PARAMS  shName/1:'shelly-07' - shIP/2:'192.168.12.107'
Aug 27 20:05:46 srv4hai mosquitto[18551]: New connection from 192.168.12.107 on port 1883.
Aug 27 20:05:46 srv4hai mosquitto[18551]: New client connected from 192.168.12.107 as shelly1-8CAAB561A920 (c1, k60, u'shmqtt').
Aug 27 20:05:46 srv4hai conf_shelly.sh: SHELLY: usage/name 'Nachtkästchen Lampe Thomas' already set for '192.168.12.107'
Aug 27 20:05:46 srv4hai conf_shelly.sh: SHELLY:'shelly-07' 'conf(0)' - allready transmitted - skipping
Aug 27 20:05:46 srv4hai conf_shelly.sh: SHELLY:'shelly-07' 'conf(1)' - allready transmitted - skipping
Aug 27 20:05:46 srv4hai conf_shelly.sh: SHELLY:'shelly-07' 'conf(2)' - allready transmitted - skipping
Aug 27 20:05:46 srv4hai conf_shelly.sh: SHELLY:'shelly-07' 'conf(3)' - allready transmitted - skipping
Aug 27 20:05:46 srv4hai conf_shelly.sh: SHELLY:'shelly-07' 'conf(4)' - allready transmitted - skipping
Aug 27 20:05:46 srv4hai conf_shelly.sh: SHELLY:'shelly-07' out:0 ON : MISSING
Aug 27 20:05:46 srv4hai conf_shelly.sh: SHELLY:'shelly-07' out:0 OFF: MISSING
Aug 27 20:05:48 srv4hai ping_SP[24780]: SHELLY (0) - ping 192.168.12.61 1 sucessfully  @~1724781948 - 'ping_SP_0'
Aug 27 20:05:48 srv4hai ping_SP[24780]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Aug 27 20:06:01 srv4hai CRON[16105]: (root) CMD (/etc/linetest/linetest.sh)
Aug 27 20:06:11 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=16112, secured, session=
Aug 27 20:06:11 srv4hai dovecot: imap(miglincit)<16112>: Logged out in=443 out=2553 deleted=0 expunged=0 trashed=0 hdr_count=1 hdr_bytes=353 body_count=1 body_bytes=655
Aug 27 20:06:13 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=16116, secured, session=
Aug 27 20:06:14 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=16118, secured, session=
Aug 27 20:06:14 srv4hai dovecot: imap(miglincit)<16116>: Logged out in=620 out=31498 deleted=0 expunged=0 trashed=2 hdr_count=2 hdr_bytes=488 body_count=0 body_bytes=0
Aug 27 20:06:14 srv4hai dovecot: imap(miglincit)<16118>: Logged out in=443 out=36700 deleted=0 expunged=0 trashed=0 hdr_count=1 hdr_bytes=487 body_count=1 body_bytes=34463
Aug 27 20:06:18 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=16124, secured, session=
Aug 27 20:06:19 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=16126, secured, session=
Aug 27 20:06:19 srv4hai dovecot: imap(miglincit)<16124>: Logged out in=606 out=30887 deleted=0 expunged=0 trashed=1 hdr_count=1 hdr_bytes=226 body_count=0 body_bytes=0
Aug 27 20:06:19 srv4hai dovecot: imap(miglincit)<16126>: Logged out in=395 out=3585 deleted=0 expunged=0 trashed=0 hdr_count=1 hdr_bytes=461 body_count=1 body_bytes=1675
Aug 27 20:06:26 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=16128, secured, session=<2midGa4gIrYAAAAAAAAAAAAAAAAAAAAB>
Aug 27 20:06:26 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=16131, secured, session=
Aug 27 20:06:26 srv4hai dovecot: imap(miglincit)<16131>: Logged out in=397 out=50958 deleted=0 expunged=0 trashed=0 hdr_count=1 hdr_bytes=476 body_count=1 body_bytes=48815
Aug 27 20:06:26 srv4hai dovecot: imap(miglincit)<16128><2midGa4gIrYAAAAAAAAAAAAAAAAAAAAB>: Logged out in=620 out=30829 deleted=0 expunged=0 trashed=0 hdr_count=1 hdr_bytes=360 body_count=0 body_bytes=0
Aug 27 20:06:32 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=16133, secured, session=
Aug 27 20:06:32 srv4hai dovecot: imap(miglincit)<16133>: Logged out in=397 out=20337 deleted=0 expunged=0 trashed=0 hdr_count=1 hdr_bytes=508 body_count=1 body_bytes=18190
Aug 27 20:06:34 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=16136, secured, session=
Aug 27 20:06:34 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=16137, secured, session=
Aug 27 20:06:34 srv4hai dovecot: imap(miglincit)<16136>: Logged out in=395 out=7514 deleted=0 expunged=0 trashed=0 hdr_count=1 hdr_bytes=688 body_count=1 body_bytes=5217
Aug 27 20:06:34 srv4hai dovecot: imap(miglincit)<16137>: Logged out in=613 out=33973 deleted=0 expunged=0 trashed=1 hdr_count=2 hdr_bytes=3115 body_count=0 body_bytes=0
Aug 27 20:06:44 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=16140, secured, session=
Aug 27 20:06:44 srv4hai dovecot: imap(miglincit)<16140>: Logged out in=821 out=58869 deleted=0 expunged=0 trashed=0 hdr_count=67 hdr_bytes=28514 body_count=0 body_bytes=0
Aug 27 20:06:55 srv4hai dovecot: imap(miglincit)<26876>: Logged out in=3910546 out=5840079 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Aug 27 20:06:55 srv4hai dovecot: imap(miglincit)<26894>: Logged out in=3447909 out=5998356 deleted=0 expunged=0 trashed=0 hdr_count=9 hdr_bytes=3581 body_count=9 body_bytes=841111
Aug 27 20:06:55 srv4hai dovecot: imap(miglincit)<26875>: Logged out in=69008 out=114727 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Aug 27 20:06:55 srv4hai dovecot: imap(miglincit)<14174>: Logged out in=227911 out=902637 deleted=0 expunged=0 trashed=0 hdr_count=9 hdr_bytes=4983 body_count=9 body_bytes=525770
Aug 27 20:06:55 srv4hai dovecot: imap(miglincit)<26877>: Logged out in=12310 out=1461736 deleted=0 expunged=0 trashed=0 hdr_count=28 hdr_bytes=11186 body_count=28 body_bytes=1329383
Aug 27 20:06:56 srv4hai activate_scenario[16147]: SHELLY scenario: 'pcOFF(pcoff)' started
Aug 27 20:06:56 srv4hai activate_scenario[16147]: SHELLY dayState='night' <--> DayTime=checked NightTime=checked - shelly-0:''
Aug 27 20:06:56 srv4hai activate_scenario[16147]: SHELLY scenario: 'pcOFF(pcoff)' TO BE EXECUTED, shelly-0:''
Aug 27 20:06:56 srv4hai postfix/pickup[3030]: 431556345B: uid=33 from=
Aug 27 20:06:56 srv4hai postfix/cleanup[16200]: 431556345B: message-id=<20240827180656.431556345B@mail.viehai.at>
Aug 27 20:06:56 srv4hai postfix/qmgr[8566]: 431556345B: from=, size=12640, nrcpt=1 (queue active)
Aug 27 20:06:56 srv4hai activate_scenario[16147]: SHELLY: scenario: 'pcOFF(pcoff)'-'shelly-20'-'Thomas Schreibtischlampe' --> '0?turn=off'
Aug 27 20:06:56 srv4hai proc_url[16218]: SHELLY: OF:'/srv/www/viehai/shelly/log_scenarios/2024/08/27/_0.log' - shellyUsage:'Thomas Schreibtischlampe' - scUrl:'http://192.168.12.120/relay/0?turn=off' - phName:'shelly-0:'''
Aug 27 20:06:56 srv4hai postfix/local[16213]: 431556345B: to=, orig_to=, relay=local, delay=0.07, delays=0.04/0.01/0/0.02, dsn=5.1.1, status=bounced (unknown user: "null")
Aug 27 20:06:56 srv4hai postfix/cleanup[16200]: 4F3FB6345C: message-id=<20240827180656.4F3FB6345C@mail.viehai.at>
Aug 27 20:06:56 srv4hai postfix/bounce[16214]: 431556345B: sender non-delivery notification: 4F3FB6345C
Aug 27 20:06:56 srv4hai postfix/qmgr[8566]: 4F3FB6345C: from=<>, size=14522, nrcpt=1 (queue active)
Aug 27 20:06:56 srv4hai postfix/qmgr[8566]: 431556345B: removed
Aug 27 20:06:56 srv4hai postfix/local[16213]: 4F3FB6345C: to=, relay=local, delay=0.01, delays=0/0/0/0.01, dsn=2.0.0, status=sent (delivered to command: /bin/procmail)
Aug 27 20:06:56 srv4hai postfix/qmgr[8566]: 4F3FB6345C: removed
Aug 27 20:06:56 srv4hai activate_scenario[16147]: SHELLY scenario: 'pcOFF(pcoff)' took 136 msec, shelly-0:''
Aug 27 20:07:01 srv4hai CRON[16247]: (root) CMD (/etc/linetest/linetest.sh)
Aug 27 20:07:01 srv4hai ping_SP[24780]: SHELLY (0) - ping 192.168.12.61 1 sucessfully  @~1724782021 - 'ping_SP_0'
Aug 27 20:07:01 srv4hai ping_SP[24780]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Aug 27 20:07:20 srv4hai common[16270]: SHELLY common.sh inkludiert von ./bt_scan.sh
Aug 27 20:07:30 srv4hai bt_scan[16270]: SHELLY - who: 'Thomas' / phoneName: 'FP4_Thomas' / '0' / shellyIP: '192.168.12.61' - found: 'Yes'
Aug 27 20:07:30 srv4hai bt_scan[16270]: SHELLY - ping_SP.sh already running for BTmac: '0' - IPv4: '192.168.12.61'
Aug 27 20:07:36 srv4hai common[16304]: SHELLY common.sh inkludiert von ./bt_scan.sh
Aug 27 20:07:46 srv4hai bt_scan[16304]: SHELLY - who: 'Grete' / phoneName: 'S20_Grete' / '1' / shellyIP: '192.168.12.62' - found: 'Yes'
Aug 27 20:07:47 srv4hai bt_scan[16304]: SHELLY - ping_SP.sh started for BTmac: '1' - IPv4: '192.168.12.62'
Aug 27 20:07:47 srv4hai common[16338]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//ping_SP.sh
Aug 27 20:07:47 srv4hai ping_SP[16338]: SHELLY ping_SP.sh started from bt_scan, user:'www-data'
Aug 27 20:07:47 srv4hai common[16361]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//check_presence.sh
Aug 27 20:07:47 srv4hai common.sh: SHELLY: BTstatus[0]:'Off' dweetAge:'10'=1724782067-1724782057
Aug 27 20:07:47 srv4hai check_presence[16361]: SHELLY (0) found /srv/www/viehai/shelly//meter/ping_SP_0.LIVE and Off//srv/www/viehai/shelly//meter/BTscan_BTid_0.Yes
Aug 27 20:07:47 srv4hai check_presence[16361]: SHELLY (ping_SP__bt_scan)-(1) found '/srv/www/viehai/shelly//meter/ping_SP_0.LIVE' for what: 'coming'
Aug 27 20:07:47 srv4hai common.sh: SHELLY: BTstatus[1]:'On' dweetAge:'10'=1724782067-1724782057
Aug 27 20:07:47 srv4hai check_presence[16361]: SHELLY (1) found /srv/www/viehai/shelly//meter/ping_SP_1.TRY} and On//srv/www/viehai/shelly//meter/BTscan_BTid_1.Yes
Aug 27 20:07:47 srv4hai common.sh: SHELLY: BTstatus[2]:'Off' dweetAge:'10'=1724782067-1724782057
Aug 27 20:07:47 srv4hai check_presence[16361]: SHELLY (2) found /srv/www/viehai/shelly//meter/ping_SP_2.DEAD and Off//srv/www/viehai/shelly//meter/BTscan_BTid_2.No
Aug 27 20:07:47 srv4hai check_presence[16361]: SHELLY (ping_SP__bt_scan)-(1) what: 'coming' - persPresent: '(__/LIVE) (BT/__) (__/__)' - anyPresent: 'true' - otherPresent:'true'
Aug 27 20:07:47 srv4hai check_presence[16361]: SHELLY - check 'coming': anyPresent(true) - lScene(scene_pcOFF) - sceneONsmall(vorraumOn)
Aug 27 20:07:47 srv4hai check_presence[16361]: SHELLY (ping_SP__bt_scan)-(1)-coming: activate_scenario.shtml: vorraumOn
Aug 27 20:07:47 srv4hai activate_scenario[16508]: SHELLY scenario: 'vorraumOn(vorraumon)' started
Aug 27 20:07:47 srv4hai activate_scenario[16508]: SHELLY dayState='night' <--> DayTime=checked NightTime=checked - phoneName:'1'

finished