Raspberry Zero W — failed to send packet

Discussions about RaZberry - Z-Wave board for Raspberry computer
Post Reply
shemigon
Posts: 2
Joined: 16 Oct 2017 07:49

Raspberry Zero W — failed to send packet

Post by shemigon »

After reading all I could found on the Internet, I feel like I'm stuck. Does anybody have such an issue? I would greatly appreciate any input.

First, the log:

Code: Select all

[2017-10-16 00:53:43.123] [I] [core] Executing script: /*** Z-Way Home Automation Engine main executable ***************************** ...
[2017-10-16 00:53:43.133] [I] [core] Executing script: // Comon utilities and functions ...
[2017-10-16 00:53:43.161] [I] [core] Executing script: // This script transforms old formats to new ...
[2017-10-16 00:53:43.982] [I] [core] Executing script: /*! EventEmitter2 https://github.com/hij1nx/EventEmitter2 Copyright (c) 2013 hij1nx Licensed under the MIT license.*/ ...
[2017-10-16 00:53:44.099] [I] [core] Executing script: //     Underscore.js 1.8.3 ...
[2017-10-16 00:53:44.196] [I] [core] Executing script: /*! Papa Parse v4.1.2 https://github.com/mholt/PapaParse */ ...
[2017-10-16 00:53:44.312] [I] [core] Executing script: /* @license zlib.js 2012 - imaya [ https://github.com/imaya/zlib.js ] The MIT License */ ...
[2017-10-16 00:53:44.387] [I] [core] Executing script: // BAOS_API_2011_01_29_001 ...
[2017-10-16 00:53:44.389] [I] [core] Executing script: // Converts IntelHex into binary 128 kB file ...
[2017-10-16 00:53:44.392] [I] [core] Executing script: // Public domain version of Base64 encoder/decoder found somewhere in the Internet ...
[2017-10-16 00:53:44.411] [I] [core] Executing script: //--------------------------------------------------------------------- ...
[2017-10-16 00:53:44.487] [I] [core] Executing script: /* ...
[2017-10-16 00:53:44.609] [I] [core] Executing script: /*** Z-Way HA Controller class module ***************************************** ...
[2017-10-16 00:53:44.623] [I] [core] Executing script: /*** Automation Webserver Auth Controller ************************************* ...
[2017-10-16 00:53:44.629] [I] [core] Executing script: /*** Z-Way HA Automation module base class ************************************ ...
[2017-10-16 00:53:44.632] [I] [core] Executing script: /*** Initialize Webserver and Handlers ***************************************** ...
[2017-10-16 00:53:44.646] [I] [core] Executing script: /*** ZAutomationAPI Web Request Handler ***************************************** ...
[2017-10-16 00:53:44.712] [I] [core] Executing script: /*** ZAutomationAPI Provider ************************************************** ...
[2017-10-16 00:53:44.716] [I] [core] Executing script: // Make this testable by pulling in underscore from the parent module ...
[2017-10-16 00:53:44.728] [I] [core] Executing script: /*** Main Automation storage module ***************************************** ...
[2017-10-16 00:53:44.771] [I] [core] --- Loading ZAutomation classes
[2017-10-16 00:53:45.005] [I] [core] Starting ZWay Automation webserver
[2017-10-16 00:53:45.027] [I] [core] Loading modules...
[2017-10-16 00:53:45.068] [I] [core] Loading module Cron from modules/Cron
[2017-10-16 00:53:45.072] [I] [core] Executing script: /*** Cron ZAutomation module ************************************************** ...
[2017-10-16 00:53:45.112] [I] [core] Instantiating module 2 from class Cron
[2017-10-16 00:53:45.119] [I] [core] --- Starting module System Clock (CRON)
[2017-10-16 00:53:45.185] [I] [core] Loading module ZWave from modules/ZWave
[2017-10-16 00:53:45.259] [I] [core] Executing script: /*** Z-Wave Binding module ******************************************************** ...
[2017-10-16 00:53:45.290] [I] [core] Instantiating module 13 from class ZWave
[2017-10-16 00:53:45.297] [I] [core] --- Starting module Z-Wave Network Access
[2017-10-16 00:53:46.193] [I] [zway] Adding job: Get controller info and supported function classes
[2017-10-16 00:53:46.194] [C] [i/o] Serial port write error: (null)
[2017-10-16 00:53:46.194] [E] [zway] Failed to send packet: (null)
[2017-10-16 00:53:46.215] [I] [core] Terminating Z-Wave binding
[2017-10-16 00:53:46.245] [C] [zway] Get Serial API Capabilities returned zero.
[2017-10-16 00:53:46.291] [I] [core] Trying to restart Z-Wave binding (zway) in 10 seconds
[2017-10-16 00:53:46.303] [I] [core] Error: Bad data - communication failed
[2017-10-16 00:53:46.304] [I] [core] Trying to restart Z-Wave binding (zway) in 10 seconds
[2017-10-16 00:53:46.311] [I] [core] Loading module FosCam9805 from modules/FosCam9805
[2017-10-16 00:53:46.314] [I] [core] Executing script: /*** FosCam9805 Z-Way HA module ******************************************* ...
[2017-10-16 00:53:46.327] [I] [core] Loading module TamperAutoOff from modules/TamperAutoOff
[2017-10-16 00:53:46.330] [I] [core] Executing script: /*** TamperAutoOff Z-Way HA module ******************************************* ...
[2017-10-16 00:53:46.332] [I] [core] Loading module FosCam9826 from modules/FosCam9826
[2017-10-16 00:53:46.335] [I] [core] Executing script: /*** FosCam9826 Z-Way HA module ******************************************* ...
[2017-10-16 00:53:46.346] [I] [core] Loading module RGB from modules/RGB
[2017-10-16 00:53:46.349] [I] [core] Executing script: /*** RGB Z-Way HA module ******************************************* ...
[2017-10-16 00:53:46.352] [I] [core] Loading module PoppCam from modules/PoppCam
[2017-10-16 00:53:46.355] [I] [core] Executing script: /*** PoppCam Z-Way HA module ******************************************* ...
[2017-10-16 00:53:46.358] [I] [core] Loading module SensorValueLogging from modules/SensorValueLogging
[2017-10-16 00:53:46.361] [I] [core] Executing script: /*** SensorValueLogging Z-Way Home Automation module ************************************* ...
[2017-10-16 00:53:46.363] [I] [core] Loading module MultilineSensor from modules/MultilineSensor
[2017-10-16 00:53:46.368] [I] [core] Executing script: /*** MultilineSensor Z-Way HA module ******************************************* ...
[2017-10-16 00:53:46.371] [I] [core] Loading module AutoLock from modules/AutoLock
[2017-10-16 00:53:46.373] [I] [core] Executing script: /*** AutoLock Z-Way Home Automation module ************************************* ...
[2017-10-16 00:53:46.375] [I] [core] Loading module ZMEOpenWRT from modules/ZMEOpenWRT
[2017-10-16 00:53:46.377] [I] [core] Executing script: /*** ZMEOpenWRT Z-Way HA module ******************************************* ...
[2017-10-16 00:53:46.379] [I] [core] Loading module TechnaxxTX66 from modules/TechnaxxTX66
[2017-10-16 00:53:46.382] [I] [core] Executing script: /*** TechnaxxTX66 Z-Way HA module ******************************************* ...
[2017-10-16 00:53:46.386] [I] [core] Loading module OpenWeather from modules/OpenWeather
[2017-10-16 00:53:46.389] [I] [core] Executing script: /*** OpenWeather Extended Z-Way HA module ******************************************* ...
[2017-10-16 00:53:46.397] [I] [core] Loading module SwitchPolling from modules/SwitchPolling
[2017-10-16 00:53:46.400] [I] [core] Executing script: /*** SwitchPolling Z-Way HA module ******************************************* ...
[2017-10-16 00:53:46.404] [I] [core] Loading module CloudBackup from modules/CloudBackup
[2017-10-16 00:53:46.409] [I] [core] Executing script: /*** CloudBackup Z-Way HA module ******************************************* ...
[2017-10-16 00:53:46.425] [I] [core] Instantiating module 9 from class CloudBackup
[2017-10-16 00:53:46.433] [I] [core] --- Starting module CloudBackup
[2017-10-16 00:53:46.477] [I] [core] Loading module Sonos from modules/Sonos
[2017-10-16 00:53:46.484] [I] [core] Executing script: /*** Sonos Z-Way HA module ******************************************* ...
[2017-10-16 00:53:46.488] [I] [core] Loading module GlobalCache from modules/GlobalCache
[2017-10-16 00:53:46.490] [I] [core] Executing script: /*** GlobalCache Z-Way HA module ******************************************* ...
[2017-10-16 00:53:46.493] [I] [core] Loading module MobileAppSupport from modules/MobileAppSupport
[2017-10-16 00:53:46.506] [I] [core] Executing script: /* Mobile App Support ...
[2017-10-16 00:53:46.563] [I] [core] Instantiating module 11 from class MobileAppSupport
[2017-10-16 00:53:46.577] [I] [core] --- Starting module Mobile App Support
[2017-10-16 00:53:46.606] [I] [core] Creating device text MobileAppSupport
[2017-10-16 00:53:46.703] [I] [core] Loading module SensorsPollingLogging from modules/SensorsPollingLogging
[2017-10-16 00:53:46.706] [I] [core] Executing script: /*** SensorsPollingLogging Z-Way HA module ******************************************* ...
[2017-10-16 00:53:46.708] [I] [core] Loading module SecurityMode from modules/SecurityMode
[2017-10-16 00:53:46.713] [I] [core] Executing script: /*** SecurityMode Z-Way HA module ******************************************* ...
[2017-10-16 00:53:46.721] [I] [core] Loading module VistaCam from modules/VistaCam
[2017-10-16 00:53:46.723] [I] [core] Executing script: /*** VistaCam Z-Way HA module ******************************************* ...
[2017-10-16 00:53:46.727] [I] [core] Loading module IfThen from modules/IfThen
[2017-10-16 00:53:46.730] [I] [core] Executing script: /*** IfThen Z-Way HA module ******************************************* ...
[2017-10-16 00:53:46.732] [I] [core] Loading module InfoWidget from modules/InfoWidget
[2017-10-16 00:53:46.735] [I] [core] Executing script: /*** InfoWidget Z-Way HA module ******************************************* ...
[2017-10-16 00:53:46.749] [I] [core] Instantiating module 5 from class InfoWidget
[2017-10-16 00:53:46.754] [I] [core] --- Starting module Information Widget
[2017-10-16 00:53:46.787] [I] [core] Creating device text InfoWidget_5_Int
[2017-10-16 00:53:46.833] [I] [core] Instantiating module 10 from class InfoWidget
[2017-10-16 00:53:46.834] [I] [core] --- Starting module Information Widget
[2017-10-16 00:53:46.863] [I] [core] Creating device text InfoWidget_10_Int
[2017-10-16 00:53:46.886] [I] [core] Loading module AutoOff from modules/AutoOff
[2017-10-16 00:53:46.888] [I] [core] Executing script: /*** AutoOff Z-Way Home Automation module ************************************* ...
[2017-10-16 00:53:46.890] [I] [core] Loading module CustomUserCodeZWay from modules/CustomUserCodeZWay
[2017-10-16 00:53:46.893] [I] [core] Executing script:   /*** CustomUserCodeZWay ZAutomation module **************************************** ...
[2017-10-16 00:53:46.895] [I] [core] Loading module TagOnOff from modules/TagOnOff
[2017-10-16 00:53:46.898] [I] [core] Executing script: /*** TagOnOff Z-Way HA module ******************************************* ...
[2017-10-16 00:53:46.901] [I] [core] Loading module Camera from modules/Camera
[2017-10-16 00:53:46.905] [I] [core] Executing script: /*** Camera Z-Way HA module ******************************************* ...
[2017-10-16 00:53:46.910] [I] [core] Loading module FosCam9821 from modules/FosCam9821
[2017-10-16 00:53:46.912] [I] [core] Executing script: /*** FosCam9821 Z-Way HA module ******************************************* ...
[2017-10-16 00:53:46.916] [I] [core] Loading module RoundRobinScenes from modules/RoundRobinScenes
[2017-10-16 00:53:46.918] [I] [core] Executing script: /*** RoundRobinScenes Z-Way HA module ******************************************* ...
[2017-10-16 00:53:46.921] [I] [core] Loading module RemoteAccess from modules/RemoteAccess
[2017-10-16 00:53:46.925] [I] [core] Executing script: /*** RemoteAccess Z-Way HA module ******************************************* ...
[2017-10-16 00:53:46.933] [I] [core] Instantiating module 4 from class RemoteAccess
[2017-10-16 00:53:46.937] [I] [core] --- Starting module Remote Access
[2017-10-16 00:53:46.970] [I] [core] success! start zbw ... 
[2017-10-16 00:53:46.986] [I] [core] Loading module LogicalRules from modules/LogicalRules
[2017-10-16 00:53:46.991] [I] [core] Executing script: /*** LogicalRules Z-Way HA module ******************************************* ...
[2017-10-16 00:53:46.994] [I] [core] Loading module LightScene from modules/LightScene
[2017-10-16 00:53:46.997] [I] [core] Executing script: /*** LightScene Z-Way HA module ******************************************* ...
[2017-10-16 00:53:46.999] [I] [core] Loading module ImportRemoteHA from modules/ImportRemoteHA
[2017-10-16 00:53:47.004] [I] [core] Executing script: /*** ImportRemoteHA Z-Way HA module ******************************************* ...
[2017-10-16 00:53:47.007] [I] [core] Loading module CorrectValue from modules/CorrectValue
[2017-10-16 00:53:47.009] [I] [core] Executing script: /*** CorrectValue Z-Way Home Automation module ************************************* ...
[2017-10-16 00:53:47.016] [I] [core] Loading module LightMotionRockerAutocontrol from modules/LightMotionRockerAutocontrol
[2017-10-16 00:53:47.022] [I] [core] Executing script: /*** LightMotionRockerAutocontrol Z-Way Home Automation module ************************************* ...
[2017-10-16 00:53:47.025] [I] [core] Loading module NotificationSMSru from modules/NotificationSMSru
[2017-10-16 00:53:47.027] [I] [core] Executing script: /*** NotificationSMSru Z-Way HA module ******************************************* ...
[2017-10-16 00:53:47.030] [I] [core] Loading module CounterTriggeringSensor from modules/CounterTriggeringSensor
[2017-10-16 00:53:47.032] [I] [core] Executing script: /*** Counter triggering binary sensor Z-Way HA module ******************************************* ...
[2017-10-16 00:53:47.034] [I] [core] Loading module SwitchControlGenerator from modules/SwitchControlGenerator
[2017-10-16 00:53:47.041] [I] [core] Executing script: /*** SwitchControlGenerator Z-Way HA module ******************************************* ...
[2017-10-16 00:53:47.048] [I] [core] Instantiating module 8 from class SwitchControlGenerator
[2017-10-16 00:53:47.062] [I] [core] --- Starting module Trap events from Remotes and Sensors
[2017-10-16 00:53:47.097] [I] [core] Loading module CustomUserCode from modules/CustomUserCode
[2017-10-16 00:53:47.099] [I] [core] Executing script:   /*** CustomUserCode ZAutomation module **************************************** ...
[2017-10-16 00:53:47.116] [I] [core] Loading module SensorsPolling from modules/SensorsPolling
[2017-10-16 00:53:47.119] [I] [core] Executing script: /*** SensorsPolling Z-Way HA module ******************************************* ...
[2017-10-16 00:53:47.122] [I] [core] Loading module ScheduledScene from modules/ScheduledScene
[2017-10-16 00:53:47.125] [I] [core] Executing script: /*** ScheduledScene Z-Way HA module ******************************************* ...
[2017-10-16 00:53:47.127] [I] [core] Loading module TechnaxxTX65 from modules/TechnaxxTX65
[2017-10-16 00:53:47.130] [I] [core] Executing script: /*** TechnaxxTX65 Z-Way HA module ******************************************* ...
[2017-10-16 00:53:47.141] [I] [core] Loading module BatteryPolling from modules/BatteryPolling
[2017-10-16 00:53:47.144] [I] [core] Executing script: /*** BatteryPolling Z-Way HA module ******************************************* ...
[2017-10-16 00:53:47.147] [I] [core] Loading module SmartLight from modules/SmartLight
[2017-10-16 00:53:47.150] [I] [core] Executing script: /*** SmartLight Z-Way Home Automation module ************************************* ...
[2017-10-16 00:53:47.153] [I] [core] Loading module CustomUserCodeLoader from modules/CustomUserCodeLoader
[2017-10-16 00:53:47.155] [I] [core] Executing script:   /*** CustomUserCodeLoader ZAutomation module **************************************** ...
[2017-10-16 00:53:47.157] [I] [core] Loading module TechnaxxTX67 from modules/TechnaxxTX67
[2017-10-16 00:53:47.160] [I] [core] Executing script: /*** TechnaxxTX67 Z-Way HA module ******************************************* ...
[2017-10-16 00:53:47.163] [I] [core] Loading module PhilioHW from modules/PhilioHW
[2017-10-16 00:53:47.169] [I] [core] Executing script: /*** PhilioHW Z-Way HA module ******************************************* ...
[2017-10-16 00:53:47.172] [I] [core] Loading module FosCam9828 from modules/FosCam9828
[2017-10-16 00:53:47.175] [I] [core] Executing script: /*** FosCam9828 Z-Way HA module ******************************************* ...
[2017-10-16 00:53:47.179] [I] [core] Loading module EnOcean from modules/EnOcean
[2017-10-16 00:53:47.188] [I] [core] Executing script: /*** EnOcean Binding module ******************************************************** ...
[2017-10-16 00:53:47.194] [I] [core] Loading module InbandNotifications from modules/InbandNotifications
[2017-10-16 00:53:47.198] [I] [core] Executing script: /*** InbandNotifications Z-Way HA module ******************************************* ...
[2017-10-16 00:53:47.206] [I] [core] Instantiating module 3 from class InbandNotifications
[2017-10-16 00:53:47.212] [I] [core] --- Starting module Inband Notifier
[2017-10-16 00:53:47.237] [I] [core] Loading module OpenRemoteHelpers from modules/OpenRemoteHelpers
[2017-10-16 00:53:47.241] [I] [core] Executing script: /* RaZOR ...
[2017-10-16 00:53:47.244] [I] [core] Loading module MailNotifier from modules/MailNotifier
[2017-10-16 00:53:47.247] [I] [core] Executing script: /*** MailNotifier Z-Way HA module ******************************************* ...
[2017-10-16 00:53:47.256] [I] [core] Instantiating module 12 from class MailNotifier
[2017-10-16 00:53:47.262] [I] [core] --- Starting module E-mail ME
[2017-10-16 00:53:47.290] [I] [core] Creating device toggleButton MailNotifier_12
[2017-10-16 00:53:47.344] [I] [core] Loading module Notification from modules/Notification
[2017-10-16 00:53:47.347] [I] [core] Executing script: /*** Notification Z-Way HA module ******************************************* ...
[2017-10-16 00:53:47.349] [I] [core] Starting automation...
[2017-10-16 00:53:47.419] [I] [core] Starting storage...
[2017-10-16 00:53:47.423] [I] [core] ZWay Automation started
[2017-10-16 00:53:56.302] [I] [core] Restarting Z-Wave binding (zway)
[2017-10-16 00:53:56.995] [I] [zway] Adding job: Get controller info and supported function classes
[2017-10-16 00:53:56.998] [C] [i/o] Serial port write error: (null)
[2017-10-16 00:53:56.999] [E] [zway] Failed to send packet: (null)
[2017-10-16 00:53:57.020] [C] [zway] Get Serial API Capabilities returned zero.
[2017-10-16 00:53:57.066] [I] [core] Error: Bad data - communication failed
[2017-10-16 00:53:57.068] [I] [core] Trying to restart Z-Wave binding (zway) in 10 seconds
[2017-10-16 00:53:57.069] [I] [core] Restarting Z-Wave binding (zway)
[2017-10-16 00:53:57.718] [I] [zway] Adding job: Get controller info and supported function classes
[2017-10-16 00:53:57.721] [C] [i/o] Serial port write error: (null)
[2017-10-16 00:53:57.722] [E] [zway] Failed to send packet: (null)
[2017-10-16 00:53:57.743] [C] [zway] Get Serial API Capabilities returned zero.
[2017-10-16 00:53:57.790] [I] [core] Error: Bad data - communication failed
[2017-10-16 00:53:57.791] [I] [core] Trying to restart Z-Wave binding (zway) in 10 seconds
[2017-10-16 00:54:07.071] [I] [core] Restarting Z-Wave binding (zway)
[2017-10-16 00:54:07.438] [I] [zway] Adding job: Get controller info and supported function classes
[2017-10-16 00:54:07.439] [C] [i/o] Serial port write error: (null)
[2017-10-16 00:54:07.439] [E] [zway] Failed to send packet: (null)
[2017-10-16 00:54:07.461] [C] [zway] Get Serial API Capabilities returned zero.
[2017-10-16 00:54:07.505] [I] [core] Error: Bad data - communication failed
[2017-10-16 00:54:07.508] [I] [core] Trying to restart Z-Wave binding (zway) in 10 seconds
[2017-10-16 00:54:07.794] [I] [core] Restarting Z-Wave binding (zway)
[2017-10-16 00:54:08.128] [I] [zway] Adding job: Get controller info and supported function classes
[2017-10-16 00:54:08.129] [C] [i/o] Serial port write error: (null)
[2017-10-16 00:54:08.129] [E] [zway] Failed to send packet: (null)
[2017-10-16 00:54:08.151] [C] [zway] Get Serial API Capabilities returned zero.
[2017-10-16 00:54:08.197] [I] [core] Error: Bad data - communication failed
[2017-10-16 00:54:08.205] [I] [core] Tried 5 times without success. Stopping tries.
[2017-10-16 00:54:08.238] [I] [core] Notification: critical (z-wave): Cannot start Z-Wave binding: Error: Bad data - communication failed
What I have checked/done:
  • latest firmware with rpi-update
  • all packages are upgraded with apt-get
  • There is only one device in the system — /dev/ttyAMA0
  • Pins are soldered well (checked voltage in high/low)
  • /etc/inittab does not exist
  • /boot/cmdline.txt does not have any mentions of ttyAMA0 (only tty1)
  • wrote a python script to manually write the bytes into the port, got write timeout.
User avatar
PoltoS
Posts: 7562
Joined: 26 Jan 2011 19:36

Re: Raspberry Zero W — failed to send packet

Post by PoltoS »

First of all enable debug mode in the log
Thomas
Posts: 20
Joined: 25 Oct 2017 12:06

Re: Raspberry Zero W — failed to send packet

Post by Thomas »

hello
also trying to connect to a raspberry pi zero-w with a raspberry camera and not getting the picture.
If i connect directly to
http://hostnameOfRPIZW.local:8080/stream
i get nice video
also verified this url works
http://hostnameOfRPIZW.local:8080/stream/snapshot.jpeg
So in z-wave smarthome i add a web camera, and enter the 2 urls
change nothing more, and save
When i list all elements, choose the web camera and press the cam icon, i get a white frame with a little broken graphics icon, and nothing more.
When i tcpdump from z-wave server to rpizw i see no packets go over.
My log level is set to 0 (debug, and i see this when restarting the z-way-server process :
[2017-11-20 23:01:20.016] [core] Loading module Camera from modules/Camera
[2017-11-20 23:01:20.016] [D] [zway] SETDATA devices.3.data.isAwake = False
[2017-11-20 23:01:20.016] [D] [zway] Sending the queue for node 3 into sleep
[2017-11-20 23:01:20.016] [D] [zway] SENDING (cb 0x03): ( 01 09 00 13 04 02 98 40 05 03 3D )
[2017-11-20 23:01:20.017] [core] Executing script: /*** Camera Z-Way HA module ******************************************* ...
[2017-11-20 23:01:20.018] [D] [zway] RECEIVED ACK
[2017-11-20 23:01:20.022] [D] [zway] RECEIVED: ( 01 04 01 13 01 E8 )
[2017-11-20 23:01:20.022] [D] [zway] SENT ACK
[2017-11-20 23:01:20.022] [D] [zway] Delivered to Z-Wave stack
[2017-11-20 23:01:20.022] [core] Instantiating module 13 from class Camera
[2017-11-20 23:01:20.024] [core] --- Starting module Web Camera
[2017-11-20 23:01:20.041] [core] Creating device camera CameraDevice_13
[2017-11-20 23:01:20.044] [core] Loading module BatteryPolling from modules/BatteryPolling
[2017-11-20 23:01:20.046] [core] Executing script: /*** BatteryPolling Z-Way HA module ********************


any idea welcome :-)
Thomas
Posts: 20
Joined: 25 Oct 2017 12:06

Re: Raspberry Zero W — failed to send packet

Post by Thomas »

(any use in trying out v3.0.0-rc8)
Post Reply