mycontroller-org / mycontroller-v1-legacy

The Open Source Controller
http://www.MyController.org
Apache License 2.0
148 stars 89 forks source link

JRE crash #241

Closed yd1234 closed 8 years ago

yd1234 commented 8 years ago

Hello, I'm trying last release of my controller.org and after fresh installation on my raspberry PI 2 running raspbian jessie and gateway configuration (serial type) I had a crash on JRE:

#
# A fatal error has been detected by the Java Runtime Environment:
#
#  SIGSEGV (0xb) at pc=0xb66988f8, pid=2127, tid=2882204768
#
# JRE version: Java(TM) SE Runtime Environment (8.0_65-b17) (build 1.8.0_65-b17)
# Java VM: Java HotSpot(TM) Client VM (25.65-b01 mixed mode linux-arm )
# Problematic frame:
# V  [libjvm.so+0x2e68f8]
#
# Failed to write core dump. Core dumps have been disabled. To enable core dumping, try "ulimit -c unlimited" before starting Java again
#
# If you would like to submit a bug report, please visit:
#   http://bugreport.java.com/bugreport/crash.jsp
#

---------------  T H R E A D  ---------------

Current thread (0x02237910):  JavaThread "Thread-10" [_thread_in_vm, id=2158, stack(0xab4b0000,0xabcb0000)]

siginfo: si_signo: 11 (SIGSEGV), si_code: 1 (SEGV_MAPERR), si_addr: 0x00000000

Registers:
  r0  = 0x02237910
  r1  = 0x02237c54
  r2  = 0x00000076
  r3  = 0xffffffff
  r4  = 0x02237910
  r5  = 0xffffffff
  r6  = 0x00000000
  r7  = 0xb694ffb8
  r8  = 0x00000000
  r9  = 0xb6698874
  r10 = 0xabf497b8
  fp  = 0xabcaed7c
  r12 = 0x0000deab
  sp  = 0xabcaed40
  lr  = 0xb66988f4
  pc  = 0xb66988f8
  cpsr = 0x80000010

Top of Stack: (sp=0xabcaed40)
0xabcaed40:   02237c54 02237910 00000000 02237910
0xabcaed50:   02237910 02237b68 00000076 b6923688
0xabcaed60:   00000020 00000020 abf499bc abcaedaf
0xabcaed70:   00000022 00000000 abcaee5c abf2b1f4
0xabcaed80:   abcaed88 00000000 abf497bc abf32f74
0xabcaed90:   abf499c0 abf450ec abcaedc0 abcaed88
0xabcaeda0:   abcaed80 abf32f10 00000000 00000000
0xabcaedb0:   00000001 00000000 00000020 00000000 

Instructions: (pc=0xb66988f8)
0xb66988d8:   e3a03000 e1520003 e50b402c e50b3028
0xb66988e8:   0a000001 e24b002c eb0507f0 e1953006
0xb66988f8:   e5980000 4a000003 e5903008 e0852006
0xb6698908:   e1520003 9a000014 e59f30b0 e59f10b0 

Register to memory mapping:

  r0  = 0x02237910
0x02237910 is a thread

  r1  = 0x02237c54
0x02237c54 is an unknown value

  r2  = 0x00000076
0x00000076 is an unknown value

  r3  = 0xffffffff
0xffffffff is an unknown value

  r4  = 0x02237910
0x02237910 is a thread

  r5  = 0xffffffff
0xffffffff is an unknown value

  r6  = 0x00000000
0x00000000 is an unknown value

  r7  = 0xb694ffb8
0xb694ffb8: <offset 0x59dfb8> in /usr/lib/jvm/jdk-8-oracle-arm32-vfp-hflt/jre/lib/arm/client/libjvm.so at 0xb63b2000

  r8  = 0x00000000
0x00000000 is an unknown value

  r9  = 0xb6698874
0xb6698874: <offset 0x2e6874> in /usr/lib/jvm/jdk-8-oracle-arm32-vfp-hflt/jre/lib/arm/client/libjvm.so at 0xb63b2000

  r10 = 0xabf497b8
0xabf497b8: serial_callback_method+0 in /tmp/libpi4j3539501130881317486.so at 0xabf23000

  fp  = 0xabcaed7c
0xabcaed7c is pointing into the stack for thread: 0x02237910

  r12 = 0x0000deab
0x0000deab is an unknown value

  sp  = 0xabcaed40
0xabcaed40 is pointing into the stack for thread: 0x02237910

  lr  = 0xb66988f4
0xb66988f4: <offset 0x2e68f4> in /usr/lib/jvm/jdk-8-oracle-arm32-vfp-hflt/jre/lib/arm/client/libjvm.so at 0xb63b2000

  pc  = 0xb66988f8
0xb66988f8: <offset 0x2e68f8> in /usr/lib/jvm/jdk-8-oracle-arm32-vfp-hflt/jre/lib/arm/client/libjvm.so at 0xb63b2000

Stack: [0xab4b0000,0xabcb0000],  sp=0xabcaed40,  free space=8187k
Native frames: (J=compiled Java code, j=interpreted, Vv=VM code, C=native code)
V  [libjvm.so+0x2e68f8]

---------------  P R O C E S S  ---------------

Java Threads: ( => current thread )
=>0x02237910 JavaThread "Thread-10" [_thread_in_vm, id=2158, stack(0xab4b0000,0xabcb0000)]
  0xb6105aa0 JavaThread "DestroyJavaVM" [_thread_blocked, id=2130, stack(0xb62e7000,0xb6337000)]
  0xaad8d260 JavaThread "Thread-9" [_thread_in_native, id=2179, stack(0xaa9b0000,0xaaa00000)]
  0xac3bf120 JavaThread "KeepAlive cleaner" daemon [_thread_blocked, id=2178, stack(0xaae11000,0xaae61000)]
  0xac3beaa8 JavaThread "Session cleaner" [_thread_blocked, id=2177, stack(0xaaea0000,0xaaef0000)]
  0xabe4f5e8 JavaThread "Quartz Scheduler Thread" [_thread_blocked, id=2170, stack(0xab0f0000,0xab140000)]
  0xabe490d8 JavaThread "Quartz_Scheduler_Worker-10" [_thread_blocked, id=2169, stack(0xab140000,0xab190000)]
  0xabe47c98 JavaThread "Quartz_Scheduler_Worker-9" [_thread_blocked, id=2168, stack(0xab190000,0xab1e0000)]
  0xabe46998 JavaThread "Quartz_Scheduler_Worker-8" [_thread_blocked, id=2167, stack(0xab1e0000,0xab230000)]
  0xabe456b8 JavaThread "Quartz_Scheduler_Worker-7" [_thread_blocked, id=2166, stack(0xab230000,0xab280000)]
  0xabe44250 JavaThread "Quartz_Scheduler_Worker-6" [_thread_blocked, id=2165, stack(0xab280000,0xab2d0000)]
  0xabe42f50 JavaThread "Quartz_Scheduler_Worker-5" [_thread_blocked, id=2164, stack(0xab2d0000,0xab320000)]
  0xabe41c88 JavaThread "Quartz_Scheduler_Worker-4" [_thread_blocked, id=2163, stack(0xab320000,0xab370000)]
  0xabe3c4f8 JavaThread "Quartz_Scheduler_Worker-3" [_thread_blocked, id=2162, stack(0xab370000,0xab3c0000)]
  0xabe3b148 JavaThread "Quartz_Scheduler_Worker-2" [_thread_blocked, id=2161, stack(0xab3c0000,0xab410000)]
  0xabe3cec8 JavaThread "Quartz_Scheduler_Worker-1" [_thread_blocked, id=2160, stack(0xab410000,0xab460000)]
  0xacd5ec10 JavaThread "Thread-7" [_thread_blocked, id=2159, stack(0xab460000,0xab4b0000)]
  0xacd6a008 JavaThread "nioEventLoopGroup-2-1" [_thread_in_native, id=2157, stack(0xabdb0000,0xabe00000)]
  0xac3db248 JavaThread "pool-1-thread-1" [_thread_blocked, id=2155, stack(0xac0b0000,0xac100000)]
  0xac365e00 JavaThread "Thread-4" [_thread_blocked, id=2154, stack(0xac410000,0xac460000)]
  0xac893960 JavaThread "JdbcPooledConnectionSource connection tester" daemon [_thread_blocked, id=2145, stack(0xac560000,0xac5b0000)]
  0xac821490 JavaThread "H2 Log Writer MYCONTROLLER" daemon [_thread_blocked, id=2144, stack(0xace1c000,0xace6c000)]
  0xac820fb0 JavaThread "H2 File Lock Watchdog /home/pi/mycontroller/conf/mycontroller.lock.db" daemon [_thread_blocked, id=2143, stack(0xac6b0000,0xac700000)]
  0xb6182188 JavaThread "Service Thread" daemon [_thread_blocked, id=2137, stack(0xad057000,0xad0a7000)]
  0xb617f098 JavaThread "C1 CompilerThread0" daemon [_thread_blocked, id=2136, stack(0xad0a7000,0xad127000)]
  0xb617dbc0 JavaThread "Signal Dispatcher" daemon [_thread_blocked, id=2135, stack(0xad127000,0xad177000)]
  0xb61613b8 JavaThread "Finalizer" daemon [_thread_blocked, id=2134, stack(0xad412000,0xad462000)]
  0xb615ffa8 JavaThread "Reference Handler" daemon [_thread_blocked, id=2133, stack(0xad462000,0xad4b2000)]

Other Threads:
  0xb615d1f8 VMThread [stack: 0xad4b2000,0xad532000] [id=2132]
  0xb6183c18 WatcherThread [stack: 0xacfd7000,0xad057000] [id=2138]

VM state:not at safepoint (normal execution)

VM Mutex/Monitor currently owned by a thread: None

Heap:
 def new generation   total 9728K, used 5340K [0xadc00000, 0xae680000, 0xafd40000)
  eden space 8704K,  61% used [0xadc00000, 0xae137148, 0xae480000)
  from space 1024K,   0% used [0xae480000, 0xae480000, 0xae580000)
  to   space 1024K,   0% used [0xae580000, 0xae580000, 0xae680000)
 tenured generation   total 21284K, used 12769K [0xafd40000, 0xb1209000, 0xb4000000)
   the space 21284K,  59% used [0xafd40000, 0xb09b86e0, 0xb09b8800, 0xb1209000)
 Metaspace       used 18142K, capacity 18309K, committed 18456K, reserved 18736K

Card table byte_map: [0xb404d000,0xb4080000] byte_map_base: 0xb3adf000

Polling page: 0xb6fa3000

CodeCache: size=32768Kb used=2713Kb max_used=2713Kb free=30054Kb
 bounds [0xb4100000, 0xb43a8000, 0xb6100000]
 total_blobs=1477 nmethods=1280 adapters=128
 compilation: enabled

Compilation events (10 events):
Event: 146.798 Thread 0xb617f098 1295             org.h2.jdbc.JdbcStatement::setExecutingStatement (39 bytes)
Event: 146.810 Thread 0xb617f098 nmethod 1295 0xb43a5e48 code [0xb43a5f30, 0xb43a6044]
Event: 161.647 Thread 0xb617f098 1296             sun.util.calendar.CalendarDate::getYear (5 bytes)
Event: 161.649 Thread 0xb617f098 nmethod 1296 0xb43a6108 code [0xb43a61e0, 0xb43a6268]
Event: 161.687 Thread 0xb617f098 1297             java.util.StringTokenizer::skipDelimiters (116 bytes)
Event: 161.707 Thread 0xb617f098 nmethod 1297 0xb43a62c8 code [0xb43a63f0, 0xb43a6728]
Event: 166.799 Thread 0xb617f098 1298             org.h2.index.PageDataCursor::getSearchRow (5 bytes)
Event: 166.800 Thread 0xb617f098 nmethod 1298 0xb43a6a48 code [0xb43a6b20, 0xb43a6ba8]
Event: 166.889 Thread 0xb617f098 1299             org.h2.expression.ValueExpression::getNull (7 bytes)
Event: 166.890 Thread 0xb617f098 nmethod 1299 0xb43a6c08 code [0xb43a6cf0, 0xb43a6d78]

GC Heap History (10 events):
Event: 103.038 GC heap before
{Heap before GC invocations=30 (full 4):
 def new generation   total 5760K, used 5120K [0xadc00000, 0xae240000, 0xafd40000)
  eden space 5120K, 100% used [0xadc00000, 0xae100000, 0xae100000)
  from space 640K,   0% used [0xae100000, 0xae100000, 0xae1a0000)
  to   space 640K,   0% used [0xae1a0000, 0xae1a0000, 0xae240000)
 tenured generation   total 12616K, used 7567K [0xafd40000, 0xb0992000, 0xb4000000)
   the space 12616K,  59% used [0xafd40000, 0xb04a3eb0, 0xb04a4000, 0xb0992000)
 Metaspace       used 16240K, capacity 16293K, committed 16408K, reserved 16688K
Event: 103.151 GC heap after
Heap after GC invocations=31 (full 4):
 def new generation   total 5760K, used 640K [0xadc00000, 0xae240000, 0xafd40000)
  eden space 5120K,   0% used [0xadc00000, 0xadc00000, 0xae100000)
  from space 640K, 100% used [0xae1a0000, 0xae240000, 0xae240000)
  to   space 640K,   0% used [0xae100000, 0xae100000, 0xae1a0000)
 tenured generation   total 12616K, used 7784K [0xafd40000, 0xb0992000, 0xb4000000)
   the space 12616K,  61% used [0xafd40000, 0xb04da180, 0xb04da200, 0xb0992000)
 Metaspace       used 16240K, capacity 16293K, committed 16408K, reserved 16688K
}
Event: 106.706 GC heap before
{Heap before GC invocations=31 (full 4):
 def new generation   total 5760K, used 5760K [0xadc00000, 0xae240000, 0xafd40000)
  eden space 5120K, 100% used [0xadc00000, 0xae100000, 0xae100000)
  from space 640K, 100% used [0xae1a0000, 0xae240000, 0xae240000)
  to   space 640K,   0% used [0xae100000, 0xae100000, 0xae1a0000)
 tenured generation   total 12616K, used 7784K [0xafd40000, 0xb0992000, 0xb4000000)
   the space 12616K,  61% used [0xafd40000, 0xb04da180, 0xb04da200, 0xb0992000)
 Metaspace       used 16371K, capacity 16421K, committed 16536K, reserved 16688K
Event: 106.934 GC heap after
Heap after GC invocations=32 (full 4):
 def new generation   total 5760K, used 640K [0xadc00000, 0xae240000, 0xafd40000)
  eden space 5120K,   0% used [0xadc00000, 0xadc00000, 0xae100000)
  from space 640K, 100% used [0xae100000, 0xae1a0000, 0xae1a0000)
  to   space 640K,   0% used [0xae1a0000, 0xae1a0000, 0xae240000)
 tenured generation   total 12616K, used 9058K [0xafd40000, 0xb0992000, 0xb4000000)
   the space 12616K,  71% used [0xafd40000, 0xb0618980, 0xb0618a00, 0xb0992000)
 Metaspace       used 16371K, capacity 16421K, committed 16536K, reserved 16688K
}
Event: 111.179 GC heap before
{Heap before GC invocations=32 (full 4):
 def new generation   total 5760K, used 5760K [0xadc00000, 0xae240000, 0xafd40000)
  eden space 5120K, 100% used [0xadc00000, 0xae100000, 0xae100000)
  from space 640K, 100% used [0xae100000, 0xae1a0000, 0xae1a0000)
  to   space 640K,   0% used [0xae1a0000, 0xae1a0000, 0xae240000)
 tenured generation   total 12616K, used 9058K [0xafd40000, 0xb0992000, 0xb4000000)
   the space 12616K,  71% used [0xafd40000, 0xb0618980, 0xb0618a00, 0xb0992000)
 Metaspace       used 16401K, capacity 16453K, committed 16536K, reserved 16688K
Event: 111.464 GC heap after
Heap after GC invocations=33 (full 4):
 def new generation   total 5760K, used 640K [0xadc00000, 0xae240000, 0xafd40000)
  eden space 5120K,   0% used [0xadc00000, 0xadc00000, 0xae100000)
  from space 640K, 100% used [0xae1a0000, 0xae240000, 0xae240000)
  to   space 640K,   0% used [0xae100000, 0xae100000, 0xae1a0000)
 tenured generation   total 12616K, used 10425K [0xafd40000, 0xb0992000, 0xb4000000)
   the space 12616K,  82% used [0xafd40000, 0xb076e7c8, 0xb076e800, 0xb0992000)
 Metaspace       used 16401K, capacity 16453K, committed 16536K, reserved 16688K
}
Event: 118.346 GC heap before
{Heap before GC invocations=33 (full 4):
 def new generation   total 5760K, used 5760K [0xadc00000, 0xae240000, 0xafd40000)
  eden space 5120K, 100% used [0xadc00000, 0xae100000, 0xae100000)
  from space 640K, 100% used [0xae1a0000, 0xae240000, 0xae240000)
  to   space 640K,   0% used [0xae100000, 0xae100000, 0xae1a0000)
 tenured generation   total 12616K, used 10425K [0xafd40000, 0xb0992000, 0xb4000000)
   the space 12616K,  82% used [0xafd40000, 0xb076e7c8, 0xb076e800, 0xb0992000)
 Metaspace       used 17624K, capacity 17788K, committed 17816K, reserved 18736K
Event: 118.596 GC heap after
Heap after GC invocations=34 (full 4):
 def new generation   total 5760K, used 640K [0xadc00000, 0xae240000, 0xafd40000)
  eden space 5120K,   0% used [0xadc00000, 0xadc00000, 0xae100000)
  from space 640K, 100% used [0xae100000, 0xae1a0000, 0xae1a0000)
  to   space 640K,   0% used [0xae1a0000, 0xae1a0000, 0xae240000)
 tenured generation   total 12616K, used 11916K [0xafd40000, 0xb0992000, 0xb4000000)
   the space 12616K,  94% used [0xafd40000, 0xb08e3370, 0xb08e3400, 0xb0992000)
 Metaspace       used 17624K, capacity 17788K, committed 17816K, reserved 18736K
}
Event: 122.738 GC heap before
{Heap before GC invocations=34 (full 4):
 def new generation   total 5760K, used 5760K [0xadc00000, 0xae240000, 0xafd40000)
  eden space 5120K, 100% used [0xadc00000, 0xae100000, 0xae100000)
  from space 640K, 100% used [0xae100000, 0xae1a0000, 0xae1a0000)
  to   space 640K,   0% used [0xae1a0000, 0xae1a0000, 0xae240000)
 tenured generation   total 12616K, used 11916K [0xafd40000, 0xb0992000, 0xb4000000)
   the space 12616K,  94% used [0xafd40000, 0xb08e3370, 0xb08e3400, 0xb0992000)
 Metaspace       used 17966K, capacity 18160K, committed 18200K, reserved 18736K
Event: 124.553 GC heap after
Heap after GC invocations=35 (full 5):
 def new generation   total 9728K, used 0K [0xadc00000, 0xae680000, 0xafd40000)
  eden space 8704K,   0% used [0xadc00000, 0xadc00000, 0xae480000)
  from space 1024K,   0% used [0xae480000, 0xae480000, 0xae580000)
  to   space 1024K,   0% used [0xae580000, 0xae580000, 0xae680000)
 tenured generation   total 21284K, used 12769K [0xafd40000, 0xb1209000, 0xb4000000)
   the space 21284K,  59% used [0xafd40000, 0xb09b86e0, 0xb09b8800, 0xb1209000)
 Metaspace       used 17936K, capacity 18115K, committed 18200K, reserved 18736K
}

Deoptimization events (0 events):
No events

Internal exceptions (10 events):
Event: 107.704 Thread 0xb6105aa0 Exception <a 'java/lang/ArrayIndexOutOfBoundsException'> (0xadcdedf8) thrown at [/HUDSON/workspace/8-2-build-glinux-arm-vfp-hflt/jdk8u65/4987/hotspot/src/share/vm/runtime/sharedRuntime.cpp, line 605]
Event: 107.793 Thread 0xb6105aa0 Exception <a 'java/lang/ArrayIndexOutOfBoundsException'> (0xadcfd580) thrown at [/HUDSON/workspace/8-2-build-glinux-arm-vfp-hflt/jdk8u65/4987/hotspot/src/share/vm/runtime/sharedRuntime.cpp, line 605]
Event: 108.047 Thread 0xb6105aa0 Exception <a 'java/lang/ArrayIndexOutOfBoundsException'> (0xadd47820) thrown at [/HUDSON/workspace/8-2-build-glinux-arm-vfp-hflt/jdk8u65/4987/hotspot/src/share/vm/runtime/sharedRuntime.cpp, line 605]
Event: 108.091 Thread 0xb6105aa0 Exception <a 'java/lang/ArrayIndexOutOfBoundsException'> (0xadd4bb60) thrown at [/HUDSON/workspace/8-2-build-glinux-arm-vfp-hflt/jdk8u65/4987/hotspot/src/share/vm/runtime/sharedRuntime.cpp, line 605]
Event: 108.305 Thread 0xb6105aa0 Exception <a 'java/lang/ArrayIndexOutOfBoundsException'> (0xadd860f8) thrown at [/HUDSON/workspace/8-2-build-glinux-arm-vfp-hflt/jdk8u65/4987/hotspot/src/share/vm/runtime/sharedRuntime.cpp, line 605]
Event: 108.322 Thread 0xb6105aa0 Exception <a 'java/lang/ArrayIndexOutOfBoundsException'> (0xadd897d8) thrown at [/HUDSON/workspace/8-2-build-glinux-arm-vfp-hflt/jdk8u65/4987/hotspot/src/share/vm/runtime/sharedRuntime.cpp, line 605]
Event: 117.281 Thread 0xb6105aa0 Exception <a 'java/lang/NoSuchFieldError': method resolution failed> (0xae040ab0) thrown at [/HUDSON/workspace/8-2-build-glinux-arm-vfp-hflt/jdk8u65/4987/hotspot/src/share/vm/prims/methodHandles.cpp, line 1146]
Event: 117.336 Thread 0xb6105aa0 Exception <a 'java/lang/NoSuchFieldError': method resolution failed> (0xae04cf98) thrown at [/HUDSON/workspace/8-2-build-glinux-arm-vfp-hflt/jdk8u65/4987/hotspot/src/share/vm/prims/methodHandles.cpp, line 1146]
Event: 121.913 Thread 0xb6105aa0 Exception <a 'java/lang/ClassNotFoundException': com/sun/net/ssl/internal/www/protocol/http/Handler> (0xadfc8140) thrown at [/HUDSON/workspace/8-2-build-glinux-arm-vfp-hflt/jdk8u65/4987/hotspot/src/share/vm/classfile/systemDictionary.cpp, line 210]
Event: 173.470 Thread 0x02237910 Exception <a 'java/lang/NegativeArraySizeException'> (0xae12fe68) thrown at [/HUDSON/workspace/8-2-build-glinux-arm-vfp-hflt/jdk8u65/4987/hotspot/src/share/vm/oops/typeArrayKlass.cpp, line 118]

Events (10 events):
Event: 127.981 loading class sun/security/ssl/InputRecord done
Event: 127.983 loading class sun/security/ssl/AppOutputStream
Event: 127.984 loading class sun/security/ssl/AppOutputStream done
Event: 127.985 loading class sun/security/ssl/OutputRecord
Event: 127.986 loading class sun/security/ssl/OutputRecord done
Event: 146.684 loading class com/j256/ormlite/stmt/query/IsNotNull
Event: 146.684 loading class com/j256/ormlite/stmt/query/IsNotNull done
Event: 173.468 Thread 0x02237910 Thread added: 0x02237910
Event: 173.469 loading class java/lang/NegativeArraySizeException
Event: 173.470 loading class java/lang/NegativeArraySizeException done

Dynamic libraries:
00008000-00009000 r-xp 00000000 b3:02 15202      /usr/lib/jvm/jdk-8-oracle-arm32-vfp-hflt/jre/bin/java
00010000-00011000 rw-p 00000000 b3:02 15202      /usr/lib/jvm/jdk-8-oracle-arm32-vfp-hflt/jre/bin/java
01fd5000-022d9000 rw-p 00000000 00:00 0          [heap]
aa9b0000-aa9b3000 ---p 00000000 00:00 0 
aa9b3000-aaa00000 rwxp 00000000 00:00 0          [stack:2179]
aaa00000-aaac0000 rw-p 00000000 00:00 0 
aaac0000-aab00000 ---p 00000000 00:00 0 
aab00000-aac00000 rw-p 00000000 00:00 0 
aac00000-aad00000 rw-p 00000000 00:00 0 
aad00000-aadbc000 rw-p 00000000 00:00 0 
aadbc000-aae00000 ---p 00000000 00:00 0 
aae11000-aae14000 ---p 00000000 00:00 0 
aae14000-aae61000 rwxp 00000000 00:00 0          [stack:2178]
aae61000-aae90000 r-xp 00000000 b3:02 15301      /usr/lib/jvm/jdk-8-oracle-arm32-vfp-hflt/jre/lib/arm/libsunec.so
aae90000-aae98000 ---p 0002f000 b3:02 15301      /usr/lib/jvm/jdk-8-oracle-arm32-vfp-hflt/jre/lib/arm/libsunec.so
aae98000-aae9b000 rw-p 0002f000 b3:02 15301      /usr/lib/jvm/jdk-8-oracle-arm32-vfp-hflt/jre/lib/arm/libsunec.so
aae9b000-aaea0000 rw-p 00000000 00:00 0 
aaea0000-aaea3000 ---p 00000000 00:00 0 
aaea3000-aaef0000 rwxp 00000000 00:00 0          [stack:2177]
aaef0000-aaff0000 rw-p 00000000 00:00 0 
aaff0000-ab0f0000 rw-p 00000000 00:00 0 
ab0f0000-ab0f3000 ---p 00000000 00:00 0 
ab0f3000-ab140000 rwxp 00000000 00:00 0          [stack:2170]
ab140000-ab143000 ---p 00000000 00:00 0 
ab143000-ab190000 rwxp 00000000 00:00 0          [stack:2169]
ab190000-ab193000 ---p 00000000 00:00 0 
ab193000-ab1e0000 rwxp 00000000 00:00 0          [stack:2168]
ab1e0000-ab1e3000 ---p 00000000 00:00 0 
ab1e3000-ab230000 rwxp 00000000 00:00 0          [stack:2167]
ab230000-ab233000 ---p 00000000 00:00 0 
ab233000-ab280000 rwxp 00000000 00:00 0          [stack:2166]
ab280000-ab283000 ---p 00000000 00:00 0 
ab283000-ab2d0000 rwxp 00000000 00:00 0          [stack:2165]
ab2d0000-ab2d3000 ---p 00000000 00:00 0 
ab2d3000-ab320000 rwxp 00000000 00:00 0          [stack:2164]
ab320000-ab323000 ---p 00000000 00:00 0 
ab323000-ab370000 rwxp 00000000 00:00 0          [stack:2163]
ab370000-ab373000 ---p 00000000 00:00 0 
ab373000-ab3c0000 rwxp 00000000 00:00 0          [stack:2162]
ab3c0000-ab3c3000 ---p 00000000 00:00 0 
ab3c3000-ab410000 rwxp 00000000 00:00 0          [stack:2161]
ab410000-ab413000 ---p 00000000 00:00 0 
ab413000-ab460000 rwxp 00000000 00:00 0          [stack:2160]
ab460000-ab463000 ---p 00000000 00:00 0 
ab463000-ab4b0000 rwxp 00000000 00:00 0          [stack:2159]
ab4b0000-ab4b3000 ---p 00000000 00:00 0 
ab4b3000-abcb0000 rwxp 00000000 00:00 0          [stack:2158]
abcb0000-abdb0000 rw-p 00000000 00:00 0 
abdb0000-abdb3000 ---p 00000000 00:00 0 
abdb3000-abe00000 rwxp 00000000 00:00 0          [stack:2157]
abe00000-abf00000 rw-p 00000000 00:00 0 
abf23000-abf35000 r-xp 00000000 b3:02 2355       /tmp/libpi4j3539501130881317486.so
abf35000-abf45000 ---p 00012000 b3:02 2355       /tmp/libpi4j3539501130881317486.so
abf45000-abf46000 rw-p 00012000 b3:02 2355       /tmp/libpi4j3539501130881317486.so
abf46000-ac0b0000 rw-p 00000000 00:00 0 
ac0b0000-ac0b3000 ---p 00000000 00:00 0 
ac0b3000-ac100000 rwxp 00000000 00:00 0          [stack:2155]
ac100000-ac200000 rw-p 00000000 00:00 0 
ac200000-ac300000 rw-p 00000000 00:00 0 
ac300000-ac3ff000 rw-p 00000000 00:00 0 
ac3ff000-ac400000 ---p 00000000 00:00 0 
ac410000-ac413000 ---p 00000000 00:00 0 
ac413000-ac460000 rwxp 00000000 00:00 0          [stack:2154]
ac460000-ac560000 rw-p 00000000 00:00 0 
ac560000-ac563000 ---p 00000000 00:00 0 
ac563000-ac5b0000 rwxp 00000000 00:00 0          [stack:2145]
ac5b0000-ac6b0000 rw-p 00000000 00:00 0 
ac6b0000-ac6b3000 ---p 00000000 00:00 0 
ac6b3000-ac700000 rwxp 00000000 00:00 0          [stack:2143]
ac700000-ac800000 rw-p 00000000 00:00 0 
ac800000-ac900000 rw-p 00000000 00:00 0 
ac91d000-aca3f000 rw-p 00000000 00:00 0 
aca3f000-acb3f000 rw-p 00000000 00:00 0 
acb3f000-acd00000 rw-p 00000000 00:00 0 
acd00000-acdff000 rw-p 00000000 00:00 0 
acdff000-ace00000 ---p 00000000 00:00 0 
ace06000-ace14000 r-xp 00000000 b3:02 15295      /usr/lib/jvm/jdk-8-oracle-arm32-vfp-hflt/jre/lib/arm/libnio.so
ace14000-ace1b000 ---p 0000e000 b3:02 15295      /usr/lib/jvm/jdk-8-oracle-arm32-vfp-hflt/jre/lib/arm/libnio.so
ace1b000-ace1c000 rw-p 0000d000 b3:02 15295      /usr/lib/jvm/jdk-8-oracle-arm32-vfp-hflt/jre/lib/arm/libnio.so
ace1c000-ace1f000 ---p 00000000 00:00 0 
ace1f000-ace6c000 rwxp 00000000 00:00 0          [stack:2144]
ace6c000-acea0000 rw-p 00000000 00:00 0 
acea0000-acfd7000 r--s 01204000 b3:02 268647     /home/pi/mycontroller/lib/mycontroller-dist-standalone-0.0.3.Alpha2-single.jar
acfd7000-acfd8000 ---p 00000000 00:00 0 
acfd8000-ad057000 rwxp 00000000 00:00 0          [stack:2138]
ad057000-ad05a000 ---p 00000000 00:00 0 
ad05a000-ad0a7000 rwxp 00000000 00:00 0          [stack:2137]
ad0a7000-ad0aa000 ---p 00000000 00:00 0 
ad0aa000-ad127000 rwxp 00000000 00:00 0          [stack:2136]
ad127000-ad12a000 ---p 00000000 00:00 0 
ad12a000-ad177000 rwxp 00000000 00:00 0          [stack:2135]
ad177000-ad300000 r--p 00000000 b3:02 19112      /usr/lib/locale/locale-archive
ad300000-ad35d000 rw-p 00000000 00:00 0 
ad35d000-ad400000 ---p 00000000 00:00 0 
ad400000-ad412000 r--s 00345000 b3:02 15243      /usr/lib/jvm/jdk-8-oracle-arm32-vfp-hflt/jre/lib/resources.jar
ad412000-ad415000 ---p 00000000 00:00 0 
ad415000-ad462000 rwxp 00000000 00:00 0          [stack:2134]
ad462000-ad465000 ---p 00000000 00:00 0 
ad465000-ad4b2000 rwxp 00000000 00:00 0          [stack:2133]
ad4b2000-ad4b3000 ---p 00000000 00:00 0 
ad4b3000-ad532000 rwxp 00000000 00:00 0          [stack:2132]
ad532000-ad581000 rw-p 00000000 00:00 0 
ad581000-ad759000 r--s 03d20000 b3:02 15244      /usr/lib/jvm/jdk-8-oracle-arm32-vfp-hflt/jre/lib/rt.jar
ad759000-adbfa000 rw-p 00000000 00:00 0 
adbfa000-adc00000 ---p 00000000 00:00 0 
adc00000-ae680000 rw-p 00000000 00:00 0 
ae680000-afd40000 ---p 00000000 00:00 0 
afd40000-b1209000 rw-p 00000000 00:00 0 
b1209000-b4000000 ---p 00000000 00:00 0 
b400f000-b4022000 r-xp 00000000 b3:02 15294      /usr/lib/jvm/jdk-8-oracle-arm32-vfp-hflt/jre/lib/arm/libnet.so
b4022000-b402a000 ---p 00013000 b3:02 15294      /usr/lib/jvm/jdk-8-oracle-arm32-vfp-hflt/jre/lib/arm/libnet.so
b402a000-b402b000 rw-p 00013000 b3:02 15294      /usr/lib/jvm/jdk-8-oracle-arm32-vfp-hflt/jre/lib/arm/libnet.so
b402b000-b4036000 rw-p 00000000 00:00 0 
b4036000-b404d000 ---p 00000000 00:00 0 
b404d000-b4053000 rw-p 00000000 00:00 0 
b4053000-b405d000 ---p 00000000 00:00 0 
b405d000-b4069000 rw-p 00000000 00:00 0 
b4069000-b407f000 ---p 00000000 00:00 0 
b407f000-b408b000 rw-p 00000000 00:00 0 
b408b000-b4100000 ---p 00000000 00:00 0 
b4100000-b43a8000 rwxp 00000000 00:00 0 
b43a8000-b6100000 ---p 00000000 00:00 0 
b6100000-b61f9000 rw-p 00000000 00:00 0 
b61f9000-b6200000 ---p 00000000 00:00 0 
b6200000-b6201000 rw-p 00000000 00:00 0 
b6201000-b6218000 r-xp 00000000 b3:02 15305      /usr/lib/jvm/jdk-8-oracle-arm32-vfp-hflt/jre/lib/arm/libzip.so
b6218000-b621f000 ---p 00017000 b3:02 15305      /usr/lib/jvm/jdk-8-oracle-arm32-vfp-hflt/jre/lib/arm/libzip.so
b621f000-b6220000 rw-p 00016000 b3:02 15305      /usr/lib/jvm/jdk-8-oracle-arm32-vfp-hflt/jre/lib/arm/libzip.so
b6220000-b622b000 r-xp 00000000 b3:02 10546      /lib/arm-linux-gnueabihf/libnss_files-2.19.so
b622b000-b623a000 ---p 0000b000 b3:02 10546      /lib/arm-linux-gnueabihf/libnss_files-2.19.so
b623a000-b623b000 r--p 0000a000 b3:02 10546      /lib/arm-linux-gnueabihf/libnss_files-2.19.so
b623b000-b623c000 rw-p 0000b000 b3:02 10546      /lib/arm-linux-gnueabihf/libnss_files-2.19.so
b623c000-b6245000 r-xp 00000000 b3:02 10575      /lib/arm-linux-gnueabihf/libnss_nis-2.19.so
b6245000-b6254000 ---p 00009000 b3:02 10575      /lib/arm-linux-gnueabihf/libnss_nis-2.19.so
b6254000-b6255000 r--p 00008000 b3:02 10575      /lib/arm-linux-gnueabihf/libnss_nis-2.19.so
b6255000-b6256000 rw-p 00009000 b3:02 10575      /lib/arm-linux-gnueabihf/libnss_nis-2.19.so
b6256000-b6267000 r-xp 00000000 b3:02 10336      /lib/arm-linux-gnueabihf/libnsl-2.19.so
b6267000-b6276000 ---p 00011000 b3:02 10336      /lib/arm-linux-gnueabihf/libnsl-2.19.so
b6276000-b6277000 r--p 00010000 b3:02 10336      /lib/arm-linux-gnueabihf/libnsl-2.19.so
b6277000-b6278000 rw-p 00011000 b3:02 10336      /lib/arm-linux-gnueabihf/libnsl-2.19.so
b6278000-b627a000 rw-p 00000000 00:00 0 
b627a000-b6281000 r-xp 00000000 b3:02 10344      /lib/arm-linux-gnueabihf/libnss_compat-2.19.so
b6281000-b6290000 ---p 00007000 b3:02 10344      /lib/arm-linux-gnueabihf/libnss_compat-2.19.so
b6290000-b6291000 r--p 00006000 b3:02 10344      /lib/arm-linux-gnueabihf/libnss_compat-2.19.so
b6291000-b6292000 rw-p 00007000 b3:02 10344      /lib/arm-linux-gnueabihf/libnss_compat-2.19.so
b6292000-b6298000 r--s 0003f000 b3:02 15324      /usr/lib/jvm/jdk-8-oracle-arm32-vfp-hflt/jre/lib/ext/sunjce_provider.jar
b6298000-b629c000 r--s 0003a000 b3:02 15325      /usr/lib/jvm/jdk-8-oracle-arm32-vfp-hflt/jre/lib/ext/sunpkcs11.jar
b629c000-b629f000 r--s 0001a000 b3:02 15232      /usr/lib/jvm/jdk-8-oracle-arm32-vfp-hflt/jre/lib/jce.jar
b629f000-b62a4000 r--s 00096000 b3:02 15235      /usr/lib/jvm/jdk-8-oracle-arm32-vfp-hflt/jre/lib/jsse.jar
b62a4000-b62c7000 r-xp 00000000 b3:02 15280      /usr/lib/jvm/jdk-8-oracle-arm32-vfp-hflt/jre/lib/arm/libjava.so
b62c7000-b62cf000 ---p 00023000 b3:02 15280      /usr/lib/jvm/jdk-8-oracle-arm32-vfp-hflt/jre/lib/arm/libjava.so
b62cf000-b62d0000 rw-p 00023000 b3:02 15280      /usr/lib/jvm/jdk-8-oracle-arm32-vfp-hflt/jre/lib/arm/libjava.so
b62d0000-b62d6000 r-xp 00000000 b3:02 10579      /lib/arm-linux-gnueabihf/librt-2.19.so
b62d6000-b62e5000 ---p 00006000 b3:02 10579      /lib/arm-linux-gnueabihf/librt-2.19.so
b62e5000-b62e6000 r--p 00005000 b3:02 10579      /lib/arm-linux-gnueabihf/librt-2.19.so
b62e6000-b62e7000 rw-p 00006000 b3:02 10579      /lib/arm-linux-gnueabihf/librt-2.19.so
b62e7000-b62ea000 ---p 00000000 00:00 0 
b62ea000-b6337000 rwxp 00000000 00:00 0          [stack:2130]
b6337000-b63a0000 r-xp 00000000 b3:02 9259       /lib/arm-linux-gnueabihf/libm-2.19.so
b63a0000-b63b0000 ---p 00069000 b3:02 9259       /lib/arm-linux-gnueabihf/libm-2.19.so
b63b0000-b63b1000 r--p 00069000 b3:02 9259       /lib/arm-linux-gnueabihf/libm-2.19.so
b63b1000-b63b2000 rw-p 0006a000 b3:02 9259       /lib/arm-linux-gnueabihf/libm-2.19.so
b63b2000-b6935000 r-xp 00000000 b3:02 15307      /usr/lib/jvm/jdk-8-oracle-arm32-vfp-hflt/jre/lib/arm/client/libjvm.so
b6935000-b693d000 ---p 00583000 b3:02 15307      /usr/lib/jvm/jdk-8-oracle-arm32-vfp-hflt/jre/lib/arm/client/libjvm.so
b693d000-b6969000 rw-p 00583000 b3:02 15307      /usr/lib/jvm/jdk-8-oracle-arm32-vfp-hflt/jre/lib/arm/client/libjvm.so
b6969000-b6d8c000 rw-p 00000000 00:00 0 
b6d8c000-b6da9000 r-xp 00000000 b3:02 3170       /lib/arm-linux-gnueabihf/libgcc_s.so.1
b6da9000-b6db8000 ---p 0001d000 b3:02 3170       /lib/arm-linux-gnueabihf/libgcc_s.so.1
b6db8000-b6db9000 rw-p 0001c000 b3:02 3170       /lib/arm-linux-gnueabihf/libgcc_s.so.1
b6db9000-b6ee4000 r-xp 00000000 b3:02 9255       /lib/arm-linux-gnueabihf/libc-2.19.so
b6ee4000-b6ef4000 ---p 0012b000 b3:02 9255       /lib/arm-linux-gnueabihf/libc-2.19.so
b6ef4000-b6ef6000 r--p 0012b000 b3:02 9255       /lib/arm-linux-gnueabihf/libc-2.19.so
b6ef6000-b6ef7000 rw-p 0012d000 b3:02 9255       /lib/arm-linux-gnueabihf/libc-2.19.so
b6ef7000-b6efa000 rw-p 00000000 00:00 0 
b6efa000-b6efc000 r-xp 00000000 b3:02 9258       /lib/arm-linux-gnueabihf/libdl-2.19.so
b6efc000-b6f0b000 ---p 00002000 b3:02 9258       /lib/arm-linux-gnueabihf/libdl-2.19.so
b6f0b000-b6f0c000 r--p 00001000 b3:02 9258       /lib/arm-linux-gnueabihf/libdl-2.19.so
b6f0c000-b6f0d000 rw-p 00002000 b3:02 9258       /lib/arm-linux-gnueabihf/libdl-2.19.so
b6f0d000-b6f21000 r-xp 00000000 b3:02 15308      /usr/lib/jvm/jdk-8-oracle-arm32-vfp-hflt/jre/lib/arm/jli/libjli.so
b6f21000-b6f29000 ---p 00014000 b3:02 15308      /usr/lib/jvm/jdk-8-oracle-arm32-vfp-hflt/jre/lib/arm/jli/libjli.so
b6f29000-b6f2a000 rw-p 00014000 b3:02 15308      /usr/lib/jvm/jdk-8-oracle-arm32-vfp-hflt/jre/lib/arm/jli/libjli.so
b6f2a000-b6f3e000 r-xp 00000000 b3:02 10689      /lib/arm-linux-gnueabihf/libpthread-2.19.so
b6f3e000-b6f4e000 ---p 00014000 b3:02 10689      /lib/arm-linux-gnueabihf/libpthread-2.19.so
b6f4e000-b6f4f000 r--p 00014000 b3:02 10689      /lib/arm-linux-gnueabihf/libpthread-2.19.so
b6f4f000-b6f50000 rw-p 00015000 b3:02 10689      /lib/arm-linux-gnueabihf/libpthread-2.19.so
b6f50000-b6f52000 rw-p 00000000 00:00 0 
b6f52000-b6f5b000 r-xp 00000000 b3:02 15304      /usr/lib/jvm/jdk-8-oracle-arm32-vfp-hflt/jre/lib/arm/libverify.so
b6f5b000-b6f63000 ---p 00009000 b3:02 15304      /usr/lib/jvm/jdk-8-oracle-arm32-vfp-hflt/jre/lib/arm/libverify.so
b6f63000-b6f64000 rw-p 00009000 b3:02 15304      /usr/lib/jvm/jdk-8-oracle-arm32-vfp-hflt/jre/lib/arm/libverify.so
b6f64000-b6f69000 r-xp 00000000 b3:02 11994      /usr/lib/arm-linux-gnueabihf/libarmmem.so
b6f69000-b6f78000 ---p 00005000 b3:02 11994      /usr/lib/arm-linux-gnueabihf/libarmmem.so
b6f78000-b6f79000 rw-p 00004000 b3:02 11994      /usr/lib/arm-linux-gnueabihf/libarmmem.so
b6f79000-b6f99000 r-xp 00000000 b3:02 9252       /lib/arm-linux-gnueabihf/ld-2.19.so
b6f99000-b6f9a000 r--s 00009000 b3:02 15323      /usr/lib/jvm/jdk-8-oracle-arm32-vfp-hflt/jre/lib/ext/sunec.jar
b6f9a000-b6f9b000 r--p 00000000 b3:02 182433     /usr/share/locale/en_GB/LC_MESSAGES/libc.mo
b6f9b000-b6fa3000 rw-s 00000000 b3:02 269262     /tmp/hsperfdata_root/2127
b6fa3000-b6fa4000 r--p 00000000 00:00 0 
b6fa4000-b6fa8000 rw-p 00000000 00:00 0 
b6fa8000-b6fa9000 r--p 0001f000 b3:02 9252       /lib/arm-linux-gnueabihf/ld-2.19.so
b6fa9000-b6faa000 rw-p 00020000 b3:02 9252       /lib/arm-linux-gnueabihf/ld-2.19.so
bec3e000-bec5f000 rwxp 00000000 00:00 0          [stack]
beea3000-beea4000 r-xp 00000000 00:00 0          [sigpage]
ffff0000-ffff1000 r-xp 00000000 00:00 0          [vectors]

VM Arguments:
jvm_args: -Xms8m -Xmx100m -XX:+HeapDumpOnOutOfMemoryError -Dlogback.configurationFile=../conf/logback.xml -Dmc.conf.file=../conf/mycontroller.properties 
java_command: org.mycontroller.standalone.StartApp
java_class_path (initial): ../lib/mycontroller-dist-standalone-0.0.3.Alpha2-single.jar
Launcher Type: SUN_STANDARD

Environment Variables:
PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin
USERNAME=root
SHELL=/bin/bash

Signal Handlers:
SIGSEGV: [libjvm.so+0x506638], sa_mask[0]=11111111011111111101111111111110, sa_flags=SA_RESTART|SA_SIGINFO
SIGBUS: [libjvm.so+0x506638], sa_mask[0]=11111111011111111101111111111110, sa_flags=SA_RESTART|SA_SIGINFO
SIGFPE: [libjvm.so+0x3fd4ec], sa_mask[0]=11111111011111111101111111111110, sa_flags=SA_RESTART|SA_SIGINFO
SIGPIPE: [libjvm.so+0x3fd4ec], sa_mask[0]=11111111011111111101111111111110, sa_flags=SA_RESTART|SA_SIGINFO
SIGXFSZ: [libjvm.so+0x3fd4ec], sa_mask[0]=11111111011111111101111111111110, sa_flags=SA_RESTART|SA_SIGINFO
SIGILL: [libjvm.so+0x3fd4ec], sa_mask[0]=11111111011111111101111111111110, sa_flags=SA_RESTART|SA_SIGINFO
SIGUSR1: SIG_DFL, sa_mask[0]=00000000000000000000000000000000, sa_flags=none
SIGUSR2: [libjvm.so+0x3fd650], sa_mask[0]=00000000000000000000000000000000, sa_flags=SA_RESTART|SA_SIGINFO
SIGHUP: [libjvm.so+0x3fda84], sa_mask[0]=11111111011111111101111111111110, sa_flags=SA_RESTART|SA_SIGINFO
SIGINT: SIG_IGN, sa_mask[0]=00000000000000000000000000000000, sa_flags=none
SIGTERM: [libjvm.so+0x3fda84], sa_mask[0]=11111111011111111101111111111110, sa_flags=SA_RESTART|SA_SIGINFO
SIGQUIT: [libjvm.so+0x3fda84], sa_mask[0]=11111111011111111101111111111110, sa_flags=SA_RESTART|SA_SIGINFO

---------------  S Y S T E M  ---------------

OS:PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)"
NAME="Raspbian GNU/Linux"
VERSION_ID="8"
VERSION="8 (jessie)"
ID=raspbian
ID_LIKE=debian
HOME_URL="http://www.raspbian.org/"
SUPPORT_URL="http://www.raspbian.org/RaspbianForums"
BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs"

uname:Linux 4.4.7+ #878 Tue Apr 19 18:59:39 BST 2016 armv6l
libc:glibc 2.19 NPTL 2.19 
rlimit: STACK 8192k, CORE 0k, NPROC 3411, NOFILE 65536, AS infinity
load average:2.59 2.06 1.85

/proc/meminfo:
MemTotal:         445204 kB
MemFree:           39348 kB
MemAvailable:     241620 kB
Buffers:           36192 kB
Cached:           188488 kB
SwapCached:            0 kB
Active:           255808 kB
Inactive:         122036 kB
Active(anon):     138400 kB
Inactive(anon):    20776 kB
Active(file):     117408 kB
Inactive(file):   101260 kB
Unevictable:           0 kB
Mlocked:               0 kB
SwapTotal:        102396 kB
SwapFree:         102396 kB
Dirty:               152 kB
Writeback:             0 kB
AnonPages:        153172 kB
Mapped:            81592 kB
Shmem:              6012 kB
Slab:              14436 kB
SReclaimable:       8164 kB
SUnreclaim:         6272 kB
KernelStack:        1720 kB
PageTables:         2672 kB
NFS_Unstable:          0 kB
Bounce:                0 kB
WritebackTmp:          0 kB
CommitLimit:      324996 kB
Committed_AS:     704008 kB
VmallocTotal:     573440 kB
VmallocUsed:           0 kB
VmallocChunk:          0 kB
CmaTotal:           8192 kB
CmaFree:            3740 kB

CPU:total 1 (ARMv6), vfp

/proc/cpuinfo:
processor   : 0
model name  : ARMv6-compatible processor rev 7 (v6l)
BogoMIPS    : 697.95
Features    : half thumb fastmult vfp edsp java tls 
CPU implementer : 0x41
CPU architecture: 7
CPU variant : 0x0
CPU part    : 0xb76
CPU revision    : 7

Hardware    : BCM2708
Revision    : 0010
Serial      : 0000000010c7fa98

Memory: 4k page, physical 445204k(39348k free), swap 102396k(102396k free)

vm_info: Java HotSpot(TM) Client VM (25.65-b01) for linux-arm-vfp-hflt JRE (1.8.0_65-b17), built on Oct  6 2015 16:19:04 by "java_re" with gcc 4.7.2 20120910 (prerelease)

time: Thu Aug 11 16:43:12 2016
elapsed time: 173 seconds (0d 0h 2m 53s)

Can you please help me? Thanks and regards.

jkandasa commented 8 years ago

@yd1234 What type of serial gateway are you using on Raspberry PI(RPI)?

yd1234 commented 8 years ago

Hi, I'm using an arduino with ATmega328 microprocessor and RFM69 radio connected to RPI UART Tx/Rx directly. The gateway I'm using is a board with arduino and radio as described above.

Regards

jkandasa commented 8 years ago

@yd1234 By default RPI serial port will be used for console service. You have to disable default settings for serial port.

You may follow this tutorial

yd1234 commented 8 years ago

It's already done on my RPI. When using minicom I can see messages sent and received by the gateway.

jkandasa commented 8 years ago

@yd1234 can you post mycontroller/logs/mycontroller.log? you can email me jkandasa at gmail dot com

yd1234 commented 8 years ago

Hi please find my log file

Thanks and regards.

Y. Diop

Le 11 août 2016 à 20:23, Jeeva Kandasamy notifications@github.com a écrit :

@yd1234 https://github.com/yd1234 can you post mycontroller/logs/mycontroller.log? you can email me jkandasa at gmail dot com

— You are receiving this because you were mentioned. Reply to this email directly, view it on GitHub https://github.com/mycontroller-org/mycontroller/issues/241#issuecomment-239246959, or mute the thread https://github.com/notifications/unsubscribe-auth/AF0t_f75tU4nz9XhDilLhO_x1lHKtaVUks5qe2iNgaJpZM4JiU2d.

yd1234 commented 8 years ago

Hi please find this other log

thanks and regard

Y. Diop

Le 11 août 2016 à 20:23, Jeeva Kandasamy notifications@github.com a écrit :

@yd1234 https://github.com/yd1234 can you post mycontroller/logs/mycontroller.log? you can email me jkandasa at gmail dot com

— You are receiving this because you were mentioned. Reply to this email directly, view it on GitHub https://github.com/mycontroller-org/mycontroller/issues/241#issuecomment-239246959, or mute the thread https://github.com/notifications/unsubscribe-auth/AF0t_f75tU4nz9XhDilLhO_x1lHKtaVUks5qe2iNgaJpZM4JiU2d.

jkandasa commented 8 years ago

@yd1234 Thank you for the log file. From your log I could not get what could be the exact problem on your environment. But few things I noted,

yd1234 commented 8 years ago

HI thanks for your quick reply.

Exception in thread "Thread-150" java.lang.NumberFormatException: For input string: ",pt=1,l=,pt=0,l==1,pt=0,t=4,l=4,MSG:READ2"
    at sun.misc.FloatingDecimal.readJavaFormatString(FloatingDecimal.java:2043)
    at sun.misc.FloatingDecimal.parseDouble(FloatingDecimal.java:110)
    at java.lang.Double.parseDouble(Double.java:538)
    at java.lang.Double.valueOf(Double.java:502)
    at org.mycontroller.standalone.utils.McUtils.getDouble(McUtils.java:95)
    at org.mycontroller.standalone.message.McMessageEngine.internalSubMessageTypeSelector(McMessageEngine.java:246)
    at org.mycontroller.standalone.message.McMessageEngine.execute(McMessageEngine.java:129)
    at org.mycontroller.standalone.message.McMessageEngine.run(McMessageEngine.java:962)
    at java.lang.Thread.run(Thread.java:745)
Exception in thread "Thread-161" java.lang.NumberFormatException: For input string: ",pt=1,l=,pt=0,l==1,pt=0,t=4,l=4,MSG:READ4"
    at sun.misc.FloatingDecimal.readJavaFormatString(FloatingDecimal.java:2043)
    at sun.misc.FloatingDecimal.parseDouble(FloatingDecimal.java:110)
    at java.lang.Double.parseDouble(Double.java:538)
    at java.lang.Double.valueOf(Double.java:502)
    at org.mycontroller.standalone.utils.McUtils.getDouble(McUtils.java:95)
    at org.mycontroller.standalone.message.McMessageEngine.internalSubMessageTypeSelector(McMessageEngine.java:246)
    at org.mycontroller.standalone.message.McMessageEngine.execute(McMessageEngine.java:129)
    at org.mycontroller.standalone.message.McMessageEngine.run(McMessageEngine.java:962)
    at java.lang.Thread.run(Thread.java:745)
Exception in thread "Thread-174" java.lang.NumberFormatException: For input string: "t=1,pt=0,pt=1,l=,pt=0,l==1,pt=0,t=4,l=4,MSG:READ6"
    at sun.misc.FloatingDecimal.readJavaFormatString(FloatingDecimal.java:2043)
    at sun.misc.FloatingDecimal.parseDouble(FloatingDecimal.java:110)
    at java.lang.Double.parseDouble(Double.java:538)
    at java.lang.Double.valueOf(Double.java:502)
    at org.mycontroller.standalone.utils.McUtils.getDouble(McUtils.java:95)
    at org.mycontroller.standalone.message.McMessageEngine.internalSubMessageTypeSelector(McMessageEngine.java:246)
    at org.mycontroller.standalone.message.McMessageEngine.execute(McMessageEngine.java:129)
    at org.mycontroller.standalone.message.McMessageEngine.run(McMessageEngine.java:962)
    at java.lang.Thread.run(Thread.java:745)
Exception in thread "Thread-186" java.lang.NumberFormatException: For input string: ",pt=1,l=,pt=0,l=0,ft=0,st=4,l=4,MSG:READ8"
    at sun.misc.FloatingDecimal.readJavaFormatString(FloatingDecimal.java:2043)
    at sun.misc.FloatingDecimal.parseDouble(FloatingDecimal.java:110)
    at java.lang.Double.parseDouble(Double.java:538)
    at java.lang.Double.valueOf(Double.java:502)
    at org.mycontroller.standalone.utils.McUtils.getDouble(McUtils.java:95)
    at org.mycontroller.standalone.message.McMessageEngine.internalSubMessageTypeSelector(McMessageEngine.java:246)
    at org.mycontroller.standalone.message.McMessageEngine.execute(McMessageEngine.java:129)
    at org.mycontroller.standalone.message.McMessageEngine.run(McMessageEngine.java:962)
    at java.lang.Thread.run(Thread.java:745)
Exception in thread "Thread-197" java.lang.NumberFormatException: For input string: ",pt=1,l=,pt=0,l==1,pt=0,t=4,l=4,MSG:READ20"
    at sun.misc.FloatingDecimal.readJavaFormatString(FloatingDecimal.java:2043)
    at sun.misc.FloatingDecimal.parseDouble(FloatingDecimal.java:110)
    at java.lang.Double.parseDouble(Double.java:538)
    at java.lang.Double.valueOf(Double.java:502)
    at org.mycontroller.standalone.utils.McUtils.getDouble(McUtils.java:95)
    at org.mycontroller.standalone.message.McMessageEngine.internalSubMessageTypeSelector(McMessageEngine.java:246)
    at org.mycontroller.standalone.message.McMessageEngine.execute(McMessageEngine.java:129)
    at org.mycontroller.standalone.message.McMessageEngine.run(McMessageEngine.java:962)
    at java.lang.Thread.run(Thread.java:745)
jkandasa commented 8 years ago

@yd1234 looks like you are sending node Battery level message with string payload like mentioned on the error, t=1,pt=0,pt=1,l=,pt=0,l==1,pt=0,t=4,l=4,MSG:READ6

To narrow down this issue could you please enable following debug (DEBUG) logs on MyController?

File name: mycontroller/conf/logback.xml

NOTE: You have to wait two minutes to get updated your changes or To get enable DEBUG immediately, you have to restart (stop/start) MyController

yd1234 commented 8 years ago

please find below the log content:

2016-08-13 13:04:57,165 INFO [main] [org.mycontroller.standalone.db.DataBaseUtils:154] Application information: [Version:0.0.3.Alpha2, Database version:1.02.10 - 2016 Jul 29]
2016-08-13 13:04:59,269 DEBUG [Thread-4] [org.mycontroller.standalone.message.MessageMonitorThread:82] MessageMonitorThread new thread started.
2016-08-13 13:04:59,292 DEBUG [Thread-4] [org.mycontroller.standalone.message.RawMessageQueue:44] Defined Queue Size:1000
2016-08-13 13:05:02,041 DEBUG [main] [org.mycontroller.standalone.gateway.serialport.SerialPortPi4jImpl:64] Serial port gateway initialized, GatewayTable[GatewaySerial(super=Gateway(id=1, enabled=true, name=MyGw, type=SERIAL, networkType=MY_SENSORS, timestamp=null, state=UNAVAILABLE, statusMessage=Yet to start this gateway!, statusSince=1471093500930), driver=AUTO, portName=/dev/ttyAMA0, baudRate=9600, retryFrequency=120, runningDriver=PI4J)]
2016-08-13 13:05:07,175 DEBUG [pi4j-single-executor-0] [org.mycontroller.standalone.gateway.serialport.SerialDataListenerPi4j:52] Received a message:[,pt=1,l=,pt=0,l==1,pt=0,t=4,l=4,MSG:READ6]
2016-08-13 13:05:07,221 DEBUG [pi4j-single-executor-0] [org.mycontroller.standalone.message.RawMessageQueue:50] Added new message, Queue size:1, Message:[RawMessage(gatewayId=1, data=,pt=1,l=,pt=0,l==1,pt=0,t=4,l=4,MSG:READ6, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 13:05:07,228 DEBUG [Thread-4] [org.mycontroller.standalone.message.RawMessageQueue:60] Removed a message, Queue size:0, Message:[RawMessage(gatewayId=1, data=,pt=1,l=,pt=0,l==1,pt=0,t=4,l=4,MSG:READ6, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 13:05:07,236 DEBUG [Thread-4] [org.mycontroller.standalone.message.MessageMonitorThread:61] Processing message:[RawMessage(gatewayId=1, data=,pt=1,l=,pt=0,l==1,pt=0,t=4,l=4,MSG:READ6, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 13:05:07,574 ERROR [Thread-4] [org.mycontroller.standalone.provider.mysensors.MySensorsProviderBridge:60] Unable to process this rawMessage:RawMessage(gatewayId=1, data=,pt=1,l=,pt=0,l==1,pt=0,t=4,l=4,MSG:READ6, subData=null, isTxMessage=false, networkType=MY_SENSORS)
org.mycontroller.standalone.message.RawMessageException: Unknown message format:[,pt=1,l=,pt=0,l==1,pt=0,t=4,l=4,MSG:READ6]
    at org.mycontroller.standalone.provider.mysensors.MySensorsRawMessage.updateSerialMessage(MySensorsRawMessage.java:135) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.provider.mysensors.MySensorsRawMessage.<init>(MySensorsRawMessage.java:66) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.provider.mysensors.MySensorsProviderBridge.executeRawMessage(MySensorsProviderBridge.java:58) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.message.McMessageUtils.sendToProviderBridge(McMessageUtils.java:536) [mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.message.MessageMonitorThread.processRawMessage(MessageMonitorThread.java:64) [mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.message.MessageMonitorThread.run(MessageMonitorThread.java:85) [mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at java.lang.Thread.run(Thread.java:745) [na:1.8.0_101]
2016-08-13 13:05:17,611 DEBUG [pi4j-single-executor-0] [org.mycontroller.standalone.gateway.serialport.SerialDataListenerPi4j:52] Received a message:[254;255;3;0;0;,pt=1,l=,pt=0,l==1,pt=0,t=4,l=4,MSG:READ2]
2016-08-13 13:05:17,618 DEBUG [pi4j-single-executor-0] [org.mycontroller.standalone.message.RawMessageQueue:50] Added new message, Queue size:1, Message:[RawMessage(gatewayId=1, data=254;255;3;0;0;,pt=1,l=,pt=0,l==1,pt=0,t=4,l=4,MSG:READ2, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 13:05:17,628 DEBUG [Thread-4] [org.mycontroller.standalone.message.RawMessageQueue:60] Removed a message, Queue size:0, Message:[RawMessage(gatewayId=1, data=254;255;3;0;0;,pt=1,l=,pt=0,l==1,pt=0,t=4,l=4,MSG:READ2, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 13:05:17,632 DEBUG [Thread-4] [org.mycontroller.standalone.message.MessageMonitorThread:61] Processing message:[RawMessage(gatewayId=1, data=254;255;3;0;0;,pt=1,l=,pt=0,l==1,pt=0,t=4,l=4,MSG:READ2, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 13:05:17,709 DEBUG [Thread-27] [org.mycontroller.standalone.message.McMessageEngine:98] McMessage:McMessage(gatewayId=1, nodeEui=254, sensorId=SENSOR_BC, type=C_INTERNAL, subType=Heartbeat, acknowledge=false, payload=, isTxMessage=true, networkType=MY_SENSORS, isScreeningDone=true, timestamp=1471093517711)
2016-08-13 13:05:17,820 DEBUG [Thread-27] [org.mycontroller.standalone.message.McMessageEngine:787] Node:[Node(id=16, eui=254, gatewayTable=GatewayTable(id=1, enabled=true, name=MyGw, networkType=MY_SENSORS, timestamp=null, state=UP, statusMessage=Connected Successfully, statusSince=1471093502047, type=SERIAL, properties={br=9600, rdr=pi4j, rf=120, dr=Auto, pn=/dev/ttyAMA0}), name=MyNode, version=null, type=S_ARDUINO_NODE, libVersion=null, batteryLevel=,c=3,t=0,pt=1,l=,c=3,t=1,pt=0,l==1,pt=0,=1,t=0,pt=4,l=4,MSG:READ=18,pt=055,c=3,t=18,pt=0,pt=1,l=,pt=0,l==1,pt=0,t=4,l=4,MSG:READ0, eraseConfig=null, firmware=null, state=UP, lastSeen=1471093434155, rssi=null, properties={}, parentNodeEui=null, registrationState=REGISTERED)], message:[McMessage(gatewayId=1, nodeEui=254, sensorId=SENSOR_BC, type=C_INTERNAL, subType=Heartbeat, acknowledge=false, payload=, isTxMessage=true, networkType=MY_SENSORS, isScreeningDone=true, timestamp=1471093517711)]
2016-08-13 13:05:17,977 DEBUG [Quartz_Scheduler_Worker-1] [org.mycontroller.standalone.message.McMessageUtils:523] Message sent to gateway, RawMessage(gatewayId=1, data=254;255;3;0;18;
, subData=null, isTxMessage=false, networkType=MY_SENSORS)
2016-08-13 13:05:18,021 DEBUG [Thread-28] [org.mycontroller.standalone.message.McMessageEngine:98] McMessage:McMessage(gatewayId=1, nodeEui=254, sensorId=SENSOR_BC, type=C_INTERNAL, subType=Battery level, acknowledge=true, payload=,pt=1,l=,pt=0,l==1,pt=0,t=4,l=4,MSG:READ2, isTxMessage=false, networkType=MY_SENSORS, isScreeningDone=true, timestamp=1471093518021)
2016-08-13 13:05:18,035 DEBUG [Thread-29] [org.mycontroller.standalone.message.McMessageEngine:98] McMessage:McMessage(gatewayId=1, nodeEui=NODE_BC, sensorId=SENSOR_BC, type=C_INTERNAL, subType=Heartbeat, acknowledge=false, payload=, isTxMessage=true, networkType=MY_SENSORS, isScreeningDone=true, timestamp=1471093518035)
2016-08-13 13:05:18,093 DEBUG [Quartz_Scheduler_Worker-1] [org.mycontroller.standalone.message.McMessageUtils:523] Message sent to gateway, RawMessage(gatewayId=1, data=255;255;3;0;18;
, subData=null, isTxMessage=false, networkType=MY_SENSORS)
2016-08-13 13:05:18,149 DEBUG [Thread-28] [org.mycontroller.standalone.message.McMessageEngine:787] Node:[Node(id=16, eui=254, gatewayTable=GatewayTable(id=1, enabled=true, name=MyGw, networkType=MY_SENSORS, timestamp=null, state=UP, statusMessage=Connected Successfully, statusSince=1471093502047, type=SERIAL, properties={br=9600, rdr=pi4j, rf=120, dr=Auto, pn=/dev/ttyAMA0}), name=MyNode, version=null, type=S_ARDUINO_NODE, libVersion=null, batteryLevel=,c=3,t=0,pt=1,l=,c=3,t=1,pt=0,l==1,pt=0,=1,t=0,pt=4,l=4,MSG:READ=18,pt=055,c=3,t=18,pt=0,pt=1,l=,pt=0,l==1,pt=0,t=4,l=4,MSG:READ0, eraseConfig=null, firmware=null, state=UP, lastSeen=1471093434155, rssi=null, properties={}, parentNodeEui=null, registrationState=REGISTERED)], message:[McMessage(gatewayId=1, nodeEui=254, sensorId=SENSOR_BC, type=C_INTERNAL, subType=Battery level, acknowledge=true, payload=,pt=1,l=,pt=0,l==1,pt=0,t=4,l=4,MSG:READ2, isTxMessage=false, networkType=MY_SENSORS, isScreeningDone=true, timestamp=1471093518021)]
2016-08-13 13:05:18,291 DEBUG [pi4j-single-executor-0] [org.mycontroller.standalone.gateway.serialport.SerialDataListenerPi4j:52] Received a message:[254;255;3;0;0;=18,pt=0;255;3;=4,sg=0::SEND 0-c:]
2016-08-13 13:05:18,294 DEBUG [pi4j-single-executor-0] [org.mycontroller.standalone.message.RawMessageQueue:50] Added new message, Queue size:1, Message:[RawMessage(gatewayId=1, data=254;255;3;0;0;=18,pt=0;255;3;=4,sg=0::SEND 0-c:, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 13:05:18,304 DEBUG [Thread-29] [org.mycontroller.standalone.message.McMessageEngine:230] Message Type:I_HEARTBEAT
2016-08-13 13:05:18,311 DEBUG [Thread-28] [org.mycontroller.standalone.message.McMessageEngine:230] Message Type:I_BATTERY_LEVEL
2016-08-13 13:05:18,320 DEBUG [Thread-27] [org.mycontroller.standalone.message.McMessageEngine:230] Message Type:I_HEARTBEAT
2016-08-13 13:05:18,324 DEBUG [Thread-4] [org.mycontroller.standalone.message.RawMessageQueue:60] Removed a message, Queue size:0, Message:[RawMessage(gatewayId=1, data=254;255;3;0;0;=18,pt=0;255;3;=4,sg=0::SEND 0-c:, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 13:05:18,359 DEBUG [Thread-28] [org.mycontroller.standalone.message.McMessageEngine:236] Battery Level:[nodeId:254,Level:,pt=1,l=,pt=0,l==1,pt=0,t=4,l=4,MSG:READ2%]
2016-08-13 13:05:18,360 DEBUG [Thread-4] [org.mycontroller.standalone.message.MessageMonitorThread:61] Processing message:[RawMessage(gatewayId=1, data=254;255;3;0;0;=18,pt=0;255;3;=4,sg=0::SEND 0-c:, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 13:05:18,380 DEBUG [Thread-35] [org.mycontroller.standalone.message.McMessageEngine:98] McMessage:McMessage(gatewayId=1, nodeEui=254, sensorId=SENSOR_BC, type=C_INTERNAL, subType=Battery level, acknowledge=true, payload=null, isTxMessage=false, networkType=MY_SENSORS, isScreeningDone=true, timestamp=1471093518381)
Exception in thread "Thread-28" java.lang.NumberFormatException: For input string: ",pt=1,l=,pt=0,l==1,pt=0,t=4,l=4,MSG:READ2"
    at sun.misc.FloatingDecimal.readJavaFormatString(FloatingDecimal.java:2043)
2016-08-13 13:05:18,469 DEBUG [Thread-35] [org.mycontroller.standalone.message.McMessageEngine:787] Node:[Node(id=16, eui=254, gatewayTable=GatewayTable(id=1, enabled=true, name=MyGw, networkType=MY_SENSORS, timestamp=null, state=UP, statusMessage=Connected Successfully, statusSince=1471093502047, type=SERIAL, properties={br=9600, rdr=pi4j, rf=120, dr=Auto, pn=/dev/ttyAMA0}), name=MyNode, version=null, type=S_ARDUINO_NODE, libVersion=null, batteryLevel=,pt=1,l=,pt=0,l==1,pt=0,t=4,l=4,MSG:READ2, eraseConfig=null, firmware=null, state=UP, lastSeen=1471093518368, rssi=null, properties={}, parentNodeEui=null, registrationState=REGISTERED)], message:[McMessage(gatewayId=1, nodeEui=254, sensorId=SENSOR_BC, type=C_INTERNAL, subType=Battery level, acknowledge=true, payload=null, isTxMessage=false, networkType=MY_SENSORS, isScreeningDone=true, timestamp=1471093518381)]
    at sun.misc.FloatingDecimal.parseDouble(FloatingDecimal.java:110)
    at java.lang.Double.parseDouble(Double.java:538)
    at java.lang.Double.valueOf(Double.java:502)
    at org.mycontroller.standalone.utils.McUtils.getDouble(McUtils.java:95)
    at org.mycontroller.standalone.message.McMessageEngine.internalSubMessageTypeSelector(McMessageEngine.java:246)
    at org.mycontroller.standalone.message.McMessageEngine.execute(McMessageEngine.java:129)
    at org.mycontroller.standalone.message.McMessageEngine.run(McMessageEngine.java:962)
    at java.lang.Thread.run(Thread.java:745)
2016-08-13 13:05:18,580 DEBUG [Thread-35] [org.mycontroller.standalone.message.McMessageEngine:230] Message Type:I_BATTERY_LEVEL
2016-08-13 13:05:18,583 DEBUG [Thread-35] [org.mycontroller.standalone.message.McMessageEngine:236] Battery Level:[nodeId:254,Level:null%]
2016-08-13 13:05:18,696 ERROR [Thread-35] [org.mycontroller.standalone.db.dao.BaseAbstractDaoImpl:187] unable to add new item:[MetricsBatteryUsage(node=Node(id=16, eui=254, gatewayTable=GatewayTable(id=1, enabled=true, name=MyGw, networkType=MY_SENSORS, timestamp=null, state=UP, statusMessage=Connected Successfully, statusSince=1471093502047, type=SERIAL, properties={br=9600, rdr=pi4j, rf=120, dr=Auto, pn=/dev/ttyAMA0}), name=MyNode, version=null, type=S_ARDUINO_NODE, libVersion=null, batteryLevel=null, eraseConfig=null, firmware=null, state=UP, lastSeen=1471093518599, rssi=null, properties={}, parentNodeEui=null, registrationState=REGISTERED), timestamp=1471093518632, samples=1, min=null, max=null, avg=null, aggregationType=RAW, timestampFrom=null, timestampTo=null)]
java.sql.SQLException: Unable to run insert stmt on object MetricsBatteryUsage(node=Node(id=16, eui=254, gatewayTable=GatewayTable(id=1, enabled=true, name=MyGw, networkType=MY_SENSORS, timestamp=null, state=UP, statusMessage=Connected Successfully, statusSince=1471093502047, type=SERIAL, properties={br=9600, rdr=pi4j, rf=120, dr=Auto, pn=/dev/ttyAMA0}), name=MyNode, version=null, type=S_ARDUINO_NODE, libVersion=null, batteryLevel=null, eraseConfig=null, firmware=null, state=UP, lastSeen=1471093518599, rssi=null, properties={}, parentNodeEui=null, registrationState=REGISTERED), timestamp=1471093518632, samples=1, min=null, max=null, avg=null, aggregationType=RAW, timestampFrom=null, timestampTo=null): INSERT INTO `metrics_battery_usage` (`nodeId` ,`timestamp` ,`samples` ,`min` ,`max` ,`avg` ,`aggregationType` ) VALUES (?,?,?,?,?,?,?)
    at com.j256.ormlite.misc.SqlExceptionUtil.create(SqlExceptionUtil.java:22) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at com.j256.ormlite.stmt.mapped.MappedCreate.insert(MappedCreate.java:135) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at com.j256.ormlite.stmt.StatementExecutor.create(StatementExecutor.java:450) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at com.j256.ormlite.dao.BaseDaoImpl.create(BaseDaoImpl.java:310) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.db.dao.BaseAbstractDaoImpl.create(BaseAbstractDaoImpl.java:184) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.message.McMessageEngine.internalSubMessageTypeSelector(McMessageEngine.java:250) [mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.message.McMessageEngine.execute(McMessageEngine.java:129) [mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.message.McMessageEngine.run(McMessageEngine.java:962) [mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at java.lang.Thread.run(Thread.java:745) [na:1.8.0_101]
Caused by: org.h2.jdbc.JdbcSQLException: NULL not allowed for column "AVG"; SQL statement:
INSERT INTO `metrics_battery_usage` (`nodeId` ,`timestamp` ,`samples` ,`min` ,`max` ,`avg` ,`aggregationType` ) VALUES (?,?,?,?,?,?,?) [23502-176]
    at org.h2.message.DbException.getJdbcSQLException(DbException.java:344) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.h2.message.DbException.get(DbException.java:178) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.h2.message.DbException.get(DbException.java:154) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.h2.table.Column.validateConvertUpdateSequence(Column.java:308) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.h2.table.Table.validateConvertUpdateSequence(Table.java:726) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.h2.command.dml.Insert.insertRows(Insert.java:152) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.h2.command.dml.Insert.update(Insert.java:115) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.h2.command.CommandContainer.update(CommandContainer.java:79) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.h2.command.Command.executeUpdate(Command.java:254) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.h2.jdbc.JdbcPreparedStatement.executeUpdateInternal(JdbcPreparedStatement.java:158) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.h2.jdbc.JdbcPreparedStatement.executeUpdate(JdbcPreparedStatement.java:144) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at com.j256.ormlite.jdbc.JdbcDatabaseConnection.insert(JdbcDatabaseConnection.java:170) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at com.j256.ormlite.stmt.mapped.MappedCreate.insert(MappedCreate.java:91) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    ... 7 common frames omitted
2016-08-13 13:05:18,785 DEBUG [Thread-35] [org.mycontroller.standalone.message.McMessageEngine:787] Node:[Node(id=16, eui=254, gatewayTable=GatewayTable(id=1, enabled=true, name=MyGw, networkType=MY_SENSORS, timestamp=null, state=UP, statusMessage=Connected Successfully, statusSince=1471093502047, type=SERIAL, properties={br=9600, rdr=pi4j, rf=120, dr=Auto, pn=/dev/ttyAMA0}), name=MyNode, version=null, type=S_ARDUINO_NODE, libVersion=null, batteryLevel=null, eraseConfig=null, firmware=null, state=UP, lastSeen=1471093518599, rssi=null, properties={}, parentNodeEui=null, registrationState=REGISTERED)], message:[McMessage(gatewayId=1, nodeEui=254, sensorId=SENSOR_BC, type=C_INTERNAL, subType=Battery level, acknowledge=true, payload=null, isTxMessage=false, networkType=MY_SENSORS, isScreeningDone=true, timestamp=1471093518381)]
2016-08-13 13:05:27,050 DEBUG [Quartz_Scheduler_Worker-4] [org.mycontroller.standalone.message.McActionEngine:276] Sending Node discover
2016-08-13 13:05:27,088 DEBUG [Thread-38] [org.mycontroller.standalone.message.McMessageEngine:98] McMessage:McMessage(gatewayId=1, nodeEui=NODE_BC, sensorId=SENSOR_BC, type=C_INTERNAL, subType=Discover, acknowledge=false, payload=, isTxMessage=true, networkType=MY_SENSORS, isScreeningDone=true, timestamp=1471093527089)
2016-08-13 13:05:27,116 DEBUG [Thread-38] [org.mycontroller.standalone.message.McMessageEngine:230] Message Type:I_DISCOVER
2016-08-13 13:05:27,123 DEBUG [Quartz_Scheduler_Worker-4] [org.mycontroller.standalone.message.McMessageUtils:523] Message sent to gateway, RawMessage(gatewayId=1, data=255;255;3;0;20;
, subData=null, isTxMessage=false, networkType=MY_SENSORS)
2016-08-13 13:05:28,292 INFO [main] [org.mycontroller.standalone.StartApp:209] TJWS server started successfully, HTTPS Enabled?:true, HTTP(S) Port: [8443]
2016-08-13 13:05:28,297 INFO [main] [org.mycontroller.standalone.StartApp:107] MyController.org server started in [52611] ms
2016-08-13 13:05:28,391 DEBUG [pi4j-single-executor-0] [org.mycontroller.standalone.gateway.serialport.SerialDataListenerPi4j:52] Received a message:[,pt=1,l==20,pt=01,pt=1,lEAD 254-55;3;0;1;]
2016-08-13 13:05:28,394 DEBUG [pi4j-single-executor-0] [org.mycontroller.standalone.message.RawMessageQueue:50] Added new message, Queue size:1, Message:[RawMessage(gatewayId=1, data=,pt=1,l==20,pt=01,pt=1,lEAD 254-55;3;0;1;, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 13:05:28,398 DEBUG [Thread-4] [org.mycontroller.standalone.message.RawMessageQueue:60] Removed a message, Queue size:0, Message:[RawMessage(gatewayId=1, data=,pt=1,l==20,pt=01,pt=1,lEAD 254-55;3;0;1;, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 13:05:28,401 DEBUG [Thread-4] [org.mycontroller.standalone.message.MessageMonitorThread:61] Processing message:[RawMessage(gatewayId=1, data=,pt=1,l==20,pt=01,pt=1,lEAD 254-55;3;0;1;, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 13:05:28,407 ERROR [Thread-4] [org.mycontroller.standalone.provider.mysensors.MySensorsProviderBridge:60] Unable to process this rawMessage:RawMessage(gatewayId=1, data=,pt=1,l==20,pt=01,pt=1,lEAD 254-55;3;0;1;, subData=null, isTxMessage=false, networkType=MY_SENSORS)
org.mycontroller.standalone.message.RawMessageException: Unknown message format:[,pt=1,l==20,pt=01,pt=1,lEAD 254-55;3;0;1;]
    at org.mycontroller.standalone.provider.mysensors.MySensorsRawMessage.updateSerialMessage(MySensorsRawMessage.java:135) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.provider.mysensors.MySensorsRawMessage.<init>(MySensorsRawMessage.java:66) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.provider.mysensors.MySensorsProviderBridge.executeRawMessage(MySensorsProviderBridge.java:58) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.message.McMessageUtils.sendToProviderBridge(McMessageUtils.java:536) [mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.message.MessageMonitorThread.processRawMessage(MessageMonitorThread.java:64) [mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.message.MessageMonitorThread.run(MessageMonitorThread.java:85) [mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at java.lang.Thread.run(Thread.java:745) [na:1.8.0_101]
2016-08-13 13:05:29,047 DEBUG [pi4j-single-executor-0] [org.mycontroller.standalone.gateway.serialport.SerialDataListenerPi4j:52] Received a message:[t=4,l=4,MSG:READ9]
2016-08-13 13:05:29,058 DEBUG [pi4j-single-executor-0] [org.mycontroller.standalone.message.RawMessageQueue:50] Added new message, Queue size:1, Message:[RawMessage(gatewayId=1, data=t=4,l=4,MSG:READ9, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 13:05:29,068 DEBUG [Thread-4] [org.mycontroller.standalone.message.RawMessageQueue:60] Removed a message, Queue size:0, Message:[RawMessage(gatewayId=1, data=t=4,l=4,MSG:READ9, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 13:05:29,079 DEBUG [Thread-4] [org.mycontroller.standalone.message.MessageMonitorThread:61] Processing message:[RawMessage(gatewayId=1, data=t=4,l=4,MSG:READ9, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 13:05:29,100 ERROR [Thread-4] [org.mycontroller.standalone.provider.mysensors.MySensorsProviderBridge:60] Unable to process this rawMessage:RawMessage(gatewayId=1, data=t=4,l=4,MSG:READ9, subData=null, isTxMessage=false, networkType=MY_SENSORS)
org.mycontroller.standalone.message.RawMessageException: Unknown message format:[t=4,l=4,MSG:READ9]
    at org.mycontroller.standalone.provider.mysensors.MySensorsRawMessage.updateSerialMessage(MySensorsRawMessage.java:135) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.provider.mysensors.MySensorsRawMessage.<init>(MySensorsRawMessage.java:66) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.provider.mysensors.MySensorsProviderBridge.executeRawMessage(MySensorsProviderBridge.java:58) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.message.McMessageUtils.sendToProviderBridge(McMessageUtils.java:536) [mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.message.MessageMonitorThread.processRawMessage(MessageMonitorThread.java:64) [mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.message.MessageMonitorThread.run(MessageMonitorThread.java:85) [mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at java.lang.Thread.run(Thread.java:745) [na:1.8.0_101]
2016-08-13 13:05:39,483 DEBUG [pi4j-single-executor-0] [org.mycontroller.standalone.gateway.serialport.SerialDataListenerPi4j:52] Received a message:[254;255;3;0;0;,pt=0,l==1,pt=0,t=4,l=4,MSG:READ6]
2016-08-13 13:05:39,496 DEBUG [pi4j-single-executor-0] [org.mycontroller.standalone.message.RawMessageQueue:50] Added new message, Queue size:1, Message:[RawMessage(gatewayId=1, data=254;255;3;0;0;,pt=0,l==1,pt=0,t=4,l=4,MSG:READ6, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 13:05:39,512 DEBUG [Thread-4] [org.mycontroller.standalone.message.RawMessageQueue:60] Removed a message, Queue size:0, Message:[RawMessage(gatewayId=1, data=254;255;3;0;0;,pt=0,l==1,pt=0,t=4,l=4,MSG:READ6, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 13:05:39,515 DEBUG [Thread-4] [org.mycontroller.standalone.message.MessageMonitorThread:61] Processing message:[RawMessage(gatewayId=1, data=254;255;3;0;0;,pt=0,l==1,pt=0,t=4,l=4,MSG:READ6, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 13:05:39,531 DEBUG [Thread-63] [org.mycontroller.standalone.message.McMessageEngine:98] McMessage:McMessage(gatewayId=1, nodeEui=254, sensorId=SENSOR_BC, type=C_INTERNAL, subType=Battery level, acknowledge=true, payload=,pt=0,l==1,pt=0,t=4,l=4,MSG:READ6, isTxMessage=false, networkType=MY_SENSORS, isScreeningDone=true, timestamp=1471093539531)
2016-08-13 13:05:39,689 DEBUG [Thread-63] [org.mycontroller.standalone.message.McMessageEngine:787] Node:[Node(id=16, eui=254, gatewayTable=GatewayTable(id=1, enabled=true, name=MyGw, networkType=MY_SENSORS, timestamp=null, state=UP, statusMessage=Connected Successfully, statusSince=1471093502047, type=SERIAL, properties={br=9600, rdr=pi4j, rf=120, dr=Auto, pn=/dev/ttyAMA0}), name=MyNode, version=null, type=S_ARDUINO_NODE, libVersion=null, batteryLevel=null, eraseConfig=null, firmware=null, state=UP, lastSeen=1471093518796, rssi=null, properties={}, parentNodeEui=null, registrationState=REGISTERED)], message:[McMessage(gatewayId=1, nodeEui=254, sensorId=SENSOR_BC, type=C_INTERNAL, subType=Battery level, acknowledge=true, payload=,pt=0,l==1,pt=0,t=4,l=4,MSG:READ6, isTxMessage=false, networkType=MY_SENSORS, isScreeningDone=true, timestamp=1471093539531)]
2016-08-13 13:05:39,776 DEBUG [Thread-63] [org.mycontroller.standalone.message.McMessageEngine:230] Message Type:I_BATTERY_LEVEL
2016-08-13 13:05:39,782 DEBUG [Thread-63] [org.mycontroller.standalone.message.McMessageEngine:236] Battery Level:[nodeId:254,Level:,pt=0,l==1,pt=0,t=4,l=4,MSG:READ6%]
Exception in thread "Thread-63" java.lang.NumberFormatException: For input string: ",pt=0,l==1,pt=0,t=4,l=4,MSG:READ6"
    at sun.misc.FloatingDecimal.readJavaFormatString(FloatingDecimal.java:2043)
    at sun.misc.FloatingDecimal.parseDouble(FloatingDecimal.java:110)
    at java.lang.Double.parseDouble(Double.java:538)
    at java.lang.Double.valueOf(Double.java:502)
    at org.mycontroller.standalone.utils.McUtils.getDouble(McUtils.java:95)
    at org.mycontroller.standalone.message.McMessageEngine.internalSubMessageTypeSelector(McMessageEngine.java:246)
    at org.mycontroller.standalone.message.McMessageEngine.execute(McMessageEngine.java:129)
    at org.mycontroller.standalone.message.McMessageEngine.run(McMessageEngine.java:962)
    at java.lang.Thread.run(Thread.java:745)
2016-08-13 13:06:00,354 DEBUG [pi4j-single-executor-0] [org.mycontroller.standalone.gateway.serialport.SerialDataListenerPi4j:52] Received a message:[254;255;3;0;0;,pt=1,l=,pt=0,l==1,pt=0,t=4,l=4,MSG:READ,c=3,t=0,pt=1,l=,pt=0,l==1,pt=0,t=4,l=4,MSG:READ2]
2016-08-13 13:06:00,358 DEBUG [pi4j-single-executor-0] [org.mycontroller.standalone.message.RawMessageQueue:50] Added new message, Queue size:1, Message:[RawMessage(gatewayId=1, data=254;255;3;0;0;,pt=1,l=,pt=0,l==1,pt=0,t=4,l=4,MSG:READ,c=3,t=0,pt=1,l=,pt=0,l==1,pt=0,t=4,l=4,MSG:READ2, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 13:06:00,362 DEBUG [Thread-4] [org.mycontroller.standalone.message.RawMessageQueue:60] Removed a message, Queue size:0, Message:[RawMessage(gatewayId=1, data=254;255;3;0;0;,pt=1,l=,pt=0,l==1,pt=0,t=4,l=4,MSG:READ,c=3,t=0,pt=1,l=,pt=0,l==1,pt=0,t=4,l=4,MSG:READ2, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 13:06:00,370 DEBUG [Thread-4] [org.mycontroller.standalone.message.MessageMonitorThread:61] Processing message:[RawMessage(gatewayId=1, data=254;255;3;0;0;,pt=1,l=,pt=0,l==1,pt=0,t=4,l=4,MSG:READ,c=3,t=0,pt=1,l=,pt=0,l==1,pt=0,t=4,l=4,MSG:READ2, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 13:06:00,389 DEBUG [Thread-85] [org.mycontroller.standalone.message.McMessageEngine:98] McMessage:McMessage(gatewayId=1, nodeEui=254, sensorId=SENSOR_BC, type=C_INTERNAL, subType=Battery level, acknowledge=true, payload=,pt=1,l=,pt=0,l==1,pt=0,t=4,l=4,MSG:READ,c=3,t=0,pt=1,l=,pt=0,l==1,pt=0,t=4,l=4,MSG:READ2, isTxMessage=false, networkType=MY_SENSORS, isScreeningDone=true, timestamp=1471093560389)
2016-08-13 13:06:00,414 DEBUG [Thread-85] [org.mycontroller.standalone.message.McMessageEngine:787] Node:[Node(id=16, eui=254, gatewayTable=GatewayTable(id=1, enabled=true, name=MyGw, networkType=MY_SENSORS, timestamp=null, state=UP, statusMessage=Connected Successfully, statusSince=1471093502047, type=SERIAL, properties={br=9600, rdr=pi4j, rf=120, dr=Auto, pn=/dev/ttyAMA0}), name=MyNode, version=null, type=S_ARDUINO_NODE, libVersion=null, batteryLevel=,pt=0,l==1,pt=0,t=4,l=4,MSG:READ6, eraseConfig=null, firmware=null, state=UP, lastSeen=1471093539785, rssi=null, properties={}, parentNodeEui=null, registrationState=REGISTERED)], message:[McMessage(gatewayId=1, nodeEui=254, sensorId=SENSOR_BC, type=C_INTERNAL, subType=Battery level, acknowledge=true, payload=,pt=1,l=,pt=0,l==1,pt=0,t=4,l=4,MSG:READ,c=3,t=0,pt=1,l=,pt=0,l==1,pt=0,t=4,l=4,MSG:READ2, isTxMessage=false, networkType=MY_SENSORS, isScreeningDone=true, timestamp=1471093560389)]
2016-08-13 13:06:00,441 DEBUG [Thread-85] [org.mycontroller.standalone.message.McMessageEngine:230] Message Type:I_BATTERY_LEVEL
2016-08-13 13:06:00,443 DEBUG [Thread-85] [org.mycontroller.standalone.message.McMessageEngine:236] Battery Level:[nodeId:254,Level:,pt=1,l=,pt=0,l==1,pt=0,t=4,l=4,MSG:READ,c=3,t=0,pt=1,l=,pt=0,l==1,pt=0,t=4,l=4,MSG:READ2%]
Exception in thread "Thread-85" java.lang.NumberFormatException: For input string: ",pt=1,l=,pt=0,l==1,pt=0,t=4,l=4,MSG:READ,c=3,t=0,pt=1,l=,pt=0,l==1,pt=0,t=4,l=4,MSG:READ2"
    at sun.misc.FloatingDecimal.readJavaFormatString(FloatingDecimal.java:2043)
    at sun.misc.FloatingDecimal.parseDouble(FloatingDecimal.java:110)
    at java.lang.Double.parseDouble(Double.java:538)
    at java.lang.Double.valueOf(Double.java:502)
    at org.mycontroller.standalone.utils.McUtils.getDouble(McUtils.java:95)
    at org.mycontroller.standalone.message.McMessageEngine.internalSubMessageTypeSelector(McMessageEngine.java:246)
    at org.mycontroller.standalone.message.McMessageEngine.execute(McMessageEngine.java:129)
    at org.mycontroller.standalone.message.McMessageEngine.run(McMessageEngine.java:962)
    at java.lang.Thread.run(Thread.java:745)
jkandasa commented 8 years ago

@yd1234 You have received a invalid message from your MySensors node.

2016-08-13 13:05:17,611 DEBUG [pi4j-single-executor-0] [org.mycontroller.standalone.gateway.serialport.SerialDataListenerPi4j:52] Received a message:[254;255;3;0;0;,pt=1,l=,pt=0,l==1,pt=0,t=4,l=4,MSG:READ2]

254;255;3;0;0;,pt=1,l=,pt=0,l==1,pt=0,t=4,l=4,MSG:READ2 It says,

Which version of MySensor firmware are you using? Right now I do not have any node with battery level to test it locally.

Still I couldn't guess problem with MySensors or with MyController. Could you please do one more debug? Stop MyController. watch your serial gateway on serial port directly and report the result here.

Thank you for everything!

yd1234 commented 8 years ago

I'm using 2.0.0 release of mysensors. Here an example of serial port using minicom:

;255;3;0;9;TSP:SG:READ 254-2540 s=255,c=3,t=11,pt=0,l14,sg=0:MockMySensors 
54;255;3;0;11;MockMySensors 
0;255;3;0;9;TSP:MSG:READ 254-254-0 s=255,c=3,t=12,pt=0,l=4,sg=0:v0.5
254;255;3;0;12;v0.5
0;255;3;0;9;TSP:SANCHK:OK
0;255;3;0;9;TSP:MSG:READ 254-254-0 s=7,c=0,t=6,pt=0,l=16,sg=0:House Temperarue
254;7;0;0;6;House Temperarue
0;255;3;0;9;TSP:MSG:READ 254-254-0 s=255,c=3,t=26,pt=1,l=1,sg=0:2
0;255;3;0;9;TSP:MSG:SEND 0-0-254-254 s=255,c=3,t=27,pt=1,l=1,sg=0,ft=0,st=ok:1
0;255;3;0;9;TSP:MSG:READ 254-254-0 s=255,c=3,t=0,pt=1,l=1,sg=0:43
254;255;3;0;0;43
0;255;3;0;9;TSP:MSG:READ 254-254-0 s=255,c=3,t=1,pt=0,l=0,sg=0:
254;255;3;0;1;
0;255;3;0;9;TSP:MSG:READ 254-254-0 s=7,c=1,t=0,pt=4,l=4,sg=0:19
254;7;1;0;0;19
0;255;3;0;9;TSP:MSG:READ 254-254-0 s=255,c=3,t=0,pt=1,l=1,sg=0:43
254;255;3;0;0;43
0;255;3;0;9;TSP:MSG:READ 254-254-0 s=255,c=3,t=0,pt=1,l=1,sg=0:51
254;255;3;0;0;51
0;255;3;0;9;TSP:MSG:READ 254-254-0 s=255,c=3,t=1,pt=0,l=0,sg=0:
254;255;3;0;1;
0;255;3;0;9;TSP:MSG:READ 254-254-0 s=7,c=1,t=0,pt=4,l=4,sg=0:22
254;7;1;0;0;22
0;255;3;0;9;TSP:MSG:READ 254-254-0 s=255,c=3,t=0,pt=1,l=1,sg=0:51
254;255;3;0;0;51
0;255;3;0;9;TSP:MSG:READ 254-254-0 s=255,c=3,t=0,pt=1,l=1,sg=0:87
254;255;3;0;0;87
0;255;3;0;9;TSP:MSG:READ 254-254-0 s=255,c=3,t=1,pt=0,l=0,sg=0:
254;255;3;0;1;
0;255;3;0;9;TSP:MSG:READ 254-254-0 s=7,c=1,t=0,pt=4,l=4,sg=0:39
254;7;1;0;0;39
0;255;3;0;9;TSP:MSG:READ 254-254-0 s=255,c=3,t=0,pt=1,l=1,sg=0:87
254;255;3;0;0;87
jkandasa commented 8 years ago

@yd1234 Thank you for the report. Problem with MyController. I will find the root case and fix it soon.

Thank you so much!

jkandasa commented 8 years ago

@yd1234 Can you change serial driver from Auto to jSerialComm? And check do you face this issue?

If possible can you come on GITTER chat?

yd1234 commented 8 years ago

When changing to JSerialComm, then new nodes automatically appears in nodes pages (node id 23, 24 and 0), same for sensors page (one sensor with nodded 24, sensorid 7) I got the battery status but no temperature data. Log below:

2016-08-13 14:46:06,446 DEBUG [Thread-4] [org.mycontroller.standalone.message.RawMessageQueue:60] Removed a message, Queue size:0, Message:[RawMessage(gatewayId=1, data=254;255;3;0;0;9, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 14:46:06,449 DEBUG [Thread-4] [org.mycontroller.standalone.message.MessageMonitorThread:61] Processing message:[RawMessage(gatewayId=1, data=254;255;3;0;0;9, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 14:46:06,453 DEBUG [Thread-90] [org.mycontroller.standalone.message.McMessageEngine:98] McMessage:McMessage(gatewayId=1, nodeEui=254, sensorId=SENSOR_BC, type=C_INTERNAL, subType=Battery level, acknowledge=true, payload=9, isTxMessage=false, networkType=MY_SENSORS, isScreeningDone=true, timestamp=1471099566454)
2016-08-13 14:46:06,474 DEBUG [Thread-90] [org.mycontroller.standalone.message.McMessageEngine:787] Node:[Node(id=16, eui=254, gatewayTable=GatewayTable(id=1, enabled=true, name=MyGw, networkType=MY_SENSORS, timestamp=null, state=UP, statusMessage=Connected Successfully, statusSince=1471099169841, type=SERIAL, properties={br=9600, rdr=jSerialComm, rf=120, dr=jSerialComm, pn=/dev/ttyAMA0}), name=MyNode, version=null, type=S_ARDUINO_NODE, libVersion=null, batteryLevel=0, eraseConfig=null, firmware=null, state=UP, lastSeen=1471099554779, rssi=null, properties={}, parentNodeEui=null, registrationState=REGISTERED)], message:[McMessage(gatewayId=1, nodeEui=254, sensorId=SENSOR_BC, type=C_INTERNAL, subType=Battery level, acknowledge=true, payload=9, isTxMessage=false, networkType=MY_SENSORS, isScreeningDone=true, timestamp=1471099566454)]
2016-08-13 14:46:06,495 DEBUG [Thread-90] [org.mycontroller.standalone.message.McMessageEngine:230] Message Type:I_BATTERY_LEVEL
2016-08-13 14:46:06,497 DEBUG [Thread-90] [org.mycontroller.standalone.message.McMessageEngine:236] Battery Level:[nodeId:254,Level:9%]
2016-08-13 14:46:06,539 DEBUG [Thread-90] [org.mycontroller.standalone.message.McMessageEngine:787] Node:[Node(id=16, eui=254, gatewayTable=GatewayTable(id=1, enabled=true, name=MyGw, networkType=MY_SENSORS, timestamp=null, state=UP, statusMessage=Connected Successfully, statusSince=1471099169841, type=SERIAL, properties={br=9600, rdr=jSerialComm, rf=120, dr=jSerialComm, pn=/dev/ttyAMA0}), name=MyNode, version=null, type=S_ARDUINO_NODE, libVersion=null, batteryLevel=9, eraseConfig=null, firmware=null, state=UP, lastSeen=1471099566499, rssi=null, properties={}, parentNodeEui=null, registrationState=REGISTERED)], message:[McMessage(gatewayId=1, nodeEui=254, sensorId=SENSOR_BC, type=C_INTERNAL, subType=Battery level, acknowledge=true, payload=9, isTxMessage=false, networkType=MY_SENSORS, isScreeningDone=true, timestamp=1471099566454)]
2016-08-13 14:46:15,551 DEBUG [Thread-42] [org.mycontroller.standalone.gateway.serialport.SerialDataListenerjSerialComm:64] Received a message:[;255;3;0;9;TSP:SG:READ 254-2540 s=255,c=3,t=0pt=1,l=1,sg=0:7]
2016-08-13 14:46:15,553 DEBUG [Thread-42] [org.mycontroller.standalone.message.RawMessageQueue:50] Added new message, Queue size:1, Message:[RawMessage(gatewayId=1, data=;255;3;0;9;TSP:SG:READ 254-2540 s=255,c=3,t=0pt=1,l=1,sg=0:7, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 14:46:15,556 DEBUG [Thread-4] [org.mycontroller.standalone.message.RawMessageQueue:60] Removed a message, Queue size:0, Message:[RawMessage(gatewayId=1, data=;255;3;0;9;TSP:SG:READ 254-2540 s=255,c=3,t=0pt=1,l=1,sg=0:7, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 14:46:15,566 DEBUG [Thread-4] [org.mycontroller.standalone.message.MessageMonitorThread:61] Processing message:[RawMessage(gatewayId=1, data=;255;3;0;9;TSP:SG:READ 254-2540 s=255,c=3,t=0pt=1,l=1,sg=0:7, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 14:46:15,568 DEBUG [Thread-42] [org.mycontroller.standalone.gateway.serialport.SerialDataListenerjSerialComm:64] Received a message:[254;2574]
2016-08-13 14:46:15,572 DEBUG [Thread-42] [org.mycontroller.standalone.message.RawMessageQueue:50] Added new message, Queue size:1, Message:[RawMessage(gatewayId=1, data=254;2574, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 14:46:15,576 ERROR [Thread-4] [org.mycontroller.standalone.message.MessageMonitorThread:70] RawMessage[RawMessage(gatewayId=1, data=;255;3;0;9;TSP:SG:READ 254-2540 s=255,c=3,t=0pt=1,l=1,sg=0:7, subData=null, isTxMessage=false, networkType=MY_SENSORS)] throws exception while processing!, 
java.lang.NumberFormatException: For input string: ""
    at java.lang.NumberFormatException.forInputString(NumberFormatException.java:65) ~[na:1.8.0_101]
    at java.lang.Integer.parseInt(Integer.java:592) ~[na:1.8.0_101]
    at java.lang.Integer.valueOf(Integer.java:766) ~[na:1.8.0_101]
    at org.mycontroller.standalone.provider.mysensors.MySensorsRawMessage.updateSerialMessage(MySensorsRawMessage.java:127) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.provider.mysensors.MySensorsRawMessage.<init>(MySensorsRawMessage.java:66) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.provider.mysensors.MySensorsProviderBridge.executeRawMessage(MySensorsProviderBridge.java:58) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.message.McMessageUtils.sendToProviderBridge(McMessageUtils.java:536) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.message.MessageMonitorThread.processRawMessage(MessageMonitorThread.java:64) [mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.message.MessageMonitorThread.run(MessageMonitorThread.java:85) [mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at java.lang.Thread.run(Thread.java:745) [na:1.8.0_101]
2016-08-13 14:46:15,585 DEBUG [Thread-4] [org.mycontroller.standalone.message.RawMessageQueue:60] Removed a message, Queue size:0, Message:[RawMessage(gatewayId=1, data=254;2574, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 14:46:15,587 DEBUG [Thread-4] [org.mycontroller.standalone.message.MessageMonitorThread:61] Processing message:[RawMessage(gatewayId=1, data=254;2574, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 14:46:15,591 ERROR [Thread-4] [org.mycontroller.standalone.provider.mysensors.MySensorsProviderBridge:60] Unable to process this rawMessage:RawMessage(gatewayId=1, data=254;2574, subData=null, isTxMessage=false, networkType=MY_SENSORS)
org.mycontroller.standalone.message.RawMessageException: Unknown message format:[254;2574]
    at org.mycontroller.standalone.provider.mysensors.MySensorsRawMessage.updateSerialMessage(MySensorsRawMessage.java:135) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.provider.mysensors.MySensorsRawMessage.<init>(MySensorsRawMessage.java:66) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.provider.mysensors.MySensorsProviderBridge.executeRawMessage(MySensorsProviderBridge.java:58) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.message.McMessageUtils.sendToProviderBridge(McMessageUtils.java:536) [mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.message.MessageMonitorThread.processRawMessage(MessageMonitorThread.java:64) [mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.message.MessageMonitorThread.run(MessageMonitorThread.java:85) [mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at java.lang.Thread.run(Thread.java:745) [na:1.8.0_101]
2016-08-13 14:46:16,179 DEBUG [Thread-42] [org.mycontroller.standalone.gateway.serialport.SerialDataListenerjSerialComm:64] Received a message:[;255;3;0;9;TSP:SG:READ 254-2540 s=255,c=3,t=1pt=0,l=0,sg=0:]
2016-08-13 14:46:16,182 DEBUG [Thread-42] [org.mycontroller.standalone.message.RawMessageQueue:50] Added new message, Queue size:1, Message:[RawMessage(gatewayId=1, data=;255;3;0;9;TSP:SG:READ 254-2540 s=255,c=3,t=1pt=0,l=0,sg=0:, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 14:46:16,189 DEBUG [Thread-4] [org.mycontroller.standalone.message.RawMessageQueue:60] Removed a message, Queue size:0, Message:[RawMessage(gatewayId=1, data=;255;3;0;9;TSP:SG:READ 254-2540 s=255,c=3,t=1pt=0,l=0,sg=0:, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 14:46:16,191 DEBUG [Thread-4] [org.mycontroller.standalone.message.MessageMonitorThread:61] Processing message:[RawMessage(gatewayId=1, data=;255;3;0;9;TSP:SG:READ 254-2540 s=255,c=3,t=1pt=0,l=0,sg=0:, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 14:46:16,195 ERROR [Thread-4] [org.mycontroller.standalone.message.MessageMonitorThread:70] RawMessage[RawMessage(gatewayId=1, data=;255;3;0;9;TSP:SG:READ 254-2540 s=255,c=3,t=1pt=0,l=0,sg=0:, subData=null, isTxMessage=false, networkType=MY_SENSORS)] throws exception while processing!, 
java.lang.NumberFormatException: For input string: ""
    at java.lang.NumberFormatException.forInputString(NumberFormatException.java:65) ~[na:1.8.0_101]
    at java.lang.Integer.parseInt(Integer.java:592) ~[na:1.8.0_101]
    at java.lang.Integer.valueOf(Integer.java:766) ~[na:1.8.0_101]
    at org.mycontroller.standalone.provider.mysensors.MySensorsRawMessage.updateSerialMessage(MySensorsRawMessage.java:127) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.provider.mysensors.MySensorsRawMessage.<init>(MySensorsRawMessage.java:66) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.provider.mysensors.MySensorsProviderBridge.executeRawMessage(MySensorsProviderBridge.java:58) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.message.McMessageUtils.sendToProviderBridge(McMessageUtils.java:536) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.message.MessageMonitorThread.processRawMessage(MessageMonitorThread.java:64) [mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.message.MessageMonitorThread.run(MessageMonitorThread.java:85) [mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at java.lang.Thread.run(Thread.java:745) [na:1.8.0_101]
2016-08-13 14:46:16,199 DEBUG [Thread-42] [org.mycontroller.standalone.gateway.serialport.SerialDataListenerjSerialComm:64] Received a message:[54;255;;0;1;]
2016-08-13 14:46:16,201 DEBUG [Thread-42] [org.mycontroller.standalone.message.RawMessageQueue:50] Added new message, Queue size:1, Message:[RawMessage(gatewayId=1, data=54;255;;0;1;, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 14:46:16,208 DEBUG [Thread-4] [org.mycontroller.standalone.message.RawMessageQueue:60] Removed a message, Queue size:0, Message:[RawMessage(gatewayId=1, data=54;255;;0;1;, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 14:46:16,212 DEBUG [Thread-4] [org.mycontroller.standalone.message.MessageMonitorThread:61] Processing message:[RawMessage(gatewayId=1, data=54;255;;0;1;, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 14:46:16,216 ERROR [Thread-4] [org.mycontroller.standalone.message.MessageMonitorThread:70] RawMessage[RawMessage(gatewayId=1, data=54;255;;0;1;, subData=null, isTxMessage=false, networkType=MY_SENSORS)] throws exception while processing!, 
java.lang.NumberFormatException: For input string: ""
    at java.lang.NumberFormatException.forInputString(NumberFormatException.java:65) ~[na:1.8.0_101]
    at java.lang.Integer.parseInt(Integer.java:592) ~[na:1.8.0_101]
    at java.lang.Integer.valueOf(Integer.java:766) ~[na:1.8.0_101]
    at org.mycontroller.standalone.provider.mysensors.MySensorsRawMessage.updateSerialMessage(MySensorsRawMessage.java:129) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.provider.mysensors.MySensorsRawMessage.<init>(MySensorsRawMessage.java:66) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.provider.mysensors.MySensorsProviderBridge.executeRawMessage(MySensorsProviderBridge.java:58) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.message.McMessageUtils.sendToProviderBridge(McMessageUtils.java:536) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.message.MessageMonitorThread.processRawMessage(MessageMonitorThread.java:64) [mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.message.MessageMonitorThread.run(MessageMonitorThread.java:85) [mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at java.lang.Thread.run(Thread.java:745) [na:1.8.0_101]
2016-08-13 14:46:16,762 DEBUG [Thread-42] [org.mycontroller.standalone.gateway.serialport.SerialDataListenerjSerialComm:64] Received a message:[;255;3;0;9;TSP:SG:READ 254-2540 s=7,c=1,t=0,p=4,l=4,sg=0:33]
2016-08-13 14:46:16,764 DEBUG [Thread-42] [org.mycontroller.standalone.message.RawMessageQueue:50] Added new message, Queue size:1, Message:[RawMessage(gatewayId=1, data=;255;3;0;9;TSP:SG:READ 254-2540 s=7,c=1,t=0,p=4,l=4,sg=0:33, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 14:46:16,768 DEBUG [Thread-4] [org.mycontroller.standalone.message.RawMessageQueue:60] Removed a message, Queue size:0, Message:[RawMessage(gatewayId=1, data=;255;3;0;9;TSP:SG:READ 254-2540 s=7,c=1,t=0,p=4,l=4,sg=0:33, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 14:46:16,771 DEBUG [Thread-4] [org.mycontroller.standalone.message.MessageMonitorThread:61] Processing message:[RawMessage(gatewayId=1, data=;255;3;0;9;TSP:SG:READ 254-2540 s=7,c=1,t=0,p=4,l=4,sg=0:33, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 14:46:16,776 ERROR [Thread-4] [org.mycontroller.standalone.message.MessageMonitorThread:70] RawMessage[RawMessage(gatewayId=1, data=;255;3;0;9;TSP:SG:READ 254-2540 s=7,c=1,t=0,p=4,l=4,sg=0:33, subData=null, isTxMessage=false, networkType=MY_SENSORS)] throws exception while processing!, 
java.lang.NumberFormatException: For input string: ""
    at java.lang.NumberFormatException.forInputString(NumberFormatException.java:65) ~[na:1.8.0_101]
    at java.lang.Integer.parseInt(Integer.java:592) ~[na:1.8.0_101]
    at java.lang.Integer.valueOf(Integer.java:766) ~[na:1.8.0_101]
    at org.mycontroller.standalone.provider.mysensors.MySensorsRawMessage.updateSerialMessage(MySensorsRawMessage.java:127) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.provider.mysensors.MySensorsRawMessage.<init>(MySensorsRawMessage.java:66) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.provider.mysensors.MySensorsProviderBridge.executeRawMessage(MySensorsProviderBridge.java:58) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.message.McMessageUtils.sendToProviderBridge(McMessageUtils.java:536) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.message.MessageMonitorThread.processRawMessage(MessageMonitorThread.java:64) [mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.message.MessageMonitorThread.run(MessageMonitorThread.java:85) [mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at java.lang.Thread.run(Thread.java:745) [na:1.8.0_101]
2016-08-13 14:46:16,781 DEBUG [Thread-42] [org.mycontroller.standalone.gateway.serialport.SerialDataListenerjSerialComm:64] Received a message:[54;7;1;;0;33]
2016-08-13 14:46:16,783 DEBUG [Thread-42] [org.mycontroller.standalone.message.RawMessageQueue:50] Added new message, Queue size:1, Message:[RawMessage(gatewayId=1, data=54;7;1;;0;33, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 14:46:16,789 DEBUG [Thread-4] [org.mycontroller.standalone.message.RawMessageQueue:60] Removed a message, Queue size:0, Message:[RawMessage(gatewayId=1, data=54;7;1;;0;33, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 14:46:16,791 DEBUG [Thread-4] [org.mycontroller.standalone.message.MessageMonitorThread:61] Processing message:[RawMessage(gatewayId=1, data=54;7;1;;0;33, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 14:46:16,797 ERROR [Thread-4] [org.mycontroller.standalone.message.MessageMonitorThread:70] RawMessage[RawMessage(gatewayId=1, data=54;7;1;;0;33, subData=null, isTxMessage=false, networkType=MY_SENSORS)] throws exception while processing!, 
java.lang.NumberFormatException: For input string: ""
    at java.lang.NumberFormatException.forInputString(NumberFormatException.java:65) ~[na:1.8.0_101]
    at java.lang.Integer.parseInt(Integer.java:592) ~[na:1.8.0_101]
    at java.lang.Integer.valueOf(Integer.java:766) ~[na:1.8.0_101]
    at org.mycontroller.standalone.provider.mysensors.MySensorsRawMessage.updateSerialMessage(MySensorsRawMessage.java:130) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.provider.mysensors.MySensorsRawMessage.<init>(MySensorsRawMessage.java:66) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.provider.mysensors.MySensorsProviderBridge.executeRawMessage(MySensorsProviderBridge.java:58) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.message.McMessageUtils.sendToProviderBridge(McMessageUtils.java:536) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.message.MessageMonitorThread.processRawMessage(MessageMonitorThread.java:64) [mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.message.MessageMonitorThread.run(MessageMonitorThread.java:85) [mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at java.lang.Thread.run(Thread.java:745) [na:1.8.0_101]
2016-08-13 14:46:16,858 DEBUG [Thread-42] [org.mycontroller.standalone.gateway.serialport.SerialDataListenerjSerialComm:64] Received a message:[;255;3;0;9;TSP:SG:READ 254-2540 s=255,c=3,t=0pt=1,l=1,sg=0:7]
2016-08-13 14:46:16,860 DEBUG [Thread-42] [org.mycontroller.standalone.message.RawMessageQueue:50] Added new message, Queue size:1, Message:[RawMessage(gatewayId=1, data=;255;3;0;9;TSP:SG:READ 254-2540 s=255,c=3,t=0pt=1,l=1,sg=0:7, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 14:46:16,863 DEBUG [Thread-4] [org.mycontroller.standalone.message.RawMessageQueue:60] Removed a message, Queue size:0, Message:[RawMessage(gatewayId=1, data=;255;3;0;9;TSP:SG:READ 254-2540 s=255,c=3,t=0pt=1,l=1,sg=0:7, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 14:46:16,865 DEBUG [Thread-4] [org.mycontroller.standalone.message.MessageMonitorThread:61] Processing message:[RawMessage(gatewayId=1, data=;255;3;0;9;TSP:SG:READ 254-2540 s=255,c=3,t=0pt=1,l=1,sg=0:7, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 14:46:16,871 ERROR [Thread-4] [org.mycontroller.standalone.message.MessageMonitorThread:70] RawMessage[RawMessage(gatewayId=1, data=;255;3;0;9;TSP:SG:READ 254-2540 s=255,c=3,t=0pt=1,l=1,sg=0:7, subData=null, isTxMessage=false, networkType=MY_SENSORS)] throws exception while processing!, 
java.lang.NumberFormatException: For input string: ""
    at java.lang.NumberFormatException.forInputString(NumberFormatException.java:65) ~[na:1.8.0_101]
    at java.lang.Integer.parseInt(Integer.java:592) ~[na:1.8.0_101]
    at java.lang.Integer.valueOf(Integer.java:766) ~[na:1.8.0_101]
    at org.mycontroller.standalone.provider.mysensors.MySensorsRawMessage.updateSerialMessage(MySensorsRawMessage.java:127) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.provider.mysensors.MySensorsRawMessage.<init>(MySensorsRawMessage.java:66) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.provider.mysensors.MySensorsProviderBridge.executeRawMessage(MySensorsProviderBridge.java:58) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.message.McMessageUtils.sendToProviderBridge(McMessageUtils.java:536) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.message.MessageMonitorThread.processRawMessage(MessageMonitorThread.java:64) [mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.message.MessageMonitorThread.run(MessageMonitorThread.java:85) [mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at java.lang.Thread.run(Thread.java:745) [na:1.8.0_101]
2016-08-13 14:46:16,873 DEBUG [Thread-42] [org.mycontroller.standalone.gateway.serialport.SerialDataListenerjSerialComm:64] Received a message:[254;255;3;0;0;4]
2016-08-13 14:46:16,877 DEBUG [Thread-42] [org.mycontroller.standalone.message.RawMessageQueue:50] Added new message, Queue size:1, Message:[RawMessage(gatewayId=1, data=254;255;3;0;0;4, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 14:46:16,879 DEBUG [Thread-4] [org.mycontroller.standalone.message.RawMessageQueue:60] Removed a message, Queue size:0, Message:[RawMessage(gatewayId=1, data=254;255;3;0;0;4, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 14:46:16,882 DEBUG [Thread-4] [org.mycontroller.standalone.message.MessageMonitorThread:61] Processing message:[RawMessage(gatewayId=1, data=254;255;3;0;0;4, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 14:46:16,886 DEBUG [Thread-91] [org.mycontroller.standalone.message.McMessageEngine:98] McMessage:McMessage(gatewayId=1, nodeEui=254, sensorId=SENSOR_BC, type=C_INTERNAL, subType=Battery level, acknowledge=true, payload=4, isTxMessage=false, networkType=MY_SENSORS, isScreeningDone=true, timestamp=1471099576886)
2016-08-13 14:46:16,899 DEBUG [Thread-91] [org.mycontroller.standalone.message.McMessageEngine:787] Node:[Node(id=16, eui=254, gatewayTable=GatewayTable(id=1, enabled=true, name=MyGw, networkType=MY_SENSORS, timestamp=null, state=UP, statusMessage=Connected Successfully, statusSince=1471099169841, type=SERIAL, properties={br=9600, rdr=jSerialComm, rf=120, dr=jSerialComm, pn=/dev/ttyAMA0}), name=MyNode, version=null, type=S_ARDUINO_NODE, libVersion=null, batteryLevel=9, eraseConfig=null, firmware=null, state=UP, lastSeen=1471099566542, rssi=null, properties={}, parentNodeEui=null, registrationState=REGISTERED)], message:[McMessage(gatewayId=1, nodeEui=254, sensorId=SENSOR_BC, type=C_INTERNAL, subType=Battery level, acknowledge=true, payload=4, isTxMessage=false, networkType=MY_SENSORS, isScreeningDone=true, timestamp=1471099576886)]
2016-08-13 14:46:16,917 DEBUG [Thread-91] [org.mycontroller.standalone.message.McMessageEngine:230] Message Type:I_BATTERY_LEVEL
2016-08-13 14:46:16,919 DEBUG [Thread-91] [org.mycontroller.standalone.message.McMessageEngine:236] Battery Level:[nodeId:254,Level:4%]
2016-08-13 14:46:16,954 DEBUG [Thread-91] [org.mycontroller.standalone.message.McMessageEngine:787] Node:[Node(id=16, eui=254, gatewayTable=GatewayTable(id=1, enabled=true, name=MyGw, networkType=MY_SENSORS, timestamp=null, state=UP, statusMessage=Connected Successfully, statusSince=1471099169841, type=SERIAL, properties={br=9600, rdr=jSerialComm, rf=120, dr=jSerialComm, pn=/dev/ttyAMA0}), name=MyNode, version=null, type=S_ARDUINO_NODE, libVersion=null, batteryLevel=4, eraseConfig=null, firmware=null, state=UP, lastSeen=1471099576922, rssi=null, properties={}, parentNodeEui=null, registrationState=REGISTERED)], message:[McMessage(gatewayId=1, nodeEui=254, sensorId=SENSOR_BC, type=C_INTERNAL, subType=Battery level, acknowledge=true, payload=4, isTxMessage=false, networkType=MY_SENSORS, isScreeningDone=true, timestamp=1471099576886)]
2016-08-13 14:46:25,987 DEBUG [Thread-42] [org.mycontroller.standalone.gateway.serialport.SerialDataListenerjSerialComm:64] Received a message:[;255;3;0;9;TSP:SG:READ 254-2540 s=255,c=3,t=0pt=1,l=1,sg=0:8]
2016-08-13 14:46:25,989 DEBUG [Thread-42] [org.mycontroller.standalone.message.RawMessageQueue:50] Added new message, Queue size:1, Message:[RawMessage(gatewayId=1, data=;255;3;0;9;TSP:SG:READ 254-2540 s=255,c=3,t=0pt=1,l=1,sg=0:8, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 14:46:25,992 DEBUG [Thread-4] [org.mycontroller.standalone.message.RawMessageQueue:60] Removed a message, Queue size:0, Message:[RawMessage(gatewayId=1, data=;255;3;0;9;TSP:SG:READ 254-2540 s=255,c=3,t=0pt=1,l=1,sg=0:8, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 14:46:25,995 DEBUG [Thread-4] [org.mycontroller.standalone.message.MessageMonitorThread:61] Processing message:[RawMessage(gatewayId=1, data=;255;3;0;9;TSP:SG:READ 254-2540 s=255,c=3,t=0pt=1,l=1,sg=0:8, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 14:46:26,000 ERROR [Thread-4] [org.mycontroller.standalone.message.MessageMonitorThread:70] RawMessage[RawMessage(gatewayId=1, data=;255;3;0;9;TSP:SG:READ 254-2540 s=255,c=3,t=0pt=1,l=1,sg=0:8, subData=null, isTxMessage=false, networkType=MY_SENSORS)] throws exception while processing!, 
java.lang.NumberFormatException: For input string: ""
    at java.lang.NumberFormatException.forInputString(NumberFormatException.java:65) ~[na:1.8.0_101]
    at java.lang.Integer.parseInt(Integer.java:592) ~[na:1.8.0_101]
    at java.lang.Integer.valueOf(Integer.java:766) ~[na:1.8.0_101]
    at org.mycontroller.standalone.provider.mysensors.MySensorsRawMessage.updateSerialMessage(MySensorsRawMessage.java:127) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.provider.mysensors.MySensorsRawMessage.<init>(MySensorsRawMessage.java:66) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.provider.mysensors.MySensorsProviderBridge.executeRawMessage(MySensorsProviderBridge.java:58) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.message.McMessageUtils.sendToProviderBridge(McMessageUtils.java:536) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.message.MessageMonitorThread.processRawMessage(MessageMonitorThread.java:64) [mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.message.MessageMonitorThread.run(MessageMonitorThread.java:85) [mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at java.lang.Thread.run(Thread.java:745) [na:1.8.0_101]
2016-08-13 14:46:26,002 DEBUG [Thread-42] [org.mycontroller.standalone.gateway.serialport.SerialDataListenerjSerialComm:64] Received a message:[254;255;3;0;0;7]
2016-08-13 14:46:26,006 DEBUG [Thread-42] [org.mycontroller.standalone.message.RawMessageQueue:50] Added new message, Queue size:1, Message:[RawMessage(gatewayId=1, data=254;255;3;0;0;7, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 14:46:26,009 DEBUG [Thread-4] [org.mycontroller.standalone.message.RawMessageQueue:60] Removed a message, Queue size:0, Message:[RawMessage(gatewayId=1, data=254;255;3;0;0;7, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 14:46:26,011 DEBUG [Thread-4] [org.mycontroller.standalone.message.MessageMonitorThread:61] Processing message:[RawMessage(gatewayId=1, data=254;255;3;0;0;7, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 14:46:26,016 DEBUG [Thread-92] [org.mycontroller.standalone.message.McMessageEngine:98] McMessage:McMessage(gatewayId=1, nodeEui=254, sensorId=SENSOR_BC, type=C_INTERNAL, subType=Battery level, acknowledge=true, payload=7, isTxMessage=false, networkType=MY_SENSORS, isScreeningDone=true, timestamp=1471099586023)
2016-08-13 14:46:26,036 DEBUG [Thread-92] [org.mycontroller.standalone.message.McMessageEngine:787] Node:[Node(id=16, eui=254, gatewayTable=GatewayTable(id=1, enabled=true, name=MyGw, networkType=MY_SENSORS, timestamp=null, state=UP, statusMessage=Connected Successfully, statusSince=1471099169841, type=SERIAL, properties={br=9600, rdr=jSerialComm, rf=120, dr=jSerialComm, pn=/dev/ttyAMA0}), name=MyNode, version=null, type=S_ARDUINO_NODE, libVersion=null, batteryLevel=4, eraseConfig=null, firmware=null, state=UP, lastSeen=1471099576957, rssi=null, properties={}, parentNodeEui=null, registrationState=REGISTERED)], message:[McMessage(gatewayId=1, nodeEui=254, sensorId=SENSOR_BC, type=C_INTERNAL, subType=Battery level, acknowledge=true, payload=7, isTxMessage=false, networkType=MY_SENSORS, isScreeningDone=true, timestamp=1471099586023)]
2016-08-13 14:46:26,054 DEBUG [Thread-92] [org.mycontroller.standalone.message.McMessageEngine:230] Message Type:I_BATTERY_LEVEL
2016-08-13 14:46:26,056 DEBUG [Thread-92] [org.mycontroller.standalone.message.McMessageEngine:236] Battery Level:[nodeId:254,Level:7%]
2016-08-13 14:46:26,078 DEBUG [Thread-92] [org.mycontroller.standalone.message.McMessageEngine:787] Node:[Node(id=16, eui=254, gatewayTable=GatewayTable(id=1, enabled=true, name=MyGw, networkType=MY_SENSORS, timestamp=null, state=UP, statusMessage=Connected Successfully, statusSince=1471099169841, type=SERIAL, properties={br=9600, rdr=jSerialComm, rf=120, dr=jSerialComm, pn=/dev/ttyAMA0}), name=MyNode, version=null, type=S_ARDUINO_NODE, libVersion=null, batteryLevel=7, eraseConfig=null, firmware=null, state=UP, lastSeen=1471099586058, rssi=null, properties={}, parentNodeEui=null, registrationState=REGISTERED)], message:[McMessage(gatewayId=1, nodeEui=254, sensorId=SENSOR_BC, type=C_INTERNAL, subType=Battery level, acknowledge=true, payload=7, isTxMessage=false, networkType=MY_SENSORS, isScreeningDone=true, timestamp=1471099586023)]
2016-08-13 14:46:26,615 DEBUG [Thread-42] [org.mycontroller.standalone.gateway.serialport.SerialDataListenerjSerialComm:64] Received a message:[;255;3;0;9;TSP:SG:READ 254-2540 s=255,c=3,t=1pt=0,l=0,sg=0:]
2016-08-13 14:46:26,617 DEBUG [Thread-42] [org.mycontroller.standalone.message.RawMessageQueue:50] Added new message, Queue size:1, Message:[RawMessage(gatewayId=1, data=;255;3;0;9;TSP:SG:READ 254-2540 s=255,c=3,t=1pt=0,l=0,sg=0:, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 14:46:26,620 DEBUG [Thread-4] [org.mycontroller.standalone.message.RawMessageQueue:60] Removed a message, Queue size:0, Message:[RawMessage(gatewayId=1, data=;255;3;0;9;TSP:SG:READ 254-2540 s=255,c=3,t=1pt=0,l=0,sg=0:, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 14:46:26,623 DEBUG [Thread-4] [org.mycontroller.standalone.message.MessageMonitorThread:61] Processing message:[RawMessage(gatewayId=1, data=;255;3;0;9;TSP:SG:READ 254-2540 s=255,c=3,t=1pt=0,l=0,sg=0:, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 14:46:26,628 ERROR [Thread-4] [org.mycontroller.standalone.message.MessageMonitorThread:70] RawMessage[RawMessage(gatewayId=1, data=;255;3;0;9;TSP:SG:READ 254-2540 s=255,c=3,t=1pt=0,l=0,sg=0:, subData=null, isTxMessage=false, networkType=MY_SENSORS)] throws exception while processing!, 
java.lang.NumberFormatException: For input string: ""
    at java.lang.NumberFormatException.forInputString(NumberFormatException.java:65) ~[na:1.8.0_101]
    at java.lang.Integer.parseInt(Integer.java:592) ~[na:1.8.0_101]
    at java.lang.Integer.valueOf(Integer.java:766) ~[na:1.8.0_101]
    at org.mycontroller.standalone.provider.mysensors.MySensorsRawMessage.updateSerialMessage(MySensorsRawMessage.java:127) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.provider.mysensors.MySensorsRawMessage.<init>(MySensorsRawMessage.java:66) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.provider.mysensors.MySensorsProviderBridge.executeRawMessage(MySensorsProviderBridge.java:58) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.message.McMessageUtils.sendToProviderBridge(McMessageUtils.java:536) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.message.MessageMonitorThread.processRawMessage(MessageMonitorThread.java:64) [mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.message.MessageMonitorThread.run(MessageMonitorThread.java:85) [mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at java.lang.Thread.run(Thread.java:745) [na:1.8.0_101]
2016-08-13 14:46:26,634 DEBUG [Thread-42] [org.mycontroller.standalone.gateway.serialport.SerialDataListenerjSerialComm:64] Received a message:[54;255;;0;1;]
2016-08-13 14:46:26,637 DEBUG [Thread-42] [org.mycontroller.standalone.message.RawMessageQueue:50] Added new message, Queue size:1, Message:[RawMessage(gatewayId=1, data=54;255;;0;1;, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 14:46:26,642 DEBUG [Thread-4] [org.mycontroller.standalone.message.RawMessageQueue:60] Removed a message, Queue size:0, Message:[RawMessage(gatewayId=1, data=54;255;;0;1;, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 14:46:26,644 DEBUG [Thread-4] [org.mycontroller.standalone.message.MessageMonitorThread:61] Processing message:[RawMessage(gatewayId=1, data=54;255;;0;1;, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 14:46:26,649 ERROR [Thread-4] [org.mycontroller.standalone.message.MessageMonitorThread:70] RawMessage[RawMessage(gatewayId=1, data=54;255;;0;1;, subData=null, isTxMessage=false, networkType=MY_SENSORS)] throws exception while processing!, 
java.lang.NumberFormatException: For input string: ""
    at java.lang.NumberFormatException.forInputString(NumberFormatException.java:65) ~[na:1.8.0_101]
    at java.lang.Integer.parseInt(Integer.java:592) ~[na:1.8.0_101]
    at java.lang.Integer.valueOf(Integer.java:766) ~[na:1.8.0_101]
    at org.mycontroller.standalone.provider.mysensors.MySensorsRawMessage.updateSerialMessage(MySensorsRawMessage.java:129) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.provider.mysensors.MySensorsRawMessage.<init>(MySensorsRawMessage.java:66) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.provider.mysensors.MySensorsProviderBridge.executeRawMessage(MySensorsProviderBridge.java:58) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.message.McMessageUtils.sendToProviderBridge(McMessageUtils.java:536) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.message.MessageMonitorThread.processRawMessage(MessageMonitorThread.java:64) [mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.message.MessageMonitorThread.run(MessageMonitorThread.java:85) [mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at java.lang.Thread.run(Thread.java:745) [na:1.8.0_101]
2016-08-13 14:46:27,197 DEBUG [Thread-42] [org.mycontroller.standalone.gateway.serialport.SerialDataListenerjSerialComm:64] Received a message:[;255;3;0;9;TSP:SG:READ 254-2540 s=7,c=1,t=0,p=4,l=4,sg=0:39]
2016-08-13 14:46:27,200 DEBUG [Thread-42] [org.mycontroller.standalone.message.RawMessageQueue:50] Added new message, Queue size:1, Message:[RawMessage(gatewayId=1, data=;255;3;0;9;TSP:SG:READ 254-2540 s=7,c=1,t=0,p=4,l=4,sg=0:39, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 14:46:27,202 DEBUG [Thread-4] [org.mycontroller.standalone.message.RawMessageQueue:60] Removed a message, Queue size:0, Message:[RawMessage(gatewayId=1, data=;255;3;0;9;TSP:SG:READ 254-2540 s=7,c=1,t=0,p=4,l=4,sg=0:39, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 14:46:27,204 DEBUG [Thread-4] [org.mycontroller.standalone.message.MessageMonitorThread:61] Processing message:[RawMessage(gatewayId=1, data=;255;3;0;9;TSP:SG:READ 254-2540 s=7,c=1,t=0,p=4,l=4,sg=0:39, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 14:46:27,215 ERROR [Thread-4] [org.mycontroller.standalone.message.MessageMonitorThread:70] RawMessage[RawMessage(gatewayId=1, data=;255;3;0;9;TSP:SG:READ 254-2540 s=7,c=1,t=0,p=4,l=4,sg=0:39, subData=null, isTxMessage=false, networkType=MY_SENSORS)] throws exception while processing!, 
java.lang.NumberFormatException: For input string: ""
    at java.lang.NumberFormatException.forInputString(NumberFormatException.java:65) ~[na:1.8.0_101]
    at java.lang.Integer.parseInt(Integer.java:592) ~[na:1.8.0_101]
    at java.lang.Integer.valueOf(Integer.java:766) ~[na:1.8.0_101]
    at org.mycontroller.standalone.provider.mysensors.MySensorsRawMessage.updateSerialMessage(MySensorsRawMessage.java:127) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.provider.mysensors.MySensorsRawMessage.<init>(MySensorsRawMessage.java:66) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.provider.mysensors.MySensorsProviderBridge.executeRawMessage(MySensorsProviderBridge.java:58) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.message.McMessageUtils.sendToProviderBridge(McMessageUtils.java:536) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.message.MessageMonitorThread.processRawMessage(MessageMonitorThread.java:64) [mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.message.MessageMonitorThread.run(MessageMonitorThread.java:85) [mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at java.lang.Thread.run(Thread.java:745) [na:1.8.0_101]
2016-08-13 14:46:27,217 DEBUG [Thread-42] [org.mycontroller.standalone.gateway.serialport.SerialDataListenerjSerialComm:64] Received a message:[54;7;1;;0;39]
2016-08-13 14:46:27,222 DEBUG [Thread-42] [org.mycontroller.standalone.message.RawMessageQueue:50] Added new message, Queue size:1, Message:[RawMessage(gatewayId=1, data=54;7;1;;0;39, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 14:46:27,225 DEBUG [Thread-4] [org.mycontroller.standalone.message.RawMessageQueue:60] Removed a message, Queue size:0, Message:[RawMessage(gatewayId=1, data=54;7;1;;0;39, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 14:46:27,228 DEBUG [Thread-4] [org.mycontroller.standalone.message.MessageMonitorThread:61] Processing message:[RawMessage(gatewayId=1, data=54;7;1;;0;39, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 14:46:27,233 ERROR [Thread-4] [org.mycontroller.standalone.message.MessageMonitorThread:70] RawMessage[RawMessage(gatewayId=1, data=54;7;1;;0;39, subData=null, isTxMessage=false, networkType=MY_SENSORS)] throws exception while processing!, 
java.lang.NumberFormatException: For input string: ""
    at java.lang.NumberFormatException.forInputString(NumberFormatException.java:65) ~[na:1.8.0_101]
    at java.lang.Integer.parseInt(Integer.java:592) ~[na:1.8.0_101]
    at java.lang.Integer.valueOf(Integer.java:766) ~[na:1.8.0_101]
    at org.mycontroller.standalone.provider.mysensors.MySensorsRawMessage.updateSerialMessage(MySensorsRawMessage.java:130) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.provider.mysensors.MySensorsRawMessage.<init>(MySensorsRawMessage.java:66) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.provider.mysensors.MySensorsProviderBridge.executeRawMessage(MySensorsProviderBridge.java:58) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.message.McMessageUtils.sendToProviderBridge(McMessageUtils.java:536) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.message.MessageMonitorThread.processRawMessage(MessageMonitorThread.java:64) [mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.message.MessageMonitorThread.run(MessageMonitorThread.java:85) [mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at java.lang.Thread.run(Thread.java:745) [na:1.8.0_101]
2016-08-13 14:46:27,294 DEBUG [Thread-42] [org.mycontroller.standalone.gateway.serialport.SerialDataListenerjSerialComm:64] Received a message:[;255;3;0;9;TSP:SG:READ 254-2540 s=255,c=3,t=0pt=1,l=1,sg=0:8]
2016-08-13 14:46:27,296 DEBUG [Thread-42] [org.mycontroller.standalone.message.RawMessageQueue:50] Added new message, Queue size:1, Message:[RawMessage(gatewayId=1, data=;255;3;0;9;TSP:SG:READ 254-2540 s=255,c=3,t=0pt=1,l=1,sg=0:8, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 14:46:27,299 DEBUG [Thread-4] [org.mycontroller.standalone.message.RawMessageQueue:60] Removed a message, Queue size:0, Message:[RawMessage(gatewayId=1, data=;255;3;0;9;TSP:SG:READ 254-2540 s=255,c=3,t=0pt=1,l=1,sg=0:8, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 14:46:27,301 DEBUG [Thread-4] [org.mycontroller.standalone.message.MessageMonitorThread:61] Processing message:[RawMessage(gatewayId=1, data=;255;3;0;9;TSP:SG:READ 254-2540 s=255,c=3,t=0pt=1,l=1,sg=0:8, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 14:46:27,307 ERROR [Thread-4] [org.mycontroller.standalone.message.MessageMonitorThread:70] RawMessage[RawMessage(gatewayId=1, data=;255;3;0;9;TSP:SG:READ 254-2540 s=255,c=3,t=0pt=1,l=1,sg=0:8, subData=null, isTxMessage=false, networkType=MY_SENSORS)] throws exception while processing!, 
java.lang.NumberFormatException: For input string: ""
    at java.lang.NumberFormatException.forInputString(NumberFormatException.java:65) ~[na:1.8.0_101]
    at java.lang.Integer.parseInt(Integer.java:592) ~[na:1.8.0_101]
    at java.lang.Integer.valueOf(Integer.java:766) ~[na:1.8.0_101]
    at org.mycontroller.standalone.provider.mysensors.MySensorsRawMessage.updateSerialMessage(MySensorsRawMessage.java:127) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.provider.mysensors.MySensorsRawMessage.<init>(MySensorsRawMessage.java:66) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.provider.mysensors.MySensorsProviderBridge.executeRawMessage(MySensorsProviderBridge.java:58) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.message.McMessageUtils.sendToProviderBridge(McMessageUtils.java:536) ~[mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.message.MessageMonitorThread.processRawMessage(MessageMonitorThread.java:64) [mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at org.mycontroller.standalone.message.MessageMonitorThread.run(MessageMonitorThread.java:85) [mycontroller-dist-standalone-0.0.3.Alpha2-single.jar:0.0.3.Alpha2]
    at java.lang.Thread.run(Thread.java:745) [na:1.8.0_101]
2016-08-13 14:46:27,309 DEBUG [Thread-42] [org.mycontroller.standalone.gateway.serialport.SerialDataListenerjSerialComm:64] Received a message:[254;255;3;0;0;7]
2016-08-13 14:46:27,313 DEBUG [Thread-42] [org.mycontroller.standalone.message.RawMessageQueue:50] Added new message, Queue size:1, Message:[RawMessage(gatewayId=1, data=254;255;3;0;0;7, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 14:46:27,315 DEBUG [Thread-4] [org.mycontroller.standalone.message.RawMessageQueue:60] Removed a message, Queue size:0, Message:[RawMessage(gatewayId=1, data=254;255;3;0;0;7, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 14:46:27,317 DEBUG [Thread-4] [org.mycontroller.standalone.message.MessageMonitorThread:61] Processing message:[RawMessage(gatewayId=1, data=254;255;3;0;0;7, subData=null, isTxMessage=false, networkType=MY_SENSORS)]
2016-08-13 14:46:27,322 DEBUG [Thread-93] [org.mycontroller.standalone.message.McMessageEngine:98] McMessage:McMessage(gatewayId=1, nodeEui=254, sensorId=SENSOR_BC, type=C_INTERNAL, subType=Battery level, acknowledge=true, payload=7, isTxMessage=false, networkType=MY_SENSORS, isScreeningDone=true, timestamp=1471099587322)
2016-08-13 14:46:27,335 DEBUG [Thread-93] [org.mycontroller.standalone.message.McMessageEngine:787] Node:[Node(id=16, eui=254, gatewayTable=GatewayTable(id=1, enabled=true, name=MyGw, networkType=MY_SENSORS, timestamp=null, state=UP, statusMessage=Connected Successfully, statusSince=1471099169841, type=SERIAL, properties={br=9600, rdr=jSerialComm, rf=120, dr=jSerialComm, pn=/dev/ttyAMA0}), name=MyNode, version=null, type=S_ARDUINO_NODE, libVersion=null, batteryLevel=7, eraseConfig=null, firmware=null, state=UP, lastSeen=1471099586081, rssi=null, properties={}, parentNodeEui=null, registrationState=REGISTERED)], message:[McMessage(gatewayId=1, nodeEui=254, sensorId=SENSOR_BC, type=C_INTERNAL, subType=Battery level, acknowledge=true, payload=7, isTxMessage=false, networkType=MY_SENSORS, isScreeningDone=true, timestamp=1471099587322)]
2016-08-13 14:46:27,349 DEBUG [Thread-93] [org.mycontroller.standalone.message.McMessageEngine:230] Message Type:I_BATTERY_LEVEL
2016-08-13 14:46:27,351 DEBUG [Thread-93] [org.mycontroller.standalone.message.McMessageEngine:236] Battery Level:[nodeId:254,Level:7%]
2016-08-13 14:46:27,389 DEBUG [Thread-93] [org.mycontroller.standalone.message.McMessageEngine:787] Node:[Node(id=16, eui=254, gatewayTable=GatewayTable(id=1, enabled=true, name=MyGw, networkType=MY_SENSORS, timestamp=null, state=UP, statusMessage=Connected Successfully, statusSince=1471099169841, type=SERIAL, properties={br=9600, rdr=jSerialComm, rf=120, dr=jSerialComm, pn=/dev/ttyAMA0}), name=MyNode, version=null, type=S_ARDUINO_NODE, libVersion=null, batteryLevel=7, eraseConfig=null, firmware=null, state=UP, lastSeen=1471099587354, rssi=null, properties={}, parentNodeEui=null, registrationState=REGISTERED)], message:[McMessage(gatewayId=1, nodeEui=254, sensorId=SENSOR_BC, type=C_INTERNAL, subType=Battery level, acknowledge=true, payload=7, isTxMessage=false, networkType=MY_SENSORS, isScreeningDone=true, timestamp=1471099587322)]
jkandasa commented 8 years ago

@yd1234 are you sharing serial port with some other process/application? it looks like MyController receiving corrupted dada.

yd1234 commented 8 years ago

mycontroller.log.txt

I don't think to share serial port port with other application. I currently doing some tests on one another new node on my laptop (nodeid 23) that it. Removed old things, clear the log and restart MYC. Please find new log above.

Thanks

jkandasa commented 8 years ago

@yd1234 Still are you facing issue on your environment?

yd1234 commented 8 years ago

Hi, I restart from scratch with a new RPI, reinstall everything and it seems to work, no more issue since last time.

Thanks

Le 19 août 2016 à 19:57, Jeeva Kandasamy notifications@github.com a écrit :

@yd1234 https://github.com/yd1234 Still are you facing issue on your environment?

— You are receiving this because you were mentioned. Reply to this email directly, view it on GitHub https://github.com/mycontroller-org/mycontroller/issues/241#issuecomment-241089294, or mute the thread https://github.com/notifications/unsubscribe-auth/AF0t_ZxtORoj_uqGu9S0Oni7ejUylQ5Rks5qhe6lgaJpZM4JiU2d.