Mar 13 15:09:11 IQ305002 systemd[1]: Started MQTT Rule engine for Wiren Board. Mar 13 15:09:11 IQ305002 wb-rules[4695]: INFO: broker URL is default and mosquitto socket detected, trying to connect via it Mar 13 15:09:11 IQ305002 wb-rules[4695]: INFO: driver is created Mar 13 15:09:11 IQ305002 wb-rules[4695]: INFO: [wbgo_mqtt] rules-IQ305002-4695: MQTT connection established Mar 13 15:09:11 IQ305002 wb-rules[4695]: INFO: driver loop is started Mar 13 15:09:11 IQ305002 wb-rules[4695]: INFO: wait for driver to become ready Mar 13 15:09:11 IQ305002 wb-rules[4695]: INFO: driver is ready Mar 13 15:09:11 IQ305002 wb-rules[4695]: INFO: [rule info] using file /var/lib/wirenboard/wbrules-persistent.db for persistent DB Mar 13 15:09:11 IQ305002 wb-rules[4695]: INFO: [wbgo_mqtt] wb-rules-engine-IQ305002-4695: MQTT connection established Mar 13 15:09:11 IQ305002 wb-rules[4695]: INFO: [engine] Starting main loop Mar 13 15:09:11 IQ305002 wb-rules[4695]: INFO: the engine is ready Mar 13 15:09:11 IQ305002 wb-rules[4695]: INFO: [engine] Starting sync loop Mar 13 15:09:12 IQ305002 wb-rules[4695]: fatal error: concurrent map read and map write Mar 13 15:09:12 IQ305002 wb-rules[4695]: goroutine 52 [running]: Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules.(*RuleEngine).DefineMqttTracker.(*RuleEngine).newTrackHandler.func2({{0xcc06d8, 0x18}, {0xcc0708, 0x12}, 0x0, 0x1}) Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules/engine.go:1943 +0x64 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wbgo-private.(*PahoMQTTClient).subscribe.func1({0xaa875218, 0xdda000}, {0xaa874fb4, 0x11520c0}) Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wbgo-private/mqtt.go:321 +0x370 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho%2emqtt%2egolang.(*router).matchAndDispatch.func2() Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho.mqtt.golang@v1.5.0/router.go:218 +0xa14 Mar 13 15:09:12 IQ305002 wb-rules[4695]: created by github.com/eclipse/paho%2emqtt%2egolang.(*router).matchAndDispatch in goroutine 50 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho.mqtt.golang@v1.5.0/router.go:173 +0x2a4 Mar 13 15:09:12 IQ305002 wb-rules[4695]: goroutine 1 [chan receive]: Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules.(*ESEngine).LiveLoadFile(0xdadc70, {0x1036bc0, 0x32}) Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules/esengine.go:920 +0xf0 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules.(*ESEngine).LoadFile(0xdadc70, {0x1036bc0, 0x32}) Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules/esengine.go:672 +0x2c Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wbgo-private.(*dirWatcher).loadFile(0xe2d310, {0x1036bc0, 0x32}, 0x0, 0x0) Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wbgo-private/dirwatcher.go:202 +0x1dc Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wbgo-private.(*dirWatcher).loadDir(0xe2d310, {0xbef31e9c, 0x21}, 0x0) Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wbgo-private/dirwatcher.go:187 +0x4bc Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wbgo-private.(*dirWatcher).doLoad(0xe2d310, {0xbef31e9c, 0x21}, 0x1, 0x0) Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wbgo-private/dirwatcher.go:213 +0x250 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wbgo-private.(*dirWatcher).Load(0xe2d310, {0xbef31e9c, 0x21}) Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wbgo-private/dirwatcher.go:247 +0xac Mar 13 15:09:12 IQ305002 wb-rules[4695]: main.main() Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/main.go:186 +0x1528 Mar 13 15:09:12 IQ305002 wb-rules[4695]: goroutine 5 [syscall]: Mar 13 15:09:12 IQ305002 wb-rules[4695]: os/signal.signal_recv() Mar 13 15:09:12 IQ305002 wb-rules[4695]: runtime/sigqueue.go:152 +0x34 Mar 13 15:09:12 IQ305002 wb-rules[4695]: os/signal.loop() Mar 13 15:09:12 IQ305002 wb-rules[4695]: os/signal/signal_unix.go:23 +0x14 Mar 13 15:09:12 IQ305002 wb-rules[4695]: created by os/signal.Notify.func1.1 in goroutine 1 Mar 13 15:09:12 IQ305002 wb-rules[4695]: os/signal/signal.go:151 +0x28 Mar 13 15:09:12 IQ305002 wb-rules[4695]: goroutine 20 [select]: Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wbgo-private.(*driverBase).LoopOnce(0xca66c0, 0x0) Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wbgo-private/driver_frontend.go:692 +0x11c Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wbgo-private.(*driverBase).StartLoop.func1() Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wbgo-private/driver_frontend.go:732 +0xb0 Mar 13 15:09:12 IQ305002 wb-rules[4695]: created by github.com/wirenboard/wbgo-private.(*driverBase).StartLoop in goroutine 1 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wbgo-private/driver_frontend.go:728 +0xf4 Mar 13 15:09:12 IQ305002 wb-rules[4695]: goroutine 33 [select]: Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wbgo-private.(*PahoMQTTClient).Start.func1() Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wbgo-private/mqtt.go:218 +0x84 Mar 13 15:09:12 IQ305002 wb-rules[4695]: created by github.com/wirenboard/wbgo-private.(*PahoMQTTClient).Start in goroutine 1 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wbgo-private/mqtt.go:216 +0x17c Mar 13 15:09:12 IQ305002 wb-rules[4695]: goroutine 22 [select]: Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho%2emqtt%2egolang.keepalive(0xcd0780, {0xade574f0, 0xd27e38}) Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho.mqtt.golang@v1.5.0/ping.go:48 +0x230 Mar 13 15:09:12 IQ305002 wb-rules[4695]: created by github.com/eclipse/paho%2emqtt%2egolang.(*client).startCommsWorkers in goroutine 21 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho.mqtt.golang@v1.5.0/client.go:595 +0x388 Mar 13 15:09:12 IQ305002 wb-rules[4695]: goroutine 23 [chan receive]: Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho%2emqtt%2egolang.(*router).matchAndDispatch.func2() Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho.mqtt.golang@v1.5.0/router.go:174 +0x84 Mar 13 15:09:12 IQ305002 wb-rules[4695]: created by github.com/eclipse/paho%2emqtt%2egolang.(*router).matchAndDispatch in goroutine 21 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho.mqtt.golang@v1.5.0/router.go:173 +0x2a4 Mar 13 15:09:12 IQ305002 wb-rules[4695]: goroutine 25 [select]: Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho%2emqtt%2egolang.(*client).startCommsWorkers.func1() Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho.mqtt.golang@v1.5.0/client.go:627 +0x128 Mar 13 15:09:12 IQ305002 wb-rules[4695]: created by github.com/eclipse/paho%2emqtt%2egolang.(*client).startCommsWorkers in goroutine 21 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho.mqtt.golang@v1.5.0/client.go:624 +0x754 Mar 13 15:09:12 IQ305002 wb-rules[4695]: goroutine 26 [IO wait]: Mar 13 15:09:12 IQ305002 wb-rules[4695]: internal/poll.runtime_pollWait(0xade54d70, 0x72) Mar 13 15:09:12 IQ305002 wb-rules[4695]: runtime/netpoll.go:343 +0x54 Mar 13 15:09:12 IQ305002 wb-rules[4695]: internal/poll.(*pollDesc).wait(0xca05b8, 0x72, 0x0) Mar 13 15:09:12 IQ305002 wb-rules[4695]: internal/poll/fd_poll_runtime.go:84 +0x30 Mar 13 15:09:12 IQ305002 wb-rules[4695]: internal/poll.(*pollDesc).waitRead(...) Mar 13 15:09:12 IQ305002 wb-rules[4695]: internal/poll/fd_poll_runtime.go:89 Mar 13 15:09:12 IQ305002 wb-rules[4695]: internal/poll.(*FD).Read(0xca05a0, {0xf38e62, 0x1, 0x1}) Mar 13 15:09:12 IQ305002 wb-rules[4695]: internal/poll/fd_unix.go:164 +0x240 Mar 13 15:09:12 IQ305002 wb-rules[4695]: net.(*netFD).Read(0xca05a0, {0xf38e62, 0x1, 0x1}) Mar 13 15:09:12 IQ305002 wb-rules[4695]: net/fd_posix.go:55 +0x38 Mar 13 15:09:12 IQ305002 wb-rules[4695]: net.(*conn).Read(0xd27e38, {0xf38e62, 0x1, 0x1}) Mar 13 15:09:12 IQ305002 wb-rules[4695]: net/net.go:179 +0x48 Mar 13 15:09:12 IQ305002 wb-rules[4695]: io.ReadAtLeast({0x7881f4, 0xd27e38}, {0xf38e62, 0x1, 0x1}, 0x1) Mar 13 15:09:12 IQ305002 wb-rules[4695]: io/io.go:335 +0xa0 Mar 13 15:09:12 IQ305002 wb-rules[4695]: io.ReadFull(...) Mar 13 15:09:12 IQ305002 wb-rules[4695]: io/io.go:354 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho.mqtt.golang/packets.ReadPacket({0x7881f4, 0xd27e38}) Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho.mqtt.golang@v1.5.0/packets/packets.go:131 +0x80 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho%2emqtt%2egolang.startIncoming.func1() Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho.mqtt.golang@v1.5.0/net.go:124 +0x114 Mar 13 15:09:12 IQ305002 wb-rules[4695]: created by github.com/eclipse/paho%2emqtt%2egolang.startIncoming in goroutine 21 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho.mqtt.golang@v1.5.0/net.go:122 +0x1d0 Mar 13 15:09:12 IQ305002 wb-rules[4695]: goroutine 27 [select]: Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho%2emqtt%2egolang.startIncomingComms.func1() Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho.mqtt.golang@v1.5.0/net.go:175 +0x198 Mar 13 15:09:12 IQ305002 wb-rules[4695]: created by github.com/eclipse/paho%2emqtt%2egolang.startIncomingComms in goroutine 21 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho.mqtt.golang@v1.5.0/net.go:164 +0x244 Mar 13 15:09:12 IQ305002 wb-rules[4695]: goroutine 28 [select]: Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho%2emqtt%2egolang.startOutgoingComms.func1() Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho.mqtt.golang@v1.5.0/net.go:277 +0x1b4 Mar 13 15:09:12 IQ305002 wb-rules[4695]: created by github.com/eclipse/paho%2emqtt%2egolang.startOutgoingComms in goroutine 21 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho.mqtt.golang@v1.5.0/net.go:264 +0x2e0 Mar 13 15:09:12 IQ305002 wb-rules[4695]: goroutine 29 [chan receive]: Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho%2emqtt%2egolang.startComms.func1() Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho.mqtt.golang@v1.5.0/net.go:407 +0x70 Mar 13 15:09:12 IQ305002 wb-rules[4695]: created by github.com/eclipse/paho%2emqtt%2egolang.startComms in goroutine 21 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho.mqtt.golang@v1.5.0/net.go:406 +0x264 Mar 13 15:09:12 IQ305002 wb-rules[4695]: goroutine 30 [chan receive]: Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho%2emqtt%2egolang.startComms.func2() Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho.mqtt.golang@v1.5.0/net.go:430 +0x84 Mar 13 15:09:12 IQ305002 wb-rules[4695]: created by github.com/eclipse/paho%2emqtt%2egolang.startComms in goroutine 21 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho.mqtt.golang@v1.5.0/net.go:429 +0x2e4 Mar 13 15:09:12 IQ305002 wb-rules[4695]: goroutine 31 [semacquire]: Mar 13 15:09:12 IQ305002 wb-rules[4695]: sync.runtime_Semacquire(0xca4818) Mar 13 15:09:12 IQ305002 wb-rules[4695]: runtime/sema.go:62 +0x3c Mar 13 15:09:12 IQ305002 wb-rules[4695]: sync.(*WaitGroup).Wait(0xca4810) Mar 13 15:09:12 IQ305002 wb-rules[4695]: sync/waitgroup.go:116 +0x68 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho%2emqtt%2egolang.startComms.func3() Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho.mqtt.golang@v1.5.0/net.go:438 +0x24 Mar 13 15:09:12 IQ305002 wb-rules[4695]: created by github.com/eclipse/paho%2emqtt%2egolang.startComms in goroutine 21 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho.mqtt.golang@v1.5.0/net.go:437 +0x354 Mar 13 15:09:12 IQ305002 wb-rules[4695]: goroutine 32 [select]: Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho%2emqtt%2egolang.(*client).startCommsWorkers.func2() Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho.mqtt.golang@v1.5.0/client.go:662 +0xe8 Mar 13 15:09:12 IQ305002 wb-rules[4695]: created by github.com/eclipse/paho%2emqtt%2egolang.(*client).startCommsWorkers in goroutine 21 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho.mqtt.golang@v1.5.0/client.go:657 +0x9e0 Mar 13 15:09:12 IQ305002 wb-rules[4695]: goroutine 35 [select]: Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wbgo-private.(*DriverMQTTBackend).messageHandlersLoop(0xcce300) Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wbgo-private/driver_backend.go:1546 +0xa4 Mar 13 15:09:12 IQ305002 wb-rules[4695]: created by github.com/wirenboard/wbgo-private.(*DriverMQTTBackend).Start in goroutine 1 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wbgo-private/driver_backend.go:1621 +0x16c Mar 13 15:09:12 IQ305002 wb-rules[4695]: goroutine 36 [select]: Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wbgo-private.(*DriverMQTTBackend).requestProcessorsLoop(0xcce300) Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wbgo-private/driver_backend.go:1561 +0xbc Mar 13 15:09:12 IQ305002 wb-rules[4695]: created by github.com/wirenboard/wbgo-private.(*DriverMQTTBackend).Start in goroutine 1 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wbgo-private/driver_backend.go:1624 +0x1cc Mar 13 15:09:12 IQ305002 wb-rules[4695]: goroutine 62 [runnable]: Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho%2emqtt%2egolang.(*baseToken).WaitTimeout(0xd56240, 0x2540be400) Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho.mqtt.golang@v1.5.0/token.go:85 +0x94 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wbgo-private.(*PahoMQTTClient).processToken(0xdb8d40, {0xaa874804, 0xd56240}) Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wbgo-private/mqtt.go:233 +0x34 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wbgo-private.(*PahoMQTTClient).Start.func1() Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wbgo-private/mqtt.go:225 +0xe0 Mar 13 15:09:12 IQ305002 wb-rules[4695]: created by github.com/wirenboard/wbgo-private.(*PahoMQTTClient).Start in goroutine 1 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wbgo-private/mqtt.go:216 +0x17c Mar 13 15:09:12 IQ305002 wb-rules[4695]: goroutine 51 [select]: Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho%2emqtt%2egolang.keepalive(0xdda000, {0xade574f0, 0xd93b68}) Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho.mqtt.golang@v1.5.0/ping.go:48 +0x230 Mar 13 15:09:12 IQ305002 wb-rules[4695]: created by github.com/eclipse/paho%2emqtt%2egolang.(*client).startCommsWorkers in goroutine 50 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho.mqtt.golang@v1.5.0/client.go:595 +0x388 Mar 13 15:09:12 IQ305002 wb-rules[4695]: goroutine 6 [IO wait]: Mar 13 15:09:12 IQ305002 wb-rules[4695]: internal/poll.runtime_pollWait(0xade54c30, 0x72) Mar 13 15:09:12 IQ305002 wb-rules[4695]: runtime/netpoll.go:343 +0x54 Mar 13 15:09:12 IQ305002 wb-rules[4695]: internal/poll.(*pollDesc).wait(0xecf7d8, 0x72, 0x1) Mar 13 15:09:12 IQ305002 wb-rules[4695]: internal/poll/fd_poll_runtime.go:84 +0x30 Mar 13 15:09:12 IQ305002 wb-rules[4695]: internal/poll.(*pollDesc).waitRead(...) Mar 13 15:09:12 IQ305002 wb-rules[4695]: internal/poll/fd_poll_runtime.go:89 Mar 13 15:09:12 IQ305002 wb-rules[4695]: internal/poll.(*FD).Read(0xecf7c0, {0xe84400, 0x200, 0x200}) Mar 13 15:09:12 IQ305002 wb-rules[4695]: internal/poll/fd_unix.go:164 +0x240 Mar 13 15:09:12 IQ305002 wb-rules[4695]: os.(*File).read(...) Mar 13 15:09:12 IQ305002 systemd[1]: wb-rules.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Mar 13 15:09:12 IQ305002 wb-rules[4695]: os/file_posix.go:29 Mar 13 15:09:12 IQ305002 wb-rules[4695]: os.(*File).Read(0xc22de8, {0xe84400, 0x200, 0x200}) Mar 13 15:09:12 IQ305002 wb-rules[4695]: os/file.go:118 +0x80 Mar 13 15:09:12 IQ305002 wb-rules[4695]: bytes.(*Buffer).ReadFrom(0xe9f8f0, {0x7879bc, 0xc22de8}) Mar 13 15:09:12 IQ305002 wb-rules[4695]: bytes/buffer.go:211 +0xa4 Mar 13 15:09:12 IQ305002 wb-rules[4695]: io.copyBuffer({0x787c8c, 0xe9f8f0}, {0x7879bc, 0xc22de8}, {0x0, 0x0, 0x0}) Mar 13 15:09:12 IQ305002 wb-rules[4695]: io/io.go:416 +0x154 Mar 13 15:09:12 IQ305002 wb-rules[4695]: io.Copy(...) Mar 13 15:09:12 IQ305002 wb-rules[4695]: io/io.go:389 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules.captureCommandOutput.func1() Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules/spawn.go:25 +0x90 Mar 13 15:09:12 IQ305002 wb-rules[4695]: created by github.com/wirenboard/wb-rules/wbrules.captureCommandOutput in goroutine 64 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules/spawn.go:23 +0xa4 Mar 13 15:09:12 IQ305002 wb-rules[4695]: goroutine 54 [select]: Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho%2emqtt%2egolang.(*client).startCommsWorkers.func1() Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho.mqtt.golang@v1.5.0/client.go:627 +0x128 Mar 13 15:09:12 IQ305002 wb-rules[4695]: created by github.com/eclipse/paho%2emqtt%2egolang.(*client).startCommsWorkers in goroutine 50 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho.mqtt.golang@v1.5.0/client.go:624 +0x754 Mar 13 15:09:12 IQ305002 wb-rules[4695]: goroutine 55 [runnable]: Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho%2emqtt%2egolang.startIncoming.func1() Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho.mqtt.golang@v1.5.0/net.go:136 +0x100 Mar 13 15:09:12 IQ305002 wb-rules[4695]: created by github.com/eclipse/paho%2emqtt%2egolang.startIncoming in goroutine 50 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho.mqtt.golang@v1.5.0/net.go:122 +0x1d0 Mar 13 15:09:12 IQ305002 wb-rules[4695]: goroutine 56 [select]: Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho%2emqtt%2egolang.startIncomingComms.func1() Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho.mqtt.golang@v1.5.0/net.go:175 +0x198 Mar 13 15:09:12 IQ305002 wb-rules[4695]: created by github.com/eclipse/paho%2emqtt%2egolang.startIncomingComms in goroutine 50 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho.mqtt.golang@v1.5.0/net.go:164 +0x244 Mar 13 15:09:12 IQ305002 wb-rules[4695]: goroutine 57 [select]: Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho%2emqtt%2egolang.startOutgoingComms.func1() Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho.mqtt.golang@v1.5.0/net.go:277 +0x1b4 Mar 13 15:09:12 IQ305002 wb-rules[4695]: created by github.com/eclipse/paho%2emqtt%2egolang.startOutgoingComms in goroutine 50 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho.mqtt.golang@v1.5.0/net.go:264 +0x2e0 Mar 13 15:09:12 IQ305002 wb-rules[4695]: goroutine 58 [chan receive]: Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho%2emqtt%2egolang.startComms.func1() Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho.mqtt.golang@v1.5.0/net.go:407 +0x70 Mar 13 15:09:12 IQ305002 wb-rules[4695]: created by github.com/eclipse/paho%2emqtt%2egolang.startComms in goroutine 50 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho.mqtt.golang@v1.5.0/net.go:406 +0x264 Mar 13 15:09:12 IQ305002 wb-rules[4695]: goroutine 59 [chan receive]: Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho%2emqtt%2egolang.startComms.func2() Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho.mqtt.golang@v1.5.0/net.go:430 +0x84 Mar 13 15:09:12 IQ305002 wb-rules[4695]: created by github.com/eclipse/paho%2emqtt%2egolang.startComms in goroutine 50 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho.mqtt.golang@v1.5.0/net.go:429 +0x2e4 Mar 13 15:09:12 IQ305002 wb-rules[4695]: goroutine 60 [semacquire]: Mar 13 15:09:12 IQ305002 wb-rules[4695]: sync.runtime_Semacquire(0xd82c98) Mar 13 15:09:12 IQ305002 wb-rules[4695]: runtime/sema.go:62 +0x3c Mar 13 15:09:12 IQ305002 wb-rules[4695]: sync.(*WaitGroup).Wait(0xd82c90) Mar 13 15:09:12 IQ305002 wb-rules[4695]: sync/waitgroup.go:116 +0x68 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho%2emqtt%2egolang.startComms.func3() Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho.mqtt.golang@v1.5.0/net.go:438 +0x24 Mar 13 15:09:12 IQ305002 wb-rules[4695]: created by github.com/eclipse/paho%2emqtt%2egolang.startComms in goroutine 50 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho.mqtt.golang@v1.5.0/net.go:437 +0x354 Mar 13 15:09:12 IQ305002 wb-rules[4695]: goroutine 61 [select]: Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho%2emqtt%2egolang.(*client).startCommsWorkers.func2() Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho.mqtt.golang@v1.5.0/client.go:662 +0xe8 Mar 13 15:09:12 IQ305002 wb-rules[4695]: created by github.com/eclipse/paho%2emqtt%2egolang.(*client).startCommsWorkers in goroutine 50 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho.mqtt.golang@v1.5.0/client.go:657 +0x9e0 Mar 13 15:09:12 IQ305002 wb-rules[4695]: goroutine 66 [chan receive]: Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules.(*RuleEngine).mainLoop(0xda0200) Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules/engine.go:860 +0x37c Mar 13 15:09:12 IQ305002 wb-rules[4695]: created by github.com/wirenboard/wb-rules/wbrules.(*RuleEngine).Start in goroutine 1 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules/engine.go:1337 +0x28c Mar 13 15:09:12 IQ305002 wb-rules[4695]: goroutine 67 [runnable, locked to thread]: Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho%2emqtt%2egolang.(*client).SubscribeMultiple(0xdda000, 0xc9d5c0, 0x10a4b10) Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/eclipse/paho.mqtt.golang@v1.5.0/client.go:963 +0x9a8 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wbgo-private.(*PahoMQTTClient).subscribe(0xdb8d40, 0xc9a9b0, {0x10a4b00, 0x1, 0x1}) Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wbgo-private/mqtt.go:325 +0x1fc Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wbgo-private.(*PahoMQTTClient).Subscribe(0xdb8d40, 0xc9a9b0, {0x10a4b00, 0x1, 0x1}) Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wbgo-private/mqtt.go:343 +0x2bc Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules.(*RuleEngine).DefineMqttTracker(0xda0200, {0xdb2300, 0x16}, 0xdc20c0) Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules/engine.go:1925 +0x23c Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules.(*ESEngine).trackMqtt(0xdadc70, 0xdc20c0) Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules/esengine.go:2280 +0x234 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules.(*ESContext).DefineFunctions.func1(0x10a4af8) Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules/escontext.go:510 +0x60 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/go-duktape.goCall(0xa982b020) Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/go-duktape@v0.0.0-20240729075045-b4150233e350/duktape.go:197 +0xc8 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/go-duktape._Cfunc_duk_pcall(0xa982b020, 0x1) Mar 13 15:09:12 IQ305002 wb-rules[4695]: _cgo_gotypes.go:1930 +0x38 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules.(*ESContext).LoadScenario.(*Context).Pcall.func4(0xf154f0, 0x1) Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/go-duktape@v0.0.0-20240729075045-b4150233e350/api.go:673 +0x50 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/go-duktape.(*Context).Pcall(...) Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/go-duktape@v0.0.0-20240729075045-b4150233e350/api.go:673 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules.(*ESContext).LoadScenario(0xdc20c0, {0x1036bc0, 0x32}) Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules/escontext.go:470 +0x220 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules.(*ESEngine).loadScript(0xdadc70, {0x1036bc0, 0x32}, 0x0) Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules/esengine.go:803 +0x6a0 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules.(*ESEngine).loadScriptAndRefresh(0xdadc70, {0x1036bc0, 0x32}, 0x0) Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules/esengine.go:866 +0x34 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules.(*ESEngine).LiveLoadFile.func1() Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules/esengine.go:917 +0x3c Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules.(*RuleEngine).syncLoop(0xda0200) Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules/engine.go:790 +0x120 Mar 13 15:09:12 IQ305002 wb-rules[4695]: created by github.com/wirenboard/wb-rules/wbrules.(*RuleEngine).Start in goroutine 1 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules/engine.go:1338 +0x2d4 Mar 13 15:09:12 IQ305002 wb-rules[4695]: goroutine 68 [syscall]: Mar 13 15:09:12 IQ305002 wb-rules[4695]: syscall.Syscall6(0xfc, 0xf, 0x100fdb8, 0x7, 0xffffffff, 0x0, 0x0) Mar 13 15:09:12 IQ305002 wb-rules[4695]: syscall/syscall_linux.go:91 +0x8 Mar 13 15:09:12 IQ305002 wb-rules[4695]: golang.org/x/sys/unix.EpollWait(0xf, {0x100fdb8, 0x7, 0x7}, 0xffffffff) Mar 13 15:09:12 IQ305002 wb-rules[4695]: golang.org/x/sys@v0.22.0/unix/zsyscall_linux_arm.go:223 +0x64 Mar 13 15:09:12 IQ305002 wb-rules[4695]: gopkg.in/fsnotify%2ev1.(*fdPoller).wait(0xe06c60) Mar 13 15:09:12 IQ305002 wb-rules[4695]: gopkg.in/fsnotify.v1@v1.3.0/inotify_poller.go:86 +0x5c Mar 13 15:09:12 IQ305002 wb-rules[4695]: gopkg.in/fsnotify%2ev1.(*Watcher).readEvents(0xe5b5c0) Mar 13 15:09:12 IQ305002 wb-rules[4695]: gopkg.in/fsnotify.v1@v1.3.0/inotify.go:192 +0x29c Mar 13 15:09:12 IQ305002 wb-rules[4695]: created by gopkg.in/fsnotify%2ev1.NewWatcher in goroutine 1 Mar 13 15:09:12 IQ305002 wb-rules[4695]: gopkg.in/fsnotify.v1@v1.3.0/inotify.go:61 +0x270 Mar 13 15:09:12 IQ305002 wb-rules[4695]: goroutine 69 [select]: Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wbgo-private.(*dirWatcher).startWatching.func1() Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wbgo-private/dirwatcher.go:150 +0xdc Mar 13 15:09:12 IQ305002 wb-rules[4695]: created by github.com/wirenboard/wbgo-private.(*dirWatcher).startWatching in goroutine 1 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wbgo-private/dirwatcher.go:148 +0x298 Mar 13 15:09:12 IQ305002 wb-rules[4695]: goroutine 64 [semacquire]: Mar 13 15:09:12 IQ305002 wb-rules[4695]: sync.runtime_Semacquire(0xc10a78) Mar 13 15:09:12 IQ305002 wb-rules[4695]: runtime/sema.go:62 +0x3c Mar 13 15:09:12 IQ305002 wb-rules[4695]: sync.(*WaitGroup).Wait(0xc10a70) Mar 13 15:09:12 IQ305002 wb-rules[4695]: sync/waitgroup.go:116 +0x68 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules.Spawn({0xd82f86, 0x7}, {0xf888f0, 0x2, 0x2}, 0x1, 0x0, 0x0) Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules/spawn.go:79 +0x58c Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules.(*ESEngine).esWbSpawn.func1() Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules/esengine.go:2175 +0x9c Mar 13 15:09:12 IQ305002 wb-rules[4695]: created by github.com/wirenboard/wb-rules/wbrules.(*ESEngine).esWbSpawn in goroutine 67 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules/esengine.go:2174 +0x32c Mar 13 15:09:12 IQ305002 wb-rules[4695]: goroutine 74 [IO wait]: Mar 13 15:09:12 IQ305002 wb-rules[4695]: internal/poll.runtime_pollWait(0xade54410, 0x72) Mar 13 15:09:12 IQ305002 wb-rules[4695]: runtime/netpoll.go:343 +0x54 Mar 13 15:09:12 IQ305002 wb-rules[4695]: internal/poll.(*pollDesc).wait(0x104a058, 0x72, 0x1) Mar 13 15:09:12 IQ305002 wb-rules[4695]: internal/poll/fd_poll_runtime.go:84 +0x30 Mar 13 15:09:12 IQ305002 wb-rules[4695]: internal/poll.(*pollDesc).waitRead(...) Mar 13 15:09:12 IQ305002 wb-rules[4695]: internal/poll/fd_poll_runtime.go:89 Mar 13 15:09:12 IQ305002 wb-rules[4695]: internal/poll.(*FD).Read(0x104a040, {0xe1ec00, 0x200, 0x200}) Mar 13 15:09:12 IQ305002 wb-rules[4695]: internal/poll/fd_unix.go:164 +0x240 Mar 13 15:09:12 IQ305002 wb-rules[4695]: os.(*File).read(...) Mar 13 15:09:12 IQ305002 wb-rules[4695]: os/file_posix.go:29 Mar 13 15:09:12 IQ305002 wb-rules[4695]: os.(*File).Read(0x10284c0, {0xe1ec00, 0x200, 0x200}) Mar 13 15:09:12 IQ305002 wb-rules[4695]: os/file.go:118 +0x80 Mar 13 15:09:12 IQ305002 wb-rules[4695]: bytes.(*Buffer).ReadFrom(0x1027290, {0x7879bc, 0x10284c0}) Mar 13 15:09:12 IQ305002 wb-rules[4695]: bytes/buffer.go:211 +0xa4 Mar 13 15:09:12 IQ305002 wb-rules[4695]: io.copyBuffer({0x787c8c, 0x1027290}, {0x7879bc, 0x10284c0}, {0x0, 0x0, 0x0}) Mar 13 15:09:12 IQ305002 wb-rules[4695]: io/io.go:416 +0x154 Mar 13 15:09:12 IQ305002 wb-rules[4695]: io.Copy(...) Mar 13 15:09:12 IQ305002 wb-rules[4695]: io/io.go:389 Mar 13 15:09:12 IQ305002 systemd[1]: wb-rules.service: Failed with result 'exit-code'. Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules.captureCommandOutput.func1() Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules/spawn.go:25 +0x90 Mar 13 15:09:12 IQ305002 wb-rules[4695]: created by github.com/wirenboard/wb-rules/wbrules.captureCommandOutput in goroutine 71 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules/spawn.go:23 +0xa4 Mar 13 15:09:12 IQ305002 wb-rules[4695]: goroutine 86 [syscall]: Mar 13 15:09:12 IQ305002 wb-rules[4695]: syscall.Syscall6(0x118, 0x1, 0x128c, 0xde8df4, 0x1000004, 0x0, 0x0) Mar 13 15:09:12 IQ305002 wb-rules[4695]: syscall/syscall_linux.go:91 +0x8 Mar 13 15:09:12 IQ305002 wb-rules[4695]: os.(*Process).blockUntilWaitable(0xef45a0) Mar 13 15:09:12 IQ305002 wb-rules[4695]: os/wait_waitid.go:32 +0x64 Mar 13 15:09:12 IQ305002 wb-rules[4695]: os.(*Process).wait(0xef45a0) Mar 13 15:09:12 IQ305002 wb-rules[4695]: os/exec_unix.go:22 +0x2c Mar 13 15:09:12 IQ305002 wb-rules[4695]: os.(*Process).Wait(...) Mar 13 15:09:12 IQ305002 wb-rules[4695]: os/exec.go:134 Mar 13 15:09:12 IQ305002 wb-rules[4695]: os/exec.(*Cmd).Wait(0xe70240) Mar 13 15:09:12 IQ305002 wb-rules[4695]: os/exec/exec.go:890 +0x38 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules.Spawn({0xd83d57, 0x7}, {0xff1430, 0x2, 0x2}, 0x0, 0x0, 0x0) Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules/spawn.go:85 +0x618 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules.(*ESEngine).esWbSpawn.func1() Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules/esengine.go:2175 +0x9c Mar 13 15:09:12 IQ305002 wb-rules[4695]: created by github.com/wirenboard/wb-rules/wbrules.(*ESEngine).esWbSpawn in goroutine 67 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules/esengine.go:2174 +0x32c Mar 13 15:09:12 IQ305002 wb-rules[4695]: goroutine 89 [syscall]: Mar 13 15:09:12 IQ305002 wb-rules[4695]: syscall.Syscall6(0x118, 0x1, 0x128b, 0xdecdf4, 0x1000004, 0x0, 0x0) Mar 13 15:09:12 IQ305002 wb-rules[4695]: syscall/syscall_linux.go:91 +0x8 Mar 13 15:09:12 IQ305002 wb-rules[4695]: os.(*Process).blockUntilWaitable(0xef4690) Mar 13 15:09:12 IQ305002 wb-rules[4695]: os/wait_waitid.go:32 +0x64 Mar 13 15:09:12 IQ305002 wb-rules[4695]: os.(*Process).wait(0xef4690) Mar 13 15:09:12 IQ305002 wb-rules[4695]: os/exec_unix.go:22 +0x2c Mar 13 15:09:12 IQ305002 wb-rules[4695]: os.(*Process).Wait(...) Mar 13 15:09:12 IQ305002 wb-rules[4695]: os/exec.go:134 Mar 13 15:09:12 IQ305002 wb-rules[4695]: os/exec.(*Cmd).Wait(0xf8c240) Mar 13 15:09:12 IQ305002 wb-rules[4695]: os/exec/exec.go:890 +0x38 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules.Spawn({0xd83d90, 0x7}, {0xff1490, 0x2, 0x2}, 0x0, 0x0, 0x0) Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules/spawn.go:85 +0x618 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules.(*ESEngine).esWbSpawn.func1() Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules/esengine.go:2175 +0x9c Mar 13 15:09:12 IQ305002 wb-rules[4695]: created by github.com/wirenboard/wb-rules/wbrules.(*ESEngine).esWbSpawn in goroutine 67 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules/esengine.go:2174 +0x32c Mar 13 15:09:12 IQ305002 wb-rules[4695]: goroutine 92 [syscall]: Mar 13 15:09:12 IQ305002 wb-rules[4695]: syscall.Syscall6(0x118, 0x1, 0x12a9, 0xc3fdf4, 0x1000004, 0x0, 0x0) Mar 13 15:09:12 IQ305002 wb-rules[4695]: syscall/syscall_linux.go:91 +0x8 Mar 13 15:09:12 IQ305002 wb-rules[4695]: os.(*Process).blockUntilWaitable(0xe593e0) Mar 13 15:09:12 IQ305002 systemd[1]: wb-rules.service: Consumed 3.248s CPU time. Mar 13 15:09:12 IQ305002 wb-rules[4695]: os/wait_waitid.go:32 +0x64 Mar 13 15:09:12 IQ305002 wb-rules[4695]: os.(*Process).wait(0xe593e0) Mar 13 15:09:12 IQ305002 wb-rules[4695]: os/exec_unix.go:22 +0x2c Mar 13 15:09:12 IQ305002 wb-rules[4695]: os.(*Process).Wait(...) Mar 13 15:09:12 IQ305002 wb-rules[4695]: os/exec.go:134 Mar 13 15:09:12 IQ305002 wb-rules[4695]: os/exec.(*Cmd).Wait(0xe70480) Mar 13 15:09:12 IQ305002 wb-rules[4695]: os/exec/exec.go:890 +0x38 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules.Spawn({0xd83dc7, 0x7}, {0xff14f0, 0x2, 0x2}, 0x0, 0x0, 0x0) Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules/spawn.go:85 +0x618 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules.(*ESEngine).esWbSpawn.func1() Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules/esengine.go:2175 +0x9c Mar 13 15:09:12 IQ305002 wb-rules[4695]: created by github.com/wirenboard/wb-rules/wbrules.(*ESEngine).esWbSpawn in goroutine 67 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules/esengine.go:2174 +0x32c Mar 13 15:09:12 IQ305002 wb-rules[4695]: goroutine 95 [syscall]: Mar 13 15:09:12 IQ305002 wb-rules[4695]: syscall.Syscall6(0x118, 0x1, 0x12a7, 0xf4fdf4, 0x1000004, 0x0, 0x0) Mar 13 15:09:12 IQ305002 wb-rules[4695]: syscall/syscall_linux.go:91 +0x8 Mar 13 15:09:12 IQ305002 wb-rules[4695]: os.(*Process).blockUntilWaitable(0xe59410) Mar 13 15:09:12 IQ305002 wb-rules[4695]: os/wait_waitid.go:32 +0x64 Mar 13 15:09:12 IQ305002 wb-rules[4695]: os.(*Process).wait(0xe59410) Mar 13 15:09:12 IQ305002 wb-rules[4695]: os/exec_unix.go:22 +0x2c Mar 13 15:09:12 IQ305002 wb-rules[4695]: os.(*Process).Wait(...) Mar 13 15:09:12 IQ305002 wb-rules[4695]: os/exec.go:134 Mar 13 15:09:12 IQ305002 wb-rules[4695]: os/exec.(*Cmd).Wait(0xcce600) Mar 13 15:09:12 IQ305002 wb-rules[4695]: os/exec/exec.go:890 +0x38 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules.Spawn({0xd83e00, 0x7}, {0xff1550, 0x2, 0x2}, 0x0, 0x0, 0x0) Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules/spawn.go:85 +0x618 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules.(*ESEngine).esWbSpawn.func1() Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules/esengine.go:2175 +0x9c Mar 13 15:09:12 IQ305002 wb-rules[4695]: created by github.com/wirenboard/wb-rules/wbrules.(*ESEngine).esWbSpawn in goroutine 67 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules/esengine.go:2174 +0x32c Mar 13 15:09:12 IQ305002 wb-rules[4695]: goroutine 109 [select]: Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules.(*RuleEngine).StartTimer.func1.1() Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules/engine.go:1410 +0x98 Mar 13 15:09:12 IQ305002 wb-rules[4695]: created by github.com/wirenboard/wb-rules/wbrules.(*RuleEngine).StartTimer.func1 in goroutine 67 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules/engine.go:1408 +0x260 Mar 13 15:09:12 IQ305002 wb-rules[4695]: goroutine 71 [semacquire]: Mar 13 15:09:12 IQ305002 wb-rules[4695]: sync.runtime_Semacquire(0xe075a8) Mar 13 15:09:12 IQ305002 wb-rules[4695]: runtime/sema.go:62 +0x3c Mar 13 15:09:12 IQ305002 wb-rules[4695]: sync.(*WaitGroup).Wait(0xe075a0) Mar 13 15:09:12 IQ305002 wb-rules[4695]: sync/waitgroup.go:116 +0x68 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules.Spawn({0xe074c8, 0x7}, {0x10271a8, 0x2, 0x2}, 0x1, 0x0, 0x0) Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules/spawn.go:79 +0x58c Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules.(*ESEngine).esWbSpawn.func1() Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules/esengine.go:2175 +0x9c Mar 13 15:09:12 IQ305002 wb-rules[4695]: created by github.com/wirenboard/wb-rules/wbrules.(*ESEngine).esWbSpawn in goroutine 67 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules/esengine.go:2174 +0x32c Mar 13 15:09:12 IQ305002 wb-rules[4695]: goroutine 72 [select]: Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules.(*RuleEngine).StartTimer.func1.1() Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules/engine.go:1410 +0x98 Mar 13 15:09:12 IQ305002 wb-rules[4695]: created by github.com/wirenboard/wb-rules/wbrules.(*RuleEngine).StartTimer.func1 in goroutine 67 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules/engine.go:1408 +0x260 Mar 13 15:09:12 IQ305002 wb-rules[4695]: goroutine 16 [syscall]: Mar 13 15:09:12 IQ305002 wb-rules[4695]: syscall.Syscall6(0x118, 0x1, 0x12e7, 0xf4bdf4, 0x1000004, 0x0, 0x0) Mar 13 15:09:12 IQ305002 wb-rules[4695]: syscall/syscall_linux.go:91 +0x8 Mar 13 15:09:12 IQ305002 wb-rules[4695]: os.(*Process).blockUntilWaitable(0xe583f0) Mar 13 15:09:12 IQ305002 wb-rules[4695]: os/wait_waitid.go:32 +0x64 Mar 13 15:09:12 IQ305002 wb-rules[4695]: os.(*Process).wait(0xe583f0) Mar 13 15:09:12 IQ305002 wb-rules[4695]: os/exec_unix.go:22 +0x2c Mar 13 15:09:12 IQ305002 wb-rules[4695]: os.(*Process).Wait(...) Mar 13 15:09:12 IQ305002 wb-rules[4695]: os/exec.go:134 Mar 13 15:09:12 IQ305002 wb-rules[4695]: os/exec.(*Cmd).Wait(0xede0c0) Mar 13 15:09:12 IQ305002 wb-rules[4695]: os/exec/exec.go:890 +0x38 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules.Spawn({0xf382fc, 0x4}, {0x1026968, 0x2, 0x2}, 0x0, 0x0, 0x0) Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules/spawn.go:85 +0x618 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules.(*ESEngine).esWbSpawn.func1() Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules/esengine.go:2175 +0x9c Mar 13 15:09:12 IQ305002 wb-rules[4695]: created by github.com/wirenboard/wb-rules/wbrules.(*ESEngine).esWbSpawn in goroutine 67 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules/esengine.go:2174 +0x32c Mar 13 15:09:12 IQ305002 wb-rules[4695]: goroutine 47 [runnable]: Mar 13 15:09:12 IQ305002 wb-rules[4695]: syscall.Syscall(0x3, 0x14, 0xca296f, 0x291) Mar 13 15:09:12 IQ305002 wb-rules[4695]: syscall/syscall_linux.go:69 +0x8 Mar 13 15:09:12 IQ305002 wb-rules[4695]: syscall.read(0x14, {0xca296f, 0x291, 0x291}) Mar 13 15:09:12 IQ305002 wb-rules[4695]: syscall/zsyscall_linux_arm.go:721 +0x48 Mar 13 15:09:12 IQ305002 wb-rules[4695]: syscall.Read(...) Mar 13 15:09:12 IQ305002 wb-rules[4695]: syscall/syscall_unix.go:181 Mar 13 15:09:12 IQ305002 wb-rules[4695]: internal/poll.ignoringEINTRIO(...) Mar 13 15:09:12 IQ305002 wb-rules[4695]: internal/poll/fd_unix.go:736 Mar 13 15:09:12 IQ305002 wb-rules[4695]: internal/poll.(*FD).Read(0x1082c40, {0xca296f, 0x291, 0x291}) Mar 13 15:09:12 IQ305002 wb-rules[4695]: internal/poll/fd_unix.go:160 +0x278 Mar 13 15:09:12 IQ305002 wb-rules[4695]: os.(*File).read(...) Mar 13 15:09:12 IQ305002 wb-rules[4695]: os/file_posix.go:29 Mar 13 15:09:12 IQ305002 wb-rules[4695]: os.(*File).Read(0xc222a0, {0xca296f, 0x291, 0x291}) Mar 13 15:09:12 IQ305002 wb-rules[4695]: os/file.go:118 +0x80 Mar 13 15:09:12 IQ305002 wb-rules[4695]: bytes.(*Buffer).ReadFrom(0xff0930, {0x7879bc, 0xc222a0}) Mar 13 15:09:12 IQ305002 wb-rules[4695]: bytes/buffer.go:211 +0xa4 Mar 13 15:09:12 IQ305002 wb-rules[4695]: io.copyBuffer({0x787c8c, 0xff0930}, {0x7879bc, 0xc222a0}, {0x0, 0x0, 0x0}) Mar 13 15:09:12 IQ305002 wb-rules[4695]: io/io.go:416 +0x154 Mar 13 15:09:12 IQ305002 wb-rules[4695]: io.Copy(...) Mar 13 15:09:12 IQ305002 wb-rules[4695]: io/io.go:389 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules.captureCommandOutput.func1() Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules/spawn.go:25 +0x90 Mar 13 15:09:12 IQ305002 wb-rules[4695]: created by github.com/wirenboard/wb-rules/wbrules.captureCommandOutput in goroutine 118 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules/spawn.go:23 +0xa4 Mar 13 15:09:12 IQ305002 wb-rules[4695]: goroutine 118 [semacquire]: Mar 13 15:09:12 IQ305002 wb-rules[4695]: sync.runtime_Semacquire(0x109e318) Mar 13 15:09:12 IQ305002 wb-rules[4695]: runtime/sema.go:62 +0x3c Mar 13 15:09:12 IQ305002 wb-rules[4695]: sync.(*WaitGroup).Wait(0x109e310) Mar 13 15:09:12 IQ305002 wb-rules[4695]: sync/waitgroup.go:116 +0x68 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules.Spawn({0xf383f8, 0x7}, {0x1026a28, 0x2, 0x2}, 0x1, 0x0, 0x0) Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules/spawn.go:79 +0x58c Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules.(*ESEngine).esWbSpawn.func1() Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules/esengine.go:2175 +0x9c Mar 13 15:09:12 IQ305002 wb-rules[4695]: created by github.com/wirenboard/wb-rules/wbrules.(*ESEngine).esWbSpawn in goroutine 67 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules/esengine.go:2174 +0x32c Mar 13 15:09:12 IQ305002 wb-rules[4695]: goroutine 119 [select]: Mar 13 15:09:12 IQ305002 wb-rules[4695]: gopkg.in/robfig/cron%2ev1.(*Cron).run(0x10a2660) Mar 13 15:09:12 IQ305002 wb-rules[4695]: gopkg.in/robfig/cron.v1@v1.2.0/cron.go:191 +0x520 Mar 13 15:09:12 IQ305002 wb-rules[4695]: created by gopkg.in/robfig/cron%2ev1.(*Cron).Start in goroutine 67 Mar 13 15:09:12 IQ305002 wb-rules[4695]: gopkg.in/robfig/cron.v1@v1.2.0/cron.go:144 +0x70 Mar 13 15:09:12 IQ305002 wb-rules[4695]: goroutine 111 [semacquire]: Mar 13 15:09:12 IQ305002 wb-rules[4695]: sync.runtime_Semacquire(0xc10e18) Mar 13 15:09:12 IQ305002 wb-rules[4695]: runtime/sema.go:62 +0x3c Mar 13 15:09:12 IQ305002 wb-rules[4695]: sync.(*WaitGroup).Wait(0xc10e10) Mar 13 15:09:12 IQ305002 wb-rules[4695]: sync/waitgroup.go:116 +0x68 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules.Spawn({0xc10c58, 0x4}, {0xcbe428, 0x2, 0x2}, 0x1, 0x0, 0x0) Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules/spawn.go:79 +0x58c Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules.(*ESEngine).esWbSpawn.func1() Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules/esengine.go:2175 +0x9c Mar 13 15:09:12 IQ305002 wb-rules[4695]: created by github.com/wirenboard/wb-rules/wbrules.(*ESEngine).esWbSpawn in goroutine 67 Mar 13 15:09:12 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules/esengine.go:2174 +0x32c Mar 13 15:09:12 IQ305002 wb-rules[4695]: goroutine 130 [IO wait]: Mar 13 15:09:12 IQ305002 wb-rules[4695]: internal/poll.runtime_pollWait(0xade542d0, 0x72) Mar 13 15:09:12 IQ305002 wb-rules[4695]: runtime/netpoll.go:343 +0x54 Mar 13 15:09:12 IQ305002 wb-rules[4695]: internal/poll.(*pollDesc).wait(0xf8aed8, 0x72, 0x1) Mar 13 15:09:12 IQ305002 wb-rules[4695]: internal/poll/fd_poll_runtime.go:84 +0x30 Mar 13 15:09:13 IQ305002 wb-rules[4695]: internal/poll.(*pollDesc).waitRead(...) Mar 13 15:09:13 IQ305002 wb-rules[4695]: internal/poll/fd_poll_runtime.go:89 Mar 13 15:09:13 IQ305002 wb-rules[4695]: internal/poll.(*FD).Read(0xf8aec0, {0xe84600, 0x200, 0x200}) Mar 13 15:09:13 IQ305002 wb-rules[4695]: internal/poll/fd_unix.go:164 +0x240 Mar 13 15:09:13 IQ305002 wb-rules[4695]: os.(*File).read(...) Mar 13 15:09:13 IQ305002 wb-rules[4695]: os/file_posix.go:29 Mar 13 15:09:13 IQ305002 wb-rules[4695]: os.(*File).Read(0x1028d50, {0xe84600, 0x200, 0x200}) Mar 13 15:09:13 IQ305002 wb-rules[4695]: os/file.go:118 +0x80 Mar 13 15:09:13 IQ305002 wb-rules[4695]: bytes.(*Buffer).ReadFrom(0xcbe660, {0x7879bc, 0x1028d50}) Mar 13 15:09:13 IQ305002 wb-rules[4695]: bytes/buffer.go:211 +0xa4 Mar 13 15:09:13 IQ305002 wb-rules[4695]: io.copyBuffer({0x787c8c, 0xcbe660}, {0x7879bc, 0x1028d50}, {0x0, 0x0, 0x0}) Mar 13 15:09:13 IQ305002 wb-rules[4695]: io/io.go:416 +0x154 Mar 13 15:09:13 IQ305002 wb-rules[4695]: io.Copy(...) Mar 13 15:09:13 IQ305002 wb-rules[4695]: io/io.go:389 Mar 13 15:09:13 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules.captureCommandOutput.func1() Mar 13 15:09:13 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules/spawn.go:25 +0x90 Mar 13 15:09:13 IQ305002 wb-rules[4695]: created by github.com/wirenboard/wb-rules/wbrules.captureCommandOutput in goroutine 111 Mar 13 15:09:13 IQ305002 wb-rules[4695]: github.com/wirenboard/wb-rules/wbrules/spawn.go:23 +0xa4 Mar 13 15:09:13 IQ305002 systemd[1]: wb-rules.service: Scheduled restart job, restart counter is at 1. Mar 13 15:09:13 IQ305002 systemd[1]: Stopped MQTT Rule engine for Wiren Board. Mar 13 15:09:13 IQ305002 systemd[1]: wb-rules.service: Consumed 3.248s CPU time. Mar 13 15:09:13 IQ305002 systemd[1]: Started MQTT Rule engine for Wiren Board. Mar 13 15:09:14 IQ305002 wb-rules[4934]: INFO: broker URL is default and mosquitto socket detected, trying to connect via it Mar 13 15:09:14 IQ305002 wb-rules[4934]: INFO: driver is created Mar 13 15:09:14 IQ305002 wb-rules[4934]: INFO: [wbgo_mqtt] rules-IQ305002-4934: MQTT connection established Mar 13 15:09:14 IQ305002 wb-rules[4934]: INFO: driver loop is started Mar 13 15:09:14 IQ305002 wb-rules[4934]: INFO: wait for driver to become ready Mar 13 15:09:14 IQ305002 wb-rules[4934]: INFO: driver is ready Mar 13 15:09:14 IQ305002 wb-rules[4934]: INFO: [rule info] using file /var/lib/wirenboard/wbrules-persistent.db for persistent DB Mar 13 15:09:14 IQ305002 wb-rules[4934]: INFO: [wbgo_mqtt] wb-rules-engine-IQ305002-4934: MQTT connection established Mar 13 15:09:14 IQ305002 wb-rules[4934]: INFO: [engine] Starting main loop Mar 13 15:09:14 IQ305002 wb-rules[4934]: INFO: the engine is ready Mar 13 15:09:14 IQ305002 wb-rules[4934]: INFO: [engine] Starting sync loop Mar 13 15:09:15 IQ305002 wb-rules[4934]: ERROR: [rule error] Error in getting device: Device with given ID doesn't exist Mar 13 15:09:15 IQ305002 wb-rules[4934]: ERROR: [rule error] Error in getting device: Device with given ID doesn't exist Mar 13 15:09:15 IQ305002 wb-rules[4934]: ERROR: [rule error] Error in getting device: Device with given ID doesn't exist Mar 13 15:09:15 IQ305002 wb-rules[4934]: ERROR: [rule error] Error in getting device: Device with given ID doesn't exist Mar 13 15:09:15 IQ305002 wb-rules[4934]: ERROR: [rule error] Error in getting device: Device with given ID doesn't exist Mar 13 15:09:15 IQ305002 wb-rules[4934]: ERROR: [rule error] Error in getting device: Device with given ID doesn't exist Mar 13 15:09:15 IQ305002 wb-rules[4934]: ERROR: [rule error] Error in getting device: Device with given ID doesn't exist Mar 13 15:09:15 IQ305002 wb-rules[4934]: ERROR: [rule error] Error in getting device: Device with given ID doesn't exist Mar 13 15:09:15 IQ305002 wb-rules[4934]: ERROR: [rule error] Error in getting device: Device with given ID doesn't exist Mar 13 15:09:15 IQ305002 wb-rules[4934]: ERROR: [rule error] Error in getting device: Device with given ID doesn't exist Mar 13 15:09:15 IQ305002 wb-rules[4934]: WARNING: [rule warning] DAC: no config file Mar 13 15:09:15 IQ305002 wb-rules[4934]: INFO: all rule files are loaded -- Boot d117a5afbfd044c8b879cd8387a57453 -- Mar 13 15:11:11 IQ305002 systemd[1]: Started MQTT Rule engine for Wiren Board. Mar 13 15:11:12 IQ305002 wb-rules[4703]: INFO: broker URL is default and mosquitto socket detected, trying to connect via it Mar 13 15:11:12 IQ305002 wb-rules[4703]: INFO: driver is created Mar 13 15:11:12 IQ305002 wb-rules[4703]: INFO: [wbgo_mqtt] rules-IQ305002-4703: MQTT connection established Mar 13 15:11:12 IQ305002 wb-rules[4703]: INFO: driver loop is started Mar 13 15:11:12 IQ305002 wb-rules[4703]: INFO: wait for driver to become ready Mar 13 15:11:12 IQ305002 wb-rules[4703]: INFO: driver is ready Mar 13 15:11:12 IQ305002 wb-rules[4703]: INFO: [rule info] using file /var/lib/wirenboard/wbrules-persistent.db for persistent DB Mar 13 15:11:12 IQ305002 wb-rules[4703]: INFO: [wbgo_mqtt] wb-rules-engine-IQ305002-4703: MQTT connection established Mar 13 15:11:12 IQ305002 wb-rules[4703]: INFO: [engine] Starting main loop Mar 13 15:11:12 IQ305002 wb-rules[4703]: INFO: the engine is ready Mar 13 15:11:12 IQ305002 wb-rules[4703]: INFO: [engine] Starting sync loop Mar 13 15:11:13 IQ305002 wb-rules[4703]: ERROR: [rule error] Error in getting device: Device with given ID doesn't exist Mar 13 15:11:13 IQ305002 wb-rules[4703]: ERROR: [rule error] Error in getting device: Device with given ID doesn't exist Mar 13 15:11:13 IQ305002 wb-rules[4703]: ERROR: [rule error] Error in getting device: Device with given ID doesn't exist Mar 13 15:11:13 IQ305002 wb-rules[4703]: ERROR: [rule error] Error in getting device: Device with given ID doesn't exist Mar 13 15:11:13 IQ305002 wb-rules[4703]: ERROR: [rule error] Error in getting device: Device with given ID doesn't exist Mar 13 15:11:13 IQ305002 wb-rules[4703]: ERROR: [rule error] Error in getting device: Device with given ID doesn't exist Mar 13 15:11:13 IQ305002 wb-rules[4703]: ERROR: [rule error] Error in getting device: Device with given ID doesn't exist Mar 13 15:11:13 IQ305002 wb-rules[4703]: ERROR: [rule error] Error in getting device: Device with given ID doesn't exist Mar 13 15:11:13 IQ305002 wb-rules[4703]: ERROR: [rule error] Error in getting device: Device with given ID doesn't exist Mar 13 15:11:13 IQ305002 wb-rules[4703]: ERROR: [rule error] Error in getting device: Device with given ID doesn't exist Mar 13 15:11:13 IQ305002 wb-rules[4703]: WARNING: [rule warning] DAC: no config file Mar 13 15:11:13 IQ305002 wb-rules[4703]: INFO: all rule files are loaded Mar 13 15:12:43 IQ305002 wb-rules[4703]: INFO: [engine] Stopping sync loop Mar 13 15:12:43 IQ305002 wb-rules[4703]: INFO: [engine] Stop main loop Mar 13 15:12:43 IQ305002 systemd[1]: Stopping MQTT Rule engine for Wiren Board... Mar 13 15:12:43 IQ305002 systemd[1]: wb-rules.service: Succeeded. Mar 13 15:12:43 IQ305002 systemd[1]: Stopped MQTT Rule engine for Wiren Board. Mar 13 15:12:43 IQ305002 systemd[1]: wb-rules.service: Consumed 13.265s CPU time. Mar 13 15:12:43 IQ305002 systemd[1]: Started MQTT Rule engine for Wiren Board. Mar 13 15:12:43 IQ305002 wb-rules[6073]: INFO: broker URL is default and mosquitto socket detected, trying to connect via it Mar 13 15:12:43 IQ305002 wb-rules[6073]: INFO: driver is created Mar 13 15:12:43 IQ305002 wb-rules[6073]: INFO: [wbgo_mqtt] rules-IQ305002-6073: MQTT connection established Mar 13 15:12:44 IQ305002 wb-rules[6073]: INFO: driver loop is started Mar 13 15:12:44 IQ305002 wb-rules[6073]: INFO: wait for driver to become ready Mar 13 15:12:44 IQ305002 wb-rules[6073]: INFO: driver is ready Mar 13 15:12:44 IQ305002 wb-rules[6073]: INFO: [rule info] using file /var/lib/wirenboard/wbrules-persistent.db for persistent DB Mar 13 15:12:44 IQ305002 wb-rules[6073]: INFO: [wbgo_mqtt] wb-rules-engine-IQ305002-6073: MQTT connection established Mar 13 15:12:44 IQ305002 wb-rules[6073]: INFO: [engine] Starting main loop Mar 13 15:12:44 IQ305002 wb-rules[6073]: INFO: the engine is ready Mar 13 15:12:44 IQ305002 wb-rules[6073]: INFO: [engine] Starting sync loop Mar 13 15:12:45 IQ305002 wb-rules[6073]: WARNING: [rule warning] DAC: no config file Mar 13 15:12:45 IQ305002 wb-rules[6073]: INFO: all rule files are loaded