activate_scenario.sh
Tue Apr 29 22:35:51 CEST 2025
scenario: vorraumOn
phoneName: 0

dayState='night' <--> DayTime='checked' NightTime='checked' flag 'execute* set as '
-> execution of scenario

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

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

vorher: 22:35:51.896

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: 22:35:51.944
Dauer: 48 msec phName: 'phoneName:'0''


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

urls : 1
vorher : 22:35:51.877
nachher: 22:35:51.956
Dauer : 80 msec
outfile: '/srv/www/viehai/shelly/log_scenarios/2025/04/29//scene_vorraumOn_20250429_223551.txt'


syslog

Apr 29 22:32:00 srv4hai mqtt_process[28739]: SHELLY - shellyType: 'shelly-PM-mini' not handled for 'shelly-49'/'49-TBD'!!
Apr 29 22:32:01 srv4hai CRON[28765]: (root) CMD (/etc/linetest/linetest.sh)
Apr 29 22:32:10 srv4hai mqtt_process[28774]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 29 22:32:10 srv4hai mqtt_process[28799]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 29 22:32:19 srv4hai systemd[1]: Started Run anacron jobs.
Apr 29 22:32:19 srv4hai anacron[28829]: Anacron 2.3 started on 2025-04-29
Apr 29 22:32:19 srv4hai anacron[28829]: Normal exit (0 jobs run)
Apr 29 22:32:19 srv4hai systemd[1]: anacron.service: Succeeded.
Apr 29 22:32:22 srv4hai ping_SP[28481]: SHELLY (0) - ping 192.168.12.61 1 sucessfully  @~1745958742 - 'ping_SP_0'
Apr 29 22:32:22 srv4hai ping_SP[28481]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Apr 29 22:32:37 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=28854, secured, session=
Apr 29 22:32:37 srv4hai dovecot: imap(miglincit)<28854>: Logged out in=137 out=1087 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Apr 29 22:32:42 srv4hai ping_SP[18037]: SHELLY (1) - ping 192.168.12.62 1 sucessfully  @~1745958762 - 'ping_SP_1'
Apr 29 22:32:42 srv4hai ping_SP[18037]: SHELLY (1) - ping 192.168.12.62 pCnt:'1'
Apr 29 22:33:00 srv4hai mqtt_process[28873]: SHELLY - shellyType: 'shelly-PM-mini' not handled for 'shelly-49'/'49-TBD'!!
Apr 29 22:33:00 srv4hai mqtt_process[28898]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 29 22:33:00 srv4hai mqtt_process[28923]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 29 22:33:01 srv4hai CRON[28949]: (root) CMD (/etc/linetest/linetest.sh)
Apr 29 22:33:05 srv4hai kernel: [91936910.569078] CPU5: Core temperature/speed normal
Apr 29 22:33:05 srv4hai kernel: [91936910.569079] CPU1: Core temperature/speed normal
Apr 29 22:33:10 srv4hai mqtt_process[28954]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 29 22:33:10 srv4hai mqtt_process[28979]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 29 22:33:21 srv4hai fetchmail[1651]: 7 messages (7 seen) for rozmig at mail.akis.at (1753652 octets).
Apr 29 22:33:22 srv4hai fetchmail[1651]: 68724 messages (68724 seen) for tmiglinci at mail.akis.at (1372554504 octets).
Apr 29 22:33:22 srv4hai fetchmail[1651]: 11888 messages (11888 seen) for srozum at mail.akis.at (1398655474 octets).
Apr 29 22:33:23 srv4hai fetchmail[1651]: 30653 messages (30653 seen) for mrozum at mail.akis.at (984728725 octets).
Apr 29 22:33:24 srv4hai fetchmail[1651]: 1169 messages (1169 seen) for admin@bastelstube.wien at pop3.world4you.com (52284237 octets).
Apr 29 22:33:36 srv4hai ping_SP[28481]: SHELLY (0) - ping 192.168.12.61 1 sucessfully  @~1745958816 - 'ping_SP_0'
Apr 29 22:33:36 srv4hai ping_SP[28481]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Apr 29 22:33:55 srv4hai bt_scan[29034]: SHELLY - who: 'Thomas' / phoneName: 'FP4_Thomas' / '0' / shellyIP: '192.168.12.61' - found: 'No'
Apr 29 22:33:55 srv4hai bt_scan[29034]: SHELLY - lost BT-connection to BTmac: '0' found: 'No' - check presence
Apr 29 22:33:55 srv4hai common.sh: SHELLY: BTstatus[0]:'NO_BTdweet' dweetAge:''=-
Apr 29 22:33:55 srv4hai check_presence[29070]: 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 22:33:55 srv4hai check_presence[29070]: SHELLY (bt_scan)-(0) found '/srv/www/viehai/shelly/meter/ping_SP_0.LIVE' for what: 'presence'
Apr 29 22:33:55 srv4hai common.sh: SHELLY: BTstatus[1]:'NO_BTdweet' dweetAge:''=-
Apr 29 22:33:55 srv4hai check_presence[29070]: 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 22:33:55 srv4hai check_presence[29070]: SHELLY (bt_scan)-(0) found '/srv/www/viehai/shelly/meter/ping_SP_1.LIVE' for what: 'presence'
Apr 29 22:33:55 srv4hai check_presence[29070]: SHELLY (bt_scan)-(0) what: 'presence' - persPresent: '(__/LIVE) (__/LIVE)' - anyPresent: 'true' - otherPresent:'true'
Apr 29 22:33:55 srv4hai ping_SP[18037]: SHELLY (1) - ping 192.168.12.62 1 sucessfully  @~1745958835 - 'ping_SP_1'
Apr 29 22:33:55 srv4hai ping_SP[18037]: SHELLY (1) - ping 192.168.12.62 pCnt:'1'
Apr 29 22:33:55 srv4hai check_presence[29070]: SHELLY - check 'presence': anyPresent(true) - lScene(scene_vorraumOn) - sceneOFF(offAll)
Apr 29 22:33:55 srv4hai check_presence[29070]: SHELLY (bt_scan)-(0)-presence: NOT activate_scenario.shtml: offAll
Apr 29 22:34:00 srv4hai mqtt_process[29170]: SHELLY - shellyType: 'shelly-PM-mini' not handled for 'shelly-49'/'49-TBD'!!
Apr 29 22:34:00 srv4hai mqtt_process[29195]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 29 22:34:00 srv4hai mqtt_process[29220]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 29 22:34:01 srv4hai CRON[29246]: (root) CMD (/etc/linetest/linetest.sh)
Apr 29 22:34:04 srv4hai named[30406]: resolver priming query complete
Apr 29 22:34:05 srv4hai named[30406]: resolver priming query complete
Apr 29 22:34:06 srv4hai named[30406]: resolver priming query complete
Apr 29 22:34:07 srv4hai named[30406]: resolver priming query complete
Apr 29 22:34:08 srv4hai named[30406]: resolver priming query complete
Apr 29 22:34:09 srv4hai named[30406]: resolver priming query complete
Apr 29 22:34:10 srv4hai named[30406]: resolver priming query complete
Apr 29 22:34:10 srv4hai mqtt_process[29253]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 29 22:34:10 srv4hai mqtt_process[29278]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 29 22:34:13 srv4hai named[30406]: resolver priming query complete
Apr 29 22:34:14 srv4hai named[30406]: resolver priming query complete
Apr 29 22:34:24 srv4hai named[30406]: resolver priming query complete
Apr 29 22:34:30 srv4hai named[30406]: resolver priming query complete
Apr 29 22:34:31 srv4hai named[30406]: resolver priming query complete
Apr 29 22:34:35 srv4hai named[30406]: resolver priming query complete
Apr 29 22:34:39 srv4hai named[30406]: resolver priming query complete
Apr 29 22:34:43 srv4hai named[30406]: resolver priming query complete
Apr 29 22:34:47 srv4hai named[30406]: resolver priming query complete
Apr 29 22:34:49 srv4hai ping_SP[28481]: SHELLY (0) - ping 192.168.12.61 1 sucessfully  @~1745958889 - 'ping_SP_0'
Apr 29 22:34:49 srv4hai ping_SP[28481]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Apr 29 22:34:49 srv4hai named[30406]: resolver priming query complete
Apr 29 22:34:52 srv4hai named[30406]: resolver priming query complete
Apr 29 22:34:54 srv4hai named[30406]: resolver priming query complete
Apr 29 22:34:56 srv4hai named[30406]: resolver priming query complete
Apr 29 22:35:00 srv4hai mqtt_process[29333]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 29 22:35:00 srv4hai mqtt_process[29358]: SHELLY - shellyType: 'shelly-PM-mini' not handled for 'shelly-49'/'49-TBD'!!
Apr 29 22:35:00 srv4hai mqtt_process[29384]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 29 22:35:00 srv4hai named[30406]: resolver priming query complete
Apr 29 22:35:01 srv4hai CRON[29410]: (root) CMD (/etc/linetest/linetest.sh)
Apr 29 22:35:06 srv4hai named[30406]: resolver priming query complete
Apr 29 22:35:08 srv4hai ping_SP[18037]: SHELLY (1) - ping 192.168.12.62 1 sucessfully  @~1745958908 - 'ping_SP_1'
Apr 29 22:35:08 srv4hai ping_SP[18037]: SHELLY (1) - ping 192.168.12.62 pCnt:'1'
Apr 29 22:35:08 srv4hai named[30406]: resolver priming query complete
Apr 29 22:35:10 srv4hai mqtt_process[29433]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 29 22:35:10 srv4hai named[30406]: resolver priming query complete
Apr 29 22:35:10 srv4hai mqtt_process[29458]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 29 22:35:13 srv4hai named[30406]: resolver priming query complete
Apr 29 22:35:15 srv4hai named[30406]: resolver priming query complete
Apr 29 22:35:17 srv4hai named[30406]: resolver priming query complete
Apr 29 22:35:21 srv4hai dhcpd[30231]: DHCPDISCOVER from e8:78:29:c0:c2:4e via enp2s0
Apr 29 22:35:21 srv4hai dhcpd[30231]: DHCPOFFER on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0
Apr 29 22:35:21 srv4hai dhcpd[30231]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Apr 29 22:35:21 srv4hai dhcpd[30231]: execute_statement argv[1] = 192.168.12.61
Apr 29 22:35:21 srv4hai dhcpd[30231]: execute_statement argv[2] = fpThomas
Apr 29 22:35:21 srv4hai named[30406]: resolver priming query complete
Apr 29 22:35:28 srv4hai named[30406]: resolver priming query complete
Apr 29 22:35:31 srv4hai smartphone.sh: SHELLY - ping_SP.sh started for phoneIP: '192.168.12.61' / 'fpThomas' / '0'
Apr 29 22:35:31 srv4hai dhcpd[30231]: DHCPREQUEST for 192.168.12.61 (192.168.12.252) from e8:78:29:c0:c2:4e via enp2s0
Apr 29 22:35:31 srv4hai dhcpd[30231]: DHCPACK on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0
Apr 29 22:35:31 srv4hai dhcpd[30231]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Apr 29 22:35:31 srv4hai dhcpd[30231]: execute_statement argv[1] = 192.168.12.61
Apr 29 22:35:31 srv4hai dhcpd[30231]: execute_statement argv[2] = fpThomas
Apr 29 22:35:31 srv4hai ping_SP[29502]: SHELLY ping_SP.sh started from smartphone, user:'root'
Apr 29 22:35:31 srv4hai named[30406]: resolver priming query complete
Apr 29 22:35:32 srv4hai named[30406]: resolver priming query complete
Apr 29 22:35:34 srv4hai named[30406]: resolver priming query complete
Apr 29 22:35:37 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=29539, secured, session=
Apr 29 22:35:37 srv4hai dovecot: imap(miglincit)<29539>: Logged out in=137 out=1087 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Apr 29 22:35:38 srv4hai named[30406]: resolver priming query complete
Apr 29 22:35:41 srv4hai smartphone.sh: SHELLY - ping_SP.sh started for phoneIP: '192.168.12.61' / 'fpThomas' / '0'
Apr 29 22:35:41 srv4hai dhcpd[30231]: DHCPREQUEST for 192.168.12.61 (192.168.12.252) from e8:78:29:c0:c2:4e via enp2s0
Apr 29 22:35:41 srv4hai dhcpd[30231]: DHCPACK on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0
Apr 29 22:35:41 srv4hai dhcpd[30231]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Apr 29 22:35:41 srv4hai dhcpd[30231]: execute_statement argv[1] = 192.168.12.61
Apr 29 22:35:41 srv4hai dhcpd[30231]: execute_statement argv[2] = fpThomas
Apr 29 22:35:41 srv4hai named[30406]: client @0x7f93506b28d0 192.168.12.252#33875/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: fpThomas.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Apr 29 22:35:41 srv4hai ping_SP[29549]: SHELLY ping_SP.sh started from smartphone, user:'root'
Apr 29 22:35:41 srv4hai common.sh: SHELLY: BTstatus[0]:'NO_BTdweet' dweetAge:''=-
Apr 29 22:35:41 srv4hai check_presence[29554]: 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 22:35:41 srv4hai check_presence[29554]: SHELLY (ping_SP__smartphone)-(0) found '/srv/www/viehai/shelly/meter/ping_SP_0.LIVE' for what: 'coming'
Apr 29 22:35:41 srv4hai common.sh: SHELLY: BTstatus[1]:'NO_BTdweet' dweetAge:''=-
Apr 29 22:35:41 srv4hai check_presence[29554]: 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 22:35:41 srv4hai check_presence[29554]: SHELLY (ping_SP__smartphone)-(0) found '/srv/www/viehai/shelly/meter/ping_SP_1.LIVE' for what: 'coming'
Apr 29 22:35:41 srv4hai check_presence[29554]: SHELLY (ping_SP__smartphone)-(0) what: 'coming' - persPresent: '(__/LIVE) (__/LIVE)' - anyPresent: 'true' - otherPresent:'true'
Apr 29 22:35:41 srv4hai check_presence[29554]: SHELLY - check 'coming': anyPresent(true) - lScene(scene_vorraumOn) - sceneONsmall(vorraumOn)
Apr 29 22:35:41 srv4hai check_presence[29554]: SHELLY (ping_SP__smartphone)-(0)-coming: activate_scenario.shtml: vorraumOn
Apr 29 22:35:41 srv4hai activate_scenario[29670]: SHELLY scenario: 'vorraumOn(vorraumon)' started
Apr 29 22:35:41 srv4hai activate_scenario[29670]: SHELLY dayState='night' <--> DayTime='checked' NightTime='checked' flag 'execute* set as ' - phoneName:'0'
Apr 29 22:35:41 srv4hai activate_scenario[29670]: SHELLY scenario: 'vorraumOn(vorraumon)' TO BE EXECUTED, phoneName:'0'
Apr 29 22:35:41 srv4hai activate_scenario[29670]: SHELLY: scenario: 'vorraumOn(vorraumon)'-'shelly-03'-'' --> '0?turn=on'
Apr 29 22:35:41 srv4hai proc_url[29726]: SHELLY: OF:'/srv/www/viehai/shelly/log_scenarios/2025/04/29/_0.log' - shellyUsage:'' - scUrl:'http://192.168.12.103/relay/0?turn=on' - phName:'phoneName:'0''
Apr 29 22:35:41 srv4hai activate_scenario[29670]: SHELLY scenario: 'vorraumOn(vorraumon)' took 68 msec, phoneName:'0'
Apr 29 22:35:41 srv4hai ping_SP[29502]: SHELLY check_presence.sh coming 0 ping_SP__smartphone
Apr 29 22:35:43 srv4hai ping_SP[29502]: SHELLY - running process-id(29502): '28481 /bin/bash /srv/www/viehai/shelly//ping_SP.sh 192.168.12.61 bt_scan#01229502 /bin/bash /srv/www/viehai/shelly//ping_SP.sh 192.168.12.61 smartphone#01229549 /bin/bash /srv/www/viehai/shelly//ping_SP.sh 192.168.12.61 smartphone#01229567 /bin/bash /srv/www/viehai/shelly/smartphone.sh 192.168.12.61 fpThomas' - 'ping_SP_0' - exiting ==============
Apr 29 22:35:51 srv4hai smartphone.sh: SHELLY - ping_SP.sh started for phoneIP: '192.168.12.61' / 'fpThomas' / '0'
Apr 29 22:35:51 srv4hai dhcpd[30231]: DHCPREQUEST for 192.168.12.61 (192.168.12.252) from e8:78:29:c0:c2:4e via enp2s0
Apr 29 22:35:51 srv4hai dhcpd[30231]: DHCPACK on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0
Apr 29 22:35:51 srv4hai dhcpd[30231]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Apr 29 22:35:51 srv4hai dhcpd[30231]: execute_statement argv[1] = 192.168.12.61
Apr 29 22:35:51 srv4hai dhcpd[30231]: execute_statement argv[2] = fpThomas
Apr 29 22:35:51 srv4hai named[30406]: client @0x7f93506b28d0 192.168.12.252#33875/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: fpThomas.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Apr 29 22:35:51 srv4hai named[30406]: client @0x7f93100debd0 192.168.12.252#55155/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: fpThomas.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Apr 29 22:35:51 srv4hai ping_SP[29780]: SHELLY ping_SP.sh started from smartphone, user:'root'
Apr 29 22:35:51 srv4hai common.sh: SHELLY: BTstatus[0]:'NO_BTdweet' dweetAge:''=-
Apr 29 22:35:51 srv4hai check_presence[29809]: 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 22:35:51 srv4hai check_presence[29809]: SHELLY (ping_SP__smartphone)-(0) found '/srv/www/viehai/shelly/meter/ping_SP_0.LIVE' for what: 'coming'
Apr 29 22:35:51 srv4hai common.sh: SHELLY: BTstatus[1]:'NO_BTdweet' dweetAge:''=-
Apr 29 22:35:51 srv4hai check_presence[29809]: 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 22:35:51 srv4hai check_presence[29809]: SHELLY (ping_SP__smartphone)-(0) found '/srv/www/viehai/shelly/meter/ping_SP_1.LIVE' for what: 'coming'
Apr 29 22:35:51 srv4hai check_presence[29809]: SHELLY (ping_SP__smartphone)-(0) what: 'coming' - persPresent: '(__/LIVE) (__/LIVE)' - anyPresent: 'true' - otherPresent:'true'
Apr 29 22:35:51 srv4hai named[30406]: resolver priming query complete
Apr 29 22:35:51 srv4hai check_presence[29809]: SHELLY - check 'coming': anyPresent(true) - lScene(scene_vorraumOn) - sceneONsmall(vorraumOn)
Apr 29 22:35:51 srv4hai check_presence[29809]: SHELLY (ping_SP__smartphone)-(0)-coming: activate_scenario.shtml: vorraumOn
Apr 29 22:35:51 srv4hai activate_scenario[29904]: SHELLY scenario: 'vorraumOn(vorraumon)' started
Apr 29 22:35:51 srv4hai activate_scenario[29904]: SHELLY dayState='night' <--> DayTime='checked' NightTime='checked' flag 'execute* set as ' - phoneName:'0'

finished