Modul NETIO230 / 553 INVALID LOGIN

Begonnen von michaelapp, 14 Juni 2016, 15:50:54

Vorheriges Thema - Nächstes Thema
Drucken
Nach unten Seiten1
Benutzer-Aktionen

michaelapp

14 Juni 2016, 15:50:54
Hallo zusammen,

ich steuere meine NETIO230B mit dem Modul NETIO230

Code Auswählen
define Socket NETIO230 192.168.178.220 s:1 i:300 h:80 t:1234 u:admin p:Nadine1507

Das schalten funktioniert auch ohne Probleme, ich erhalte in

Code Auswählen
2016.06.14 15:44:25 3: Socket1: set Socket1 on: OK
2016.06.14 15:44:25 2: Socket1: ERROR: [553 INVALID LOGIN] => Http login failed. Wrong Credentials or simultaneously secure logins from multiple clients.

Hat mir jemand einen Tip?

Gruß
Michael

dev0

#1
14 Juni 2016, 17:11:45
Bin zwar diese Woche unterwegs, aber Du kannst schon mal ein Log mit verbose 5 hier anhängen, das den Fehler zeigt.
Wie die Meldung aber auch schon sagt, kann es durch ein gleichzeitiges Anmelden von mehreren Clients auftreten, wenn SecureLogin aktiviert ist oder Du benutzt unterschiedliche Usernamen für einzelne Sockets.

michaelapp

#2
14 Juni 2016, 22:53:35
Hallo hier mal das Log was er alles geschrieben hat

Code Auswählen
2016.06.14 22:50:52 4: Connection closed for WEB_192.168.178.68_64023: Die Verbindung wurde vom Kommunikationspartner zurückgesetzt
2016.06.14 22:50:52 4: WEB_192.168.178.68_64040 GET /fhem?XHR=1&inform=type=status;filter=room=CUL%5fHM;since=1465937451;fmt=JSON&fw_id=62×tamp=1465937452335; BUFLEN:0
2016.06.14 22:50:55 5: Socket3: NETIO230_Set() got: hash:HASH(0x259f668), name:Socket3, cmd:statusRequest, params:
2016.06.14 22:50:55 5: Socket3: NETIO230_modifyUserInput() cmd:'statusRequest' orgVal:'' newVal:''
2016.06.14 22:50:55 5: Socket3: NETIO230_setStatusRequest() got: hash:HASH(0x259f668)
2016.06.14 22:50:55 5: Triggering Socket3 (1 changes)
2016.06.14 22:50:55 5: Starting notify loop for Socket3, first event _lastNotice: set Socket3 statusRequest
2016.06.14 22:50:55 3: Socket3: set Socket3 statusRequest
2016.06.14 22:50:55 5: Socket3: NETIO230_resetTimer() call: RemoveInternalTimer(HASH(0x259f668))
2016.06.14 22:50:55 5: Socket3: NETIO230_resetTimer() InternalTimer() deleted
2016.06.14 22:50:55 5: Socket3: NETIO230_httpRequest() got: cmd:get, arg:
2016.06.14 22:50:55 5: Socket3: NETIO230_md5token() <"521BEE8F" >"5ec890179a528da12c507818f9761424"
2016.06.14 22:50:55 5: Socket3: NETIO230_httpRequest() url: http://192.168.178.220:80/tgi/control.tgi?login=c:admin:5ec890179a528da12c507818f9761424&port=list
2016.06.14 22:50:55 5: Socket3: NETIO230_httpRequest() call: HttpUtils_NonblockingGet(HASH(0x2394818))
2016.06.14 22:50:55 4: HttpUtils url=http://192.168.178.220:80/tgi/control.tgi?login=c:admin:5ec890179a528da12c507818f9761424&port=list
2016.06.14 22:50:55 5: Socket3: NETIO230_telnetRequest() got: statusRequest
2016.06.14 22:50:55 5: Socket3: NETIO230_telnetRequest() call: BlockingCall("NETIO230_doTelnetRequest", Socket3|192.168.178.220|statusRequest)
2016.06.14 22:50:55 4: BlockingCall (NETIO230_doTelnetRequest): created child (2314), uses telnetPort to connect back
2016.06.14 22:50:55 5: Socket3: NETIO230_telnetRequest() running PID: HASH(0x2570390)
2016.06.14 22:50:55 5: Socket3: NETIO230_doTelnetRequest() got: Socket3|192.168.178.220|statusRequest
2016.06.14 22:50:55 5: Socket3: NETIO230_doTelnetRequest() call: NETIO230_doTelnet(HASH(0x259f668),Net::Telnet=GLOB(0x2572ad0),"open")
2016.06.14 22:50:55 5: Socket3: NETIO230_doTelnet() got: hash:HASH(0x259f668), telnet:Net::Telnet=GLOB(0x2572ad0), what:open, cmd:
2016.06.14 22:50:55 4: http://192.168.178.220:80/tgi/control.tgi?login=c:admin:5ec890179a528da12c507818f9761424&port=list: HTTP response code 200
2016.06.14 22:50:55 4: HttpUtils http://192.168.178.220:80/tgi/control.tgi?login=c:admin:5ec890179a528da12c507818f9761424&port=list: Got data, length: 30
2016.06.14 22:50:55 5: Socket3: NETIO230_httpRequestParse() got: cmd:get, arg:, err:, data:<html>553 INVALID LOGIN</html>
2016.06.14 22:50:55 5: Socket3: NETIO230_httpRequestParse() -> data ne ""
2016.06.14 22:50:55 5: Socket3: NETIO230_httpRequest() got: cmd:get, arg:
2016.06.14 22:50:55 5: Socket3: NETIO230_doTelnet() -> login
2016.06.14 22:50:55 4: Socket3: NOTICE: [request pwdhash] => Request new password hash from device.
2016.06.14 22:50:55 5: Socket3: NETIO230_md5token() <"4930289F" >"de3dad938019a252b2759e952241efa1"
2016.06.14 22:50:55 5: Socket3: NETIO230_httpRequest() url: http://192.168.178.220:80/tgi/control.tgi?hash=hash
2016.06.14 22:50:55 5: Socket3: NETIO230_httpRequest() call: HttpUtils_NonblockingGet(HASH(0x2572b48))
2016.06.14 22:50:55 4: HttpUtils url=http://192.168.178.220:80/tgi/control.tgi?hash=hash
2016.06.14 22:50:55 5: Socket3: NETIO230_doTelnet() return: OK
2016.06.14 22:50:55 5: Socket3: NETIO230_doTelnetRequest() call: NETIO230_doTelnet(HASH(0x259f668),Net::Telnet=GLOB(0x2572ad0),"statusRequest")
2016.06.14 22:50:55 5: Socket3: NETIO230_doTelnet() got: hash:HASH(0x259f668), telnet:Net::Telnet=GLOB(0x2572ad0), what:statusRequest, cmd:
2016.06.14 22:50:55 5: Socket3: NETIO230_doTelnet() call: Net::Telnet=GLOB(0x2572ad0)->print(alias)
2016.06.14 22:50:55 4: http://192.168.178.220:80/tgi/control.tgi?hash=hash: HTTP response code 200
2016.06.14 22:50:55 4: HttpUtils http://192.168.178.220:80/tgi/control.tgi?hash=hash: Got data, length: 21
2016.06.14 22:50:55 5: Socket3: NETIO230_httpRequestParse() got: cmd:hash, arg:, err:, data:<html>C5604A90</html>
2016.06.14 22:50:55 5: Socket3: NETIO230_doTelnet() >item:alias line:"NETIO-230B"
2016.06.14 22:50:55 5: Socket3: NETIO230_httpRequestParse() -> data ne ""
2016.06.14 22:50:55 5: Socket3: NETIO230_doTelnet() call: Net::Telnet=GLOB(0x2572ad0)->print(email server)
2016.06.14 22:50:55 5: Socket3: NETIO230_httpRequestParse() -> hash
2016.06.14 22:50:55 5: Socket3: NETIO230_httpRequestParse() hash: "C5604A90"
2016.06.14 22:50:55 4: Socket3: NOTICE: [got pwdhash] => Login with new token.
2016.06.14 22:50:55 5: Socket3: NETIO230_httpRequest() got: cmd:get, arg:
2016.06.14 22:50:55 5: Socket3: NETIO230_md5token() <"C5604A90" >"669634babcb846929d89a1df14fba5fd"
2016.06.14 22:50:55 5: Socket3: NETIO230_httpRequest() url: http://192.168.178.220:80/tgi/control.tgi?login=c:admin:669634babcb846929d89a1df14fba5fd&port=list
2016.06.14 22:50:55 5: Socket3: NETIO230_httpRequest() call: HttpUtils_NonblockingGet(HASH(0x25722f0))
2016.06.14 22:50:55 4: HttpUtils url=http://192.168.178.220:80/tgi/control.tgi?login=c:admin:669634babcb846929d89a1df14fba5fd&port=list
2016.06.14 22:50:55 5: Socket3: NETIO230_doTelnet() >item:email_server line:smtp.1blu.de
2016.06.14 22:50:55 5: Socket3: NETIO230_doTelnet() call: Net::Telnet=GLOB(0x2572ad0)->print(port setup 3)
2016.06.14 22:50:55 5: Socket3: NETIO230_doTelnet() >item:port_setup_3 line:"Rechner" manual 5 0
2016.06.14 22:50:55 5: Socket3: NETIO230_doTelnet() call: Net::Telnet=GLOB(0x2572ad0)->print(port timer 3 dt)
2016.06.14 22:50:55 4: http://192.168.178.220:80/tgi/control.tgi?login=c:admin:669634babcb846929d89a1df14fba5fd&port=list: HTTP response code 200
2016.06.14 22:50:55 4: HttpUtils http://192.168.178.220:80/tgi/control.tgi?login=c:admin:669634babcb846929d89a1df14fba5fd&port=list: Got data, length: 21
2016.06.14 22:50:55 5: Socket3: NETIO230_httpRequestParse() got: cmd:get, arg:, err:, data:<html>0 1 1 0 </html>
2016.06.14 22:50:55 5: Socket3: NETIO230_httpRequestParse() -> data ne ""
2016.06.14 22:50:55 5: Socket3: NETIO230_httpRequestParse() -> get
2016.06.14 22:50:55 5: Socket3: NETIO230_modifyReadings() reading:'socket' orgVal:'1' newVal:'on'
2016.06.14 22:50:55 5: Triggering Socket3 (2 changes)
2016.06.14 22:50:55 5: Starting notify loop for Socket3, first event socket3: on
2016.06.14 22:50:55 5: Socket3: NETIO230_doTelnet() >item:port_timer_3_dt line:once 1970/01/01,00:00:00 1970/01/01,00:00:00 0000000
2016.06.14 22:50:55 5: Socket3: NETIO230_doTelnet() call: Net::Telnet=GLOB(0x2572ad0)->print(port wd 3)
2016.06.14 22:50:55 5: Socket3: NETIO230_doTelnet() >item:port_wd_3 line:disable 0.0.0.0 9 60 3 3 disable disable
2016.06.14 22:50:55 5: Socket3: NETIO230_doTelnet() call: Net::Telnet=GLOB(0x2572ad0)->print(system discover)
2016.06.14 22:50:55 5: Socket3: NETIO230_doTelnet() >item:system_discover line:enable
2016.06.14 22:50:55 5: Socket3: NETIO230_doTelnet() call: Net::Telnet=GLOB(0x2572ad0)->print(system dns)
2016.06.14 22:50:55 4: Socket3: get Socket3 state: on
2016.06.14 22:50:55 5: Socket3: NETIO230_httpRequestParse() PWDHASH:C5604A90
2016.06.14 22:50:55 5: Socket3: NETIO230_httpRequestParse() call: NETIO230_resetTimer(HASH(0x259f668),"start")
2016.06.14 22:50:55 5: Socket3: NETIO230_doTelnet() >item:system_dns line:192.168.178.1
2016.06.14 22:50:55 5: Socket3: NETIO230_resetTimer() call: RemoveInternalTimer(HASH(0x259f668))
2016.06.14 22:50:55 5: Socket3: NETIO230_doTelnet() call: Net::Telnet=GLOB(0x2572ad0)->print(system dst)
2016.06.14 22:50:55 5: Socket3: NETIO230_resetTimer() InternalTimer(+301.704919465986,"NETIO230_timedStatusRequest", $hash, 0)
2016.06.14 22:50:55 5: Socket3: NETIO230_doTelnet() >item:system_dst line:enabled 2012/03/25,01:00:00 - 2012/10/28,03:00:00
2016.06.14 22:50:55 5: Socket3: NETIO230_doTelnet() call: Net::Telnet=GLOB(0x2572ad0)->print(system eth)
2016.06.14 22:50:55 5: Socket3: NETIO230_doTelnet() >item:system_eth line:manual 192.168.178.220 255.255.255.0 192.168.178.1
2016.06.14 22:50:55 5: Socket3: NETIO230_doTelnet() call: Net::Telnet=GLOB(0x2572ad0)->print(system sntp)
2016.06.14 22:50:55 5: Socket3: NETIO230_doTelnet() >item:system_sntp line:enable pool.ntp.org synchronized
2016.06.14 22:50:55 5: Socket3: NETIO230_doTelnet() call: Net::Telnet=GLOB(0x2572ad0)->print(system swdelay)
2016.06.14 22:50:55 5: Socket3: NETIO230_doTelnet() >item:system_swdelay line:2
2016.06.14 22:50:55 5: Socket3: NETIO230_doTelnet() call: Net::Telnet=GLOB(0x2572ad0)->print(system time)
2016.06.14 22:50:55 5: Socket3: NETIO230_doTelnet() >item:system_time line:2016/06/14,21:50:54
2016.06.14 22:50:55 5: Socket3: NETIO230_doTelnet() call: Net::Telnet=GLOB(0x2572ad0)->print(system timezone)
2016.06.14 22:50:55 5: Socket3: NETIO230_doTelnet() >item:system_timezone line:3600
2016.06.14 22:50:55 5: Socket3: NETIO230_doTelnet() call: Net::Telnet=GLOB(0x2572ad0)->print(uptime)
2016.06.14 22:50:55 5: Socket3: NETIO230_doTelnet() >item:uptime line:0 years 1 days 1 hours 43 min 32 sec
2016.06.14 22:50:55 5: Socket3: NETIO230_doTelnet() call: Net::Telnet=GLOB(0x2572ad0)->print(version)
2016.06.14 22:50:55 5: Socket3: NETIO230_doTelnet() >item:version line:V 4.05
2016.06.14 22:50:55 5: Socket3: NETIO230_doTelnet() got: hash:HASH(0x259f668), telnet:Net::Telnet=GLOB(0x2572ad0), what:close, cmd:
2016.06.14 22:50:55 4: Connection accepted from telnetPort_127.0.0.1_36592
2016.06.14 22:50:55 5: Cmd: >{NETIO230_doTelnetRequest_Parse('Socket3||statusRequest||alias::"NETIO-230B"|discover::enable|dns::192.168.178.1|dst::enabled 2012/03/25,01:00:00 - 2012/10/28,03:00:00|eth::manual 192.168.178.220 255.255.255.0 192.168.178.1|firmware::V 4.05|ntp::enable pool.ntp.org synchronized|smtp::smtp.1blu.de|socket3_setup::"Rechner" manual 5 0|socket3_timer::once 1970/01/01,00:00:00 1970/01/01,00:00:00 0000000|socket3_wd::disable 0.0.0.0 9 60 3 3 disable disable|swdelay::2|time::2016/06/14,21:50:54|timezone::3600|uptime::0 years 1 days 1 hours 43 min 32 sec')}<
2016.06.14 22:50:55 5: Socket3: NETIO230_doTelnetRequest_Parse() got: Socket3||statusRequest||alias::"NETIO-230B"|discover::enable|dns::192.168.178.1|dst::enabled 2012/03/25,01:00:00 - 2012/10/28,03:00:00|eth::manual 192.168.178.220 255.255.255.0 192.168.178.1|firmware::V 4.05|ntp::enable pool.ntp.org synchronized|smtp::smtp.1blu.de|socket3_setup::"Rechner" manual 5 0|socket3_timer::once 1970/01/01,00:00:00 1970/01/01,00:00:00 0000000|socket3_wd::disable 0.0.0.0 9 60 3 3 disable disable|swdelay::2|time::2016/06/14,21:50:54|timezone::3600|uptime::0 years 1 days 1 hours 43 min 32 sec
2016.06.14 22:50:55 5: Socket3: NETIO230_doTelnetRequest_Parse() -> statusRequest
2016.06.14 22:50:55 5: Socket3: NETIO230_modifyReadings() reading:'alias' orgVal:'"NETIO-230B"' newVal:'"NETIO-230B"'
2016.06.14 22:50:55 5: Socket3: NETIO230_modifyReadings() reading:'discover' orgVal:'enable' newVal:'enable'
2016.06.14 22:50:55 5: Socket3: NETIO230_modifyReadings() reading:'dns' orgVal:'192.168.178.1' newVal:'192.168.178.1'
2016.06.14 22:50:55 5: Socket3: NETIO230_modifyReadings() reading:'dst' orgVal:'enabled 2012/03/25,01:00:00 - 2012/10/28,03:00:00' newVal:'enabled 2012/03/25,01:00:00 - 2012/10/28,03:00:00'
2016.06.14 22:50:55 5: Socket3: NETIO230_modifyReadings() reading:'eth' orgVal:'manual 192.168.178.220 255.255.255.0 192.168.178.1' newVal:'manual 192.168.178.220 255.255.255.0 192.168.178.1'
2016.06.14 22:50:55 5: Socket3: NETIO230_modifyReadings() reading:'firmware' orgVal:'V 4.05' newVal:'V 4.05'
2016.06.14 22:50:55 5: Socket3: NETIO230_modifyReadings() reading:'ntp' orgVal:'enable pool.ntp.org synchronized' newVal:'enable pool.ntp.org synchronized'
2016.06.14 22:50:55 5: Socket3: NETIO230_modifyReadings() reading:'smtp' orgVal:'smtp.1blu.de' newVal:'smtp.1blu.de'
2016.06.14 22:50:55 5: Socket3: NETIO230_modifyReadings() reading:'socket3_setup' orgVal:'"Rechner" manual 5 0' newVal:'"Rechner" manual 5 0'
2016.06.14 22:50:55 5: Socket3: NETIO230_modifyReadings() reading:'socket3_timer' orgVal:'once 1970/01/01,00:00:00 1970/01/01,00:00:00 0000000' newVal:'once 1970/01/01,00:00:00 1970/01/01,00:00:00 0000000'
2016.06.14 22:50:55 5: Socket3: NETIO230_modifyReadings() reading:'socket3_wd' orgVal:'disable 0.0.0.0 9 60 3 3 disable disable' newVal:'disable 0.0.0.0 9 60 3 3 disable disable'
2016.06.14 22:50:55 5: Socket3: NETIO230_modifyReadings() reading:'swdelay' orgVal:'2' newVal:'200ms'
2016.06.14 22:50:55 5: Socket3: NETIO230_modifyReadings() reading:'time' orgVal:'2016/06/14,21:50:54' newVal:'2016/06/14,21:50:54'
2016.06.14 22:50:55 5: Socket3: NETIO230_modifyReadings() reading:'timezone' orgVal:'3600' newVal:'UTC+1'
2016.06.14 22:50:55 5: Socket3: NETIO230_modifyReadings() reading:'uptime' orgVal:'0 years 1 days 1 hours 43 min 32 sec' newVal:'0 years 1 days 1 hours 43 min 32 sec'
2016.06.14 22:50:55 5: Triggering Socket3 (11 changes)
2016.06.14 22:50:55 5: Starting notify loop for Socket3, first event alias: "NETIO-230B"
2016.06.14 22:50:55 5: Triggering Socket3 (1 changes)
2016.06.14 22:50:55 5: Starting notify loop for Socket3, first event _lastNotice: time diff >2 min: -3601 sec
2016.06.14 22:50:55 2: Socket3: NOTICE: [time diff >2 min: -3601 sec]
2016.06.14 22:50:55 5: Triggering Socket3 (1 changes)
2016.06.14 22:50:55 5: Starting notify loop for Socket3, first event timediff: -3601
2016.06.14 22:50:56 5: Socket2: NETIO230_Set() got: hash:HASH(0x259e7a8), name:Socket2, cmd:statusRequest, params:
2016.06.14 22:50:56 5: Socket2: NETIO230_modifyUserInput() cmd:'statusRequest' orgVal:'' newVal:''
2016.06.14 22:50:56 5: Socket2: NETIO230_setStatusRequest() got: hash:HASH(0x259e7a8)
2016.06.14 22:50:56 5: Triggering Socket2 (1 changes)
2016.06.14 22:50:56 5: Starting notify loop for Socket2, first event _lastNotice: set Socket2 statusRequest
2016.06.14 22:50:56 3: Socket2: set Socket2 statusRequest
2016.06.14 22:50:56 5: Socket2: NETIO230_resetTimer() call: RemoveInternalTimer(HASH(0x259e7a8))
2016.06.14 22:50:56 5: Socket2: NETIO230_resetTimer() InternalTimer() deleted
2016.06.14 22:50:56 5: Socket2: NETIO230_httpRequest() got: cmd:get, arg:
2016.06.14 22:50:56 5: Socket2: NETIO230_md5token() <"C5604A90" >"669634babcb846929d89a1df14fba5fd"
2016.06.14 22:50:56 5: Socket2: NETIO230_httpRequest() url: http://192.168.178.220:80/tgi/control.tgi?login=c:admin:669634babcb846929d89a1df14fba5fd&port=list
2016.06.14 22:50:56 5: Socket2: NETIO230_httpRequest() call: HttpUtils_NonblockingGet(HASH(0x2453068))
2016.06.14 22:50:56 4: HttpUtils url=http://192.168.178.220:80/tgi/control.tgi?login=c:admin:669634babcb846929d89a1df14fba5fd&port=list
2016.06.14 22:50:56 5: Socket2: NETIO230_telnetRequest() got: statusRequest
2016.06.14 22:50:56 5: Socket2: NETIO230_telnetRequest() call: BlockingCall("NETIO230_doTelnetRequest", Socket2|192.168.178.220|statusRequest)
2016.06.14 22:50:56 4: BlockingCall (NETIO230_doTelnetRequest): created child (2315), uses telnetPort to connect back
2016.06.14 22:50:56 5: Socket2: NETIO230_telnetRequest() running PID: HASH(0x2574468)
2016.06.14 22:50:56 5: Socket2: NETIO230_doTelnetRequest() got: Socket2|192.168.178.220|statusRequest
2016.06.14 22:50:56 5: Socket2: NETIO230_doTelnetRequest() call: NETIO230_doTelnet(HASH(0x259e7a8),Net::Telnet=GLOB(0x2571ca0),"open")
2016.06.14 22:50:56 5: Socket2: NETIO230_doTelnet() got: hash:HASH(0x259e7a8), telnet:Net::Telnet=GLOB(0x2571ca0), what:open, cmd:
2016.06.14 22:50:56 4: http://192.168.178.220:80/tgi/control.tgi?login=c:admin:669634babcb846929d89a1df14fba5fd&port=list: HTTP response code 200
2016.06.14 22:50:56 4: HttpUtils http://192.168.178.220:80/tgi/control.tgi?login=c:admin:669634babcb846929d89a1df14fba5fd&port=list: Got data, length: 30
2016.06.14 22:50:56 5: Socket2: NETIO230_httpRequestParse() got: cmd:get, arg:, err:, data:<html>553 INVALID LOGIN</html>
2016.06.14 22:50:56 5: Socket2: NETIO230_httpRequestParse() -> data ne ""
2016.06.14 22:50:56 5: Socket2: NETIO230_httpRequest() got: cmd:get, arg:
2016.06.14 22:50:56 5: Socket2: NETIO230_doTelnet() -> login
2016.06.14 22:50:56 4: Socket2: NOTICE: [request pwdhash] => Request new password hash from device.
2016.06.14 22:50:56 5: Socket2: NETIO230_md5token() <"BDC08E77" >"d241d740c511022b8633398e398a8aff"
2016.06.14 22:50:56 5: Socket2: NETIO230_httpRequest() url: http://192.168.178.220:80/tgi/control.tgi?hash=hash
2016.06.14 22:50:56 5: Socket2: NETIO230_httpRequest() call: HttpUtils_NonblockingGet(HASH(0x2572950))
2016.06.14 22:50:56 4: HttpUtils url=http://192.168.178.220:80/tgi/control.tgi?hash=hash
2016.06.14 22:50:56 5: Socket2: NETIO230_doTelnet() return: OK
2016.06.14 22:50:56 5: Socket2: NETIO230_doTelnetRequest() call: NETIO230_doTelnet(HASH(0x259e7a8),Net::Telnet=GLOB(0x2571ca0),"statusRequest")
2016.06.14 22:50:56 5: Socket2: NETIO230_doTelnet() got: hash:HASH(0x259e7a8), telnet:Net::Telnet=GLOB(0x2571ca0), what:statusRequest, cmd:
2016.06.14 22:50:56 5: Socket2: NETIO230_doTelnet() call: Net::Telnet=GLOB(0x2571ca0)->print(alias)
2016.06.14 22:50:56 4: http://192.168.178.220:80/tgi/control.tgi?hash=hash: HTTP response code 200
2016.06.14 22:50:56 4: HttpUtils http://192.168.178.220:80/tgi/control.tgi?hash=hash: Got data, length: 21
2016.06.14 22:50:56 5: Socket2: NETIO230_httpRequestParse() got: cmd:hash, arg:, err:, data:<html>39F0B06D</html>
2016.06.14 22:50:56 5: Socket2: NETIO230_doTelnet() >item:alias line:"NETIO-230B"
2016.06.14 22:50:56 5: Socket2: NETIO230_httpRequestParse() -> data ne ""
2016.06.14 22:50:56 5: Socket2: NETIO230_doTelnet() call: Net::Telnet=GLOB(0x2571ca0)->print(email server)
2016.06.14 22:50:56 5: Socket2: NETIO230_httpRequestParse() -> hash
2016.06.14 22:50:56 5: Socket2: NETIO230_httpRequestParse() hash: "39F0B06D"
2016.06.14 22:50:56 4: Socket2: NOTICE: [got pwdhash] => Login with new token.
2016.06.14 22:50:56 5: Socket2: NETIO230_httpRequest() got: cmd:get, arg:
2016.06.14 22:50:56 5: Socket2: NETIO230_md5token() <"39F0B06D" >"7464749fb608e2f764b4577626ac182b"
2016.06.14 22:50:56 5: Socket2: NETIO230_httpRequest() url: http://192.168.178.220:80/tgi/control.tgi?login=c:admin:7464749fb608e2f764b4577626ac182b&port=list
2016.06.14 22:50:56 5: Socket2: NETIO230_httpRequest() call: HttpUtils_NonblockingGet(HASH(0x2436f00))
2016.06.14 22:50:56 4: HttpUtils url=http://192.168.178.220:80/tgi/control.tgi?login=c:admin:7464749fb608e2f764b4577626ac182b&port=list
2016.06.14 22:50:56 5: Socket2: NETIO230_doTelnet() >item:email_server line:smtp.1blu.de
2016.06.14 22:50:56 5: Socket2: NETIO230_doTelnet() call: Net::Telnet=GLOB(0x2571ca0)->print(port setup 2)
2016.06.14 22:50:56 5: Socket2: NETIO230_doTelnet() >item:port_setup_2 line:"Notebook" manual 5 0
2016.06.14 22:50:56 5: Socket2: NETIO230_doTelnet() call: Net::Telnet=GLOB(0x2571ca0)->print(port timer 2 dt)
2016.06.14 22:50:56 4: http://192.168.178.220:80/tgi/control.tgi?login=c:admin:7464749fb608e2f764b4577626ac182b&port=list: HTTP response code 200
2016.06.14 22:50:56 4: HttpUtils http://192.168.178.220:80/tgi/control.tgi?login=c:admin:7464749fb608e2f764b4577626ac182b&port=list: Got data, length: 21
2016.06.14 22:50:56 5: Socket2: NETIO230_httpRequestParse() got: cmd:get, arg:, err:, data:<html>0 1 1 0 </html>
2016.06.14 22:50:56 5: Socket2: NETIO230_httpRequestParse() -> data ne ""
2016.06.14 22:50:56 5: Socket2: NETIO230_httpRequestParse() -> get
2016.06.14 22:50:56 5: Socket2: NETIO230_modifyReadings() reading:'socket' orgVal:'1' newVal:'on'
2016.06.14 22:50:56 5: Triggering Socket2 (2 changes)
2016.06.14 22:50:56 5: Starting notify loop for Socket2, first event socket2: on
2016.06.14 22:50:56 5: Socket2: NETIO230_doTelnet() >item:port_timer_2_dt line:once 1970/01/01,00:00:00 1970/01/01,00:00:00 0000000
2016.06.14 22:50:56 5: Socket2: NETIO230_doTelnet() call: Net::Telnet=GLOB(0x2571ca0)->print(port wd 2)
2016.06.14 22:50:56 5: Socket2: NETIO230_doTelnet() >item:port_wd_2 line:disable 0.0.0.0 9 60 3 3 disable disable
2016.06.14 22:50:56 5: Socket2: NETIO230_doTelnet() call: Net::Telnet=GLOB(0x2571ca0)->print(system discover)
2016.06.14 22:50:56 5: Socket2: NETIO230_doTelnet() >item:system_discover line:enable
2016.06.14 22:50:56 5: Socket2: NETIO230_doTelnet() call: Net::Telnet=GLOB(0x2571ca0)->print(system dns)
2016.06.14 22:50:56 4: Socket2: get Socket2 state: on
2016.06.14 22:50:56 5: Socket2: NETIO230_httpRequestParse() PWDHASH:39F0B06D
2016.06.14 22:50:56 5: Socket2: NETIO230_httpRequestParse() call: NETIO230_resetTimer(HASH(0x259e7a8),"start")
2016.06.14 22:50:56 5: Socket2: NETIO230_resetTimer() call: RemoveInternalTimer(HASH(0x259e7a8))
2016.06.14 22:50:56 5: Socket2: NETIO230_resetTimer() InternalTimer(+297.052646460563,"NETIO230_timedStatusRequest", $hash, 0)
2016.06.14 22:50:56 5: Socket2: NETIO230_doTelnet() >item:system_dns line:192.168.178.1
2016.06.14 22:50:56 5: Socket2: NETIO230_doTelnet() call: Net::Telnet=GLOB(0x2571ca0)->print(system dst)
2016.06.14 22:50:56 5: Socket2: NETIO230_doTelnet() >item:system_dst line:enabled 2012/03/25,01:00:00 - 2012/10/28,03:00:00
2016.06.14 22:50:56 5: Socket2: NETIO230_doTelnet() call: Net::Telnet=GLOB(0x2571ca0)->print(system eth)
2016.06.14 22:50:56 5: Socket2: NETIO230_doTelnet() >item:system_eth line:manual 192.168.178.220 255.255.255.0 192.168.178.1
2016.06.14 22:50:56 5: Socket2: NETIO230_doTelnet() call: Net::Telnet=GLOB(0x2571ca0)->print(system sntp)
2016.06.14 22:50:56 5: Socket2: NETIO230_doTelnet() >item:system_sntp line:enable pool.ntp.org synchronized
2016.06.14 22:50:56 5: Socket2: NETIO230_doTelnet() call: Net::Telnet=GLOB(0x2571ca0)->print(system swdelay)
2016.06.14 22:50:56 5: Socket2: NETIO230_doTelnet() >item:system_swdelay line:2
2016.06.14 22:50:56 5: Socket2: NETIO230_doTelnet() call: Net::Telnet=GLOB(0x2571ca0)->print(system time)
2016.06.14 22:50:56 5: Socket2: NETIO230_doTelnet() >item:system_time line:2016/06/14,21:50:54
2016.06.14 22:50:56 5: Socket2: NETIO230_doTelnet() call: Net::Telnet=GLOB(0x2571ca0)->print(system timezone)
2016.06.14 22:50:56 5: Socket2: NETIO230_doTelnet() >item:system_timezone line:3600
2016.06.14 22:50:56 5: Socket2: NETIO230_doTelnet() call: Net::Telnet=GLOB(0x2571ca0)->print(uptime)
2016.06.14 22:50:56 5: Socket2: NETIO230_doTelnet() >item:uptime line:0 years 1 days 1 hours 43 min 32 sec
2016.06.14 22:50:56 5: Socket2: NETIO230_doTelnet() call: Net::Telnet=GLOB(0x2571ca0)->print(version)
2016.06.14 22:50:56 5: Socket2: NETIO230_doTelnet() >item:version line:V 4.05
2016.06.14 22:50:56 5: Socket2: NETIO230_doTelnet() got: hash:HASH(0x259e7a8), telnet:Net::Telnet=GLOB(0x2571ca0), what:close, cmd:
2016.06.14 22:50:56 4: Connection accepted from telnetPort_127.0.0.1_36602
2016.06.14 22:50:56 5: Cmd: >{NETIO230_doTelnetRequest_Parse('Socket2||statusRequest||alias::"NETIO-230B"|discover::enable|dns::192.168.178.1|dst::enabled 2012/03/25,01:00:00 - 2012/10/28,03:00:00|eth::manual 192.168.178.220 255.255.255.0 192.168.178.1|firmware::V 4.05|ntp::enable pool.ntp.org synchronized|smtp::smtp.1blu.de|socket2_setup::"Notebook" manual 5 0|socket2_timer::once 1970/01/01,00:00:00 1970/01/01,00:00:00 0000000|socket2_wd::disable 0.0.0.0 9 60 3 3 disable disable|swdelay::2|time::2016/06/14,21:50:54|timezone::3600|uptime::0 years 1 days 1 hours 43 min 32 sec')}<
2016.06.14 22:50:56 5: Socket2: NETIO230_doTelnetRequest_Parse() got: Socket2||statusRequest||alias::"NETIO-230B"|discover::enable|dns::192.168.178.1|dst::enabled 2012/03/25,01:00:00 - 2012/10/28,03:00:00|eth::manual 192.168.178.220 255.255.255.0 192.168.178.1|firmware::V 4.05|ntp::enable pool.ntp.org synchronized|smtp::smtp.1blu.de|socket2_setup::"Notebook" manual 5 0|socket2_timer::once 1970/01/01,00:00:00 1970/01/01,00:00:00 0000000|socket2_wd::disable 0.0.0.0 9 60 3 3 disable disable|swdelay::2|time::2016/06/14,21:50:54|timezone::3600|uptime::0 years 1 days 1 hours 43 min 32 sec
2016.06.14 22:50:56 5: Socket2: NETIO230_doTelnetRequest_Parse() -> statusRequest
2016.06.14 22:50:56 5: Socket2: NETIO230_modifyReadings() reading:'alias' orgVal:'"NETIO-230B"' newVal:'"NETIO-230B"'
2016.06.14 22:50:56 5: Socket2: NETIO230_modifyReadings() reading:'discover' orgVal:'enable' newVal:'enable'
2016.06.14 22:50:56 5: Socket2: NETIO230_modifyReadings() reading:'dns' orgVal:'192.168.178.1' newVal:'192.168.178.1'
2016.06.14 22:50:56 5: Socket2: NETIO230_modifyReadings() reading:'dst' orgVal:'enabled 2012/03/25,01:00:00 - 2012/10/28,03:00:00' newVal:'enabled 2012/03/25,01:00:00 - 2012/10/28,03:00:00'
2016.06.14 22:50:56 5: Socket2: NETIO230_modifyReadings() reading:'eth' orgVal:'manual 192.168.178.220 255.255.255.0 192.168.178.1' newVal:'manual 192.168.178.220 255.255.255.0 192.168.178.1'
2016.06.14 22:50:56 5: Socket2: NETIO230_modifyReadings() reading:'firmware' orgVal:'V 4.05' newVal:'V 4.05'
2016.06.14 22:50:56 5: Socket2: NETIO230_modifyReadings() reading:'ntp' orgVal:'enable pool.ntp.org synchronized' newVal:'enable pool.ntp.org synchronized'
2016.06.14 22:50:56 5: Socket2: NETIO230_modifyReadings() reading:'smtp' orgVal:'smtp.1blu.de' newVal:'smtp.1blu.de'
2016.06.14 22:50:56 5: Socket2: NETIO230_modifyReadings() reading:'socket2_setup' orgVal:'"Notebook" manual 5 0' newVal:'"Notebook" manual 5 0'
2016.06.14 22:50:56 5: Socket2: NETIO230_modifyReadings() reading:'socket2_timer' orgVal:'once 1970/01/01,00:00:00 1970/01/01,00:00:00 0000000' newVal:'once 1970/01/01,00:00:00 1970/01/01,00:00:00 0000000'
2016.06.14 22:50:56 5: Socket2: NETIO230_modifyReadings() reading:'socket2_wd' orgVal:'disable 0.0.0.0 9 60 3 3 disable disable' newVal:'disable 0.0.0.0 9 60 3 3 disable disable'
2016.06.14 22:50:56 5: Socket2: NETIO230_modifyReadings() reading:'swdelay' orgVal:'2' newVal:'200ms'
2016.06.14 22:50:56 5: Socket2: NETIO230_modifyReadings() reading:'time' orgVal:'2016/06/14,21:50:54' newVal:'2016/06/14,21:50:54'
2016.06.14 22:50:56 5: Socket2: NETIO230_modifyReadings() reading:'timezone' orgVal:'3600' newVal:'UTC+1'
2016.06.14 22:50:56 5: Socket2: NETIO230_modifyReadings() reading:'uptime' orgVal:'0 years 1 days 1 hours 43 min 32 sec' newVal:'0 years 1 days 1 hours 43 min 32 sec'
2016.06.14 22:50:56 5: Triggering Socket2 (11 changes)
2016.06.14 22:50:56 5: Starting notify loop for Socket2, first event alias: "NETIO-230B"
2016.06.14 22:50:56 5: Triggering Socket2 (1 changes)
2016.06.14 22:50:56 5: Starting notify loop for Socket2, first event _lastNotice: time diff >2 min: -3602 sec
2016.06.14 22:50:56 2: Socket2: NOTICE: [time diff >2 min: -3602 sec]
2016.06.14 22:50:56 5: Triggering Socket2 (1 changes)
2016.06.14 22:50:56 5: Starting notify loop for Socket2, first event timediff: -3602
2016.06.14 22:50:57 4: Connection accepted from WEB_192.168.178.68_64041
2016.06.14 22:50:57 4: WEB_192.168.178.68_64041 POST /fhem?cmd.Socket1=set%20Socket1%20on&room=CUL_HM&XHR=1&fw_id=62; BUFLEN:0
2016.06.14 22:50:57 5: Cmd: >set Socket1 on<
2016.06.14 22:50:57 5: Socket1: NETIO230_Set() got: hash:HASH(0x2437ed8), name:Socket1, cmd:on, params:
2016.06.14 22:50:57 5: Socket1: NETIO230_modifyUserInput() cmd:'on' orgVal:'' newVal:''
2016.06.14 22:50:57 5: Socket1: NETIO230_httpRequest() got: cmd:set, arg:1uuu
2016.06.14 22:50:57 5: Socket1: NETIO230_md5token() <"39F0B06D" >"7464749fb608e2f764b4577626ac182b"
2016.06.14 22:50:57 5: Socket1: NETIO230_httpRequest() url: http://192.168.178.220:80/tgi/control.tgi?login=c:admin:7464749fb608e2f764b4577626ac182b&port=1uuu
2016.06.14 22:50:57 5: Socket1: NETIO230_httpRequest() call: HttpUtils_NonblockingGet(HASH(0x2433ee0))
2016.06.14 22:50:57 4: HttpUtils url=http://192.168.178.220:80/tgi/control.tgi?login=c:admin:7464749fb608e2f764b4577626ac182b&port=1uuu
2016.06.14 22:50:57 5: Triggering Socket1 (1 changes)
2016.06.14 22:50:57 5: Starting notify loop for Socket1, first event on
2016.06.14 22:50:57 4: name: /fhem?cmd.Socket1=set%20Socket1%20on&room=CUL_HM&XHR=1&fw_id=62 / RL:20 / text/plain; charset=UTF-8 / Content-Encoding: gzip
/
2016.06.14 22:50:57 4: http://192.168.178.220:80/tgi/control.tgi?login=c:admin:7464749fb608e2f764b4577626ac182b&port=1uuu: HTTP response code 200
2016.06.14 22:50:57 4: HttpUtils http://192.168.178.220:80/tgi/control.tgi?login=c:admin:7464749fb608e2f764b4577626ac182b&port=1uuu: Got data, length: 30
2016.06.14 22:50:57 5: Socket1: NETIO230_httpRequestParse() got: cmd:set, arg:1uuu, err:, data:<html>553 INVALID LOGIN</html>
2016.06.14 22:50:57 5: Socket1: NETIO230_httpRequestParse() -> data ne ""
2016.06.14 22:50:57 5: Socket1: NETIO230_httpRequest() got: cmd:set, arg:1uuu
2016.06.14 22:50:57 4: Socket1: NOTICE: [request pwdhash] => Request new password hash from device.
2016.06.14 22:50:57 5: Socket1: NETIO230_httpRequest() url: http://192.168.178.220:80/tgi/control.tgi?hash=hash
2016.06.14 22:50:57 5: Socket1: NETIO230_httpRequest() call: HttpUtils_NonblockingGet(HASH(0x2438238))
2016.06.14 22:50:57 4: HttpUtils url=http://192.168.178.220:80/tgi/control.tgi?hash=hash
2016.06.14 22:50:57 4: http://192.168.178.220:80/tgi/control.tgi?hash=hash: HTTP response code 200
2016.06.14 22:50:57 4: HttpUtils http://192.168.178.220:80/tgi/control.tgi?hash=hash: Got data, length: 21
2016.06.14 22:50:57 5: Socket1: NETIO230_httpRequestParse() got: cmd:hash, arg:1uuu, err:, data:<html>3251F463</html>
2016.06.14 22:50:57 5: Socket1: NETIO230_httpRequestParse() -> data ne ""
2016.06.14 22:50:57 5: Socket1: NETIO230_httpRequestParse() -> hash
2016.06.14 22:50:57 5: Socket1: NETIO230_httpRequestParse() hash: "3251F463"
2016.06.14 22:50:57 4: Socket1: NOTICE: [got pwdhash] => Login with new token.
2016.06.14 22:50:57 5: Socket1: NETIO230_httpRequest() got: cmd:set, arg:1uuu
2016.06.14 22:50:57 5: Socket1: NETIO230_md5token() <"3251F463" >"fcef9e3b3363ff1f36ec643f3850af6a"
2016.06.14 22:50:57 5: Socket1: NETIO230_httpRequest() url: http://192.168.178.220:80/tgi/control.tgi?login=c:admin:fcef9e3b3363ff1f36ec643f3850af6a&port=1uuu
2016.06.14 22:50:57 5: Socket1: NETIO230_httpRequest() call: HttpUtils_NonblockingGet(HASH(0x2574918))
2016.06.14 22:50:57 4: HttpUtils url=http://192.168.178.220:80/tgi/control.tgi?login=c:admin:fcef9e3b3363ff1f36ec643f3850af6a&port=1uuu
2016.06.14 22:50:57 4: http://192.168.178.220:80/tgi/control.tgi?login=c:admin:fcef9e3b3363ff1f36ec643f3850af6a&port=1uuu: HTTP response code 200
2016.06.14 22:50:57 4: HttpUtils http://192.168.178.220:80/tgi/control.tgi?login=c:admin:fcef9e3b3363ff1f36ec643f3850af6a&port=1uuu: Got data, length: 19
2016.06.14 22:50:57 5: Socket1: NETIO230_httpRequestParse() got: cmd:set, arg:1uuu, err:, data:<html>250 OK</html>
2016.06.14 22:50:57 5: Socket1: NETIO230_httpRequestParse() -> data ne ""
2016.06.14 22:50:57 5: Socket1: NETIO230_httpRequestParse() -> set
2016.06.14 22:50:57 5: Socket1: NETIO230_httpRequestParse() data:250 OK
2016.06.14 22:50:57 5: Triggering Socket1 (1 changes)
2016.06.14 22:50:57 5: Starting notify loop for Socket1, first event _lastNotice: set Socket1 on: OK
2016.06.14 22:50:57 5: Triggering Socket
2016.06.14 22:50:57 4: Socket exec {DebianMail('mail@apperger.de',$NAME.' ON',$EVENT);; }
2016.06.14 22:50:57 5: Cmd: >{DebianMail('mail@apperger.de',$NAME.' ON',$EVENT); }<
2016.06.14 22:50:57 1: sendEmail RCP: mail@apperger.de
2016.06.14 22:50:57 1: sendEmail Subject: Socket1 ON
2016.06.14 22:50:57 1: sendEmail Text: _lastNotice: set Socket1 on: OK
2016.06.14 22:50:57 1: sendEmail Anhang:
2016.06.14 22:50:58 1: sendEmail returned: Jun 14 22:50:58 raspberrypi sendEmail[2317]: Email was sent successfully!
2016.06.14 22:50:58 3: Socket1: set Socket1 on: OK
2016.06.14 22:50:58 5: Socket1: NETIO230_httpRequest() got: cmd:get, arg:
2016.06.14 22:50:58 5: Socket1: NETIO230_md5token() <"3251F463" >"fcef9e3b3363ff1f36ec643f3850af6a"
2016.06.14 22:50:58 5: Socket1: NETIO230_httpRequest() url: http://192.168.178.220:80/tgi/control.tgi?login=c:admin:fcef9e3b3363ff1f36ec643f3850af6a&port=list
2016.06.14 22:50:58 5: Socket1: NETIO230_httpRequest() call: HttpUtils_NonblockingGet(HASH(0x2571ee0))
2016.06.14 22:50:58 4: HttpUtils url=http://192.168.178.220:80/tgi/control.tgi?login=c:admin:fcef9e3b3363ff1f36ec643f3850af6a&port=list
2016.06.14 22:50:58 5: Socket1: NETIO230_httpRequestParse() PWDHASH:3251F463
2016.06.14 22:50:58 5: Socket1: NETIO230_httpRequestParse() call: NETIO230_resetTimer(HASH(0x2437ed8),"start")
2016.06.14 22:50:58 5: Socket1: NETIO230_resetTimer() call: RemoveInternalTimer(HASH(0x2437ed8))
2016.06.14 22:50:58 5: Socket1: NETIO230_resetTimer() InternalTimer(+299.670215330235,"NETIO230_timedStatusRequest", $hash, 0)
2016.06.14 22:50:58 5: Socket4: NETIO230_Set() got: hash:HASH(0x1d34d20), name:Socket4, cmd:statusRequest, params:
2016.06.14 22:50:58 5: Socket4: NETIO230_modifyUserInput() cmd:'statusRequest' orgVal:'' newVal:''
2016.06.14 22:50:58 5: Socket4: NETIO230_setStatusRequest() got: hash:HASH(0x1d34d20)
2016.06.14 22:50:58 5: Triggering Socket4 (1 changes)
2016.06.14 22:50:58 5: Starting notify loop for Socket4, first event _lastNotice: set Socket4 statusRequest
2016.06.14 22:50:58 3: Socket4: set Socket4 statusRequest
2016.06.14 22:50:58 5: Socket4: NETIO230_resetTimer() call: RemoveInternalTimer(HASH(0x1d34d20))
2016.06.14 22:50:58 5: Socket4: NETIO230_resetTimer() InternalTimer() deleted
2016.06.14 22:50:58 5: Socket4: NETIO230_httpRequest() got: cmd:get, arg:
2016.06.14 22:50:58 5: Socket4: NETIO230_md5token() <"3251F463" >"fcef9e3b3363ff1f36ec643f3850af6a"
2016.06.14 22:50:58 5: Socket4: NETIO230_httpRequest() url: http://192.168.178.220:80/tgi/control.tgi?login=c:admin:fcef9e3b3363ff1f36ec643f3850af6a&port=list
2016.06.14 22:50:58 5: Socket4: NETIO230_httpRequest() call: HttpUtils_NonblockingGet(HASH(0x2574a38))
2016.06.14 22:50:58 4: HttpUtils url=http://192.168.178.220:80/tgi/control.tgi?login=c:admin:fcef9e3b3363ff1f36ec643f3850af6a&port=list
2016.06.14 22:50:58 5: Socket4: NETIO230_telnetRequest() got: statusRequest
2016.06.14 22:50:58 5: Socket4: NETIO230_telnetRequest() call: BlockingCall("NETIO230_doTelnetRequest", Socket4|192.168.178.220|statusRequest)
2016.06.14 22:50:58 4: BlockingCall (NETIO230_doTelnetRequest): created child (2318), uses telnetPort to connect back
2016.06.14 22:50:58 5: Socket4: NETIO230_telnetRequest() running PID: HASH(0x2572320)
2016.06.14 22:50:58 5: Socket4: NETIO230_doTelnetRequest() got: Socket4|192.168.178.220|statusRequest
2016.06.14 22:50:58 5: Socket4: NETIO230_doTelnetRequest() call: NETIO230_doTelnet(HASH(0x1d34d20),Net::Telnet=GLOB(0x2574a80),"open")
2016.06.14 22:50:58 5: Socket4: NETIO230_doTelnet() got: hash:HASH(0x1d34d20), telnet:Net::Telnet=GLOB(0x2574a80), what:open, cmd:
2016.06.14 22:50:58 4: http://192.168.178.220:80/tgi/control.tgi?login=c:admin:fcef9e3b3363ff1f36ec643f3850af6a&port=list: HTTP response code 200
2016.06.14 22:50:58 4: HttpUtils http://192.168.178.220:80/tgi/control.tgi?login=c:admin:fcef9e3b3363ff1f36ec643f3850af6a&port=list: Got data, length: 30
2016.06.14 22:50:58 5: Socket1: NETIO230_httpRequestParse() got: cmd:get, arg:, err:, data:<html>553 INVALID LOGIN</html>
2016.06.14 22:50:58 5: Socket1: NETIO230_httpRequestParse() -> data ne ""
2016.06.14 22:50:58 5: Triggering Socket1 (1 changes)
2016.06.14 22:50:58 5: Starting notify loop for Socket1, first event _lastNotice: 553 retry login (1)
2016.06.14 22:50:58 4: Socket1: NOTICE: [553 retry login (1)] => Secure login failed. Awaiting new token...
2016.06.14 22:50:58 5: Socket1: NETIO230_httpRequest() got: cmd:get, arg:
2016.06.14 22:50:58 5: Socket1: NETIO230_md5token() <"3251F463" >"fcef9e3b3363ff1f36ec643f3850af6a"
2016.06.14 22:50:58 5: Socket1: NETIO230_httpRequest() url: http://192.168.178.220:80/tgi/control.tgi?login=c:admin:fcef9e3b3363ff1f36ec643f3850af6a&port=list
2016.06.14 22:50:58 5: Socket1: NETIO230_httpRequest() call: HttpUtils_NonblockingGet(HASH(0x25745d0))
2016.06.14 22:50:58 4: HttpUtils url=http://192.168.178.220:80/tgi/control.tgi?login=c:admin:fcef9e3b3363ff1f36ec643f3850af6a&port=list
2016.06.14 22:50:58 4: http://192.168.178.220:80/tgi/control.tgi?login=c:admin:fcef9e3b3363ff1f36ec643f3850af6a&port=list: HTTP response code 200
2016.06.14 22:50:58 4: HttpUtils http://192.168.178.220:80/tgi/control.tgi?login=c:admin:fcef9e3b3363ff1f36ec643f3850af6a&port=list: Got data, length: 30
2016.06.14 22:50:58 5: Socket4: NETIO230_httpRequestParse() got: cmd:get, arg:, err:, data:<html>553 INVALID LOGIN</html>
2016.06.14 22:50:58 5: Socket4: NETIO230_httpRequestParse() -> data ne ""
2016.06.14 22:50:58 5: Socket4: NETIO230_httpRequest() got: cmd:get, arg:
2016.06.14 22:50:58 4: Socket4: NOTICE: [request pwdhash] => Request new password hash from device.
2016.06.14 22:50:58 5: Socket4: NETIO230_httpRequest() url: http://192.168.178.220:80/tgi/control.tgi?hash=hash
2016.06.14 22:50:58 5: Socket4: NETIO230_httpRequest() call: HttpUtils_NonblockingGet(HASH(0x2572de8))
2016.06.14 22:50:58 4: HttpUtils url=http://192.168.178.220:80/tgi/control.tgi?hash=hash
2016.06.14 22:50:58 5: Socket4: NETIO230_doTelnet() -> login
2016.06.14 22:50:58 5: Socket4: NETIO230_md5token() <"2AD23872" >"fc7e7b9f80915bc29daf1529b791175e"
2016.06.14 22:50:58 5: Socket4: NETIO230_doTelnet() return: OK
2016.06.14 22:50:58 5: Socket4: NETIO230_doTelnetRequest() call: NETIO230_doTelnet(HASH(0x1d34d20),Net::Telnet=GLOB(0x2574a80),"statusRequest")
2016.06.14 22:50:58 5: Socket4: NETIO230_doTelnet() got: hash:HASH(0x1d34d20), telnet:Net::Telnet=GLOB(0x2574a80), what:statusRequest, cmd:
2016.06.14 22:50:58 5: Socket4: NETIO230_doTelnet() call: Net::Telnet=GLOB(0x2574a80)->print(alias)
2016.06.14 22:50:58 4: http://192.168.178.220:80/tgi/control.tgi?login=c:admin:fcef9e3b3363ff1f36ec643f3850af6a&port=list: HTTP response code 200
2016.06.14 22:50:58 4: HttpUtils http://192.168.178.220:80/tgi/control.tgi?login=c:admin:fcef9e3b3363ff1f36ec643f3850af6a&port=list: Got data, length: 30
2016.06.14 22:50:58 5: Socket1: NETIO230_httpRequestParse() got: cmd:get, arg:, err:, data:<html>553 INVALID LOGIN</html>
2016.06.14 22:50:58 5: Socket1: NETIO230_httpRequestParse() -> data ne ""
2016.06.14 22:50:58 5: Triggering Socket1 (1 changes)
2016.06.14 22:50:58 5: Starting notify loop for Socket1, first event _lastNotice: 553 INVALID LOGIN
2016.06.14 22:50:58 2: Socket1: ERROR: [553 INVALID LOGIN] => Http login failed. Wrong Credentials or simultaneously secure logins from multiple clients.
2016.06.14 22:50:58 5: Socket1: NETIO230_httpRequestParse() call: NETIO230_resetTimer(HASH(0x2437ed8),"start")
2016.06.14 22:50:58 5: Socket1: NETIO230_resetTimer() call: RemoveInternalTimer(HASH(0x2437ed8))
2016.06.14 22:50:58 5: Socket1: NETIO230_resetTimer() InternalTimer(+302.568671987531,"NETIO230_timedStatusRequest", $hash, 0)
2016.06.14 22:50:58 5: Socket4: NETIO230_doTelnet() >item:alias line:"NETIO-230B"
2016.06.14 22:50:58 5: Socket4: NETIO230_doTelnet() call: Net::Telnet=GLOB(0x2574a80)->print(email server)
2016.06.14 22:50:58 4: http://192.168.178.220:80/tgi/control.tgi?hash=hash: HTTP response code 200
2016.06.14 22:50:58 4: HttpUtils http://192.168.178.220:80/tgi/control.tgi?hash=hash: Got data, length: 21
2016.06.14 22:50:58 5: Socket4: NETIO230_httpRequestParse() got: cmd:hash, arg:, err:, data:<html>C8216A81</html>
2016.06.14 22:50:58 5: Socket4: NETIO230_httpRequestParse() -> data ne ""
2016.06.14 22:50:58 5: Socket4: NETIO230_httpRequestParse() -> hash
2016.06.14 22:50:58 5: Socket4: NETIO230_httpRequestParse() hash: "C8216A81"
2016.06.14 22:50:58 4: Socket4: NOTICE: [got pwdhash] => Login with new token.
2016.06.14 22:50:58 5: Socket4: NETIO230_httpRequest() got: cmd:get, arg:
2016.06.14 22:50:58 5: Socket4: NETIO230_doTelnet() >item:email_server line:smtp.1blu.de
2016.06.14 22:50:58 5: Socket4: NETIO230_md5token() <"C8216A81" >"9ec0aeaa50c35e030812c3f743a88652"
2016.06.14 22:50:58 5: Socket4: NETIO230_doTelnet() call: Net::Telnet=GLOB(0x2574a80)->print(port setup 4)
2016.06.14 22:50:58 5: Socket4: NETIO230_httpRequest() url: http://192.168.178.220:80/tgi/control.tgi?login=c:admin:9ec0aeaa50c35e030812c3f743a88652&port=list
2016.06.14 22:50:58 5: Socket4: NETIO230_httpRequest() call: HttpUtils_NonblockingGet(HASH(0x2574108))
2016.06.14 22:50:58 4: HttpUtils url=http://192.168.178.220:80/tgi/control.tgi?login=c:admin:9ec0aeaa50c35e030812c3f743a88652&port=list
2016.06.14 22:50:58 5: Socket4: NETIO230_doTelnet() >item:port_setup_4 line:"Drucker" manual 5 0
2016.06.14 22:50:58 5: Socket4: NETIO230_doTelnet() call: Net::Telnet=GLOB(0x2574a80)->print(port timer 4 dt)
2016.06.14 22:50:58 5: Socket4: NETIO230_doTelnet() >item:port_timer_4_dt line:once 1970/01/01,00:00:00 1970/01/01,00:00:00 0000000
2016.06.14 22:50:58 5: Socket4: NETIO230_doTelnet() call: Net::Telnet=GLOB(0x2574a80)->print(port wd 4)
2016.06.14 22:50:58 4: http://192.168.178.220:80/tgi/control.tgi?login=c:admin:9ec0aeaa50c35e030812c3f743a88652&port=list: HTTP response code 200
2016.06.14 22:50:58 4: HttpUtils http://192.168.178.220:80/tgi/control.tgi?login=c:admin:9ec0aeaa50c35e030812c3f743a88652&port=list: Got data, length: 21
2016.06.14 22:50:58 5: Socket4: NETIO230_httpRequestParse() got: cmd:get, arg:, err:, data:<html>1 1 1 0 </html>
2016.06.14 22:50:58 5: Socket4: NETIO230_httpRequestParse() -> data ne ""
2016.06.14 22:50:58 5: Socket4: NETIO230_httpRequestParse() -> get
2016.06.14 22:50:58 5: Socket4: NETIO230_modifyReadings() reading:'socket' orgVal:'0' newVal:'off'
2016.06.14 22:50:58 5: Triggering Socket4 (2 changes)
2016.06.14 22:50:58 5: Socket4: NETIO230_doTelnet() >item:port_wd_4 line:disable 0.0.0.0 9 60 3 3 disable disable
2016.06.14 22:50:58 5: Socket4: NETIO230_doTelnet() call: Net::Telnet=GLOB(0x2574a80)->print(system discover)
2016.06.14 22:50:58 5: Starting notify loop for Socket4, first event socket4: off
2016.06.14 22:50:58 5: Socket4: NETIO230_doTelnet() >item:system_discover line:enable
2016.06.14 22:50:58 5: Socket4: NETIO230_doTelnet() call: Net::Telnet=GLOB(0x2574a80)->print(system dns)
2016.06.14 22:50:58 5: Socket4: NETIO230_doTelnet() >item:system_dns line:192.168.178.1
2016.06.14 22:50:58 5: Socket4: NETIO230_doTelnet() call: Net::Telnet=GLOB(0x2574a80)->print(system dst)
2016.06.14 22:50:58 4: Socket4: get Socket4 state: off
2016.06.14 22:50:58 5: Socket4: NETIO230_httpRequestParse() PWDHASH:C8216A81
2016.06.14 22:50:58 5: Socket4: NETIO230_httpRequestParse() call: NETIO230_resetTimer(HASH(0x1d34d20),"start")
2016.06.14 22:50:58 5: Socket4: NETIO230_resetTimer() call: RemoveInternalTimer(HASH(0x1d34d20))
2016.06.14 22:50:58 5: Socket4: NETIO230_resetTimer() InternalTimer(+301.890333946363,"NETIO230_timedStatusRequest", $hash, 0)
2016.06.14 22:50:58 5: Socket4: NETIO230_doTelnet() >item:system_dst line:enabled 2012/03/25,01:00:00 - 2012/10/28,03:00:00
2016.06.14 22:50:58 5: Socket4: NETIO230_doTelnet() call: Net::Telnet=GLOB(0x2574a80)->print(system eth)
2016.06.14 22:50:58 5: Socket4: NETIO230_doTelnet() >item:system_eth line:manual 192.168.178.220 255.255.255.0 192.168.178.1
2016.06.14 22:50:58 5: Socket4: NETIO230_doTelnet() call: Net::Telnet=GLOB(0x2574a80)->print(system sntp)
2016.06.14 22:50:58 5: Socket4: NETIO230_doTelnet() >item:system_sntp line:enable pool.ntp.org synchronized
2016.06.14 22:50:58 5: Socket4: NETIO230_doTelnet() call: Net::Telnet=GLOB(0x2574a80)->print(system swdelay)
2016.06.14 22:50:58 5: Socket4: NETIO230_doTelnet() >item:system_swdelay line:2
2016.06.14 22:50:58 5: Socket4: NETIO230_doTelnet() call: Net::Telnet=GLOB(0x2574a80)->print(system time)
2016.06.14 22:50:58 5: Socket4: NETIO230_doTelnet() >item:system_time line:2016/06/14,21:50:57
2016.06.14 22:50:58 5: Socket4: NETIO230_doTelnet() call: Net::Telnet=GLOB(0x2574a80)->print(system timezone)
2016.06.14 22:50:58 5: Socket4: NETIO230_doTelnet() >item:system_timezone line:3600
2016.06.14 22:50:58 5: Socket4: NETIO230_doTelnet() call: Net::Telnet=GLOB(0x2574a80)->print(uptime)
2016.06.14 22:50:58 5: Socket4: NETIO230_doTelnet() >item:uptime line:0 years 1 days 1 hours 43 min 35 sec
2016.06.14 22:50:58 5: Socket4: NETIO230_doTelnet() call: Net::Telnet=GLOB(0x2574a80)->print(version)
2016.06.14 22:50:58 5: Socket4: NETIO230_doTelnet() >item:version line:V 4.05
2016.06.14 22:50:58 5: Socket4: NETIO230_doTelnet() got: hash:HASH(0x1d34d20), telnet:Net::Telnet=GLOB(0x2574a80), what:close, cmd:
2016.06.14 22:50:58 4: Connection accepted from telnetPort_127.0.0.1_36624
2016.06.14 22:50:58 5: Cmd: >{NETIO230_doTelnetRequest_Parse('Socket4||statusRequest||alias::"NETIO-230B"|discover::enable|dns::192.168.178.1|dst::enabled 2012/03/25,01:00:00 - 2012/10/28,03:00:00|eth::manual 192.168.178.220 255.255.255.0 192.168.178.1|firmware::V 4.05|ntp::enable pool.ntp.org synchronized|smtp::smtp.1blu.de|socket4_setup::"Drucker" manual 5 0|socket4_timer::once 1970/01/01,00:00:00 1970/01/01,00:00:00 0000000|socket4_wd::disable 0.0.0.0 9 60 3 3 disable disable|swdelay::2|time::2016/06/14,21:50:57|timezone::3600|uptime::0 years 1 days 1 hours 43 min 35 sec')}<
2016.06.14 22:50:58 5: Socket4: NETIO230_doTelnetRequest_Parse() got: Socket4||statusRequest||alias::"NETIO-230B"|discover::enable|dns::192.168.178.1|dst::enabled 2012/03/25,01:00:00 - 2012/10/28,03:00:00|eth::manual 192.168.178.220 255.255.255.0 192.168.178.1|firmware::V 4.05|ntp::enable pool.ntp.org synchronized|smtp::smtp.1blu.de|socket4_setup::"Drucker" manual 5 0|socket4_timer::once 1970/01/01,00:00:00 1970/01/01,00:00:00 0000000|socket4_wd::disable 0.0.0.0 9 60 3 3 disable disable|swdelay::2|time::2016/06/14,21:50:57|timezone::3600|uptime::0 years 1 days 1 hours 43 min 35 sec
2016.06.14 22:50:58 5: Socket4: NETIO230_doTelnetRequest_Parse() -> statusRequest
2016.06.14 22:50:58 5: Socket4: NETIO230_modifyReadings() reading:'alias' orgVal:'"NETIO-230B"' newVal:'"NETIO-230B"'
2016.06.14 22:50:58 5: Socket4: NETIO230_modifyReadings() reading:'discover' orgVal:'enable' newVal:'enable'
2016.06.14 22:50:58 5: Socket4: NETIO230_modifyReadings() reading:'dns' orgVal:'192.168.178.1' newVal:'192.168.178.1'
2016.06.14 22:50:58 5: Socket4: NETIO230_modifyReadings() reading:'dst' orgVal:'enabled 2012/03/25,01:00:00 - 2012/10/28,03:00:00' newVal:'enabled 2012/03/25,01:00:00 - 2012/10/28,03:00:00'
2016.06.14 22:50:58 5: Socket4: NETIO230_modifyReadings() reading:'eth' orgVal:'manual 192.168.178.220 255.255.255.0 192.168.178.1' newVal:'manual 192.168.178.220 255.255.255.0 192.168.178.1'
2016.06.14 22:50:58 5: Socket4: NETIO230_modifyReadings() reading:'firmware' orgVal:'V 4.05' newVal:'V 4.05'
2016.06.14 22:50:58 5: Socket4: NETIO230_modifyReadings() reading:'ntp' orgVal:'enable pool.ntp.org synchronized' newVal:'enable pool.ntp.org synchronized'
2016.06.14 22:50:58 5: Socket4: NETIO230_modifyReadings() reading:'smtp' orgVal:'smtp.1blu.de' newVal:'smtp.1blu.de'
2016.06.14 22:50:58 5: Socket4: NETIO230_modifyReadings() reading:'socket4_setup' orgVal:'"Drucker" manual 5 0' newVal:'"Drucker" manual 5 0'
2016.06.14 22:50:58 5: Socket4: NETIO230_modifyReadings() reading:'socket4_timer' orgVal:'once 1970/01/01,00:00:00 1970/01/01,00:00:00 0000000' newVal:'once 1970/01/01,00:00:00 1970/01/01,00:00:00 0000000'
2016.06.14 22:50:58 5: Socket4: NETIO230_modifyReadings() reading:'socket4_wd' orgVal:'disable 0.0.0.0 9 60 3 3 disable disable' newVal:'disable 0.0.0.0 9 60 3 3 disable disable'
2016.06.14 22:50:58 5: Socket4: NETIO230_modifyReadings() reading:'swdelay' orgVal:'2' newVal:'200ms'
2016.06.14 22:50:58 5: Socket4: NETIO230_modifyReadings() reading:'time' orgVal:'2016/06/14,21:50:57' newVal:'2016/06/14,21:50:57'
2016.06.14 22:50:58 5: Socket4: NETIO230_modifyReadings() reading:'timezone' orgVal:'3600' newVal:'UTC+1'
2016.06.14 22:50:58 5: Socket4: NETIO230_modifyReadings() reading:'uptime' orgVal:'0 years 1 days 1 hours 43 min 35 sec' newVal:'0 years 1 days 1 hours 43 min 35 sec'
2016.06.14 22:50:58 5: Triggering Socket4 (11 changes)
2016.06.14 22:50:58 5: Starting notify loop for Socket4, first event alias: "NETIO-230B"
2016.06.14 22:50:58 5: Triggering Socket4 (1 changes)
2016.06.14 22:50:58 5: Starting notify loop for Socket4, first event _lastNotice: time diff >2 min: -3601 sec
2016.06.14 22:50:58 2: Socket4: NOTICE: [time diff >2 min: -3601 sec]
2016.06.14 22:50:58 5: Triggering Socket4 (1 changes)
2016.06.14 22:50:58 5: Starting notify loop for Socket4, first event timediff: -3601
2016.06.14 22:51:01 4: Connection closed for WEB_192.168.178.68_64003: Die Verbindung wurde vom Kommunikationspartner zurückgesetzt
2016.06.14 22:51:01 4: WEB_192.168.178.68_64041 GET /fhem/FileLog_logWrapper?dev=Logfile&type=text&file=fhem-2016-06.log; BUFLEN:0

dev0

#3
19 Juni 2016, 09:35:32
Hast Du sichergestellt, dass keine andere Anwendung (Browser, NETIO App) zeitgleich auf das NETIO zugreift?
Erscheint die Fehlermeldung regelmäßig und reproduzierbar oder eher zufällig? Ist ein bestimmter Socket betroffen oder alle?
Wie verhält sich das Modul, wenn Du bei allen definierten Instanzen das Attribut secureLogin auf 0 setzt?

michaelapp

#4
19 Juni 2016, 11:01:22
Hallo dev0,

die Meldung kommt regelmäßig und so wie ich das gesehen habe nur beim Socket 2.

Im Log steht folgendes:

Code Auswählen
2016.06.19 10:58:08 2: SecurityCheck:  telnetPort has no associated allowed device with password/globalpassword.  Restart FHEM for a new check if the problem is fixed, or set the global attribute motd to none to supress this message.
2016.06.19 10:58:08 0: Featurelevel: 5.7
2016.06.19 10:58:08 0: Server started with 62 defined entities (fhem.pl:11655/2016-06-13 perl:5.020002 os:linux user:fhem pid:13049)
2016.06.19 10:58:08 3: CUL_HM set RL_FLUR_FEN statusRequest
2016.06.19 10:58:08 3: Socket4: set Socket4 statusRequest
2016.06.19 10:58:08 3: Socket1: set Socket1 statusRequest
2016.06.19 10:58:08 3: Socket3: set Socket3 statusRequest
2016.06.19 10:58:08 3: Socket2: set Socket2 statusRequest
2016.06.19 10:58:08 1: HMLAN_Parse: HMLAN1 new condition ok
2016.06.19 10:58:09 2: Socket2: ERROR: [failed: no telnet prompt] => Telnet connect failed. Please turn on your device, check for network, correct host address and telnet port.


Den secureLogin habe ich auf 0 gesetzt.

Gruß
Michael

dev0

#5
19 Juni 2016, 13:11:38 Letzte Bearbeitung: 20 Juni 2016, 07:36:19 von dev0
Benutzt Du die aktuelle github version?

Zitat von: michaelapp am 19 Juni 2016, 11:01:22
und so wie ich das gesehen habe nur beim Socket 2.
"Wie ich gesehen habe" ist etwas vage und reicht mir nicht.
Ist der letzte Logauszug mit oder ohne secureLogin?
Bitte auch den Rest beantworten.

Edit:
Zitat von: michaelapp am 19 Juni 2016, 11:01:22
2016.06.19 10:58:09 2: Socket2: ERROR: [failed: no telnet prompt] => Telnet connect failed. Please turn on your device, check for network,
Kann es sein, dass Deine Netzwerkverbindung etwas instabil ist? WLAN?
Drucken
Nach oben Seiten1
Benutzer-Aktionen