activate_scenario.sh
Tue Apr 29 20:03:48 CEST 2025
scenario: vorraumOn
phoneName: 0

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

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

vorher: 20:03:48.413

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

nachher: 20:03:48.512
Dauer: 99 msec phName: 'phoneName:'0''

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

urls : 1
vorher : 20:03:48.393
nachher: 20:03:48.526
Dauer : 133 msec
outfile: '/srv/www/viehai/shelly/log_scenarios/2025/04/29//scene_vorraumOn_20250429_200348.txt'

syslog

Apr 29 20:00:00 srv4hai mqtt_process[25642]: SHELLY - shellyType: 'shelly-PM-mini' not handled for 'shelly-49'/'49-TBD'!!
Apr 29 20:00:01 srv4hai CRON[25669]: (www-data) CMD (/srv/www/viehai/shelly/meter.sh)
Apr 29 20:00:01 srv4hai CRON[25670]: (root) CMD (/etc/linetest/linetest.sh)
Apr 29 20:00:02 srv4hai meter.sh: SHELLY meter.sh started
Apr 29 20:00:02 srv4hai meter.sh: SHELLY 'shelly-29/KÜ Schalter Herdzeile' - type: 'shelly-i3' ignoring
Apr 29 20:00:02 srv4hai check_shelly.sh: SHELLY last scenery executed: 'buero_UM'
Apr 29 20:00:02 srv4hai check_shelly.sh: SHELLY found '1' active shelly outputs
Apr 29 20:00:02 srv4hai meter.sh: SHELLY meter.sh ended
Apr 29 20:00:02 srv4hai postfix/pickup[12491]: A18B163468: uid=33 from=
Apr 29 20:00:02 srv4hai postfix/cleanup[25795]: A18B163468: message-id=<20250429180002.A18B163468@mail.viehai.at>
Apr 29 20:00:02 srv4hai postfix/qmgr[8566]: A18B163468: from=, size=663, nrcpt=1 (queue active)
Apr 29 20:00:02 srv4hai postfix/local[25797]: A18B163468: to=, orig_to=, relay=local, delay=0.04, delays=0.03/0.01/0/0.01, dsn=2.0.0, status=sent (delivered to command: /bin/procmail)
Apr 29 20:00:02 srv4hai postfix/qmgr[8566]: A18B163468: removed
Apr 29 20:00:10 srv4hai mqtt_process[25802]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 29 20:00:10 srv4hai mqtt_process[25827]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 29 20:00:23 srv4hai ping_SP[18037]: SHELLY (1) - ping 192.168.12.62 1 sucessfully  @~1745949623 - 'ping_SP_1'
Apr 29 20:00:23 srv4hai ping_SP[18037]: SHELLY (1) - ping 192.168.12.62 pCnt:'1'
Apr 29 20:00:35 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=25878, secured, session=
Apr 29 20:00:35 srv4hai dovecot: imap(rozumm)<25878>: Logged out in=92 out=1061 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Apr 29 20:00:49 srv4hai ping_SP[26783]: SHELLY (0) - ping 192.168.12.61 1 sucessfully  @~1745949649 - 'ping_SP_0'
Apr 29 20:00:49 srv4hai ping_SP[26783]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Apr 29 20:00:59 srv4hai mqtt_process[25903]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 29 20:00:59 srv4hai mqtt_process[25928]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 29 20:01:00 srv4hai mqtt_process[25953]: SHELLY - shellyType: 'shelly-PM-mini' not handled for 'shelly-49'/'49-TBD'!!
Apr 29 20:01:01 srv4hai CRON[25981]: (root) CMD (   test -x /etc/cron.daily/popularity-contest && /etc/cron.daily/popularity-contest --crond)
Apr 29 20:01:01 srv4hai CRON[25982]: (root) CMD (/etc/linetest/linetest.sh)
Apr 29 20:01:10 srv4hai mqtt_process[26016]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 29 20:01:10 srv4hai mqtt_process[26041]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 29 20:01:35 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=26103, secured, session=
Apr 29 20:01:35 srv4hai dovecot: imap(rozumm)<26103>: Logged out in=92 out=1061 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Apr 29 20:01:36 srv4hai ping_SP[18037]: SHELLY (1) - ping 192.168.12.62 1 sucessfully  @~1745949696 - 'ping_SP_1'
Apr 29 20:01:36 srv4hai ping_SP[18037]: SHELLY (1) - ping 192.168.12.62 pCnt:'1'
Apr 29 20:01:40 srv4hai fetchmail[1651]: 7 messages (7 seen) for rozmig at mail.akis.at (1753652 octets).
Apr 29 20:01:41 srv4hai fetchmail[1651]: 68721 messages (68720 seen) for tmiglinci at mail.akis.at (1372392837 octets).
Apr 29 20:01:41 srv4hai postfix/smtpd[26126]: connect from localhost[127.0.0.1]
Apr 29 20:01:41 srv4hai postfix/smtpd[26126]: 9ABDE63463: client=localhost[127.0.0.1]
Apr 29 20:01:41 srv4hai postfix/cleanup[25795]: 9ABDE63463: message-id=
Apr 29 20:01:41 srv4hai fetchmail[1651]: reading message tmiglinci@mail.akis.at:68721 of 68721 (44801 octets) not flushed
Apr 29 20:01:41 srv4hai postfix/qmgr[8566]: 9ABDE63463: from=, size=45038, nrcpt=1 (queue active)
Apr 29 20:01:41 srv4hai postfix/local[25797]: 9ABDE63463: to=, relay=local, delay=0.07, delays=0.07/0/0/0.01, dsn=2.0.0, status=sent (delivered to command: /bin/procmail)
Apr 29 20:01:41 srv4hai postfix/qmgr[8566]: 9ABDE63463: removed
Apr 29 20:01:41 srv4hai postfix/smtpd[26126]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1 data=1 quit=1 commands=5
Apr 29 20:01:42 srv4hai fetchmail[1651]: 11888 messages (11888 seen) for srozum at mail.akis.at (1398655474 octets).
Apr 29 20:01:42 srv4hai fetchmail[1651]: 30653 messages (30652 seen) for mrozum at mail.akis.at (984728725 octets).
Apr 29 20:01:42 srv4hai postfix/smtpd[26126]: connect from localhost[127.0.0.1]
Apr 29 20:01:42 srv4hai postfix/smtpd[26126]: BDD1463463: client=localhost[127.0.0.1]
Apr 29 20:01:42 srv4hai postfix/cleanup[25795]: BDD1463463: message-id=
Apr 29 20:01:42 srv4hai fetchmail[1651]: reading message mrozum@mail.akis.at:30653 of 30653 (6706 octets) not flushed
Apr 29 20:01:42 srv4hai postfix/qmgr[8566]: BDD1463463: from=, size=6988, nrcpt=1 (queue active)
Apr 29 20:01:42 srv4hai postfix/local[25797]: BDD1463463: to=, relay=local, delay=0.06, delays=0.04/0/0/0.01, dsn=2.0.0, status=sent (delivered to command: /bin/procmail)
Apr 29 20:01:42 srv4hai postfix/qmgr[8566]: BDD1463463: removed
Apr 29 20:01:42 srv4hai postfix/smtpd[26126]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1 data=1 quit=1 commands=5
Apr 29 20:01:43 srv4hai kernel: [91927827.732044] CPU4: Package temperature above threshold, cpu clock throttled (total events = 927631853)
Apr 29 20:01:43 srv4hai kernel: [91927827.732045] CPU5: Package temperature above threshold, cpu clock throttled (total events = 927631853)
Apr 29 20:01:43 srv4hai kernel: [91927827.732047] CPU6: Package temperature above threshold, cpu clock throttled (total events = 927631853)
Apr 29 20:01:43 srv4hai kernel: [91927827.732049] CPU7: Package temperature above threshold, cpu clock throttled (total events = 927631853)
Apr 29 20:01:43 srv4hai kernel: [91927827.732050] CPU3: Package temperature above threshold, cpu clock throttled (total events = 927631853)
Apr 29 20:01:43 srv4hai kernel: [91927827.732051] CPU2: Package temperature above threshold, cpu clock throttled (total events = 927631853)
Apr 29 20:01:43 srv4hai kernel: [91927827.732052] CPU0: Package temperature above threshold, cpu clock throttled (total events = 927631853)
Apr 29 20:01:43 srv4hai kernel: [91927827.732053] CPU1: Package temperature above threshold, cpu clock throttled (total events = 927631853)
Apr 29 20:01:43 srv4hai fetchmail[1651]: 1169 messages (1169 seen) for admin@bastelstube.wien at pop3.world4you.com (52284237 octets).
Apr 29 20:01:59 srv4hai mqtt_process[26154]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 29 20:01:59 srv4hai mqtt_process[26179]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 29 20:02:00 srv4hai mqtt_process[26205]: SHELLY - shellyType: 'shelly-PM-mini' not handled for 'shelly-49'/'49-TBD'!!
Apr 29 20:02:01 srv4hai CRON[26233]: (root) CMD (/etc/linetest/linetest.sh)
Apr 29 20:02:10 srv4hai mqtt_process[26253]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 29 20:02:10 srv4hai mqtt_process[26278]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 29 20:02:12 srv4hai ping_SP[26783]: SHELLY (0)- ping 192.168.12.61 1 not answered (1) @~1745949732 - 'ping_SP_0'
Apr 29 20:02:22 srv4hai bt_scan[26307]: SHELLY - who: 'Thomas' / phoneName: 'FP4_Thomas' / '0' / shellyIP: '192.168.12.61' - found: 'No'
Apr 29 20:02:22 srv4hai bt_scan[26307]: SHELLY - lost BT-connection to BTmac: '0' found: 'No' - check presence
Apr 29 20:02:22 srv4hai common.sh: SHELLY: BTstatus[0]:'NO_BTdweet' dweetAge:''=-
Apr 29 20:02:22 srv4hai check_presence[26369]: SHELLY (0) found /srv/www/viehai/shelly/meter/ping_SP_0.TRY and NO_BTdweet//srv/www/viehai/shelly/meter/BTscan_BTid_0.No
Apr 29 20:02:22 srv4hai check_presence[26369]: SHELLY (bt_scan)-(0) found '/srv/www/viehai/shelly/meter/ping_SP_0.TRY' for what: 'presence'
Apr 29 20:02:22 srv4hai common.sh: SHELLY: BTstatus[1]:'NO_BTdweet' dweetAge:''=-
Apr 29 20:02:22 srv4hai check_presence[26369]: SHELLY (1) found /srv/www/viehai/shelly/meter/ping_SP_1.LIVE and NO_BTdweet//srv/www/viehai/shelly/meter/BTscan_BTid_1.Yes
Apr 29 20:02:22 srv4hai check_presence[26369]: SHELLY (bt_scan)-(0) found '/srv/www/viehai/shelly/meter/ping_SP_1.LIVE' for what: 'presence'
Apr 29 20:02:22 srv4hai check_presence[26369]: SHELLY (bt_scan)-(0) what: 'presence' - persPresent: '(__/TRY) (__/LIVE)' - anyPresent: 'true' - otherPresent:'true'
Apr 29 20:02:22 srv4hai check_presence[26369]: SHELLY - check 'presence': anyPresent(true) - lScene(scene_buero_UM) - sceneOFF(offAll)
Apr 29 20:02:22 srv4hai check_presence[26369]: SHELLY (bt_scan)-(0)-presence: NOT activate_scenario.shtml: offAll
Apr 29 20:02:35 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=26474, secured, session=<6E75mu4z3JMAAAAAAAAAAAAAAAAAAAAB>
Apr 29 20:02:35 srv4hai dovecot: imap(rozumm)<26474><6E75mu4z3JMAAAAAAAAAAAAAAAAAAAAB>: Logged out in=647 out=35412 deleted=0 expunged=0 trashed=0 hdr_count=50 hdr_bytes=17308 body_count=0 body_bytes=0
Apr 29 20:02:37 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=26481, secured, session=
Apr 29 20:02:37 srv4hai dovecot: imap(miglincit)<26481>: Logged out in=2305 out=180194 deleted=0 expunged=0 trashed=0 hdr_count=287 hdr_bytes=98584 body_count=0 body_bytes=0
Apr 29 20:02:38 srv4hai ping_SP[26783]: SHELLY (0)- ping 192.168.12.61 2 not answered (1) @~1745949758 - 'ping_SP_0'
Apr 29 20:02:49 srv4hai ping_SP[18037]: SHELLY (1) - ping 192.168.12.62 1 sucessfully  @~1745949769 - 'ping_SP_1'
Apr 29 20:02:49 srv4hai ping_SP[18037]: SHELLY (1) - ping 192.168.12.62 pCnt:'1'
Apr 29 20:02:59 srv4hai mqtt_process[26546]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 29 20:03:00 srv4hai mqtt_process[26571]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 29 20:03:00 srv4hai mqtt_process[26596]: SHELLY - shellyType: 'shelly-PM-mini' not handled for 'shelly-49'/'49-TBD'!!
Apr 29 20:03:01 srv4hai CRON[26624]: (root) CMD (/etc/linetest/linetest.sh)
Apr 29 20:03:05 srv4hai ping_SP[26783]: SHELLY (0)- ping 192.168.12.61 3 not answered (1) @~1745949785 - 'ping_SP_0'
Apr 29 20:03:05 srv4hai kernel: [91927909.947067] CPU5: Core temperature/speed normal
Apr 29 20:03:05 srv4hai kernel: [91927909.947068] CPU1: Core temperature/speed normal
Apr 29 20:03:10 srv4hai mqtt_process[26652]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 29 20:03:10 srv4hai mqtt_process[26679]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 29 20:03:27 srv4hai dhcpd[30231]: DHCPDISCOVER from e8:78:29:c0:c2:4e via enp2s0
Apr 29 20:03:27 srv4hai dhcpd[30231]: DHCPOFFER on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0
Apr 29 20:03:27 srv4hai dhcpd[30231]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Apr 29 20:03:27 srv4hai dhcpd[30231]: execute_statement argv[1] = 192.168.12.61
Apr 29 20:03:27 srv4hai dhcpd[30231]: execute_statement argv[2] = fpThomas
Apr 29 20:03:31 srv4hai ping_SP[26783]: SHELLY (0)- ping 192.168.12.61 4 not answered (1) @~1745949811 - 'ping_SP_0'
Apr 29 20:03:35 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=26768, secured, session=
Apr 29 20:03:35 srv4hai dovecot: imap(rozumm)<26768>: Logged out in=481 out=1211 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Apr 29 20:03:37 srv4hai smartphone.sh: SHELLY - ping_SP.sh started for phoneIP: '192.168.12.61' / 'fpThomas' / '0'
Apr 29 20:03:37 srv4hai dhcpd[30231]: DHCPREQUEST for 192.168.12.61 (192.168.12.252) from e8:78:29:c0:c2:4e via enp2s0
Apr 29 20:03:37 srv4hai dhcpd[30231]: DHCPACK on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0
Apr 29 20:03:37 srv4hai dhcpd[30231]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Apr 29 20:03:37 srv4hai dhcpd[30231]: execute_statement argv[1] = 192.168.12.61
Apr 29 20:03:37 srv4hai dhcpd[30231]: execute_statement argv[2] = fpThomas
Apr 29 20:03:37 srv4hai ping_SP[26773]: SHELLY ping_SP.sh started from smartphone, user:'root'
Apr 29 20:03:47 srv4hai smartphone.sh: SHELLY - ping_SP.sh started for phoneIP: '192.168.12.61' / 'fpThomas' / '0'
Apr 29 20:03:47 srv4hai dhcpd[30231]: DHCPREQUEST for 192.168.12.61 (192.168.12.252) from e8:78:29:c0:c2:4e via enp2s0
Apr 29 20:03:47 srv4hai dhcpd[30231]: DHCPACK on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0
Apr 29 20:03:47 srv4hai dhcpd[30231]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Apr 29 20:03:47 srv4hai dhcpd[30231]: execute_statement argv[1] = 192.168.12.61
Apr 29 20:03:47 srv4hai dhcpd[30231]: execute_statement argv[2] = fpThomas
Apr 29 20:03:47 srv4hai named[30406]: client @0x7f933004a8d0 192.168.12.252#44093/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: fpThomas.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Apr 29 20:03:47 srv4hai ping_SP[26845]: SHELLY ping_SP.sh started from smartphone, user:'root'
Apr 29 20:03:47 srv4hai common.sh: SHELLY: BTstatus[0]:'NO_BTdweet' dweetAge:''=-
Apr 29 20:03:48 srv4hai check_presence[26854]: SHELLY (0) found /srv/www/viehai/shelly/meter/ping_SP_0.LIVE and NO_BTdweet//srv/www/viehai/shelly/meter/BTscan_BTid_0.No
Apr 29 20:03:48 srv4hai check_presence[26854]: SHELLY (ping_SP__smartphone)-(0) found '/srv/www/viehai/shelly/meter/ping_SP_0.LIVE' for what: 'coming'
Apr 29 20:03:48 srv4hai common.sh: SHELLY: BTstatus[1]:'NO_BTdweet' dweetAge:''=-
Apr 29 20:03:48 srv4hai check_presence[26854]: SHELLY (1) found /srv/www/viehai/shelly/meter/ping_SP_1.LIVE and NO_BTdweet//srv/www/viehai/shelly/meter/BTscan_BTid_1.Yes
Apr 29 20:03:48 srv4hai check_presence[26854]: SHELLY (ping_SP__smartphone)-(0) found '/srv/www/viehai/shelly/meter/ping_SP_1.LIVE' for what: 'coming'
Apr 29 20:03:48 srv4hai check_presence[26854]: SHELLY (ping_SP__smartphone)-(0) what: 'coming' - persPresent: '(__/LIVE) (__/LIVE)' - anyPresent: 'true' - otherPresent:'true'
Apr 29 20:03:48 srv4hai check_presence[26854]: SHELLY - check 'coming': anyPresent(true) - lScene(scene_buero_UM) - sceneONsmall(vorraumOn)
Apr 29 20:03:48 srv4hai check_presence[26854]: SHELLY (ping_SP__smartphone)-(0)-coming: activate_scenario.shtml: vorraumOn
Apr 29 20:03:48 srv4hai bt_scan[26810]: SHELLY - who: 'Thomas' / phoneName: 'FP4_Thomas' / '0' / shellyIP: '192.168.12.61' - found: 'Yes'
Apr 29 20:03:48 srv4hai activate_scenario[26967]: SHELLY scenario: 'vorraumOn(vorraumon)' started
Apr 29 20:03:48 srv4hai activate_scenario[26967]: SHELLY dayState='night' <--> DayTime='checked' NightTime='checked' flag 'execute* set as ' - phoneName:'0'
Apr 29 20:03:48 srv4hai bt_scan[26810]: SHELLY - ping_SP.sh already running for BTmac: '0' - IPv4: '192.168.12.61'

finished