- √ - Fehler bei Umstellung auf Smarthome.py 1.0

[Michi26206]

Guten Abend zusammen,

ich habe heute auf Smarthome.py 1.0 umgestellt und bekomme jetzt zyklisch aber in unbestimmten Abständen folgenden Error:

15.11.2013 22:12:22
Connection polling failed: release unlocked lock

Log zeigt:

2013-11-15 21:34:29 INFO     1_wire.IBB_Cape-eval Item 1_wire.IBB_Cape: evaluating value if ((value > (self()*1.03)) or (value < (self()*0.97)) or (value > (self()+0.5)) or (value < (self()-0.5)) or (self.age() > 900)) else None returns None
2013-11-15 21:34:56 ERROR    Main         Connection polling failed: release unlocked lock
Traceback (most recent call last):
  File "/usr/local/smarthome/bin/smarthome.py", line 342, in start
    self.connections.poll()
  File "/usr/local/smarthome/lib/connection.py", line 103, in poll
    server.handle_connection()
  File "/usr/local/smarthome/plugins/visu/__init__.py", line 126, in handle_connection
    self._lock.release()
_thread.error: release unlocked lock

Scheinbar passt was in der Visu nicht? Ist das ein Bug? Wo muss ich zu suchen Anfangen?

Grüße & Danke

[macflei]

Das mit der 1wire-Meldung weiß ich nicht, aber hast Du den Eibd mal neu gestartet?

[callidomus]

Hallo Michi,

hups, da ist was schief gegangen. Ist in master gefixed. Pi Image updaten oder https://github.com/mknx/smarthome/archive/master.zip

Danke für die Meldung.

Bis bald

Marcus

[Michi26206]

den Eibd mal neu gestartet?

ja hatte ich

Hallo Michi,

hups, da ist was schief gegangen.
Danke für die Meldung.

Jetzt funktioniert es wieder :-)
Kein Problem. Gerne wieder. Danke für das schnelle fixen!

[StefanSweden]

KNX Plugin Fehler list index out of range

Hallo,

ich habe gerade ein update durchgeführt (Update System), leider funktioniert die KNX Anbindung immer noch nicht.
Ich nutze das RP Image vom 14.11. Die eibd Busanbindung erfolgt über einen GIRA 2167 (I02). Die ETS habe ich über eine eigene USB Schnittstelle an den Bus gehängt.
Über die ETS kann ich eine knx_listen GA setzen und über knx_reply lesen, Grundsätzlich scheint dann ja das Plugin zu funktionieren. Über die Visu kann ich aber keine GA setzen weder wird der Status angezeigt den ich über die ETS sende. Es scheint mir ein Problem zwischen sh.py und der Visu zu sein. Im debug mode taucht auch ein Fehler auf, hier mal die Ausgabe:

admin@smarthome:/usr/smarthome$ smarthome.py -d
2013-11-16 12:07:38,099 INFO Main Start SmartHome.py 0.9-495-g98dbb4b -- smarthome.py:__init__:231
2013-11-16 12:07:38,104 DEBUG Main Python 3.2.3 -- smarthome.py:__init__:232
2013-11-16 12:07:38,111 INFO Main Init Scheduler -- scheduler.py:__init__:86
2013-11-16 12:07:38,117 INFO Main Init Plugins -- smarthome.py:start:274
2013-11-16 12:07:38,132 DEBUG Scheduler creating 5 workers -- scheduler.py:run:93
2013-11-16 12:07:38,140 DEBUG Main Plugin: knx -- plugin.py:__init__:43
2013-11-16 12:07:38,165 DEBUG Main Plugin: visu -- plugin.py:__init__:43
2013-11-16 12:07:38,255 DEBUG Main Plugin: cli -- plugin.py:__init__:43
2013-11-16 12:07:38,267 DEBUG Main Plugin: sql -- plugin.py:__init__:43
2013-11-16 12:07:38,299 DEBUG Main SQLite 3.7.13 -- __init__.py:__init__:62
2013-11-16 12:07:38,354 DEBUG Main SQLite: database integrity ok -- __init__.py:__init__:78
2013-11-16 12:07:38,380 DEBUG Main SQLite pack next time: 2013-11-17 03:02:00+01:00 -- scheduler.py:_next_time:289
2013-11-16 12:07:38,387 INFO Main Init Items -- smarthome.py:start:280
2013-11-16 12:07:38,461 DEBUG Main Item env.core.memory = 13311677.790402938 via SQLite None None -- item.py:set:457
2013-11-16 12:07:38,487 DEBUG Main Item env.core.threads = 7.0 via SQLite None None -- item.py:set:457
2013-11-16 12:07:38,511 DEBUG Main Item env.core.garbage = 0.0 via SQLite None None -- item.py:set:457
2013-11-16 12:07:38,518 DEBUG Main Item env.core: no type specified. -- item.py:__init__:242
2013-11-16 12:07:38,559 DEBUG Main Item env.location: no type specified. -- item.py:__init__:242
2013-11-16 12:07:38,589 DEBUG Main Item env.system.load = 0.29 via SQLite None None -- item.py:set:457
2013-11-16 12:07:38,599 DEBUG Main Item env.system: no type specified. -- item.py:__init__:242
2013-11-16 12:07:38,604 DEBUG Main Item env: no type specified. -- item.py:__init__:242
2013-11-16 12:07:38,620 DEBUG Main KNX: Light listen on 5/5/12 -- __init__.pyarse_item:235
2013-11-16 12:07:38,625 DEBUG Main KNX: Light reply to 5/5/13 -- __init__.pyarse_item:267
2013-11-16 12:07:38,629 DEBUG Main Item first.hallway: no type specified. -- item.py:__init__:242
2013-11-16 12:07:38,634 DEBUG Main Item first: no type specified. -- item.py:__init__:242
2013-11-16 12:07:38,641 INFO Main Start Plugins -- plugin.py:start:65
2013-11-16 12:07:38,691 INFO Main Start Logics -- logic.py:__init__:33
2013-11-16 12:07:38,695 DEBUG Main Reading Logics from /usr/smarthome/lib/env/logic_conf -- logic.py:_read_logics:64
2013-11-16 12:07:38,718 DEBUG Main Reading Logics from /usr/smarthome/etc/logic.conf -- logic.py:_read_logics:64
2013-11-16 12:07:38,739 CRITICAL Main [Errno 2] No such file or directory: '/usr/smarthome/etc/logic.conf' -- logic.py:_read_logics:71
2013-11-16 12:07:38,779 DEBUG Main Logic: env_init -- logic.py:__init__:44
2013-11-16 12:07:38,879 DEBUG Main Logic: env_stat -- logic.py:__init__:44
2013-11-16 12:07:38,919 DEBUG Main env_stat next time: 2013-11-16 12:07:48+01:00 -- scheduler.py:_next_time:289
2013-11-16 12:07:38,935 DEBUG Main Logic: env_loc -- logic.py:__init__:44
2013-11-16 12:07:38,952 DEBUG Connections KNX: connected to 127.0.0.1:6720 -- connection.py:connect:384
2013-11-16 12:07:38,958 DEBUG Connections KNX: enable group monitor -- __init__.py:handle_connect:117
2013-11-16 12:07:38,965 DEBUG Connections WebSocket: binding to 127.0.0.1:2424 (TCP) -- connection.py:connect:160
2013-11-16 12:07:38,971 DEBUG Connections CLI: binding to 0.0.0.0:2323 (TCP) -- connection.py:connect:160
2013-11-16 12:07:44,336 DEBUG env_loc Item env.location.sunrise = 2013-11-17 07:38:03.060315+01:00 via Logic None None -- item.py:__update:363
2013-11-16 12:07:44,341 DEBUG env_init Item env.core.version = 0.9-495-g98dbb4b via Logic None None -- item.py:__update:363
2013-11-16 12:07:44,342 DEBUG sh.gc Garbage collector: collected 0 objects. -- smarthome.py:_garbage_collection:498
2013-11-16 12:07:44,378 DEBUG env_init Item env.core.start = 2013-11-16 12:07:44.375511+01:00 via Logic None None -- item.py:__update:363
2013-11-16 12:07:44,399 DEBUG env_loc Item env.location.sunset = 2013-11-16 16:29:04.845630+01:00 via Logic None None -- item.py:__update:363
2013-11-16 12:07:44,406 DEBUG env_init Item env.system.name = smarthome.local via Logic None None -- item.py:__update:363
2013-11-16 12:07:44,472 DEBUG env_init Item env.system.start = 2013-11-16 10:39:53.469257+01:00 via Logic None None -- item.py:__update:363
2013-11-16 12:07:44,476 DEBUG env_loc Item env.location.moonrise = 2013-11-16 15:58:03.069074+01:00 via Logic None None -- item.py:__update:363
2013-11-16 12:07:44,559 DEBUG env_loc Item env.location.moonset = 2013-11-17 07:14:38.140459+01:00 via Logic None None -- item.py:__update:363
2013-11-16 12:07:44,605 DEBUG env_loc Item env.location.moonphase = 4 via Logic None None -- item.py:__update:363
2013-11-16 12:07:44,680 DEBUG env_loc Item env.location.day = True via Logic None None -- item.py:__update:363
2013-11-16 12:07:44,685 DEBUG sh.gc Object references: 8886 -- smarthome.py:_maintenance:490
2013-11-16 12:07:44,892 DEBUG Scheduler env_loc next time: 2013-11-16 12:37:44+01:00 -- scheduler.py:_next_time:289
2013-11-16 12:07:44,913 DEBUG Scheduler sh.gc next time: 2013-11-17 02:04:00+01:00 -- scheduler.py:_next_time:289
2013-11-16 12:07:48,496 DEBUG env_stat Item env.core.memory = 13164544 via Logic None None -- item.py:__update:363
2013-11-16 12:07:48,526 DEBUG env_stat Item env.system.load = 0.33 via Logic None None -- item.py:__update:363
2013-11-16 12:07:48,535 DEBUG env_stat Item env.location.moonlight = 99 via Logic None None -- item.py:__update:363
2013-11-16 12:07:48,947 DEBUG Scheduler env_stat next time: 2013-11-16 12:12:48+01:00 -- scheduler.py:_next_time:289
2013-11-16 12:08:01,900 DEBUG Main knx: 5.1.250 set 1/0/5 to cc0802 -- __init__.pyarse_telegram:181
2013-11-16 12:08:02,050 DEBUG Main knx: 5.1.250 set 1/0/6 to 100b0d -- __init__.pyarse_telegram:181
2013-11-16 12:08:21,700 DEBUG Main knx: 1.1.255 set 5/5/10 to 01 -- __init__.pyarse_telegram:181
2013-11-16 12:08:25,249 DEBUG Main knx: 1.1.255 set 5/5/11 to 01 -- __init__.pyarse_telegram:181
2013-11-16 12:08:28,850 DEBUG Main knx: 1.1.255 set 5/5/12 to True -- __init__.pyarse_telegram:190
2013-11-16 12:08:28,856 DEBUG Main Item first.hallway.light = True via KNX 1.1.255 5/5/12 -- item.py:__update:363
2013-11-16 12:08:28,861 ERROR Main Item first.hallway.light: problem running <bound method KNX.update_item of <plugins.knx.KNX object at 0x84ae90>>: list index out of range -- item.py:__update:371
Traceback (most recent call last):
File "/usr/smarthome/lib/item.py", line 369, in __update
method(self, caller, source, dest)
File "/usr/smarthome/plugins/knx/__init__.py", line 327, in update_item
self.groupwrite(ga, item(), item.conf['knx_dpt'])
File "/usr/smarthome/plugins/knx/__init__.py", line 67, in groupwrite
pkt.extend(self.encode(ga, 'ga'))
File "/usr/smarthome/plugins/knx/__init__.py", line 143, in encode
return dpts.encode[str(dpt)](data)
File "/usr/smarthome/plugins/knx/dpts.py", line 308, in enga
return [int(ga[0]) << 3 | int(ga[1]), int(ga[2])]
IndexError: list index out of range
2013-11-16 12:08:32,050 DEBUG Main knx: 1.1.255 set 5/5/13 to 01 -- __init__.pyarse_telegram:181
2013-11-16 12:09:02,050 DEBUG Main knx: 5.1.250 set 1/0/5 to cc0902 -- __init__.pyarse_telegram:181
2013-11-16 12:09:02,201 DEBUG Main knx: 5.1.250 set 1/0/6 to 100b0d -- __init__.pyarse_telegram:181
2013-11-16 12:10:02,201 DEBUG Main knx: 5.1.250 set 1/0/5 to cc0a01 -- __init__.pyarse_telegram:181
2013-11-16 12:10:02,350 DEBUG Main knx: 5.1.250 set 1/0/6 to 100b0d -- __init__.pyarse_telegram:181
2013-11-16 12:11:03,351 DEBUG Main knx: 5.1.250 set 1/0/5 to cc0b02 -- __init__.pyarse_telegram:181
2013-11-16 12:11:03,500 DEBUG Main knx: 5.1.250 set 1/0/6 to 100b0d -- __init__.pyarse_telegram:181
2013-11-16 12:11:23,400 DEBUG Main knx: 1.1.5 set 0/1/4 to 0c44 -- __init__.pyarse_telegram:181
2013-11-16 12:12:03,500 DEBUG Main knx: 5.1.250 set 1/0/5 to cc0c02 -- __init__.pyarse_telegram:181
2013-11-16 12:12:03,650 DEBUG Main knx: 5.1.250 set 1/0/6 to 100b0d -- __init__.pyarse_telegram:181
2013-11-16 12:12:48,175 DEBUG env_stat Item env.core.memory = 13312000 via Logic None None -- item.py:__update:363
2013-11-16 12:12:48,190 DEBUG env_stat Item env.system.load = 0.17 via Logic None None -- item.py:__update:363
2013-11-16 12:12:48,622 DEBUG Scheduler env_stat next time: 2013-11-16 12:17:48+01:00 -- scheduler.py:_next_time:289
2013-11-16 12:13:03,650 DEBUG Main knx: 5.1.250 set 1/0/5 to cc0d02 -- __init__.pyarse_telegram:181
2013-11-16 12:13:03,801 DEBUG Main knx: 5.1.250 set 1/0/6 to 100b0d -- __init__.pyarse_telegram:181
2013-11-16 12:14:03,801 DEBUG Main knx: 5.1.250 set 1/0/5 to cc0e02 -- __init__.pyarse_telegram:181
2013-11-16 12:14:03,951 DEBUG Main knx: 5.1.250 set 1/0/6 to 100b0d -- __init__.pyarse_telegram:181
2013-11-16 12:15:03,101 DEBUG Main knx: 5.1.250 set 1/0/6 to 100b0d -- __init__.pyarse_telegram:181
2013-11-16 12:15:03,951 DEBUG Main knx: 5.1.250 set 1/0/5 to cc0f02 -- __init__.pyarse_telegram:181
2013-11-16 12:16:03,101 DEBUG Main knx: 5.1.250 set 1/0/5 to cc1002 -- __init__.pyarse_telegram:181
2013-11-16 12:16:03,251 DEBUG Main knx: 5.1.250 set 1/0/6 to 100b0d -- __init__.pyarse_telegram:181
2013-11-16 12:16:23,401 DEBUG Main knx: 1.1.5 set 0/1/4 to 0c43 -- __init__.pyarse_telegram:181
2013-11-16 12:17:03,251 DEBUG Main knx: 5.1.250 set 1/0/5 to cc1102 -- __init__.pyarse_telegram:181
2013-11-16 12:17:03,401 DEBUG Main knx: 5.1.250 set 1/0/6 to 100b0d -- __init__.pyarse_telegram:181
2013-11-16 12:17:48,377 DEBUG env_stat Item env.core.memory = 13324288 via Logic None None -- item.py:__update:363
2013-11-16 12:17:48,396 DEBUG env_stat Item env.system.load = 0.09 via Logic None None -- item.py:__update:363
2013-11-16 12:17:48,826 DEBUG Scheduler env_stat next time: 2013-11-16 12:22:48+01:00 -- scheduler.py:_next_time:289
2013-11-16 12:18:03,402 DEBUG Main knx: 5.1.250 set 1/0/5 to cc1202 -- __init__.pyarse_telegram:181
2013-11-16 12:18:03,551 DEBUG Main knx: 5.1.250 set 1/0/6 to 100b0d -- __init__.pyarse_telegram:181


smartvisu.conf
==========
[first]
[[hallway]]
name = Hallway
sv_page = room
sv_img = scene_stairs.png
[[[light]]]
name = Light
type = bool
knx_dpt = 1
knx_send = 5/5/10
knx_status = 5/5/11
knx_listen = 5/5/12
knx_reply = 5/5/13
visu_acl = rw
sv_widget = {{ basic.switch('item', 'item') }}


plugin.conf
========
[knx]
class_name = KNX
class_path = plugins.knx
host = 127.0.0.1
port = 6720
# send_time = 60 # update date/time every 600 seconds, default none
# time_ga = 2/20/1 # default none
# date_ga = 2/20/2 # default none

[visu]
class_name = WebSocket
class_path = plugins.visu
ip = '127.0.0.1'
port = 2424
acl = rw
smartvisu_dir = /var/www/smartvisu


Hat einer eine Idee was hier schief läuft?

Danke
Stefan

[callidomus]

Hallo Stefan,

Ich nutze das RP Image vom 14.11.
....

2013-11-16 12:07:38,099 INFO Main Start SmartHome.py 0.9-495-g98dbb4b -- smarthome.py:__init__:231

irgendwas passt nicht zusammen. Das Image hatte die Version 1.0
Ich habe vorhin noch mal ein neues Image hochgestellt das den einen wichtigen Fehler korrigiert hat.

Bitte lade es herunter und teste es damit. Wenn etwas nicht klappt, mach bitte einen neuen Thread auf.

Bis bald

Marcus

[Orion]

Hallo Marcus,

ist mir gerade auch aufgefallen. ich hatte das Image installiert gestern und habe im Log stehen:

Init SmartHome.py 0.9-495-g98dbb4b

Ich hab jetzt auch nochmal ein Update gemacht mit den Bordmitteln (smartcontrol) und bekomme:

Updating system...
Please wait..............
[OK]
Updating SmartHome.py

HEAD is now at 98dbb4b adding cache to example config
Already up-to-date.
Restarting SmartHome.py smarthome.py

Michel

[StefanSweden]

Neues Image, nun gehts :-)

Hallo Stefan,

Bitte lade es herunter und teste es damit. Wenn etwas nicht klappt, mach bitte einen neuen Thread auf.

All klar, das wars :-) Das update über die Website zeitgt zwar up-todate, ein manuelles ausführen über die Shell brachte dann die Version 1.0. Ich habe jetzt das Image von heute auf dem Pi und nun kann ich von der Visu GA auf dem Bus setzen.

Super Job! Danke :-)