activate_scenario.sh
Tue Apr 22 17:43:20 CEST 2025
scenario: vorraumOn
phoneName: 1

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

(/srv/www/viehai/shelly/log_scenarios/2025/04/22/_0.log)

vorher: 17:43:20.800

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

nachher: 17:43:20.832
Dauer: 31 msec phName: 'phoneName:'1''

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

urls : 1
vorher : 17:43:20.780
nachher: 17:43:20.844
Dauer : 63 msec
outfile: '/srv/www/viehai/shelly/log_scenarios/2025/04/22//scene_vorraumOn_20250422_174320.txt'

syslog

Apr 22 17:40:00 srv4hai mqtt_process[7331]: SHELLY - shellyType: 'shelly-PM-mini' not handled for 'shelly-49'/'49-TBD'!!
Apr 22 17:40:00 srv4hai mqtt_process[7356]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 22 17:40:00 srv4hai mqtt_process[7381]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 22 17:40:01 srv4hai CRON[7412]: (www-data) CMD (/srv/www/viehai/shelly/meter.sh)
Apr 22 17:40:01 srv4hai CRON[7413]: (root) CMD (/etc/linetest/linetest.sh)
Apr 22 17:40:01 srv4hai meter.sh: SHELLY meter.sh started
Apr 22 17:40:02 srv4hai meter.sh: SHELLY 'shelly-29/KÜ Schalter Herdzeile' - type: 'shelly-i3' ignoring
Apr 22 17:40:02 srv4hai check_shelly.sh: SHELLY last scenery executed: 'offAll'
Apr 22 17:40:02 srv4hai check_shelly.sh: SHELLY found '2' active shelly outputs
Apr 22 17:40:02 srv4hai meter.sh: SHELLY meter.sh ended
Apr 22 17:40:02 srv4hai postfix/pickup[24839]: 3735A63463: uid=33 from=
Apr 22 17:40:02 srv4hai postfix/cleanup[7538]: 3735A63463: message-id=<20250422154002.3735A63463@mail.viehai.at>
Apr 22 17:40:02 srv4hai postfix/qmgr[8566]: 3735A63463: from=, size=663, nrcpt=1 (queue active)
Apr 22 17:40:02 srv4hai postfix/local[7540]: 3735A63463: to=, orig_to=, relay=local, delay=0.04, delays=0.02/0.01/0/0.01, dsn=2.0.0, status=sent (delivered to command: /bin/procmail)
Apr 22 17:40:02 srv4hai postfix/qmgr[8566]: 3735A63463: removed
Apr 22 17:40:25 srv4hai mqtt_process[7556]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 22 17:40:25 srv4hai mqtt_process[7581]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 22 17:40:27 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=7608, secured, session=
Apr 22 17:40:27 srv4hai dovecot: imap(miglincit)<7608>: Logged out in=2179 out=173458 deleted=0 expunged=0 trashed=0 hdr_count=273 hdr_bytes=95153 body_count=0 body_bytes=0
Apr 22 17:41:00 srv4hai mqtt_process[7616]: SHELLY - shellyType: 'shelly-PM-mini' not handled for 'shelly-49'/'49-TBD'!!
Apr 22 17:41:00 srv4hai mqtt_process[7641]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 22 17:41:00 srv4hai mqtt_process[7666]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 22 17:41:01 srv4hai CRON[7692]: (root) CMD (/etc/linetest/linetest.sh)
Apr 22 17:41:07 srv4hai kernel: [91314585.160859] CPU6: Package temperature/speed normal
Apr 22 17:41:07 srv4hai kernel: [91314585.160861] CPU4: Package temperature/speed normal
Apr 22 17:41:07 srv4hai kernel: [91314585.160862] CPU5: Package temperature/speed normal
Apr 22 17:41:07 srv4hai kernel: [91314585.160863] CPU3: Package temperature/speed normal
Apr 22 17:41:07 srv4hai kernel: [91314585.160864] CPU1: Package temperature/speed normal
Apr 22 17:41:07 srv4hai kernel: [91314585.160865] CPU7: Package temperature/speed normal
Apr 22 17:41:07 srv4hai kernel: [91314585.160866] CPU0: Package temperature/speed normal
Apr 22 17:41:07 srv4hai kernel: [91314585.160867] CPU2: Package temperature/speed normal
Apr 22 17:41:25 srv4hai mqtt_process[7702]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 22 17:41:25 srv4hai mqtt_process[7727]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 22 17:41:59 srv4hai mqtt_process[7753]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 22 17:42:00 srv4hai mqtt_process[7778]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 22 17:42:00 srv4hai mqtt_process[7803]: SHELLY - shellyType: 'shelly-PM-mini' not handled for 'shelly-49'/'49-TBD'!!
Apr 22 17:42:01 srv4hai CRON[7829]: (root) CMD (/etc/linetest/linetest.sh)
Apr 22 17:42:25 srv4hai mqtt_process[7839]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 22 17:42:25 srv4hai mqtt_process[7864]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 22 17:42:29 srv4hai kernel: [91314666.991899] CPU1: Core temperature/speed normal
Apr 22 17:42:29 srv4hai kernel: [91314666.991900] CPU5: Core temperature/speed normal
Apr 22 17:42:51 srv4hai dhcpd[30231]: DHCPDISCOVER from 08:3a:8d:d0:46:5a via enp2s0
Apr 22 17:42:51 srv4hai dhcpd[30231]: DHCPOFFER on 192.168.12.5 to 08:3a:8d:d0:46:5a via enp2s0
Apr 22 17:42:53 srv4hai postfix/smtpd[7896]: warning: hostname hebei.2.60.in-addr.arpa does not resolve to address 60.2.243.174: No address associated with hostname
Apr 22 17:42:53 srv4hai postfix/smtpd[7896]: connect from unknown[60.2.243.174]
Apr 22 17:42:53 srv4hai postfix/smtpd[7896]: warning: unknown[60.2.243.174]: SASL LOGIN authentication failed: Invalid authentication mechanism
Apr 22 17:42:53 srv4hai postfix/smtpd[7896]: disconnect from unknown[60.2.243.174] ehlo=1 auth=0/1 quit=1 commands=2/3
Apr 22 17:42:55 srv4hai dhcpd[30231]: DHCPDISCOVER from 08:3a:8d:d0:46:5a via enp2s0
Apr 22 17:42:55 srv4hai dhcpd[30231]: DHCPOFFER on 192.168.12.5 to 08:3a:8d:d0:46:5a via enp2s0
Apr 22 17:42:55 srv4hai dhcpd[30231]: execute_statement argv[0] = /srv/www/viehai/shelly/dhcpcommit.sh
Apr 22 17:42:55 srv4hai dhcpd[30231]: execute_statement argv[1] = 192.168.12.5
Apr 22 17:42:55 srv4hai dhcpd[30231]: execute_statement argv[2] = btscan1
Apr 22 17:42:55 srv4hai dhcpd[30231]: DHCPREQUEST for 192.168.12.5 (192.168.12.252) from 08:3a:8d:d0:46:5a via enp2s0
Apr 22 17:42:55 srv4hai dhcpd[30231]: DHCPACK on 192.168.12.5 to 08:3a:8d:d0:46:5a via enp2s0
Apr 22 17:42:55 srv4hai named[30406]: client @0x7f933011a820 192.168.12.252#51319/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: btscan1.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Apr 22 17:42:55 srv4hai named[30406]: client @0x7f931c09c730 192.168.12.252#43697/key rndc-key: signer "rndc-key" approved
Apr 22 17:42:55 srv4hai named[30406]: client @0x7f931c09c730 192.168.12.252#43697/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'btscan1.viehai.at' TXT
Apr 22 17:42:55 srv4hai named[30406]: client @0x7f931c09c730 192.168.12.252#43697/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'btscan1.viehai.at' TXT "000118916176265854cff71f762ffe0635"
Apr 22 17:42:55 srv4hai dhcpcommit.sh: SHELLY ip:'192.168.12.5'(5) device 'btscan1'
Apr 22 17:42:55 srv4hai named[30406]: client @0x7f931c09c730 192.168.12.252#43697/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'btscan1.viehai.at' A
Apr 22 17:42:55 srv4hai named[30406]: client @0x7f931c09c730 192.168.12.252#43697/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'btscan1.viehai.at' A 192.168.12.5
Apr 22 17:42:55 srv4hai dhcpd[30231]: Added new forward map from btscan1.viehai.at to 192.168.12.5
Apr 22 17:42:55 srv4hai named[30406]: client @0x7f932c03e370 192.168.12.252#55881/key rndc-key: signer "rndc-key" approved
Apr 22 17:42:55 srv4hai named[30406]: client @0x7f932c03e370 192.168.12.252#55881/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '5.12.168.192.in-addr.arpa' PTR
Apr 22 17:42:55 srv4hai named[30406]: client @0x7f932c03e370 192.168.12.252#55881/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.
Apr 22 17:42:55 srv4hai dhcpd[30231]: Added reverse map from 5.12.168.192.in-addr.arpa. to btscan1.viehai.at
Apr 22 17:42:55 srv4hai dhcpcommit.sh: SHELLY device 'btscan1' known and accepted
Apr 22 17:42:58 srv4hai fetchmail[1651]: 7 messages (7 seen) for rozmig at mail.akis.at (1753652 octets).
Apr 22 17:42:58 srv4hai fetchmail[1651]: 68268 messages (68267 seen) for tmiglinci at mail.akis.at (1329637114 octets).
Apr 22 17:42:58 srv4hai postfix/smtpd[7896]: connect from localhost[127.0.0.1]
Apr 22 17:42:58 srv4hai postfix/smtpd[7896]: DEF696000F: client=localhost[127.0.0.1]
Apr 22 17:42:58 srv4hai postfix/cleanup[7907]: DEF696000F: message-id=<010201965e29ccfd-25d1d4e4-4705-412f-8dfb-e22327cfc26e-000000@eu-west-1.amazonses.com>
Apr 22 17:42:59 srv4hai fetchmail[1651]: reading message tmiglinci@mail.akis.at:68268 of 68268 (171977 octets) not flushed
Apr 22 17:42:59 srv4hai postfix/qmgr[8566]: DEF696000F: from=, size=172200, nrcpt=1 (queue active)
Apr 22 17:42:59 srv4hai postfix/smtpd[7896]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1 data=1 quit=1 commands=5
Apr 22 17:42:59 srv4hai postfix/local[7908]: DEF696000F: to=, relay=local, delay=0.13, delays=0.12/0.01/0/0.01, dsn=2.0.0, status=sent (delivered to command: /bin/procmail)
Apr 22 17:42:59 srv4hai postfix/qmgr[8566]: DEF696000F: removed
Apr 22 17:42:59 srv4hai fetchmail[1651]: 11863 messages (11863 seen) for srozum at mail.akis.at (1397078845 octets).
Apr 22 17:42:59 srv4hai fetchmail[1651]: 30476 messages (30476 seen) for mrozum at mail.akis.at (963516702 octets).
Apr 22 17:43:00 srv4hai mqtt_process[7910]: SHELLY - shellyType: 'shelly-PM-mini' not handled for 'shelly-49'/'49-TBD'!!
Apr 22 17:43:00 srv4hai fetchmail[1651]: 1169 messages (1169 seen) for admin@bastelstube.wien at pop3.world4you.com (52284237 octets).
Apr 22 17:43:00 srv4hai mqtt_process[7935]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 22 17:43:00 srv4hai mqtt_process[7960]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 22 17:43:01 srv4hai CRON[7986]: (root) CMD (/etc/linetest/linetest.sh)
Apr 22 17:43:10 srv4hai bt_scan[7993]: SHELLY - who: 'Thomas' / phoneName: 'FP4_Thomas' / '0' / shellyIP: '192.168.12.61' - found: 'Yes'
Apr 22 17:43:10 srv4hai bt_scan[7993]: SHELLY - ping_SP.sh started for BTmac: '0' - IPv4: '192.168.12.61'
Apr 22 17:43:10 srv4hai ping_SP[8024]: SHELLY ping_SP.sh started from bt_scan, user:'www-data'
Apr 22 17:43:10 srv4hai common.sh: SHELLY: BTstatus[0]:'NO_BTdweet' dweetAge:''=-
Apr 22 17:43:10 srv4hai check_presence[8060]: SHELLY (0) found /srv/www/viehai/shelly/meter/ping_SP_0.TRY and NO_BTdweet//srv/www/viehai/shelly/meter/BTscan_BTid_0.Yes
Apr 22 17:43:10 srv4hai check_presence[8060]: SHELLY (ping_SP__bt_scan_existing_IP)-(0) found '/srv/www/viehai/shelly/meter/ping_SP_0.TRY' for what: 'presence'
Apr 22 17:43:10 srv4hai common.sh: SHELLY: BTstatus[1]:'NO_BTdweet' dweetAge:''=-
Apr 22 17:43:10 srv4hai check_presence[8060]: SHELLY (1) found /srv/www/viehai/shelly/meter/ping_SP_1.DEAD and NO_BTdweet//srv/www/viehai/shelly/meter/BTscan_BTid_1.Yes
Apr 22 17:43:10 srv4hai check_presence[8060]: SHELLY (ping_SP__bt_scan_existing_IP)-(0) what: 'presence' - persPresent: '(__/TRY) (__/__)' - anyPresent: 'true' - otherPresent:'false'
Apr 22 17:43:10 srv4hai check_presence[8060]: SHELLY - check 'presence': anyPresent(true) - lScene(scene_offAll) - sceneOFF(offAll)
Apr 22 17:43:10 srv4hai check_presence[8060]: SHELLY (ping_SP__bt_scan_existing_IP)-(0)-presence: NOT activate_scenario.shtml: offAll
Apr 22 17:43:10 srv4hai ping_SP[8024]: SHELLY check_presence.sh coming 0 ping_SP__bt_scan
Apr 22 17:43:10 srv4hai ping_SP[8024]: SHELLY - new process for '192.168.12.61' - '/srv/www/viehai/shelly/meter/ping_SP_0' @~1745336590
Apr 22 17:43:19 srv4hai dhcpd[30231]: DHCPDISCOVER from f0:39:65:25:8d:c7 via enp2s0
Apr 22 17:43:19 srv4hai dhcpd[30231]: DHCPOFFER on 192.168.12.62 to f0:39:65:25:8d:c7 via enp2s0
Apr 22 17:43:19 srv4hai dhcpd[30231]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Apr 22 17:43:19 srv4hai dhcpd[30231]: execute_statement argv[1] = 192.168.12.62
Apr 22 17:43:19 srv4hai dhcpd[30231]: execute_statement argv[2] = GreteS20
Apr 22 17:43:20 srv4hai bt_scan[8031]: SHELLY - who: 'Grete' / phoneName: 'S20_Grete' / '1' / shellyIP: '192.168.12.62' - found: 'Yes'
Apr 22 17:43:20 srv4hai bt_scan[8031]: SHELLY - ping_SP.sh started for BTmac: '1' - IPv4: '192.168.12.62'
Apr 22 17:43:20 srv4hai ping_SP[8190]: SHELLY ping_SP.sh started from bt_scan, user:'www-data'
Apr 22 17:43:20 srv4hai common.sh: SHELLY: BTstatus[0]:'NO_BTdweet' dweetAge:''=-
Apr 22 17:43:20 srv4hai check_presence[8206]: SHELLY (0) found /srv/www/viehai/shelly/meter/ping_SP_0.TRY and NO_BTdweet//srv/www/viehai/shelly/meter/BTscan_BTid_0.Yes
Apr 22 17:43:20 srv4hai check_presence[8206]: SHELLY (ping_SP__bt_scan)-(1) found '/srv/www/viehai/shelly/meter/ping_SP_0.TRY' for what: 'coming'
Apr 22 17:43:20 srv4hai common.sh: SHELLY: BTstatus[1]:'NO_BTdweet' dweetAge:''=-
Apr 22 17:43:20 srv4hai check_presence[8206]: SHELLY (1) found /srv/www/viehai/shelly/meter/ping_SP_1.TRY and NO_BTdweet//srv/www/viehai/shelly/meter/BTscan_BTid_1.Yes
Apr 22 17:43:20 srv4hai check_presence[8206]: SHELLY (ping_SP__bt_scan)-(1) found '/srv/www/viehai/shelly/meter/ping_SP_1.TRY' for what: 'coming'
Apr 22 17:43:20 srv4hai check_presence[8206]: SHELLY (ping_SP__bt_scan)-(1) what: 'coming' - persPresent: '(__/TRY) (__/TRY)' - anyPresent: 'true' - otherPresent:'true'
Apr 22 17:43:20 srv4hai check_presence[8206]: SHELLY - check 'coming': anyPresent(true) - lScene(scene_offAll) - sceneONsmall(vorraumOn)
Apr 22 17:43:20 srv4hai check_presence[8206]: SHELLY (ping_SP__bt_scan)-(1)-coming: activate_scenario.shtml: vorraumOn
Apr 22 17:43:20 srv4hai activate_scenario[8296]: SHELLY scenario: 'vorraumOn(vorraumon)' started
Apr 22 17:43:20 srv4hai activate_scenario[8296]: SHELLY dayState='day' <--> DayTime='checked' NightTime='checked' flag 'execute* set as ' - phoneName:'1'

finished