Domoticz

Open source Home Automation System
https://forum.domoticz.com/

MutexImpl::Unlock error 0 (1)

https://forum.domoticz.com/viewtopic.php?t=11445

Page 1 of 1

MutexImpl::Unlock error 0 (1)

Posted: Wednesday 06 April 2016 23:05
by Egregius
Hi all,

since severall days domoticz crashes on my Syno.
I'm running 3.5031, don't know for sure that the latest update caused this.
Because the logfile is cleared upon start of Domoticz I had to create a script to copy it to another location so I could look why the crash appears.
After each crash the logfile ends with this line:

Code: Select all

MutexImpl::Unlock error 0 (1)
I guess the only change I made was including a Fibaro Wall plug for some power measurement of a new device at home.

Another strange thing I notice is that each line that cames from Openzwave starts with strange brackets:

Code: Select all

[39m[39m[39m[39m[39m[39m[39m[39m[39m[32m2016-04-05 11:27:24.908 Always,

Last 10 minutes of the log:

Code: Select all

2016-04-05 11:25:00.277  (ZWAVE) Usage (wasbakkookplaat2)
2016-04-05 11:25:00.278  (ZWAVE) General/kWh (Kwasbakkookplaat)
2016-04-05 11:25:02.802  (ZWAVE) General/kWh (Kwasbakkookplaat)
2016-04-05 11:25:02.866  (ZWAVE) Usage (wasbakkookplaat2)
2016-04-05 11:25:02.867  (ZWAVE) General/kWh (Kwasbakkookplaat)
2016-04-05 11:25:02.968  (ZWAVE) General/kWh (kWh Meter)
2016-04-05 11:25:03.060  (ZWAVE) Usage (wasbak2)
2016-04-05 11:25:03.061  (ZWAVE) General/kWh (kWh Meter)
2016-04-05 11:25:03.161  (ZWAVE) General/kWh (kWh Meter)
2016-04-05 11:25:03.254  (ZWAVE) Usage (kookplaat2)
2016-04-05 11:25:03.264  (ZWAVE) General/kWh (kWh Meter)
2016-04-05 11:25:06.310  (ZWAVE) Thermostat (kamerZ)
2016-04-05 11:25:09.471  (ZWAVE) Usage (garageterras)
2016-04-05 11:25:09.473  (ZWAVE) General/kWh (Kgarageterras)
2016-04-05 11:25:10.180  (ZWAVE) Thermostat (alexZ)
2016-04-05 11:25:20.983  (ZWAVE) Thermostat (livingZ)
2016-04-05 11:25:26.373  (RFXCOM) Lighting 2 (pirlivingR)
2016-04-05 11:25:26.940  Executing script: /volume1/web/secure/cron.sh
OPT =
2016-04-05 11:25:29.568  (ZWAVE) Usage (werkbladtuin2)
2016-04-05 11:25:29.569  (ZWAVE) General/kWh (Kwerkbladtuin)
2016-04-05 11:25:29.909  (ZWAVE) Light/Switch (pirliving)
2016-04-05 11:25:29.954  Executing script: /volume1/web/secure/cron.sh
OPT =
2016-04-05 11:25:33.844  (ZWAVE) Light/Switch (pirkeuken)
2016-04-05 11:25:33.967  Executing script: /volume1/web/secure/cron.sh
OPT =
2016-04-05 11:26:04.925  (ZWAVE) Usage (hallzolder)
2016-04-05 11:26:04.927  (ZWAVE) General/kWh (kWh Meter)
2016-04-05 11:26:34.246  (ZWAVE) Light/Switch (pirkeuken)
2016-04-05 11:26:39.445  (ZWAVE) Thermostat (badkamerZ)
2016-04-05 11:27:02.495  User: Admin initiated a switch command
2016-04-05 11:27:02.495  (RFXCOM) Lighting 2 (pirlivingR)
2016-04-05 11:27:03.724  (ZWAVE) General/kWh (Kinkomvoordeur)
2016-04-05 11:27:03.788  (ZWAVE) Usage (inkomvoordeur2)
2016-04-05 11:27:03.789  (ZWAVE) General/kWh (Kinkomvoordeur)
2016-04-05 11:27:03.889  (ZWAVE) General/kWh (kWh Meter)
2016-04-05 11:27:03.982  (ZWAVE) Usage (inkom2)
2016-04-05 11:27:03.983  (ZWAVE) General/kWh (kWh Meter)
2016-04-05 11:27:04.083  (ZWAVE) General/kWh (kWh Meter)
2016-04-05 11:27:04.175  (ZWAVE) Usage (voordeur2)
2016-04-05 11:27:04.176  (ZWAVE) General/kWh (kWh Meter)
2016-04-05 11:27:04.815  (ZWAVE) General/kWh (kWh Meter)
2016-04-05 11:27:04.905  (ZWAVE) Usage (alex Power Meter)
2016-04-05 11:27:04.907  (ZWAVE) General/kWh (kWh Meter)
2016-04-05 11:27:13.201  (ZWAVE) Light/Switch (pirkeuken)
2016-04-05 11:27:13.354  (ZWAVE) Light/Switch (pirgarage)
2016-04-05 11:27:14.087  Executing script: /volume1/web/secure/cron.sh
OPT =
[31m2016-04-05 11:27:14.905 Error, Node089, ERROR: Dropping command, expected response not received after 1 attempt(s)
[39m[32m2016-04-05 11:27:14.905 Always, 
[39m[32m2016-04-05 11:27:14.905 Always, Dumping queued log messages
[39m[32m2016-04-05 11:27:14.905 Always, 
[39m[39m[39m[39m[39m[39m[39m[39m[39m[32m2016-04-05 11:27:14.905 Always, 
[39m[32m2016-04-05 11:27:14.906 Always, End of queued log message dump
[39m[32m2016-04-05 11:27:14.906 Always, 
[39m2016-04-05 11:27:14.906  OpenZWave: Received timeout notification from HomeID: 25487143, NodeID: 89 (0x59)
2016-04-05 11:27:16.101  Executing script: /volume1/web/secure/cron.sh
OPT =
[31m2016-04-05 11:27:24.908 Error, Node089, ERROR: Dropping command, expected response not received after 1 attempt(s)
[39m[32m2016-04-05 11:27:24.908 Always, 
[39m[32m2016-04-05 11:27:24.908 Always, Dumping queued log messages
[39m[32m2016-04-05 11:27:24.908 Always, 
[39m[39m[39m[39m[39m[39m[39m[39m[39m[32m2016-04-05 11:27:24.908 Always, 
[39m[32m2016-04-05 11:27:24.909 Always, End of queued log message dump
[39m[32m2016-04-05 11:27:24.909 Always, 
[39m2016-04-05 11:27:24.909  OpenZWave: Received timeout notification from HomeID: 25487143, NodeID: 89 (0x59)
2016-04-05 11:27:40.679  (ZWAVE) Thermostat (livingZZ)
2016-04-05 11:27:41.674  (ZWAVE) Thermostat (livingZE)
2016-04-05 11:28:03.280  (ZWAVE) Thermostat (kamerZ)
2016-04-05 11:28:05.205  (ZWAVE) Thermostat (alexZ)
2016-04-05 11:28:05.579  (ZWAVE) Temp (alex)
2016-04-05 11:28:10.080  (ZWAVE) Light/Switch (pirliving)
2016-04-05 11:28:14.081  (ZWAVE) Light/Switch (pirkeuken)
2016-04-05 11:28:17.083  (ZWAVE) Thermostat (livingZ)
2016-04-05 11:28:18.438  (RFXCOM) Lighting 2 (pirlivingR)
2016-04-05 11:28:31.964  (ZWAVE) Light/Switch (pirgarage)
2016-04-05 11:28:33.261  (RFXCOM) Lighting 2 (poort)
2016-04-05 11:28:33.805  (RFXCOM) Lighting 2 (poort)
2016-04-05 11:28:34.194  Executing script: /volume1/web/secure/cron.sh
OPT =
2016-04-05 11:28:36.207  Executing script: /volume1/web/secure/cron.sh
OPT =
2016-04-05 11:28:36.346  (ZWAVE) Light/Switch (pirgarage)
2016-04-05 11:28:39.219  Executing script: /volume1/web/secure/cron.sh
OPT =
2016-04-05 11:28:44.991  (ZWAVE) Usage (lichtbadkamerB)
2016-04-05 11:28:44.992  (ZWAVE) General/kWh (Klichtbadkamer)
2016-04-05 11:29:03.600  (ZWAVE) General/kWh (kWh Meter)
2016-04-05 11:29:03.719  (ZWAVE) Usage (hallzolder)
2016-04-05 11:29:03.720  (ZWAVE) General/kWh (kWh Meter)
2016-04-05 11:29:03.874  (ZWAVE) General/kWh (kWh Meter)
2016-04-05 11:29:04.022  (ZWAVE) Usage (hall)
2016-04-05 11:29:04.024  (ZWAVE) General/kWh (kWh Meter)
2016-04-05 11:29:04.179  (ZWAVE) General/kWh (kWh Meter)
2016-04-05 11:29:04.327  (ZWAVE) Usage (zolder)
2016-04-05 11:29:04.338  (ZWAVE) General/kWh (kWh Meter)
2016-04-05 11:29:06.269  Sending SetPoint to device....
2016-04-05 11:29:28.963  (ZWAVE) Usage (inkomvoordeur2)
2016-04-05 11:29:28.964  (ZWAVE) General/kWh (Kinkomvoordeur)
2016-04-05 11:29:33.458  (ZWAVE) Thermostat (badkamerZ)
2016-04-05 11:29:37.108  (ZWAVE) Light/Switch (pirgarage)
2016-04-05 11:29:48.444  (ZWAVE) Light/Switch (pirgarage)
2016-04-05 11:29:49.309  Executing script: /volume1/web/secure/cron.sh
OPT =
2016-04-05 11:29:53.134  (ZWAVE) Temp (badkamer)
2016-04-05 11:29:57.976  (ZWAVE) Usage (keukenzolderg2)
2016-04-05 11:29:57.977  (ZWAVE) General/kWh (Kkeukenzolderg)
2016-04-05 11:29:58.627  (RFXCOM) Lighting 2 (poort)
2016-04-05 11:30:01.930  (ZWAVE) Usage (wasbakkookplaat2)
2016-04-05 11:30:01.931  (ZWAVE) General/kWh (Kwasbakkookplaat)
2016-04-05 11:30:02.934  (ZWAVE) General/kWh (Kkeukenzolderg)
2016-04-05 11:30:02.998  (ZWAVE) Usage (keukenzolderg2)
2016-04-05 11:30:02.999  (ZWAVE) General/kWh (Kkeukenzolderg)
2016-04-05 11:30:03.099  (ZWAVE) General/kWh (kWh Meter)
2016-04-05 11:30:03.192  (ZWAVE) Usage (keuken2)
2016-04-05 11:30:03.193  (ZWAVE) General/kWh (kWh Meter)
2016-04-05 11:30:03.292  (ZWAVE) General/kWh (kWh Meter)
2016-04-05 11:30:03.385  (ZWAVE) Usage (zolderg2)
2016-04-05 11:30:03.386  (ZWAVE) General/kWh (kWh Meter)
2016-04-05 11:30:03.855  (ZWAVE) General/kWh (Kwerkbladtuin)
2016-04-05 11:30:03.919  (ZWAVE) Usage (werkbladtuin2)
2016-04-05 11:30:03.921  (ZWAVE) General/kWh (Kwerkbladtuin)
2016-04-05 11:30:04.021  (ZWAVE) General/kWh (kWh Meter)
2016-04-05 11:30:04.113  (ZWAVE) Usage (werkblad2)
2016-04-05 11:30:04.114  (ZWAVE) General/kWh (kWh Meter)
2016-04-05 11:30:04.214  (ZWAVE) General/kWh (Ktuin)
2016-04-05 11:30:04.307  (ZWAVE) Usage (tuin2)
2016-04-05 11:30:04.319  (ZWAVE) General/kWh (Ktuin)
2016-04-05 11:30:10.984  (ZWAVE) Usage (garageterras)
2016-04-05 11:30:10.985  (ZWAVE) General/kWh (Kgarageterras)
2016-04-05 11:30:30.410  (ZWAVE) Usage (werkbladtuin2)
2016-04-05 11:30:30.412  (ZWAVE) General/kWh (Kwerkbladtuin)
2016-04-05 11:30:37.900  (ZWAVE) Thermostat (livingZZ)
2016-04-05 11:30:38.785  (ZWAVE) Thermostat (livingZE)
2016-04-05 11:30:49.257  (ZWAVE) Light/Switch (pirgarage)
2016-04-05 11:31:00.233  (ZWAVE) Thermostat (alexZ)
2016-04-05 11:31:00.283  (ZWAVE) Thermostat (kamerZ)
2016-04-05 11:31:00.572  (ZWAVE) Thermostat (alexZ)
2016-04-05 11:31:03.111  (ZWAVE) General/kWh (Klichtbadkamer)
2016-04-05 11:31:03.174  (ZWAVE) Usage (lichtbadkamerB)
2016-04-05 11:31:03.176  (ZWAVE) General/kWh (Klichtbadkamer)
2016-04-05 11:31:03.276  (ZWAVE) General/kWh (kWh Meter)
2016-04-05 11:31:03.368  (ZWAVE) Usage (lichtbadkamer1B)
2016-04-05 11:31:03.369  (ZWAVE) General/kWh (kWh Meter)
2016-04-05 11:31:03.469  (ZWAVE) General/kWh (kWh Meter)
2016-04-05 11:31:03.561  (ZWAVE) Usage (lichtbadkamer2B)
2016-04-05 11:31:03.563  (ZWAVE) General/kWh (kWh Meter)
2016-04-05 11:31:04.029  (ZWAVE) General/kWh (kWh Meter)
2016-04-05 11:31:04.197  (ZWAVE) Usage (zoldertrap2)
2016-04-05 11:31:04.208  (ZWAVE) General/kWh (kWh Meter)
2016-04-05 11:31:06.489  (ZWAVE) Usage (hallzolder)
2016-04-05 11:31:06.490  (ZWAVE) General/kWh (kWh Meter)
2016-04-05 11:31:13.187  (ZWAVE) Thermostat (livingZ)
2016-04-05 11:32:03.357  (ZWAVE) General/kWh (kWh Meter)
2016-04-05 11:32:03.421  (ZWAVE) Usage (zithoek)
2016-04-05 11:32:03.431  (ZWAVE) General/kWh (kWh Meter)
2016-04-05 11:32:27.497  (ZWAVE) Thermostat (badkamerZ)
2016-04-05 11:33:02.982  (ZWAVE) General/kWh (Kmedia_unused)
2016-04-05 11:33:03.016  (ZWAVE) Usage (media_unused)
2016-04-05 11:33:03.017  (ZWAVE) General/kWh (Kmedia_unused)
2016-04-05 11:33:03.052  (ZWAVE) General/kWh (Ktv)
2016-04-05 11:33:03.088  (ZWAVE) Usage (tv2)
2016-04-05 11:33:03.090  (ZWAVE) General/kWh (Ktv)
2016-04-05 11:33:03.124  (ZWAVE) General/kWh (kWh Meter)
2016-04-05 11:33:03.161  (ZWAVE) Usage (kristal2)
2016-04-05 11:33:03.162  (ZWAVE) General/kWh (kWh Meter)
2016-04-05 11:33:03.197  (ZWAVE) General/kWh (kWh Meter)
2016-04-05 11:33:03.239  (ZWAVE) Usage (tvled2)
2016-04-05 11:33:03.240  (ZWAVE) General/kWh (kWh Meter)
2016-04-05 11:33:03.275  (ZWAVE) General/kWh (Ksubwoofer)
2016-04-05 11:33:03.311  (ZWAVE) Usage (subwoofer2)
2016-04-05 11:33:03.312  (ZWAVE) General/kWh (Ksubwoofer)
2016-04-05 11:33:03.347  (ZWAVE) General/kWh (kWh Meter)
2016-04-05 11:33:03.383  (ZWAVE) Usage (kodi2)
2016-04-05 11:33:03.384  (ZWAVE) General/kWh (kWh Meter)
2016-04-05 11:33:03.419  (ZWAVE) General/kWh (kWh Meter)
2016-04-05 11:33:03.455  (ZWAVE) Usage (bureel)
2016-04-05 11:33:03.456  (ZWAVE) General/kWh (kWh Meter)
2016-04-05 11:33:03.931  (ZWAVE) General/kWh (kWh Meter)
2016-04-05 11:33:03.995  (ZWAVE) Usage (tobi2)
2016-04-05 11:33:03.997  (ZWAVE) General/kWh (kWh Meter)
2016-04-05 11:33:04.448  (ZWAVE) General/Thermostat Mode (brander)
2016-04-05 11:33:35.120  (ZWAVE) Thermostat (livingZZ)
2016-04-05 11:33:35.997  (ZWAVE) Thermostat (livingZE)
2016-04-05 11:33:45.741  (ZWAVE) Usage (lichtbadkamerB)
2016-04-05 11:33:45.743  (ZWAVE) General/kWh (Klichtbadkamer)
2016-04-05 11:33:55.249  (ZWAVE) Thermostat (alexZ)
2016-04-05 11:33:57.272  (ZWAVE) Thermostat (kamerZ)
2016-04-05 11:34:09.454  (ZWAVE) Thermostat (livingZ)
2016-04-05 11:34:30.060  (ZWAVE) Usage (inkomvoordeur2)
2016-04-05 11:34:30.061  (ZWAVE) General/kWh (Kinkomvoordeur)
2016-04-05 11:34:59.358  (ZWAVE) Usage (keukenzolderg2)
2016-04-05 11:34:59.359  (ZWAVE) General/kWh (Kkeukenzolderg)
2016-04-05 11:35:03.212  (ZWAVE) General/kWh (Kgarageterras)
2016-04-05 11:35:03.275  (ZWAVE) Usage (garageterras)
2016-04-05 11:35:03.276  (ZWAVE) General/kWh (Kgarageterras)
2016-04-05 11:35:03.377  (ZWAVE) General/kWh (kWh Meter)
2016-04-05 11:35:03.469  (ZWAVE) Usage (garage2)
2016-04-05 11:35:03.470  (ZWAVE) General/kWh (kWh Meter)
2016-04-05 11:35:03.570  (ZWAVE) General/kWh (kWh Meter)
2016-04-05 11:35:03.609  (ZWAVE) Usage (wasbakkookplaat2)
2016-04-05 11:35:03.611  (ZWAVE) General/kWh (Kwasbakkookplaat)
2016-04-05 11:35:03.663  (ZWAVE) Usage (terras2)
2016-04-05 11:35:03.673  (ZWAVE) General/kWh (kWh Meter)
2016-04-05 11:35:12.667  (ZWAVE) Usage (garageterras)
2016-04-05 11:35:12.668  (ZWAVE) General/kWh (Kgarageterras)
2016-04-05 11:35:21.488  (ZWAVE) Thermostat (badkamerZ)
2016-04-05 11:35:30.875  (ZWAVE) Usage (werkbladtuin2)
2016-04-05 11:35:30.876  (ZWAVE) General/kWh (Kwerkbladtuin)
MutexImpl::Unlock error 0 (1)
For what I can find the Mutex is used so some things don't happen simultaneously, but then, what can cause the unlock error?

Re: MutexImpl::Unlock error 0 (1)

Posted: Tuesday 12 April 2016 13:36
by Egregius
In the mean time had several beta versions, now on 3.5052
Stops still accur randomly. Luckely not to often, like 1 or 2 times a day.

Re: MutexImpl::Unlock error 0 (1)

Posted: Tuesday 03 May 2016 18:21
by Egregius
On version V3.5072 with Aeon firmware 3.08 Domoticz has stayed online for 12 days.
Yesterday updated to V3.5096 and guess what? The Mutex error and crash is there again.
Will see if it happens again, if so I'll downgrade to 3.5072 and stay there for a while.

Re: MutexImpl::Unlock error 0 (1)

Posted: Tuesday 03 May 2016 18:36
by gizmocuz
I can not find any log message in domoticz with a text like 'MutexImpl'
If you find something, please post a fix/issue on github, found this thread by accident
best to report code related issues on github

Re: MutexImpl::Unlock error 0 (1)

Posted: Wednesday 04 May 2016 15:14
by Egregius
I can't say much about it. Some weeks ago Domoticz crashed daily on my Syno. For that reason I created a script that copies the logfile and restarts Domoticz automatically.
In each copy of the logfile the last line is "MutexImpl::Unlock error 0 (1)" without a timestamp in front of it.
Now it's online for 29 hours, don't know for how long, don't know why it crashed. I don't see a pattern in it.
All times are UTC+01:00
Page 1 of 1

Powered by phpBB® Forum Software © phpBB Limited