Message "Service system_log/write called" every time I activate HA web ui browser tab

Hi,

in (at least) v0.63.2 whenever I switch to a browser tab that shows the HA web UI, a message from HA pops up at the bottom, saying “Service system_log/write called”.
Is it supposed to do that? It get’s pretty annoying…

Sebastian

I’m seeing the same when using Safari. Whenever I switch the frontend to ES5 it doesn’t appear and doesn’t write to the log file.

2 Likes

I’m using Safari, too.
I now switched from “latest” to “es5” and indeed the message stops to appear :slight_smile:
Thanks!

Sebastian

I use chrome and see the same - what are you guys talking about latest/ES5 ??

Check out the javascript_version option for the frontend component:

Sebastian

I am using es5 and still get message

Same here.
For whatever reason it worked a short while after switching from latest to es5, but now it’s the same as before.
Strange.

Sebastian

1 Like

Check the logs and see what line is written to the log and paste it here. It could be a cached version of the front end or a bug in the front end serving latest JavaScript instead of ES5

It seems it’s logging every event that happens:

home-assistant.log.1:2018-02-20 15:36:08 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 139705092524408: Received {'type': 'call_service', 'domain': 'system_log', 'service': 'write', 'service_data': {'logger': 'frontend.js.es5.201802090', 'message': ':0:0 Script error.'}, 'id': 15}
home-assistant.log.1:2018-02-20 15:36:08 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 139705092034176: Sending {'id': 291, 'type': 'event', 'event': {'event_type': 'call_service', 'data': {'domain': 'system_log', 'service': 'write', 'service_data': {'logger': 'frontend.js.es5.201802090', 'message': ':0:0 Script error.'}, 'service_call_id': '139706296896144-38'}, 'origin': 'LOCAL', 'time_fired': datetime.datetime(2018, 2, 20, 14, 36, 8, 625358, tzinfo=<UTC>)}}
home-assistant.log.1:2018-02-20 15:36:08 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 139705092034176: Sending {'id': 291, 'type': 'event', 'event': {'event_type': 'service_executed', 'data': {'service_call_id': '139706296896144-38'}, 'origin': 'LOCAL', 'time_fired': datetime.datetime(2018, 2, 20, 14, 36, 8, 628325, tzinfo=<UTC>)}}
home-assistant.log.1:2018-02-20 15:36:08 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 139705092034176: Sending {'id': 291, 'type': 'event', 'event': {'event_type': 'system_log_event', 'data': {'timestamp': 1519137368.6270914, 'level': 'ERROR', 'message': ':0:0 Script error.', 'exception': '', 'source': 'components/system_log/__init__.py'}, 'origin': 'LOCAL', 'time_fired': datetime.datetime(2018, 2, 20, 14, 36, 8, 630042, tzinfo=<UTC>)}}
home-assistant.log.1:2018-02-20 15:36:08 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 139705092524408: Sending {'id': 15, 'type': 'result', 'success': True, 'result': None}
home-assistant.log.1:2018-02-20 15:36:10 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 139705092524408: Received {'type': 'call_service', 'domain': 'system_log', 'service': 'write', 'service_data': {'logger': 'frontend.js.es5.201802090', 'message': ':0:0 Script error.'}, 'id': 16}
home-assistant.log.1:2018-02-20 15:36:10 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 139705092034176: Sending {'id': 291, 'type': 'event', 'event': {'event_type': 'call_service', 'data': {'domain': 'system_log', 'service': 'write', 'service_data': {'logger': 'frontend.js.es5.201802090', 'message': ':0:0 Script error.'}, 'service_call_id': '139706296896144-39'}, 'origin': 'LOCAL', 'time_fired': datetime.datetime(2018, 2, 20, 14, 36, 10, 67997, tzinfo=<UTC>)}}
home-assistant.log.1:2018-02-20 15:36:10 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 139705092034176: Sending {'id': 291, 'type': 'event', 'event': {'event_type': 'service_executed', 'data': {'service_call_id': '139706296896144-39'}, 'origin': 'LOCAL', 'time_fired': datetime.datetime(2018, 2, 20, 14, 36, 10, 70635, tzinfo=<UTC>)}}
home-assistant.log.1:2018-02-20 15:36:10 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 139705092034176: Sending {'id': 291, 'type': 'event', 'event': {'event_type': 'system_log_event', 'data': {'timestamp': 1519137370.0693817, 'level': 'ERROR', 'message': ':0:0 Script error.', 'exception': '', 'source': 'components/system_log/__init__.py'}, 'origin': 'LOCAL', 'time_fired': datetime.datetime(2018, 2, 20, 14, 36, 10, 72365, tzinfo=<UTC>)}}
home-assistant.log.1:2018-02-20 15:36:10 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 139705092524408: Sending {'id': 16, 'type': 'result', 'success': True, 'result': None}
home-assistant.log.1:2018-02-20 15:36:11 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 139705092524408: Received {'type': 'call_service', 'domain': 'system_log', 'service': 'write', 'service_data': {'logger': 'frontend.js.es5.201802090', 'message': ':0:0 Script error.'}, 'id': 17}
home-assistant.log.1:2018-02-20 15:36:11 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 139705092034176: Sending {'id': 291, 'type': 'event', 'event': {'event_type': 'call_service', 'data': {'domain': 'system_log', 'service': 'write', 'service_data': {'logger': 'frontend.js.es5.201802090', 'message': ':0:0 Script error.'}, 'service_call_id': '139706296896144-40'}, 'origin': 'LOCAL', 'time_fired': datetime.datetime(2018, 2, 20, 14, 36, 11, 831017, tzinfo=<UTC>)}}
home-assistant.log.1:2018-02-20 15:36:11 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 139705092034176: Sending {'id': 291, 'type': 'event', 'event': {'event_type': 'service_executed', 'data': {'service_call_id': '139706296896144-40'}, 'origin': 'LOCAL', 'time_fired': datetime.datetime(2018, 2, 20, 14, 36, 11, 833639, tzinfo=<UTC>)}}
home-assistant.log.1:2018-02-20 15:36:11 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 139705092034176: Sending {'id': 291, 'type': 'event', 'event': {'event_type': 'system_log_event', 'data': {'timestamp': 1519137371.8324647, 'level': 'ERROR', 'message': ':0:0 Script error.', 'exception': '', 'source': 'components/system_log/__init__.py'}, 'origin': 'LOCAL', 'time_fired': datetime.datetime(2018, 2, 20, 14, 36, 11, 835418, tzinfo=<UTC>)}}
home-assistant.log.1:2018-02-20 15:36:11 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 139705092524408: Sending {'id': 17, 'type': 'result', 'success': True, 'result': None}

Also, I’m seeing frontend.js.es5.201802090 somewhere in the logs, so I assume it’s using es5 instead of latest?

Sebastian

I resolved this issue by setting javascript_version: latest and clearing my cache in all my devices which have HA open in the browser. Basically just clear your cache and close HA in every browser.

1 Like

Ok, I emptied the cache, closed all open HA browser windows, changed the frontend js version to latest again, restarted HA…
That solved it for the time being. Let’s see if it sticks…

Sebastian

well it seems like if you try and open HA on Safari it seems to cause the problem even though my HA does not open in Safari if I have js version as latest it only works with es5 for Safari.

So I cleared everything up and I am now using Chrome on my mac.

This was occuring for me on all devices but I cleard the cache and it resolved on all but the ios app. This is still occuring on 64.3

This is happeneing again with 70.0

Yes this , i too have the same issue.

This was what fixed it for me. Going to this link and installing the new files from the 20180528. Seemed to be an issue with the CustomUI https://github.com/andrey-git/home-assistant-custom-ui If you have floorplan or any custom UI I would try this

I have this issue also in 0.70.1 when using Chrome on a Mac, not when using Safari.

What do I have to do ?

in 0.71 you should put 28 may version or 2 june version?

I replaced to new custom_ui files from Andrey 2nd june version, all ok now.