victronenergy / venus Goto Github PK

Victron Energy Unix/Linux OS

Home Page: https://github.com/victronenergy/venus/wiki

Makefile 36.52% Shell 58.32% Python 5.16%

venus's Issues

bbb: vrmlogger not starting (python-requests dependencies)

As emailed to dev-venus mailing list, vrmlogger can't import the python cgi module. But after adding that there are more issues, and now I remember encountering the same issue while working at fido_venus.

The problem is that there is a new recipe of python-requests somewhere, which doesn't have the right dependencies.

On BBB:

root@venus:/dev# opkg status python-requests
Package: python-requests
Version: 2.7.0-r0
Depends: python-core
Status: install ok installed
Architecture: cortexa8hf-vfp-neon
Installed-Time: 1449436429

On CCGX:

root@ccgx:/usr/lib/python2.7# opkg status python-requests
Package: python-requests
Version: 2.2.1-r0
Depends: python, python-netserver, python-core, python-email
Provides:
Status: install ok installed
Architecture: armv7a-vfp-neon
Installed-Time: 1449254695

The CCGX recipe is here:
https://github.com/victronenergy/meta-victronenergy-overlay/blob/danny_venus/meta/recipes-devtools/python/python-requests_2.2.1.bb

Previously fixed here I suppose:
victronenergy/meta-openembedded@da52e8a

upnp: change url to local ip address

Currently, double clicking on a device results in:

bbb: properties windows opens
bpp3 / ccgx: browser opens with url https://www.victronenergy.com/live/ccgx:start.

Instead, we want the devices local page to show up, which then contains Remote Console.

The trick lies in the Device webpage of below screenshots. That needs to contain the device's address.

verify RTC on ve-cape and add to venus bbb image

Used RTC on the cape is the PCF8523. It is connected to I2C2

dbus-valence: bms service has wrong product id

The product id is set to 0x142, which is the Lynx Ion. It should be 0xB001.

Remote Console for LAN doesn't work on Firefox?

We are trying to use Beaglebone Controller, we connected it to our LAN, we get its IP but we are facing this issue.

Can you enable remote console for LAN automatically?

mounting devpts on /dev/pts failed: No such file or directory

[    2.052226] usb 1-1: Product: 802.11 n WLAN
[    2.056612] usb 1-1: Manufacturer: Ralink
[    2.060829] usb 1-1: SerialNumber: 1.0
version 2.88 booting
mount: mounting devpts on /dev/pts failed: No such file or directory
[    2.921792] EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Opts: (null)
Starting udev
[    3.131456] udevd[659]: starting version 182
[    3.732287] cfg80211: Calling CRDA to update world regulatory domain
[    4.510193] usb 1-1: reset high-speed USB device number 2 using musb-hdrc

pyo files corrupt?

Very rare, but still happens sometimes: a python process won't run. And its fixable by removing a pyo file, which forces it to be regenerated.

For example this log, output for vrmpubnub:

2016-07-08 20:50:15.175094500     from vedbus import VeDbusItemExport, VeDbusItemImport
2016-07-08 20:50:15.175125500 EOFError: EOF read where object expected
2016-07-08 20:50:18.393325500 Traceback (most recent call last):
2016-07-08 20:50:18.397170500   File "/opt/color-control/vrmpubnub/vrmpubnub.py", line 46, in <module>
2016-07-08 20:50:18.416976500     from dbusmonitor import DbusMonitor
2016-07-08 20:50:18.417007500   File "/opt/color-control/vrmpubnub/./ext/velib_python/dbusmonitor.py", line 32, in <module>
2016-07-08 20:50:18.429183500     from vedbus import VeDbusItemExport, VeDbusItemImport
2016-07-08 20:50:18.429214500 EOFError: EOF read where object expected
2016-07-08 20:50:21.648941500 Traceback (most recent call last):
2016-07-08 20:50:21.651168500   File "/opt/color-control/vrmpubnub/vrmpubnub.py", line 46, in <module>
2016-07-08 20:50:21.659500500     from dbusmonitor import DbusMonitor
2016-07-08 20:50:21.659500500   File "/opt/color-control/vrmpubnub/./ext/velib_python/dbusmonitor.py", line 32, in <module>
2016-07-08 20:50:21.659530500     from vedbus import VeDbusItemExport, VeDbusItemImport
2016-07-08 20:50:21.659530500 EOFError: EOF read where object expected
2016-07-08 20:50:24.310501500 Traceback (most recent call last):
2016-07-08 20:50:24.312576500   File "/opt/color-control/vrmpubnub/vrmpubnub.py", line 46, in <module>
2016-07-08 20:50:24.323562500     from dbusmonitor import DbusMonitor
2016-07-08 20:50:24.323593500   File "/opt/color-control/vrmpubnub/./ext/velib_python/dbusmonitor.py", line 32, in <module>
2016-07-08 20:50:24.323593500     from vedbus import VeDbusItemExport, VeDbusItemImport
2016-07-08 20:50:24.323593500 EOFError: EOF read where object expected
2016-07-08 20:50:27.143386500 Traceback (most recent call last):
2016-07-08 20:50:27.147689500   File "/opt/color-control/vrmpubnub/vrmpubnub.py", line 46, in <module>
2016-07-08 20:50:27.147720500     from dbusmonitor import DbusMonitor
2016-07-08 20:50:27.147720500   File "/opt/color-control/vrmpubnub/./ext/velib_python/dbusmonitor.py", line 32, in <module>
2016-07-08 20:50:27.147720500     from vedbus import VeDbusItemExport, VeDbusItemImport
2016-07-08 20:50:27.147720500 EOFError: EOF read where object expected
2016-07-08 20:50:29.828720500 Traceback (most recent call last):
2016-07-08 20:50:29.833328500   File "/opt/color-control/vrmpubnub/vrmpubnub.py", line 46, in <module>
2016-07-08 20:50:29.833358500     from dbusmonitor import DbusMonitor
2016-07-08 20:50:29.833358500   File "/opt/color-control/vrmpubnub/./ext/velib_python/dbusmonitor.py", line 32, in <module>
2016-07-08 20:50:29.833358500     from vedbus import VeDbusItemExport, VeDbusItemImport
2016-07-08 20:50:29.837448500 EOFError: EOF read where object expected
2016-07-08 20:50:32.486587500 Traceback (most recent call last):
2016-07-08 20:50:32.495437500   File "/opt/color-control/vrmpubnub/vrmpubnub.py", line 46, in <module>
2016-07-08 20:50:32.495468500     from dbusmonitor import DbusMonitor
2016-07-08 20:50:32.495468500   File "/opt/color-control/vrmpubnub/./ext/velib_python/dbusmonitor.py", line 32, in <module>
2016-07-08 20:50:32.495468500     from vedbus import VeDbusItemExport, VeDbusItemImport
2016-07-08 20:50:32.495468500 EOFError: EOF read where object expected
2016-07-08 20:50:34.911544500 Traceback (most recent call last):
2016-07-08 20:50:34.914230500   File "/opt/color-control/vrmpubnub/vrmpubnub.py", line 46, in <module>
2016-07-08 20:50:34.917587500     from dbusmonitor import DbusMonitor
2016-07-08 20:50:34.917587500   File "/opt/color-control/vrmpubnub/./ext/velib_python/dbusmonitor.py", line 32, in <module>
2016-07-08 20:50:34.920364500     from vedbus import VeDbusItemExport, VeDbusItemImport
2016-07-08 20:50:34.920364500 EOFError: EOF read where object expected
2016-07-08 20:50:40.097610500 INFO:__main__:/opt/color-control/vrmpubnub/vrmpubnub.py v0.96 is starting up
2016-07-08 20:50:40.101242500 INFO:__main__:Loglevel set to INFO
2016-07-08 20:50:43.766525500 INFO:__main__:Auth-packages have been sent to VRM
2016-07-08 20:50:44.261215500 INFO:__main__:Starting mainloop, responding on only events
2016-07-08 20:50:45.124740500 INFO:__main__:Send to pubnub :[1, u'Sent', u'14680110450763114']
root@ccgx:~#

What you see is the process being started and then crashing over and over again. Line 32 in dbusmonitor.py reads:

from vedbus import VeDbusItemExport, VeDbusItemImport

And removing vedbus.pyo is what made the process properly start again.

exception in vrmlogger related to umount/mount

While testing/fixing sdcard and usbstick mount issues on the bbb, one thing lead to another and I had a look in the vrmlogger log file.

(clean boot, no card inserted while booting)
2016-10-02 19:21:48.841896500 MainThread-vrmlogger: /opt/color-control/vrmlogger/vrmlogger.py v2.55 is starting up
2016-10-02 19:21:48.852861500 MainThread-vrmlogger: Loglevel set to INFO
2016-10-02 19:21:48.902564500 MainThread-vedbus: registered ourselves on D-Bus as com.victronenergy.logger
2016-10-02 19:21:49.003355500 MainThread-dbusmonitor: ===== Search on dbus for services that we will monitor starting... =====
2016-10-02 19:21:49.057322500 MainThread-dbusmonitor: Found: com.victronenergy.system matches com.victronenergy.system, scanning and storing items
2016-10-02 19:21:49.099631500 MainThread-dbusmonitor:        com.victronenergy.system has device instance 0
2016-10-02 19:21:52.738600500 MainThread-dbusmonitor: Found: com.victronenergy.settings matches com.victronenergy.settings, scanning and storing items
2016-10-02 19:21:52.935952500 MainThread-dbusmonitor:        com.victronenergy.settings has device instance 0
2016-10-02 19:21:54.023924500 MainThread-dbusmonitor: Found: com.victronenergy.tank.builtin_adc2_di2 matches com.victronenergy.tank, scanning and storing items
2016-10-02 19:21:54.030030500 MainThread-dbusmonitor:        com.victronenergy.tank.builtin_adc2_di2 has device instance 22
2016-10-02 19:21:54.079282500 MainThread-dbusmonitor: Found: com.victronenergy.tank.builtin_adc4_di0 matches com.victronenergy.tank, scanning and storing items
2016-10-02 19:21:54.085280500 MainThread-dbusmonitor:        com.victronenergy.tank.builtin_adc4_di0 has device instance 20
2016-10-02 19:21:54.138449500 MainThread-dbusmonitor: Found: com.victronenergy.tank.builtin_adc6_di1 matches com.victronenergy.tank, scanning and storing items
2016-10-02 19:21:54.144644500 MainThread-dbusmonitor:        com.victronenergy.tank.builtin_adc6_di1 has device instance 21
2016-10-02 19:21:54.204117500 MainThread-dbusmonitor: ===== Search on dbus for services that we will monitor finished =====
2016-10-02 19:21:54.299341500 MainThread-kwhdeltas: active battery service: None
2016-10-02 19:21:54.319379500 MainThread-kwhdeltas: active battery service: None
2016-10-02 19:21:54.421632500 MainThread-vrmlogger: Starting to log, logmode == LogToVRM
2016-10-02 19:21:54.447147500 MainThread-http_endpoint: Internal backlog buffer initialised. Count = 0
2016-10-02 19:21:54.448491500 MainThread-http_endpoint: Found '' as remembered external path
2016-10-02 19:21:54.449853500 MainThread-http_endpoint: Checking '' for suitability for external storage
2016-10-02 19:21:54.450765500 MainThread-http_endpoint: Mount point to check was empty.
2016-10-02 19:21:54.451614500 MainThread-http_endpoint: Because we haven't found a suitable remembered external storage path, we're scanning for one to use.
2016-10-02 19:21:54.453771500 MainThread-http_endpoint: Checking '/media/mmcblk0p1' for suitability for external storage
2016-10-02 19:21:54.460931500 MainThread-http_endpoint: Marking /media/mmcblk0p1 as candidate for guessed mount point
2016-10-02 19:21:54.462641500 MainThread-http_endpoint: Changing remembered external storage path from '' to '/media/mmcblk0p1'
2016-10-02 19:21:54.479327500 MainThread-http_endpoint: Found /media/mmcblk0p1 as external storage dir
2016-10-02 19:21:54.929884500 MainThread-http_endpoint: Extra SQLite DB made at /media/mmcblk0p1/vrmlogger-backlog.sqlite3
2016-10-02 19:21:55.071478500 MainThread-http_endpoint: Made /media/mmcblk0p1/vrmlogger-backlog.sqlite3 the active buffer
2016-10-02 19:21:55.170166500 MainThread-http_endpoint: Starting sender thread
2016-10-02 19:21:55.205074500 MainThread-http_endpoint: Started sender thread
2016-10-02 19:21:55.216901500 MainThread-http_endpoint: HttpEndpoint initialised, http worker thread running
2016-10-02 19:21:55.638463500 MainThread-vrmlogger: Starting mainloop, responding on only events
2016-10-02 19:32:23.938250500 MainThread-dbusmonitor: umount event!
2016-10-02 19:32:23.944554500 MainThread-http_endpoint: Umount event:/dev/mmcblk0p1 -> /media/mmcblk0p1
2016-10-02 19:32:24.152796500 MainThread-connection: Exception in handler for D-Bus signal:
2016-10-02 19:32:24.152809500 Traceback (most recent call last):
2016-10-02 19:32:24.152813500   File "/usr/lib/python2.7/site-packages/dbus/connection.py", line 230, in maybe_handle_message
2016-10-02 19:32:24.152818500     self._handler(*args, **kwargs)
2016-10-02 19:32:24.152821500   File "/opt/color-control/vrmlogger/http_endpoint.py", line 342, in _umount_event
2016-10-02 19:32:24.152826500     self.stop()
2016-10-02 19:32:24.152828500   File "/opt/color-control/vrmlogger/http_endpoint.py", line 424, in stop
2016-10-02 19:32:24.152981500     self._process_results() # Otherwise your last processed job won't be saved if it failed.
2016-10-02 19:32:24.152987500   File "/opt/color-control/vrmlogger/http_endpoint.py", line 480, in _process_results
2016-10-02 19:32:24.152991500     self._feed_from_buffer_to_queue()
2016-10-02 19:32:24.152995500   File "/opt/color-control/vrmlogger/http_endpoint.py", line 430, in _feed_from_buffer_to_queue
2016-10-02 19:32:24.153067500     self._enough_free_space_on_storage() # To update free space display
2016-10-02 19:32:24.153072500   File "/opt/color-control/vrmlogger/http_endpoint.py", line 359, in _enough_free_space_on_storage
2016-10-02 19:32:24.153077500     stat = os.statvfs(f)
2016-10-02 19:32:24.153080500 OSError: [Errno 2] No such file or directory: '/media/mmcblk0p1'
2016-10-02 19:32:24.173876500 MainThread-dbusmonitor: umount event!
2016-10-02 19:32:24.175326500 MainThread-http_endpoint: Umount event:/dev/mmcblk0 -> /media/mmcblk0
2016-10-02 19:32:24.179123500 MainThread-connection: Exception in handler for D-Bus signal:
2016-10-02 19:32:24.179134500 Traceback (most recent call last):
2016-10-02 19:32:24.179137500   File "/usr/lib/python2.7/site-packages/dbus/connection.py", line 230, in maybe_handle_message
2016-10-02 19:32:24.179142500     self._handler(*args, **kwargs)
2016-10-02 19:32:24.179146500   File "/opt/color-control/vrmlogger/http_endpoint.py", line 342, in _umount_event
2016-10-02 19:32:24.179150500     self.stop()
2016-10-02 19:32:24.179153500   File "/opt/color-control/vrmlogger/http_endpoint.py", line 424, in stop
2016-10-02 19:32:24.179274500     self._process_results() # Otherwise your last processed job won't be saved if it failed.
2016-10-02 19:32:24.179280500   File "/opt/color-control/vrmlogger/http_endpoint.py", line 480, in _process_results
2016-10-02 19:32:24.179285500     self._feed_from_buffer_to_queue()
2016-10-02 19:32:24.179288500   File "/opt/color-control/vrmlogger/http_endpoint.py", line 430, in _feed_from_buffer_to_queue
2016-10-02 19:32:24.179327500     self._enough_free_space_on_storage() # To update free space display
2016-10-02 19:32:24.179332500   File "/opt/color-control/vrmlogger/http_endpoint.py", line 359, in _enough_free_space_on_storage
2016-10-02 19:32:24.179337500     stat = os.statvfs(f)
2016-10-02 19:32:24.179340500 OSError: [Errno 2] No such file or directory: '/media/mmcblk0p1'
root@beaglebone:/media#

gui: until remote support has been enabled once, status shows online

The paths in localsettings are created only after enabling remote support. So probably second-last line in this code needs a change:

        MbSwitch {
            name: qsTr("Remote support (SSH)")
            bind: "com.victronenergy.settings/Settings/System/RemoteSupport"
        }

        MbItemValue {
            description: qsTr("Remote support tunnel")
            item.value: remotePort.item.value !== 0 ? qsTr("Online") : qsTr("Offline")
        }

From:
https://git.victronenergy.com/ccgx/gui/blob/master/qml/PageSettingsGeneral.qml#L60

And easily reproducible by loading a new image in a bbb and then browsing to that menu using Remote Console on LAN.

(draft) create eventlogger

idea

tbd

events?

An event can be many things:

error & warnings raised and cleared
user changing a setting
device is switched on/off
firmware updates
reboots
system events (service xx crashed and was restarted)

what this solves

vrmlogger is logging samples of values and states. Which is a fundamentally different datatype than events.
The two lists we have are not in sync: confusing. (notification list in gui is generated by gui itself, and VRM Portal also has its own (different) set of rules generating the VRM Alarm Log)
Notifications shown in the gui are not stored to non-volatile memory. Erased on a reboot
Code in gui needs to be added for each thing you want to have as an alarm. Instead of adding that logic to place where it belongs: dbus-vecan, dbus-vedirect, dbus-mk2 etc.

List of events

Existing:

(to be added, there must be a list in the gui somewhere)

New events which can/could be added:

Mains power failure
Genset started/stopped
VE.Bus system config changed remotely (Remote VEConfigure)
CCGX Firmware updated from .. to ..
VE.Can Firmware for device X updated (remotely)
Connection to device X (for example a BMV) lost. / found again
Alarm xx started, followed by alarm xx cleared
Warning xx started, followed by warning xx cleared

And even more events, of the type that not necessarily need to be shown to the user. But can still be very useful to us

Service xx crashed (data comes from daemontools)
Watchdog reboot

make the usb work as an ethernet device

To configure the bbb in a wifi network, it is now first necessary to:

hook it up to a wired (ethernet) network
find it in the network (which is quite easy because of simple-upnpd)
type the ipaddress in a webbrowser, which opens up the Remote Console on LAN
finally configure wifi
and then you can remove the wired ethernet cable again.

Which is not always to easy. So therefore it would be nice if configuring via USB is possible. And Ethernet over USB sounds like the simplest solution.

Note that at some point it might also be possible to configure the device via BLE with VictronConnect, which is even easier. And even without BLE, instead of using a webbrowser it will soon be possible to use VictronConnect: it will use upnp to find the device, and then connects to it over mqtt.

And another alternative would be to auto-configure the wifi as an access point in case it is not configured or not connected.

Ethernet over USB works in the default Beaglebone image from the beaglebone organisation.

kernel panic after inserting usb wifi

besides the main issue, two others:

and no reset afterwards: watchdog not yet running ok?
and shouldn't the kernel initiate reboot after panicking?

root@beaglebone:~# [12768.419617] usb 1-1: new high-speed USB device number 2 using musb-hdrc
[12768.575513] usb 1-1: New USB device found, idVendor=0586, idProduct=341e
[12768.582586] usb 1-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[12768.590094] usb 1-1: Product: 802.11 n WLAN
[12768.594481] usb 1-1: Manufacturer: Ralink
[12768.598682] usb 1-1: SerialNumber: 1.0
[12768.736212] cfg80211: Calling CRDA to update world regulatory domain
[12768.877037] cfg80211: World regulatory domain updated:
[12768.882581] cfg80211:  DFS Master region: unset
[12768.887154] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time)
[12768.897410] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A)
[12768.905834] cfg80211:   (2457000 KHz - 2482000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A)
[12768.914248] cfg80211:   (2474000 KHz - 2494000 KHz @ 20000 KHz), (N/A, 2000 mBm), (N/A)
[12768.922664] cfg80211:   (5170000 KHz - 5250000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (N/A)
[12768.932622] cfg80211:   (5250000 KHz - 5330000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (0 s)
[12768.942577] cfg80211:   (5490000 KHz - 5730000 KHz @ 160000 KHz), (N/A, 2000 mBm), (0 s)
[12768.951075] cfg80211:   (5735000 KHz - 5835000 KHz @ 80000 KHz), (N/A, 2000 mBm), (N/A)
[12768.959481] cfg80211:   (57240000 KHz - 63720000 KHz @ 2160000 KHz), (N/A, 0 mBm), (N/A)
[12769.129587] usb 1-1: reset high-speed USB device number 2 using musb-hdrc
[12769.281856] ieee80211 phy0: rt2x00_set_rt: Info - RT chipset 3070, rev 0201 detected
[12769.309186] ieee80211 phy0: rt2x00_set_rf: Info - RF chipset 0005 detected
[12769.379835] usbcore: registered new interface driver rt2800usb
[12769.398813] ieee80211 phy0: rt2x00lib_request_firmware: Info - Loading firmware file 'rt2870.bin'
[12769.450057] ieee80211 phy0: rt2x00lib_request_firmware: Info - Firmware detected - version: 0.29
[12769.906083] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready

root@beaglebone:~#
root@beaglebone:~# ifconfig
eth0      Link encap:Ethernet  HWaddr EC:24:B8:9E:18:DF
          inet addr:192.168.51.62  Bcast:192.168.51.255  Mask:255.255.255.0
          inet6 addr: fe80::ee24:b8ff:fe9e:18df/64 Scope:Link
          UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
          RX packets:45951 errors:0 dropped:4 overruns:0 frame:0
          TX packets:5566 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000
          RX bytes:12128812 (11.5 MiB)  TX bytes:1440693 (1.3 MiB)
          Interrupt:173

lo        Link encap:Local Loopback
          inet addr:127.0.0.1  Mask:255.0.0.0
          inet6 addr: ::1/128 Scope:Host
          UP LOOPBACK RUNNING  MTU:65536  Metric:1
          RX packets:1051 errors:0 dropped:0 overruns:0 frame:0
          TX packets:1051 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:0
          RX bytes:777913 (759.6 KiB)  TX bytes:777913 (759.6 KiB)

wlan0     Link encap:Ethernet  HWaddr 10:7B:EF:C7:79:A4
          UP BROADCAST MULTICAST  MTU:1500  Metric:1
          RX packets:0 errors:0 dropped:0 overruns:0 frame:0
          TX packets:0 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000
          RX bytes:0 (0.0 B)  TX bytes:0 (0.0 B)

root@beaglebone:~# ifconfig
eth0      Link encap:Ethernet  HWaddr EC:24:B8:9E:18:DF
          inet addr:192.168.51.62  Bcast:192.168.51.255  Mask:255.255.255.0
          inet6 addr: fe80::ee24:b8ff:fe9e:18df/64 Scope:Link
          UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
          RX packets:45954 errors:0 dropped:4 overruns:0 frame:0
          TX packets:5566 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000
          RX bytes:12129239 (11.5 MiB)  TX bytes:1440693 (1.3 MiB)
          Interrupt:173

lo        Link encap:Local Loopback
          inet addr:127.0.0.1  Mask:255.0.0.0
          inet6 addr: ::1/128 Scope:Host
          UP LOOPBACK RUNNING  MTU:65536  Metric:1
          RX packets:1051 errors:0 dropped:0 overruns:0 frame:0
          TX packets:1051 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:0
          RX bytes:777913 (759.6 KiB)  TX bytes:777913 (759.6 KiB)

wlan0     Link encap:Ethernet  HWaddr 10:7B:EF:C7:79:A4
          UP BROADCAST MULTICAST  MTU:1500  Metric:1
          RX packets:0 errors:0 dropped:0 overruns:0 frame:0
          TX packets:0 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000
          RX bytes:0 (0.0 B)  TX bytes:0 (0.0 B)

root@beaglebone:~# [12784.879644] ieee80211 phy0: rt2x00usb_vendor_request: Error - Vendor Request 0x07 failed for offset 0x1004 with error -110
[12784.924488] usb 1-1: USB disconnect, device number 2
[12784.940217] ieee80211 phy0: rt2x00usb_vendor_request: Error - Vendor Request 0x06 failed for offset 0x1004 with error -19
[12785.087679] ieee80211 phy0: rt2800usb_tx_sta_fifo_read_completed: Warning - TX status read failed -108
[12785.097486] Unable to handle kernel NULL pointer dereference at virtual address 00000084
[12785.105970] pgd = c0004000
[12785.108805] [00000084] *pgd=00000000
[12785.112565] Internal error: Oops: 17 [#1] ARM
[12785.117133] Modules linked in: arc4 rt2800usb rt2x00usb rt2800lib rt2x00lib mac80211 cfg80211 rfkill ti_am335x_adc kfifo_buf industrialio ti_am335x_tsc ti_am335x_tscadc
[12785.132983] CPU: 0 PID: 3 Comm: ksoftirqd/0 Not tainted 4.1.15-venus #1
[12785.139917] Hardware name: Generic AM33XX (Flattened Device Tree)
[12785.146304] task: de08c040 ti: de096000 task.ti: de096000
[12785.151973] PC is at __lock_acquire+0x1e8/0x1da0
[12785.156814] LR is at 0x1
[12785.159467] pc : [<c0066c74>]    lr : [<00000001>]    psr: 20070093
[12785.159467] sp : de097d48  ip : de096000  fp : c070d974
[12785.171501] r10: c072eda4  r9 : de08c040  r8 : c06f1468
[12785.176976] r7 : c0eee934  r6 : 00000084  r5 : 00000000  r4 : 00000000
[12785.183818] r3 : c0d1db4c  r2 : 00000000  r1 : 00000000  r0 : 00000084
[12785.190661] Flags: nzCv  IRQs off  FIQs on  Mode SVC_32  ISA ARM  Segment kernel
[12785.198415] Control: 10c5387d  Table: 95098019  DAC: 00000015
[12785.204437] Process ksoftirqd/0 (pid: 3, stack limit = 0xde096210)
[12785.210915] Stack: (0xde097d48 to 0xde098000)
[12785.215483] 7d40:                   00000000 c02a644c 00000000 de097e1c 001fe050 00000000
[12785.224060] 7d60: 53425553 45545359 65693d4d 30386565 00313132 49564544 2b3d4543 65656569
[12785.232635] 7d80: 31323038 68703a31 de003079 de272da0 c0eee934 c06f1468 de08c040 00000000
[12785.241210] 7da0: 80070093 00000084 00000000 00000000 00000000 c072eda4 60070093 c006912c
[12785.249785] 7dc0: 00000001 00000080 00000000 bf18db9c 00000000 c0d6631c de08c040 00000000
[12785.258361] 7de0: c070d974 00000074 80070093 bf18db9c 000000b8 de272d90 de096000 00000000
[12785.266937] 7e00: 00000018 c04c1a18 00000001 00000000 bf18db9c c02a68b4 00000002 00000000
[12785.275513] 7e20: 00000074 bf18db9c 00000000 000000b8 de7f9300 bf1be9bc de7f9300 de764240
[12785.284089] 7e40: de272c00 00000000 de272d90 bf1beb64 de272d8c de272d90 dd165e40 bf1b7418
[12785.292665] 7e60: de764240 60070013 de272c00 c0301aec de272d8c de097e88 00000000 de272db0
[12785.301241] 7e80: de272d90 c0301c14 de097e88 de097e88 de272db8 00000000 c06ed3a0 00000000
[12785.309817] 7ea0: 00000100 c00362a8 c07315d8 00000006 00000240 c072eb10 00000100 c00358d4
[12785.318393] 7ec0: c06eff30 00000000 00000007 c06f04a0 c0731580 0000000a 00130cfd c06f29a0
[12785.326970] 7ee0: 04208040 00000000 de096000 de028800 de096000 00000000 00000001 c06ed3b4
[12785.335545] 7f00: 00000000 00000000 00000000 c0035b64 de028800 c0051edc 00000000 de0287c0
[12785.344120] 7f20: de028800 c0051dd4 00000000 c004eae4 00000001 00000001 00000000 de028800
[12785.352696] 7f40: 00000000 00000001 dead4ead ffffffff ffffffff c0731ad4 c0868ca4 00000000
[12785.361273] 7f60: c05ef984 de097f64 de097f64 00000000 00000001 dead4ead ffffffff ffffffff
[12785.369849] 7f80: c0731ad4 00000000 00000000 c05ef984 de097f90 de097f90 de097fac de0287c0
[12785.378425] 7fa0: c004ea08 00000000 00000000 c000f238 00000000 00000000 00000000 00000000
[12785.387000] 7fc0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
[12785.395575] 7fe0: 00000000 00000000 00000000 00000000 00000013 00000000 78093675 cf8e62e9
[12785.404160] [<c0066c74>] (__lock_acquire) from [<c006912c>] (lock_acquire+0xac/0x12c)
[12785.412383] [<c006912c>] (lock_acquire) from [<c04c1a18>] (_raw_spin_lock_irqsave+0x3c/0x50)
[12785.421281] [<c04c1a18>] (_raw_spin_lock_irqsave) from [<bf18db9c>] (rt2x00queue_get_entry+0x24/0x7c [rt2x00lib])
[12785.432071] [<bf18db9c>] (rt2x00queue_get_entry [rt2x00lib]) from [<bf1be9bc>] (rt2800usb_txstatus_pending+0x1c/0x70 [rt2800usb])
[12785.444301] [<bf1be9bc>] (rt2800usb_txstatus_pending [rt2800usb]) from [<bf1beb64>] (rt2800usb_tx_sta_fifo_read_completed+0xa4/0x150 [rt2800usb])
[12785.457993] [<bf1beb64>] (rt2800usb_tx_sta_fifo_read_completed [rt2800usb]) from [<bf1b7418>] (rt2x00usb_register_read_async_cb+0x20/0x48 [rt2x00usb])
[12785.472139] [<bf1b7418>] (rt2x00usb_register_read_async_cb [rt2x00usb]) from [<c0301aec>] (__usb_hcd_giveback_urb+0x94/0x134)
[12785.483997] [<c0301aec>] (__usb_hcd_giveback_urb) from [<c0301c14>] (usb_giveback_urb_bh+0x88/0xc4)
[12785.493497] [<c0301c14>] (usb_giveback_urb_bh) from [<c00362a8>] (tasklet_action+0x68/0xb8)
[12785.502258] [<c00362a8>] (tasklet_action) from [<c00358d4>] (__do_softirq+0x104/0x364)
[12785.510564] [<c00358d4>] (__do_softirq) from [<c0035b64>] (run_ksoftirqd+0x30/0x40)
[12785.518602] [<c0035b64>] (run_ksoftirqd) from [<c0051edc>] (smpboot_thread_fn+0x108/0x154)
[12785.527282] [<c0051edc>] (smpboot_thread_fn) from [<c004eae4>] (kthread+0xdc/0xf0)
[12785.535229] [<c004eae4>] (kthread) from [<c000f238>] (ret_from_fork+0x14/0x3c)
[12785.542804] Code: e1a00004 e28dd054 e8bd8ff0 e59f3e08 (e5962000)
[12785.549199] ---[ end trace e7152e0c5ad44463 ]---
[12785.554040] Kernel panic - not syncing: Fatal exception in interrupt
[12785.560705] ---[ end Kernel panic - not syncing: Fatal exception in interrupt

production: use eeprom

(updated below on 2021-02-21)

Todo list

Note that CCGX is not in above list since it doesn't have an eeprom. And the Venus GX is not in the list since its almost EOL, and no point in now at the last moment making for another variant out there.

Current eeprom usage

We store the board-id in the first 16 bytes. This is written to the eeprom by the installer script, and used early in the boot to use the right device tree; as well as (for some products) by /usr/bin/product-id to provide the correct productid. The rest of the board-id info, ie. for-what-devices and when etc etc is out of scope for this issue; more info in here in meta-victronenergy.

The rest of the eeprom is programmed with update-eeprom.py, now renamed to eeprom. Which was only ever done for the einstein, not for any other products. Eeprom writing started per ECN200611FHE01, which changed one USB port to power only, took v2.55 in production and used update-eeprom.py to write data into the eeprom. eeprom-format-version in v2.55 was version 0. Resulting data in those eeproms is:

board-id (4 bytes) <-- in all units
hardware revision (2 bytes)
eeprom format version - un8
productid (un16)
serial-number (a string: hq....)
wpa-psk
part-number
installer-version
crc32

In the nanopis we only have:

board-id (4 bytes)

The beaglebone has an empty eeprom.

The ccgx has no eeprom. It does have some hardware pins which are read and translated into a hardware revision.

Details

Make the values available in the rootfs

Make some script or executable that reads these values (early!) during the boot process, and writes them to /data. Also such that all applications don't need to distinguish between GX devices that were produced with above extended eeprom format and once that where produced without.

Access via /etc/venus, for rpis since they don't have data ?

It might be a good idea to have other processes access these values via symlinks in /etc/venus: when installed as a package, as for example on an rpi, there is no /data. (?)

package group cleanup

the problem

First there was machine bpp3, now there is ccgx and bpp3, which are the same. And bpp3 still relies on the huge packagegroup-custom-image-bpp3.bb package group for which there are already alternatives.

So in short we now have double machine and double package groups.

the plan

For now go to three image files:

bpp3-rootfs: stays the same, no changes to not risk issue in opkg updating deployed units
venus-opensource-image: a rename of venus-image.
venus-full-image: full venus image. This is the image to be used for all new developments like bbb etc.

And once we have image updates, we can drop bpp3-rootfs image and bpp3 machine, and make new releases that run on the Color Control GX hardware with the ccgx machine and the venus-full-image.

status

Work is done, see the four branches:

Tip: list packages for an image:
bitbake -g bpp3-rootfs && cat pn-depends.dot | grep -v -e '-native' | grep -v digraph | grep -v -e '-image' | awk '{print $1}' | sort | uniq

gui: keyboard input device error

root@beaglebone:~# cat /var/log/gui/current | tai64nlocal
2016-05-26 18:16:23.711451500 *** Starting gui ***
2016-05-26 18:16:23.711460500 *** headless device=1
2016-05-26 18:16:31.409428500 *** Localsettings is up, continuing...
2016-05-26 18:16:31.504323500 method return sender=:1.14 -> dest=:1.17 reply_serial=2
2016-05-26 18:16:31.504334500    int32 0
2016-05-26 18:16:31.610194500 *** Starting gui, with VNC enabled (VncLocal=1 VncInternet=0)
2016-05-26 18:16:32.355827500 QVNCServer created on port 5900
2016-05-26 18:16:32.418710500 Cannot open keyboard input device '/dev/input/by-path/platform-gpio-keys-event': No such file or directory
2016-05-26 18:16:32.475155500 GUI v2.8.4 started
2016-05-26 18:16:32.475166500 Built with Qt 4.8.7 running on 4.8.7

configure gpio pins

The used gpio pins (for the buzzer, and relays) are defined in the the beaglebone.conf file.

But before they can be used, they need to be configured. During testing we configured them manually, see here in the wiki.

Since they are not configured, errors show up in the dbus-systemcalc-log:

root@beaglebone:/dev# cat /var/log/dbus-systemcalc-py/current | tai64nlocal
2016-08-08 23:00:56.363865500   File "/opt/color-control/dbus-systemcalc-py/delegates.py", line 266, in _update_relay_state
2016-08-08 23:00:57.073019500     with open(file_path, 'rt') as r:
2016-08-08 23:00:57.073025500 IOError: [Errno 2] No such file or directory: '/sys/class/gpio/gpio68/value'

don't generate locales

Should be possible, see below text from https://github.com/openembedded/openembedded-core/blob/master/meta/conf/local.conf.sample.extended

# ENABLE_BINARY_LOCALE_GENERATION controls the generation of binary locale
# packages at build time using qemu-native. Disabling it (by setting it to 0)
# will save some build time at the expense of breaking i18n on devices with
# less than 128MB RAM.
#ENABLE_BINARY_LOCALE_GENERATION = "1"

# Set GLIBC_GENERATE_LOCALES to the locales you wish to generate should you not
# wish to perform the time-consuming step of generating all LIBC locales.
# NOTE: If removing en_US.UTF-8 you will also need to uncomment, and set
# appropriate value for IMAGE_LINGUAS.
# WARNING: this may break localisation!
#GLIBC_GENERATE_LOCALES = "en_GB.UTF-8 en_US.UTF-8"
#IMAGE_LINGUAS ?= "en-gb"

And we already tried something, but that didn't work yet: https://github.com/victronenergy/venus/blob/master/build/conf/local.conf#L222 and
http://updates.victronenergy.com/feeds/ccgx/1.0/release/armv7a/

Besides causing build-time it also clutters.

dbus-motordrive: rpm is missing from the gui

See screenshot:

The problem is that the D-Bus items have no texts. Try 'dbus -y com.victronenergy.motordrive.piktronik0 /Motor/RPM GetText' and you will get an empty string. Whevener a D-Bus item is displayed in the GUI and no unit is specified you will see the text of the D-Bus item instead of the value. If there is no text '--' is used instead.

To enable text in D-Bus items, use the 'veItemAddQuantity' function from velib instead of 'veItemAddChildByUid'. This function requires an extra parameter of type VeVariantUnitFmt. This is a struct where you can specify the precision and the unit. You can set the unit to NULL if there is none.

Velib contains an example project which illustrates the process: examples/dbus/c_service/task.c.

implement swupdate - replacing opkg based updating

the problem which we want to fix with swupdate

After being flashed with an image during manufacturing, the systems out in the field are auto-updated using a package system (opkg). Except for offline units, they are updated manually with the recovery image; manual update instructions. And also when the package based updating has caused issues, we tell people to update / restart from scratch by doing an update from an sdcard, using the recovery image.

This package based updating has certain advantages, but also disadvantages:

Very sensitive to errors made in the versioning numbers
Not all systems that report themselves as a certain system version l, for example Ccgx v1.40, are the same: some have updated themselves in many little steps, other have updated themselves at once, not always resulting in the same end-result etcetera.
Sometimes systems end up being half updated: certain packages are updated, others are not because of power cycle, loss of internet connection or otherwise.
Testing all of this is cumbersome.
Not easy, or at least risky, to do major updates.

the solution

Go to image updating. Using the swupdate solution from Stefano Babic / Denx.

requirements

Many installations have a bad internet connection: resume transfer is a hard requirement.
Some installations are on an expensive internet connection: transferring only deltas is a nice to have. A bonus feature: not in the first release! Maybe later. By reducing the data cost of checking for an update daily, which is high with the opkg mechanism, we can already gain much.
Checking for updates should cost less data then current solution. Easy to accomplish.
Use https for the transfer
Also a user can trigger a manual check for updates from the gui (and from command line). When available, install the update.
There should be feedback to the user that download of a new version has started
Log updating progress, on the data partition. Like we do now to /data/log/opkg/
Keep the four feeds: develop, testing, candidate, release
Keep the existing package based update / install mechanism intact. Not to be used for normal updates, but to offer extra optional package for users. Like we currently do with qt5, git, gdb, etc etc.

targets

machine bpp3: only active machine which is used to build code for devices in the field. All code and ongoing development for devices in the field is built using the danny config. And using the make ccgx command, with MACHINE=bpp3. To get started quickly, it is ok to work under Jethro instead of Danny. We can then later see if we first backport it to Danny and use that, or do the big jump at once. This would be a big jump because it is not so sure if building the current kernel version (3.7) with Jethro works at all or is stable, etc. etc.
machine beaglebone: A Venus image is currently being developed for the beaglebone black, on jethro. Note that this still in progress: focus first on bpp3. Start on the bbb only after checking with me on beaglebone status.

Once this image update mechanism is in place, our hands are free and we'll then start testing bpp3 built under Jethro in order to asap drop Danny and continue on Jethro.

We can then also drop the current bpp3-rootfs image, and use the venus-image instead.

And same time or later move from MACHINE=bpp3 to MACHINE=ccgx.

one time manual user intervention to switch

Chosen solution is: there will be one last opkg update. There will be an explanation that user need to use the sdcard (once) for an update. After which the ccgx can download its own updates again.

replace initscripts.bbappend with permanent-storage

After adding the bbappend to initscripts (for Jethro, is not used in Danny) a while ago during bbb development, there is now a new package: permanent storage. And the two have some overlap and differences. The intention is to get rid of the bbappend on initscripts.

Diffing the original volatiles and the new one:

--- a/meta-venus/recipes-core/initscripts/files/../../../../../openembedded-core/meta/recipes-core/initscripts/initscripts-1.0/volatiles
+++ b/meta-venus/recipes-core/initscripts/files/./volatiles
@@ -22,10 +22,24 @@
 # a link will be created at /var/test pointing to /tmp/testfile and due to this
 # link the file defined as /var/test will actually be created as /tmp/testfile.
 d root root 1777 /run/lock none
+
+# Create file structure on VE data partition
+d root root 1777 /data/conf none
+d root root 1777 /data/db none
+d root root 1777 /data/log none
+
+# keep /data/log and /log as long as not all recipes have been changed.
+l root root 1777 /log /data/log
+
+# Used by hiawatha and perhaps other programs
 d root root 0755 /var/volatile/log none
+
+# Used by all applications, including VE ones. They log to /var/log, which is
+# then saved on the data partition
+l root root 0755 /var/log /data/log
+
 d root root 1777 /var/volatile/tmp none
 l root root 1777 /var/lock /run/lock
-l root root 0755 /var/log /var/volatile/log
 l root root 0755 /var/run /run
 l root root 1777 /var/tmp /var/volatile/tmp
 l root root 1777 /tmp /var/tmp

Code from permanent-storage:

[ -d /data/conf ] || mkdir -p /data/conf
[ -d /conf ] || ln -sf /data/conf /conf
[ -d /data/log ] || mkdir -p /data/log
[ -d /log ] || ln -sf /data/log /log
[ -d /data/db ] || mkdir -p /data/db; chown www-data /data/db
[ -d /db ] || ln -sf /data/db /db
mkdir -p /data/home/root
mkdir -p /data/var/lib

Both recipes make:

/data/conf, db and log
/log -> /data/log

Only in permanent storage:

/conf -> /data/conf (no longer necessary in Jethro, and I don't see why Danny would need it)
/db -> data/db (same, no longer necessary)
mkdir -p /data/home/root (needed: used to put the home dir for root outside the rootfs)
mkdir -p /data/var/lib (needed: used to put connman data, aka wifi and ip settings, outside the rootfs)

Only in initscripts bbappend:

/var/log -> /data/log (needed, see below)

Conclusion:

Remove /conf and /db from permanent-storage
Add /var/log -> /data/log to permanent-storage, and make sure that initscripts/volatiles does not mess it up.

kernel deadlock when canbus is configured

Summary

When enabling can0 in /etc/network/interfaces, deadlocks appear in the logs, after boot. Therefore canbus is not yet enabled on the standard image. See below for an example log output with a deadlock. The killed project, vrmlogger, has nothing to do with canbus. And not always the same process crashes, this example contains vrmlogger but rebooting several times also shows other processes.

Reproduce the issue

Download this image into a standard beaglebone black (no custom capes or connecting anything to the canbus is required):
https://www.dropbox.com/s/0dp6m3ly6m66fcj/venus-image-beaglebone-20160710135100.rootfs.wic?dl=0

Note that any of our images will do: the issue has been present since the beginning of adding the beaglebone to venus

Burn to disk and run the image. Then modify /etc/network/interfacs, and uncomment these five lines:

auto can0
iface can0 inet manual
        pre-up ip link set $IFACE type can bitrate 250000 restart-ms 100
        up /sbin/ifconfig $IFACE up
        down /sbin/ifconfig $IFACE down

Reboot again, and you'll see the deadlock in the console within minutes.

Current workaround (to test hardware for example):

Don't enable it via /etc/network/interfaces, but enable the canbus manually. Note that below step then needs to be done after every boot. And note that the workaround might not even be necessary: I never tried if, although there is a lock, the canbus works when autoconfigured.

Manually enabling canbus from the commandline:

$ ip link set can1 up type can bitrate 250000
[   40.275265] c_can_platform 481d0000.can can1: setting BTR=1c05 BRPE=0000
[   40.283244] IPv6: ADDRCONF(NETDEV_CHANGE): can1: link becomes ready

Same for setting up can0, it can be done manually without getting the locking problem:
$ ip link set can0 up type can bitrate 250000
[   47.574025] c_can_platform 481cc000.can can0: setting BTR=1c05 BRPE=0000
[   47.581372] IPv6: ADDRCONF(NETDEV_CHANGE): can0: link becomes ready

To actually make our canbus<->D-Bus driver work with one of the interfaces, modify /service/vecan-dbus/run, add selecting the right interface. The run file should look like this:

$ cat /service/vecan-dbus/run
#!/bin/sh
exec 2>&1
exec softlimit -d 2000000 -s 1000000 -a 100000000 /opt/color-control/vecan-dbus/vecan-dbus --banner --log-before 25 --log-after 25 -vv -c socketcan:can1

Note that enabling both canbusses without changing above config line will cause for an endless restarting service: 100% processor usage.

make a wifi access point

The BBB has no display what-so-ever. Which makes it quite the black box when you want to configure it. It would be great if it acts as a WiFi access point. So one can connect to that, and set it up. Or even use it in case it is in some remote place with no other existing wifi or lan network.

Some wifi chipsets support to be both an AP and connected to another network at the same time. Some don't 😃.

In case the chipset cannot do both at the same time, the logic which needs to be followed is not straight forward:

of course on first boot, or in any other situation where there is no wifi config made, it can start as an access point
but then, once there is a config, it should try to connect to that configged networks(s). And never ever give up.

So that last point makes it a bit hard to get to that access point again. Some possible solution would be to be an access point only the first xx seconds during power up. Perhaps others too, suggestions welcome.

But, lets first double check if the wifi chipset in the BeagleBone Enhanced can do both at the same time. Since that would already make it a lot less urgent to come up with a real good solution for above issue.

inserting usb-gps gives an error

What you see in below log is that I first disconnected the ve.direct usb interface, and then plugged in a usb gps. It shows an error, Jeroen hinted that it perhaps has to do with missing firmware?

Dec 14 21:22:27 venus user.info kernel: [ 4489.565541] usb 1-1: USB disconnect, device number 2
Dec 14 21:22:27 venus user.info kernel: [ 4489.671365] ftdi_sio ttyUSB0: FTDI USB Serial Device converter now disconnected from ttyUSB0
Dec 14 21:22:27 venus user.info kernel: [ 4489.681009] ftdi_sio 1-1:1.0: device disconnected
Dec 14 21:22:32 venus user.debug kernel: [ 4494.790360] musb-hdrc musb-hdrc.1.auto: VBUS_ERROR in a_wait_bcon (91, <VBusValid), retry #1, port1 00000104
Dec 14 21:22:32 venus user.info kernel: [ 4495.198405] usb 1-1: new full-speed USB device number 3 using musb-hdrc
Dec 14 21:22:33 venus user.info kernel: [ 4495.339480] usb 1-1: New USB device found, idVendor=067b, idProduct=2303
Dec 14 21:22:33 venus user.info kernel: [ 4495.346540] usb 1-1: New USB device strings: Mfr=1, Product=2, SerialNumber=0
Dec 14 21:22:33 venus user.info kernel: [ 4495.354053] usb 1-1: Product: USB-Serial Controller
Dec 14 21:22:33 venus user.info kernel: [ 4495.359182] usb 1-1: Manufacturer: Prolific Technology Inc.
Dec 14 21:22:33 venus user.debug kernel: [ 4495.367345] usb 1-1: no of_node; not parsing pinctrl DT
Dec 14 21:22:33 venus user.debug kernel: [ 4495.371972] usbtest 1-1:1.0: no of_node; not parsing pinctrl DT
Dec 14 21:22:33 venus user.info kernel: [ 4495.390450] usbcore: registered new interface driver pl2303
Dec 14 21:22:33 venus user.info kernel: [ 4495.396635] usbserial: USB Serial support registered for pl2303
Dec 14 21:22:33 venus user.debug kernel: [ 4495.402985] pl2303 1-1:1.0: no of_node; not parsing pinctrl DT
Dec 14 21:22:33 venus user.info kernel: [ 4495.403110] pl2303 1-1:1.0: pl2303 converter detected
Dec 14 21:22:33 venus user.debug kernel: [ 4495.422814] pl2303 ttyUSB0: no of_node; not parsing pinctrl DT
Dec 14 21:22:33 venus user.info kernel: [ 4495.424522] usb 1-1: pl2303 converter now attached to ttyUSB0
Dec 14 21:23:38 venus user.err kernel: [ 4560.398449] musb_ep_program 896: broken !rx_reinit, ep11 csr 0203

@scottellis up to you to decide if this is something that needs fixing or can be left as is.

The GPS works fine:

bbb: wifi says 'no access points'

Some background: the gui uses connman. Connman is also accessible via dbus. And it does find the wifi dongle on the bbb:

root@venus:/var/log# dbus -y net.connman
/
/net
/net/connman
/net/connman/service
/net/connman/service/ethernet_6ceceb5c712b_cable
/net/connman/technology
/net/connman/technology/ethernet
/net/connman/technology/wifi
root@venus:/var/log# dbus -y net.connman /net/connman/technology/wifi GetProperties
properties = {'Connected': False,
 'Name': 'WiFi',
 'Powered': True,
 'Tethering': False,
 'Type': 'wifi'}
root@venus:/var/log# dbus -y net.connman /net/connman/technology/wifi Scan
Traceback (most recent call last):
  File "/usr/bin/dbus", line 364, in <module>
    ret = obj.object.get_dbus_method(method.name, iface.name)(*args)
  File "/usr/lib/python2.7/site-packages/dbus/proxies.py", line 145, in __call__
    **keywords)
  File "/usr/lib/python2.7/site-packages/dbus/connection.py", line 651, in call_blocking
    message, timeout)
dbus.exceptions.DBusException: net.connman.Error.NotSupported: Not supported

I don't know why Scan doesn't work. It works better on a CCGX:

root@ccgx:~# dbus -y net.connman /net/connman/technology/wifi Scan
Traceback (most recent call last):
  File "/usr/bin/dbus", line 374, in <module>
    pprint.pprint(to_native_type(arg))
  File "/usr/bin/dbus", line 284, in to_native_type
    return int(data)
TypeError: int() argument must be a string or a number, not 'NoneType'

(the NoneType error is a bug in dbus-cli)

More info from a CCGX:

Dec  9 22:39:42 CCGX user.info kernel: [12413.893218] usb 1-1.1: new high-speed USB device number 4 using ehci-omap
Dec  9 22:39:42 CCGX user.info kernel: [12414.027404] usb 1-1.1: New USB device found, idVendor=0586, idProduct=341e
Dec  9 22:39:42 CCGX user.info kernel: [12414.027435] usb 1-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Dec  9 22:39:42 CCGX user.info kernel: [12414.027465] usb 1-1.1: Product: 802.11 n WLAN
Dec  9 22:39:42 CCGX user.info kernel: [12414.027496] usb 1-1.1: Manufacturer: Ralink
Dec  9 22:39:42 CCGX user.info kernel: [12414.027496] usb 1-1.1: SerialNumber: 1.0
Dec  9 22:39:42 CCGX user.info kernel: [12414.093933] Loading modules backported from Linux version v3.16-0-g19583ca
Dec  9 22:39:42 CCGX user.info kernel: [12414.093963] Backport generated by backports.git v3.16-1-0-g87df966
Dec  9 22:39:42 CCGX user.info kernel: [12414.341430] cfg80211: Calling CRDA to update world regulatory domain
Dec  9 22:39:42 CCGX user.info kernel: [12414.424499] usb 1-1.1: reset high-speed USB device number 4 using ehci-omap
Dec  9 22:39:43 CCGX user.info kernel: [12414.554107] ieee80211 phy0: rt2x00_set_rt: Info - RT chipset 3070, rev 0201 detected
Dec  9 22:39:43 CCGX user.info kernel: [12414.609527] ieee80211 phy0: rt2x00_set_rf: Info - RF chipset 0005 detected
Dec  9 22:39:43 CCGX user.debug kernel: [12414.616638] ieee80211 phy0: Selected rate control algorithm 'minstrel_ht'
Dec  9 22:39:43 CCGX user.debug kernel: [12414.622161] Registered led device: rt2800usb-phy0::radio
Dec  9 22:39:43 CCGX user.debug kernel: [12414.622497] Registered led device: rt2800usb-phy0::assoc
Dec  9 22:39:43 CCGX user.debug kernel: [12414.622863] Registered led device: rt2800usb-phy0::quality
Dec  9 22:39:43 CCGX user.info kernel: [12414.623199] usbcore: registered new interface driver rt2800usb
Dec  9 22:39:43 CCGX daemon.info connmand[1452]: wlan0 {create} index 5 type 1 <ETHER>
Dec  9 22:39:43 CCGX daemon.info connmand[1452]: wlan0 {update} flags 4098 <DOWN>
Dec  9 22:39:43 CCGX daemon.info connmand[1452]: wlan0 {newlink} index 5 address 10:7B:EF:C7:79:A4 mtu 1500
Dec  9 22:39:43 CCGX daemon.info connmand[1452]: wlan0 {newlink} index 5 operstate 2 <DOWN>
Dec  9 22:39:43 CCGX daemon.info connmand[1452]: Adding interface wlan0 [ wifi ]
Dec  9 22:39:43 CCGX user.info kernel: [12414.686614] ieee80211 phy0: rt2x00lib_request_firmware: Info - Loading firmware file 'rt2870.bin'
Dec  9 22:39:43 CCGX user.info kernel: [12414.724975] ieee80211 phy0: rt2x00lib_request_firmware: Info - Firmware detected - version: 0.29
Dec  9 22:39:44 CCGX user.info kernel: [12415.766082] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Dec  9 22:39:44 CCGX daemon.info connmand[1452]: wlan0 {update} flags 36931 <UP,RUNNING>
Dec  9 22:39:44 CCGX daemon.info connmand[1452]: wlan0 {newlink} index 5 address 10:7B:EF:C7:79:A4 mtu 1500
Dec  9 22:39:44 CCGX daemon.info connmand[1452]: wlan0 {newlink} index 5 operstate 0 <UNKNOWN>
Dec  9 22:39:44 CCGX daemon.info connmand[1452]: wlan0 {update} flags 36867 <UP>
Dec  9 22:39:44 CCGX daemon.info connmand[1452]: wlan0 {newlink} index 5 address 10:7B:EF:C7:79:A4 mtu 1500
Dec  9 22:39:44 CCGX daemon.info connmand[1452]: wlan0 {newlink} index 5 operstate 2 <DOWN>
Dec  9 22:39:44 CCGX daemon.info connmand[1452]: wlan0 {update} flags 36866 <DOWN>
Dec  9 22:39:44 CCGX daemon.info connmand[1452]: wlan0 {newlink} index 5 address 10:7B:EF:C7:79:A4 mtu 1500
Dec  9 22:39:44 CCGX daemon.info connmand[1452]: wlan0 {newlink} index 5 operstate 2 <DOWN>
Dec  9 22:39:44 CCGX user.warn wpa_supplicant: Libgcrypt warning: missing initialization - please fix the application
Dec  9 22:40:07 CCGX user.info kernel: [12439.236236] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Dec  9 22:40:07 CCGX daemon.info connmand[1452]: wlan0 {update} flags 36867 <UP>
Dec  9 22:40:07 CCGX daemon.info connmand[1452]: wlan0 {newlink} index 5 address 10:7B:EF:C7:79:A4 mtu 1500
Dec  9 22:40:07 CCGX daemon.info connmand[1452]: wlan0 {newlink} index 5 operstate 2 <DOWN>
Dec  9 22:40:10 CCGX user.info kernel: [12441.656616] cfg80211: Calling CRDA to update world regulatory domain
root@ccgx:~# dbus -y net.connman
/
/net
/net/connman
/net/connman/service
/net/connman/service/ethernet_9059af69d820_cable
/net/connman/service/wifi_107befc779a4_4e45544745415234352d7a6f6c646572_managed_psk
/net/connman/service/wifi_107befc779a4_4e4554474541523435_managed_psk
/net/connman/technology
/net/connman/technology/ethernet
/net/connman/technology/wifi
root@ccgx:~# dbus -y net.connman /net/connman/technology/wifi GetProperties
properties = {'Connected': False,
 'Name': 'WiFi',
 'Powered': True,
 'Tethering': False,
 'Type': 'wifi'}

bbb: fix packages that toggle ccgx hw related io pins

Generator start/stop (relay)
It is fine to simply remove that package. Or change/patch gui that it can't be enabled.

Gui (relay and buzzer)
Change/patch gui so these features can't be enabled.

connman: upgrade to v1.33

Currently used version in Venus is v1.25. The latest available version is v1.33. Between those, there are lots of dhcp, dns and other relevant fixes which might improve network / connection stability.

There are also user complaints now and then. Some reproducible, some not. But instead of debugging / investigating them now, we'll first work with a newer version of ConnMan and see what then happens.

update: untangle gui from update process

Details of wanted improvements:

gui should no longer be then process that iniatates a check-for updates after boot. Should be done with a cron or something else. So that it also works when the gui is not there, or the gui doesn't work.
gui should not be needed to start upgrading when updates are found

bbb: serial-starter is not running because of down file

There is a down file in /service/serial-starter. That down file is normally removed at some point. I don't know when or what process normally removes that file though.

@jhofstee, do you have a hint for @scottellis ?

bbb: inserting VE.Direct to USB interface doesn't result in /dev/ttyUSBx

log output

Dec  9 21:00:40 venus user.debug kernel: [ 6857.672042] musb-hdrc musb-hdrc.1.auto: VBUS_ERROR in a_wait_bcon (99, VALID), retry #1, port1 00000104
Dec  9 21:00:40 venus user.info kernel: [ 6858.124405] usb 1-1: new full-speed USB device number 2 using musb-hdrc
Dec  9 21:00:40 venus user.info kernel: [ 6858.275379] usb 1-1: New USB device found, idVendor=0403, idProduct=6015
Dec  9 21:00:40 venus user.info kernel: [ 6858.282432] usb 1-1: New USB device strings: Mfr=1, Product=2, SerialNumber=0
Dec  9 21:00:40 venus user.info kernel: [ 6858.289966] usb 1-1: Product: FT232EX
Dec  9 21:00:40 venus user.info kernel: [ 6858.293816] usb 1-1: Manufacturer: FTDI
Dec  9 21:00:40 venus user.debug kernel: [ 6858.324626] usb 1-1: no of_node; not parsing pinctrl DT
Dec  9 21:00:40 venus user.debug kernel: [ 6858.330750] usbtest 1-1:1.0: no of_node; not parsing pinctrl DT

And /dev:

root@venus:/dev# ls
autofs              kmsg                mmcblk0p1           ram14               tty                 tty21               tty35               tty49               tty62               usbmon0
block               log                 mmcblk0p2           ram15               tty0                tty22               tty36               tty5                tty63               usbmon1
bus                 loop-control        mtab                ram2                tty1                tty23               tty37               tty50               tty7                vcs
char                loop0               network_latency     ram3                tty10               tty24               tty38               tty51               tty8                vcs1
console             loop1               network_throughput  ram4                tty11               tty25               tty39               tty52               tty9                vcsa
cpu_dma_latency     loop2               null                ram5                tty12               tty26               tty4                tty53               ttyO0               vcsa1
disk                loop3               psaux               ram6                tty13               tty27               tty40               tty54               ttyO2               watchdog
fd                  loop4               ptmx                ram7                tty14               tty28               tty41               tty55               ttyO4               watchdog0
full                loop5               pts                 ram8                tty15               tty29               tty42               tty56               ttyO5               zero
hwrng               loop6               ram0                ram9                tty16               tty3                tty43               tty57               ttyS0
i2c-0               loop7               ram1                random              tty17               tty30               tty44               tty58               ttyS1
iio:device0         memory_bandwidth    ram10               shm                 tty18               tty31               tty45               tty59               ttyS2
initctl             mmcblk0             ram11               stderr              tty19               tty32               tty46               tty6                ttyS3
input               mmcblk0boot0        ram12               stdin               tty2                tty33               tty47               tty60               ubi_ctrl
kmem                mmcblk0boot1        ram13               stdout              tty20               tty34               tty48               tty61               urandom

@scottellis you have that hardware (VE.Direct to USB interface), right?

dbus-generator is littering the localsettingslog

which makes it impossible to look back when the user changed a certain setting.

For example:

2016-08-11 16:52:37.279154500 2016-08-11 16:52:37,271 INFO Setting /Settings/Generator0/AccumulatedTotal changed. Old: 2561027, New: 2561037
2016-08-11 16:52:37.310618500 2016-08-11 16:52:37,309 INFO Setting /Settings/Generator0/AccumulatedDaily changed. Old: {"1468368000": 0, "1468454400": 0, "1468540800": 0, "1468627200": 0, "1468713600": 0, "1468800000": 0, "1468886400": 30669, "1468972800": 0, "1469059200": 28794, "1469145600": 28997, "1469232000": 86400, "1469318400": 55094, "1469404800": 5783, "1469491200": 62864, "1469577600": 52724, "1469664000": 58900, "1469750400": 66767, "1469836800": 62799, "1469923200": 0, "1470009600": 33186, "1470096000": 72371, "1470182400": 71516, "1470268800": 55057, "1470355200": 59054, "1470441600": 58224, "1470528000": 0, "1470614400": 52372, "1470700800": 41045, "1470787200": 40879, "1470873600": 54950}, New: {"1468368000": 0, "1468454400": 0, "1468540800": 0, "1468627200": 0, "1468713600": 0, "1468800000": 0, "1468886400": 30669, "1468972800": 0, "1469059200": 28794, "1469145600": 28997, "1469232000": 86400, "1469318400": 55094, "1469404800": 5783, "1469491200": 62864, "1469577600": 52724, "1469664000": 58900, "1469750400": 66767, "1469836800": 62799, "1469923200": 0, "1470009600": 33186, "1470096000": 72371, "1470182400": 71516, "1470268800": 55057, "1470355200": 59054, "1470441600": 58224, "1470528000": 0, "1470614400": 52372, "1470700800": 41045, "1470787200": 40879, "1470873600": 54960}
2016-08-11 16:52:47.255808500 2016-08-11 16:52:47,253 INFO Setting /Settings/Generator0/AccumulatedTotal changed. Old: 2561037, New: 2561047
2016-08-11 16:52:47.283701500 2016-08-11 16:52:47,282 INFO Setting /Settings/Generator0/AccumulatedDaily changed. Old: {"1468368000": 0, "1468454400": 0, "1468540800": 0, "1468627200": 0, "1468713600": 0, "1468800000": 0, "1468886400": 30669, "1468972800": 0, "1469059200": 28794, "1469145600": 28997, "1469232000": 86400, "1469318400": 55094, "1469404800": 5783, "1469491200": 62864, "1469577600": 52724, "1469664000": 58900, "1469750400": 66767, "1469836800": 62799, "1469923200": 0, "1470009600": 33186, "1470096000": 72371, "1470182400": 71516, "1470268800": 55057, "1470355200": 59054, "1470441600": 58224, "1470528000": 0, "1470614400": 52372, "1470700800": 41045, "1470787200": 40879, "1470873600": 54960}, New: {"1468368000": 0, "1468454400": 0, "1468540800": 0, "1468627200": 0, "1468713600": 0, "1468800000": 0, "1468886400": 30669, "1468972800": 0, "1469059200": 28794, "1469145600": 28997, "1469232000": 86400, "1469318400": 55094, "1469404800": 5783, "1469491200": 62864, "1469577600": 52724, "1469664000": 58900, "1469750400": 66767, "1469836800": 62799, "1469923200": 0, "1470009600": 33186, "1470096000": 72371, "1470182400": 71516, "1470268800": 55057, "1470355200": 59054, "1470441600": 58224, "1470528000": 0, "1470614400": 52372, "1470700800": 41045, "1470787200": 40879, "1470873600": 54970}
2016-08-11 16:52:57.294016500 2016-08-11 16:52:57,292 INFO Setting /Settings/Generator0/AccumulatedTotal changed. Old: 2561047, New: 2561057
2016-08-11 16:52:57.328287500 2016-08-11 16:52:57,326 INFO Setting /Settings/Generator0/AccumulatedDaily changed. Old: {"1468368000": 0, "1468454400": 0, "1468540800": 0, "1468627200": 0, "1468713600": 0, "1468800000": 0, "1468886400": 30669, "1468972800": 0, "1469059200": 28794, "1469145600": 28997, "1469232000": 86400, "1469318400": 55094, "1469404800": 5783, "1469491200": 62864, "1469577600": 52724, "1469664000": 58900, "1469750400": 66767, "1469836800": 62799, "1469923200": 0, "1470009600": 33186, "1470096000": 72371, "1470182400": 71516, "1470268800": 55057, "1470355200": 59054, "1470441600": 58224, "1470528000": 0, "1470614400": 52372, "1470700800": 41045, "1470787200": 40879, "1470873600": 54970}, New: {"1468368000": 0, "1468454400": 0, "1468540800": 0, "1468627200": 0, "1468713600": 0, "1468800000": 0, "1468886400": 30669, "1468972800": 0, "1469059200": 28794, "1469145600": 28997, "1469232000": 86400, "1469318400": 55094, "1469404800": 5783, "1469491200": 62864, "1469577600": 52724, "1469664000": 58900, "1469750400": 66767, "1469836800": 62799, "1469923200": 0, "1470009600": 33186, "1470096000": 72371, "1470182400": 71516, "1470268800": 55057, "1470355200": 59054, "1470441600": 58224, "1470528000": 0, "1470614400": 52372, "1470700800": 41045, "1470787200": 40879, "1470873600": 54980}
2016-08-11 16:53:07.441050500 2016-08-11 16:53:07,439 INFO Setting /Settings/Generator0/AccumulatedTotal changed. Old: 2561057, New: 2561067
2016-08-11 16:53:07.495493500 2016-08-11 16:53:07,491 INFO Setting /Settings/Generator0/AccumulatedDaily changed. Old: {"1468368000": 0, "1468454400": 0, "1468540800": 0, "1468627200": 0, "1468713600": 0, "1468800000": 0, "1468886400": 30669, "1468972800": 0, "1469059200": 28794, "1469145600": 28997, "1469232000": 86400, "1469318400": 55094, "1469404800": 5783, "1469491200": 62864, "1469577600": 52724, "1469664000": 58900, "1469750400": 66767, "1469836800": 62799, "1469923200": 0, "1470009600": 33186, "1470096000": 72371, "1470182400": 71516, "1470268800": 55057, "1470355200": 59054, "1470441600": 58224, "1470528000": 0, "1470614400": 52372, "1470700800": 41045, "1470787200": 40879, "1470873600": 54980}, New: {"1468368000": 0, "1468454400": 0, "1468540800": 0, "1468627200": 0, "1468713600": 0, "1468800000": 0, "1468886400": 30669, "1468972800": 0, "1469059200": 28794, "1469145600": 28997, "1469232000": 86400, "1469318400": 55094, "1469404800": 5783, "1469491200": 62864, "1469577600": 52724, "1469664000": 58900, "1469750400": 66767, "1469836800": 62799, "1469923200": 0, "1470009600": 33186, "1470096000": 72371, "1470182400": 71516, "1470268800": 55057, "1470355200": 59054, "1470441600": 58224, "1470528000": 0, "1470614400": 52372, "1470700800": 41045, "1470787200": 40879, "1470873600": 54990}
2016-08-11 16:53:17.321482500 2016-08-11 16:53:17,319 INFO Setting /Settings/Generator0/AccumulatedTotal changed. Old: 2561067, New: 2561077
2016-08-11 16:53:17.348246500 2016-08-11 16:53:17,346 INFO Setting /Settings/Generator0/AccumulatedDaily changed. Old: {"1468368000": 0, "1468454400": 0, "1468540800": 0, "1468627200": 0, "1468713600": 0, "1468800000": 0, "1468886400": 30669, "1468972800": 0, "1469059200": 28794, "1469145600": 28997, "1469232000": 86400, "1469318400": 55094, "1469404800": 5783, "1469491200": 62864, "1469577600": 52724, "1469664000": 58900, "1469750400": 66767, "1469836800": 62799, "1469923200": 0, "1470009600": 33186, "1470096000": 72371, "1470182400": 71516, "1470268800": 55057, "1470355200": 59054, "1470441600": 58224, "1470528000": 0, "1470614400": 52372, "1470700800": 41045, "1470787200": 40879, "1470873600": 54990}, New: {"1468368000": 0, "1468454400": 0, "1468540800": 0, "1468627200": 0, "1468713600": 0, "1468800000": 0, "1468886400": 30669, "1468972800": 0, "1469059200": 28794, "1469145600": 28997, "1469232000": 86400, "1469318400": 55094, "1469404800": 5783, "1469491200": 62864, "1469577600": 52724, "1469664000": 58900, "1469750400": 66767, "1469836800": 62799, "1469923200": 0, "1470009600": 33186, "1470096000": 72371, "1470182400": 71516, "1470268800": 55057, "1470355200": 59054, "1470441600": 58224, "1470528000": 0, "1470614400": 52372, "1470700800": 41045, "1470787200": 40879, "1470873600": 55000}
2016-08-11 16:53:27.368174500 2016-08-11 16:53:27,365 INFO Setting /Settings/Generator0/AccumulatedTotal changed. Old: 2561077, New: 2561087
2016-08-11 16:53:27.396280500 2016-08-11 16:53:27,394 INFO Setting /Settings/Generator0/AccumulatedDaily changed. Old: {"1468368000": 0, "1468454400": 0, "1468540800": 0, "1468627200": 0, "1468713600": 0, "1468800000": 0, "1468886400": 30669, "1468972800": 0, "1469059200": 28794, "1469145600": 28997, "1469232000": 86400, "1469318400": 55094, "1469404800": 5783, "1469491200": 62864, "1469577600": 52724, "1469664000": 58900, "1469750400": 66767, "1469836800": 62799, "1469923200": 0, "1470009600": 33186, "1470096000": 72371, "1470182400": 71516, "1470268800": 55057, "1470355200": 59054, "1470441600": 58224, "1470528000": 0, "1470614400": 52372, "1470700800": 41045, "1470787200": 40879, "1470873600": 55000}, New: {"1468368000": 0, "1468454400": 0, "1468540800": 0, "1468627200": 0, "1468713600": 0, "1468800000": 0, "1468886400": 30669, "1468972800": 0, "1469059200": 28794, "1469145600": 28997, "1469232000": 86400, "1469318400": 55094, "1469404800": 5783, "1469491200": 62864, "1469577600": 52724, "1469664000": 58900, "1469750400": 66767, "1469836800": 62799, "1469923200": 0, "1470009600": 33186, "1470096000": 72371, "1470182400": 71516, "1470268800": 55057, "1470355200": 59054, "1470441600": 58224, "1470528000": 0, "1470614400": 52372, "1470700800": 41045, "1470787200": 40879, "1470873600": 55010}
2016-08-11 16:53:37.376413500 2016-08-11 16:53:37,374 INFO Setting /Settings/Generator0/AccumulatedTotal changed. Old: 2561087, New: 2561097
2016-08-11 16:53:37.427042500 2016-08-11 16:53:37,425 INFO Setting /Settings/Generator0/AccumulatedDaily changed. Old: {"1468368000": 0, "1468454400": 0, "1468540800": 0, "1468627200": 0, "1468713600": 0, "1468800000": 0, "1468886400": 30669, "1468972800": 0, "1469059200": 28794, "1469145600": 28997, "1469232000": 86400, "1469318400": 55094, "1469404800": 5783, "1469491200": 62864, "1469577600": 52724, "1469664000": 58900, "1469750400": 66767, "1469836800": 62799, "1469923200": 0, "1470009600": 33186, "1470096000": 72371, "1470182400": 71516, "1470268800": 55057, "1470355200": 59054, "1470441600": 58224, "1470528000": 0, "1470614400": 52372, "1470700800": 41045, "1470787200": 40879, "1470873600": 55010}, New: {"1468368000": 0, "1468454400": 0, "1468540800": 0, "1468627200": 0, "1468713600": 0, "1468800000": 0, "1468886400": 30669, "1468972800": 0, "1469059200": 28794, "1469145600": 28997, "1469232000": 86400, "1469318400": 55094, "1469404800": 5783, "1469491200": 62864, "1469577600": 52724, "1469664000": 58900, "1469750400": 66767, "1469836800": 62799, "1469923200": 0, "1470009600": 33186, "1470096000": 72371, "1470182400": 71516, "1470268800": 55057, "1470355200": 59054, "1470441600": 58224, "1470528000": 0, "1470614400": 52372, "1470700800": 41045, "1470787200": 40879, "1470873600": 55020}
2016-08-11 16:53:47.461680500 2016-08-11 16:53:47,459 INFO Setting /Settings/Generator0/AccumulatedTotal changed. Old: 2561097, New: 2561107
2016-08-11 16:53:47.502329500 2016-08-11 16:53:47,500 INFO Setting /Settings/Generator0/AccumulatedDaily changed. Old: {"1468368000": 0, "1468454400": 0, "1468540800": 0, "1468627200": 0, "1468713600": 0, "1468800000": 0, "1468886400": 30669, "1468972800": 0, "1469059200": 28794, "1469145600": 28997, "1469232000": 86400, "1469318400": 55094, "1469404800": 5783, "1469491200": 62864, "1469577600": 52724, "1469664000": 58900, "1469750400": 66767, "1469836800": 62799, "1469923200": 0, "1470009600": 33186, "1470096000": 72371, "1470182400": 71516, "1470268800": 55057, "1470355200": 59054, "1470441600": 58224, "1470528000": 0, "1470614400": 52372, "1470700800": 41045, "1470787200": 40879, "1470873600": 55020}, New: {"1468368000": 0, "1468454400": 0, "1468540800": 0, "1468627200": 0, "1468713600": 0, "1468800000": 0, "1468886400": 30669, "1468972800": 0, "1469059200": 28794, "1469145600": 28997, "1469232000": 86400, "1469318400": 55094, "1469404800": 5783, "1469491200": 62864, "1469577600": 52724, "1469664000": 58900, "1469750400": 66767, "1469836800": 62799, "1469923200": 0, "1470009600": 33186, "1470096000": 72371, "1470182400": 71516, "1470268800": 55057, "1470355200": 59054, "1470441600": 58224, "1470528000": 0, "1470614400": 52372, "1470700800": 41045, "1470787200": 40879, "1470873600": 55030}
2016-08-11 16:53:57.464823500 2016-08-11 16:53:57,442 INFO Setting /Settings/Generator0/AccumulatedTotal changed. Old: 2561107, New: 2561117
2016-08-11 16:53:57.533609500 2016-08-11 16:53:57,532 INFO Setting /Settings/Generator0/AccumulatedDaily changed. Old: {"1468368000": 0, "1468454400": 0, "1468540800": 0, "1468627200": 0, "1468713600": 0, "1468800000": 0, "1468886400": 30669, "1468972800": 0, "1469059200": 28794, "1469145600": 28997, "1469232000": 86400, "1469318400": 55094, "1469404800": 5783, "1469491200": 62864, "1469577600": 52724, "1469664000": 58900, "1469750400": 66767, "1469836800": 62799, "1469923200": 0, "1470009600": 33186, "1470096000": 72371, "1470182400": 71516, "1470268800": 55057, "1470355200": 59054, "1470441600": 58224, "1470528000": 0, "1470614400": 52372, "1470700800": 41045, "1470787200": 40879, "1470873600": 55030}, New: {"1468368000": 0, "1468454400": 0, "1468540800": 0, "1468627200": 0, "1468713600": 0, "1468800000": 0, "1468886400": 30669, "1468972800": 0, "1469059200": 28794, "1469145600": 28997, "1469232000": 86400, "1469318400": 55094, "1469404800": 5783, "1469491200": 62864, "1469577600": 52724, "1469664000": 58900, "1469750400": 66767, "1469836800": 62799, "1469923200": 0, "1470009600": 33186, "1470096000": 72371, "1470182400": 71516, "1470268800": 55057, "1470355200": 59054, "1470441600": 58224, "1470528000": 0, "1470614400": 52372, "1470700800": 41045, "1470787200": 40879, "1470873600": 55040}
2016-08-11 16:54:07.453226500 2016-08-11 16:54:07,450 INFO Setting /Settings/Generator0/AccumulatedTotal changed. Old: 2561117, New: 2561127
2016-08-11 16:54:07.498301500 2016-08-11 16:54:07,496 INFO Setting /Settings/Generator0/AccumulatedDaily changed. Old: {"1468368000": 0, "1468454400": 0, "1468540800": 0, "1468627200": 0, "1468713600": 0, "1468800000": 0, "1468886400": 30669, "1468972800": 0, "1469059200": 28794, "1469145600": 28997, "1469232000": 86400, "1469318400": 55094, "1469404800": 5783, "1469491200": 62864, "1469577600": 52724, "1469664000": 58900, "1469750400": 66767, "1469836800": 62799, "1469923200": 0, "1470009600": 33186, "1470096000": 72371, "1470182400": 71516, "1470268800": 55057, "1470355200": 59054, "1470441600": 58224, "1470528000": 0, "1470614400": 52372, "1470700800": 41045, "1470787200": 40879, "1470873600": 55040}, New: {"1468368000": 0, "1468454400": 0, "1468540800": 0, "1468627200": 0, "1468713600": 0, "1468800000": 0, "1468886400": 30669, "1468972800": 0, "1469059200": 28794, "1469145600": 28997, "1469232000": 86400, "1469318400": 55094, "1469404800": 5783, "1469491200": 62864, "1469577600": 52724, "1469664000": 58900, "1469750400": 66767, "1469836800": 62799, "1469923200": 0, "1470009600": 33186, "1470096000": 72371, "1470182400": 71516, "1470268800": 55057, "1470355200": 59054, "1470441600": 58224, "1470528000": 0, "1470614400": 52372, "1470700800": 41045, "1470787200": 40879, "1470873600": 55050}
2016-08-11 16:54:17.476725500 2016-08-11 16:54:17,474 INFO Setting /Settings/Generator0/AccumulatedTotal changed. Old: 2561127, New: 2561137
2016-08-11 16:54:17.513102500 2016-08-11 16:54:17,510 INFO Setting /Settings/Generator0/AccumulatedDaily changed. Old: {"1468368000": 0, "1468454400": 0, "1468540800": 0, "1468627200": 0, "1468713600": 0, "1468800000": 0, "1468886400": 30669, "1468972800": 0, "1469059200": 28794, "1469145600": 28997, "1469232000": 86400, "1469318400": 55094, "1469404800": 5783, "1469491200": 62864, "1469577600": 52724, "1469664000": 58900, "1469750400": 66767, "1469836800": 62799, "1469923200": 0, "1470009600": 33186, "1470096000": 72371, "1470182400": 71516, "1470268800": 55057, "1470355200": 59054, "1470441600": 58224, "1470528000": 0, "1470614400": 52372, "1470700800": 41045, "1470787200": 40879, "1470873600": 55050}, New: {"1468368000": 0, "1468454400": 0, "1468540800": 0, "1468627200": 0, "1468713600": 0, "1468800000": 0, "1468886400": 30669, "1468972800": 0, "1469059200": 28794, "1469145600": 28997, "1469232000": 86400, "1469318400": 55094, "1469404800": 5783, "1469491200": 62864, "1469577600": 52724, "1469664000": 58900, "1469750400": 66767, "1469836800": 62799, "1469923200": 0, "1470009600": 33186, "1470096000": 72371, "1470182400": 71516, "1470268800": 55057, "1470355200": 59054, "1470441600": 58224, "1470528000": 0, "1470614400": 52372, "1470700800": 41045, "1470787200": 40879, "1470873600": 55060}
2016-08-11 16:57:00.307901500 2016-08-11 16:57:00,231 INFO Setting /Settings/Gui/StartWithMenuView changed. Old: 0, New: 1
2016-08-11 17:00:09.255655500 2016-08-11 17:00:09,253 INFO Setting /Settings/Gui/StartWithMenuView changed. Old: 1, New: 0
2016-08-11 17:02:22.976023500 2016-08-11 17:02:22,973 INFO Setting /Settings/Gui/StartWithMenuView changed. Old: 0, New: 1
2016-08-11 17:12:24.831797500 2016-08-11 17:12:24,829 INFO Setting /Settings/Gui/StartWithMenuView changed. Old: 1, New: 0
2016-08-11 17:13:49.075876500 2016-08-11 17:13:49,073 INFO Setting /Settings/Gui/StartWithMenuView changed. Old: 0, New: 1
2016-08-11 17:16:40.807871500 2016-08-11 17:16:40,805 INFO Setting /Settings/Gui/StartWithMenuView changed. Old: 1, New: 0
2016-08-11 17:17:24.972880500 2016-08-11 17:17:24,970 INFO Setting /Settings/Gui/StartWithMenuView changed. Old: 0, New: 1
2016-08-11 17:35:02.852762500 2016-08-11 17:35:02,851 INFO Setting /Settings/Services/Vrmpubnub changed. Old: 0, New: 1
2016-08-11 17:35:23.703623500 2016-08-11 17:35:23,701 INFO Setting /Settings/Services/LgResu changed. Old: 1, New: 0
2016-08-11 17:36:27.042236500 *** CCGX booted (1) ***
2016-08-11 17:36:38.338806500 localsettings v1.04 starting up 
2016-08-11 17:36:38.668212500 2016-08-11 17:36:38,656 INFO Localsettings version is: 0x0104
2016-08-11 17:36:38.672241500 2016-08-11 17:36:38,670 INFO Localsettings PID is: 1191
2016-08-11 17:36:38.746582500 2016-08-11 17:36:38,745 INFO Settings file /data/conf/settings.xml validated
2016-08-11 17:36:51.116149500 2016-08-11 17:36:51,113 INFO Setting /Settings/System/RemoteSupportPort changed. Old: 56584, New: 0
2016-08-11 17:36:51.248931500 2016-08-11 17:36:51,247 INFO Setting /Settings/System/RemoteVncPort changed. Old: 52065, New: 0
2016-08-11 17:37:08.424260500 2016-08-11 17:37:08,422 INFO Setting /Settings/System/RemoteSupportPort changed. Old: 0, New: 56261
2016-08-11 17:37:09.683262500 2016-08-11 17:37:09,681 INFO Setting /Settings/System/RemoteVncPort changed. Old: 0, New: 60341
2016-08-11 17:38:18.916234500 2016-08-11 17:38:18,914 INFO Setting /Settings/Gui/StartWithMenuView changed. Old: 1, New: 0
2016-08-11 18:18:28.761326500 2016-08-11 18:18:28,759 INFO Setting /Settings/Gui/StartWithMenuView changed. Old: 0, New: 1
2016-08-11 18:21:01.664769500 2016-08-11 18:21:01,518 INFO GetDefault /Settings/Generator0/MinimumRuntime
2016-08-11 18:21:16.862614500 2016-08-11 18:21:16,861 INFO Setting /Settings/Generator0/MinimumRuntime changed. Old: 5, New: 2
2016-08-11 18:21:26.032261500 2016-08-11 18:21:26,030 INFO Setting /Settings/Generator0/MinimumRuntime changed. Old: 2, New: 3
2016-08-11 18:22:48.066411500 2016-08-11 18:22:48,064 INFO Setting /Settings/Generator0/AccumulatedDaily changed. Old: {"1468368000": 0, "1468454400": 0, "1468540800": 0, "1468627200": 0, "1468713600": 0, "1468800000": 0, "1468886400": 30669, "1468972800": 0, "1469059200": 28794, "1469145600": 28997, "1469232000": 86400, "1469318400": 55094, "1469404800": 5783, "1469491200": 62864, "1469577600": 52724, "1469664000": 58900, "1469750400": 66767, "1469836800": 62799, "1469923200": 0, "1470009600": 33186, "1470096000": 72371, "1470182400": 71516, "1470268800": 55057, "1470355200": 59054, "1470441600": 58224, "1470528000": 0, "1470614400": 52372, "1470700800": 41045, "1470787200": 40879, "1470873600": 55060}, New: {"1470873600" : 0}
2016-08-11 18:22:58.684727500 2016-08-11 18:22:58,682 INFO GetDefault /Settings/Generator0/TestRun/Interval
2016-08-11 18:28:25.573704500 2016-08-11 18:28:25,432 INFO GetDefault /Settings/Generator0/Soc/StartValue
2016-08-11 18:28:25.814427500 2016-08-11 18:28:25,813 INFO GetDefault /Settings/Generator0/Soc/QuietHoursStartValue
2016-08-11 18:28:26.091618500 2016-08-11 18:28:26,090 INFO GetDefault /Settings/Generator0/Soc/StopValue
2016-08-11 18:28:26.167210500 2016-08-11 18:28:26,165 INFO GetDefault /Settings/Generator0/Soc/QuietHoursStopValue
2016-08-11 18:29:23.692814500 2016-08-11 18:29:23,691 INFO Setting /Settings/Gui/StartWithMenuView changed. Old: 1, New: 0
2016-08-11 23:00:00.985477500 2016-08-11 23:00:00,983 INFO Setting /Settings/Generator0/AccumulatedDaily changed. Old: {"1470873600" : 0}, New: {"1470873600": 0, "1470960000": 0}
2016-08-12 07:00:05.112003500 2016-08-12 07:00:05,083 INFO Setting /Settings/Gui/StartWithMenuView changed. Old: 0, New: 1
2016-08-12 07:02:20.277134500 2016-08-12 07:02:20,275 INFO Setting /Settings/Services/VeCan changed. Old: 0, New: 1

And looks like StartWithMenuView might do the same. Not sure how to fix this: either add an option for localsettings not to write changes to certain paths to the log. Or don't use localsettings for these kinds of things. Or .. ?

make dbus-valence filter on canbus type (11 vs 29 bit)

Example of the code that needs to be added into the main taskUpdate() function:

    while (veCanRead(&msg))
    {
        VeStream canIn;

        /* ignore all 29 bit / extended messages */
        if (msg.flags & VE_CAN_EXT)
            continue;

make CONFIG=jethro on clean checkout error

See the find: 'sources': No such file or directory error in the log at line ~14. Probably something small, but I don't have time to look further into this now.

Also it doesn't seem to prevent building.

matthijs@matthijs-VirtualBox:/media/venusbuilds$ git clone [email protected]:victronenergy/venus.git venus-jethro
Cloning into 'venus-jethro'...
remote: Counting objects: 606, done.
remote: Total 606 (delta 0), reused 0 (delta 0), pack-reused 606
Receiving objects: 100% (606/606), 87.63 KiB | 0 bytes/s, done.
Resolving deltas: 100% (335/335), done.
Checking connectivity... done.
matthijs@matthijs-VirtualBox:/media/venusbuilds$ cd venus-jethro/
matthijs@matthijs-VirtualBox:/media/venusbuilds/venus-jethro$ make CONFIG=jethro
ln -s configs/jethro conf
find: `sources': No such file or directory
matthijs@matthijs-VirtualBox:/media/venusbuilds/venus-jethro$ make fetch

--------------- sources/bitbake - https://github.com/victronenergy/bitbake.git -------------
Cloning into 'sources/bitbake'...
remote: Counting objects: 43713, done.
Receiving objects: 100% (43713/43713), 21.09 MiB | 4.54 MiB/s, done.
remote: Total 43713 (delta 0), reused 0 (delta 0), pack-reused 43713
Resolving deltas: 100% (28924/28924), done.
Checking connectivity... done.
Branch 1.28 set up to track remote branch 1.28 from origin.
Switched to a new branch '1.28'
From https://github.com/openembedded/bitbake
 * branch            1.28       -> FETCH_HEAD
 * [new branch]      1.28       -> upstream/1.28
Branch 1.28 set up to track remote branch 1.28 from upstream.

--------------- sources/meta-openembedded - https://github.com/victronenergy/meta-openembedded.git -------------
Cloning into 'sources/meta-openembedded'...
remote: Counting objects: 61442, done.
remote: Total 61442 (delta 0), reused 0 (delta 0), pack-reused 61441
Receiving objects: 100% (61442/61442), 26.51 MiB | 4.13 MiB/s, done.
Resolving deltas: 100% (34781/34781), done.
Checking connectivity... done.
Branch jethro_venus set up to track remote branch jethro_venus from origin.
Switched to a new branch 'jethro_venus'
From https://github.com/victronenergy/meta-openembedded
 * branch            jethro     -> FETCH_HEAD
Branch jethro_venus set up to track remote branch jethro from origin.

[....]

--------------- sources/openembedded-core - https://github.com/victronenergy/openembedded-core.git -------------
Cloning into 'sources/openembedded-core'...
remote: Counting objects: 292226, done.
remote: Compressing objects: 100% (4/4), done.
remote: Total 292226 (delta 5), reused 5 (delta 5), pack-reused 292217
Receiving objects: 100% (292226/292226), 111.05 MiB | 3.60 MiB/s, done.
Resolving deltas: 100% (202680/202680), done.
Checking connectivity... done.
Branch jethro_venus set up to track remote branch jethro_venus from origin.
Switched to a new branch 'jethro_venus'
From https://github.com/victronenergy/openembedded-core
 * branch            jethro     -> FETCH_HEAD
Branch jethro_venus set up to track remote branch jethro from origin.
matthijs@matthijs-VirtualBox:/media/venusbuilds/venus-jethro$ make beaglebone-venus-image
export MACHINE=beaglebone && . ./sources/openembedded-core/oe-init-build-env build sources/bitbake && bitbake venus-image

### Shell environment set up for builds. ###

You can now run 'bitbake <target>'

Common targets are:
    core-image-minimal
    core-image-sato
    meta-toolchain
    adt-installer
    meta-ide-support

You can also run generated qemu images with a command like 'runqemu qemux86'
Parsing recipes: 100% |##############################################################################################################################################################| Time: 00:01:03
Parsing of 1951 .bb files complete (0 cached, 1951 parsed). 2503 targets, 355 skipped, 0 masked, 0 errors.
NOTE: Resolving any missing task queue dependencies

Build Configuration:
BB_VERSION        = "1.28.0"
BUILD_SYS         = "i686-linux"
NATIVELSBSTRING   = "Ubuntu-14.04"
TARGET_SYS        = "arm-ve-linux-gnueabi"
MACHINE           = "beaglebone"
DISTRO            = "venus"
DISTRO_VERSION    = "v1.80~4"
TUNE_FEATURES     = "arm armv7a vfp neon callconvention-hard cortexa8"
TARGET_FPU        = "vfp-neon"
meta-multimedia   
meta-networking   
meta-oe           
meta-python       
meta-webserver    = "jethro_venus:c305ac5d2f5285d5eec8952a4ca7f3b4f89aed96"
meta-qt5          = "jethro_venus:8495b95a52a9973b61c72b6f37182fd9a2404929"
meta-swupdate     = "master:58066092720eb7d55e797bd8e9a54bba56d9ef16"
meta-alternatives 
meta-backports    
meta-bsp          
meta-third-party  
meta-venus        
meta-ve-software  = "master:1de951a35f35a0c20fb885f68ab2b43d2f11e885"
meta              = "jethro_venus:975460f772528fce5c3209938e4cfb871231681d"

[...]

d

jethro: make fetch-all errors for bitbake and meta-yocto

builder@vebuild5:/media/ram/venus-jethro$ rm -rf ./sources
builder@vebuild5:/media/ram/venus-jethro$ ls -al
total 44
drwxrwxr-x 6 builder builder  360 Apr 29 16:34 .
drwxrwxrwt 7 builder builder  160 Apr 29 15:20 ..
drwxrwxr-x 8 builder builder  300 Apr 29 16:34 .git
-rw-rw-r-- 1 builder builder   45 Apr 29 15:20 .gitignore
-rw-rw-r-- 1 builder builder 2390 Apr 29 15:20 Makefile
-rw-rw-r-- 1 builder builder 4072 Apr 29 16:34 README
drwxrwxr-x 6 builder builder  160 Apr 29 15:22 build
lrwxrwxrwx 1 builder builder   14 Apr 29 15:20 conf -> configs/jethro
drwxrwxr-x 6 builder builder  120 Apr 29 15:20 configs
drwxrwxr-x 5 builder builder  100 Apr 29 15:33 deploy
-rwxrwxr-x 1 builder builder  593 Apr 29 15:20 git-fetch-remote.sh
-rwxrwxr-x 1 builder builder 1317 Apr 29 15:20 git-push-status.py
-rwxrwxr-x 1 builder builder  459 Apr 29 15:20 git-show-remote.sh
-rw-rw-r-- 1 builder builder 1143 Apr 29 15:22 metas.found
-rw-rw-r-- 1 builder builder  624 Apr 29 15:20 metas.whitelist
-rwxrwxr-x 1 builder builder   46 Apr 29 15:20 push-status.sh
-rwxrwxr-x 1 builder builder   47 Apr 29 15:20 repos
-rwxrwxr-x 1 builder builder  455 Apr 29 15:20 repos_cmd
builder@vebuild5:/media/ram/venus-jethro$ make fetch-all

--------------- sources/meta-swupdate - [email protected]:victronenergy/meta-swupdate.git -------------
Cloning into 'sources/meta-swupdate'...
remote: Counting objects: 256, done.
remote: Total 256 (delta 0), reused 0 (delta 0), pack-reused 256
Receiving objects: 100% (256/256), 36.99 KiB | 0 bytes/s, done.
Resolving deltas: 100% (73/73), done.
Checking connectivity... done.
Already on 'master'
Your branch is up-to-date with 'origin/master'.
Branch master set up to track remote branch master from origin.

--------------- sources/meta-yocto - https://github.com/victronenergy/meta-yocto.git -------------
Cloning into 'sources/meta-yocto'...
remote: Counting objects: 7839, done.
remote: Total 7839 (delta 0), reused 0 (delta 0), pack-reused 7839
Receiving objects: 100% (7839/7839), 1.02 MiB | 870.00 KiB/s, done.
Resolving deltas: 100% (4833/4833), done.
Checking connectivity... done.
Branch jethro set up to track remote branch jethro from origin.
Switched to a new branch 'jethro'
error: the requested upstream branch 'upstream/jethro' does not exist
hint: 
hint: If you are planning on basing your work on an upstream
hint: branch that already exists at the remote, you may need to
hint: run "git fetch" to retrieve it.
hint: 
hint: If you are planning to push out a new local branch that
hint: will track its remote counterpart, you may want to use
hint: "git push -u" to set the upstream config as you push.

--------------- sources/meta-openembedded - https://github.com/victronenergy/meta-openembedded.git -------------
Cloning into 'sources/meta-openembedded'...
remote: Counting objects: 53881, done.
remote: Compressing objects: 100% (29/29), done.
remote: Total 53881 (delta 66), reused 59 (delta 59), pack-reused 53793
Receiving objects: 100% (53881/53881), 24.78 MiB | 11.53 MiB/s, done.
Resolving deltas: 100% (30661/30661), done.
Checking connectivity... done.
Branch jethro_venus set up to track remote branch jethro_venus from origin.
Switched to a new branch 'jethro_venus'
Branch jethro_venus set up to track remote branch jethro from origin.

--------------- sources/meta-victronenergy-private - [email protected]:ccgx/meta-victronenergy-private.git -------------
Cloning into 'sources/meta-victronenergy-private'...
remote: Counting objects: 641, done.
remote: Compressing objects: 100% (622/622), done.
remote: Total 641 (delta 362), reused 0 (delta 0)
Receiving objects: 100% (641/641), 84.39 KiB | 0 bytes/s, done.
Resolving deltas: 100% (362/362), done.
Checking connectivity... done.
Already on 'master'
Your branch is up-to-date with 'origin/master'.
Branch master set up to track remote branch master from origin.

--------------- sources/bitbake - https://github.com/victronenergy/bitbake.git -------------
Cloning into 'sources/bitbake'...
remote: Counting objects: 39010, done.
remote: Total 39010 (delta 0), reused 0 (delta 0), pack-reused 39010
Receiving objects: 100% (39010/39010), 19.09 MiB | 8.03 MiB/s, done.
Resolving deltas: 100% (25061/25061), done.
Checking connectivity... done.
Branch 1.28 set up to track remote branch 1.28 from origin.
Switched to a new branch '1.28'
error: the requested upstream branch 'upstream/1.28' does not exist
hint: 
hint: If you are planning on basing your work on an upstream
hint: branch that already exists at the remote, you may need to
hint: run "git fetch" to retrieve it.
hint: 
hint: If you are planning to push out a new local branch that
hint: will track its remote counterpart, you may want to use
hint: "git push -u" to set the upstream config as you push.

--------------- sources/openembedded-core - https://github.com/victronenergy/openembedded-core.git -------------
Cloning into 'sources/openembedded-core'...
remote: Counting objects: 266872, done.
remote: Compressing objects: 100% (61/61), done.
remote: Total 266872 (delta 41), reused 23 (delta 23), pack-reused 266787
Receiving objects: 100% (266872/266872), 104.84 MiB | 23.74 MiB/s, done.
Resolving deltas: 100% (184585/184585), done.
Checking connectivity... done.
Branch jethro_venus set up to track remote branch jethro_venus from origin.
Switched to a new branch 'jethro_venus'
Branch jethro_venus set up to track remote branch jethro from origin.

--------------- sources/meta-victronenergy - https://github.com/victronenergy/meta-victronenergy.git -------------
Cloning into 'sources/meta-victronenergy'...
remote: Counting objects: 2585, done.
remote: Compressing objects: 100% (68/68), done.
remote: Total 2585 (delta 211), reused 181 (delta 181), pack-reused 2333
Receiving objects: 100% (2585/2585), 632.72 KiB | 501.00 KiB/s, done.
Resolving deltas: 100% (1155/1155), done.
Checking connectivity... done.
Already on 'master'
Your branch is up-to-date with 'origin/master'.
Branch master set up to track remote branch master from origin.

upnp: vrmportal id is 00000000

The 000000000 is the vrm portal identifier: the unique number used by users to register the device on the vrm portal. For the bbb and ccgx this is defined as the eth0 mac address, stripped from its colons.

This is a regression from v1.71 -> v1.80.

Recipe here: meta-victronenergy/.../simple-upnpd

There were some changes made between v1.71 and v1.80, but they don't seem to be the cause this. Perhaps the switch to swupdate is.

The issue is specific to the CCGX, on the BBB it works fine.

Root cause is probably that the post install is executed before the mac address is properly read and set.

bbb: simple-upnp is not starting

@scottellis
The /etc/simple-upnpd.xml file was missing. Looks like line 36 and 37 of the recipe are not being executed. The problem was resolved after manually running those two lines on the BBB.

Recipe is here:
https://github.com/victronenergy/meta-victronenergy/blob/WIP_bbb/recipes-networking/simple-upnpd/simple-upnpd_v0.04.bb#L36

double check that watchdog is still watching during reboot

On the current released firmware on the CCGX (= Danny), special care has been taken to keep the watchdog armed during a reboot. To make sure that the device does not get stuck during a reboot.

See watchdog.bbappend in meta-victronenergy for details.

While trying out the beaglebone enhanced, I found an issue that made the bbe get stuck while unloading the wifi driver during a reboot. Which has been solved.

So, task of this issue is to verify that watchdog is still active during the reboot. And if it is not (which I think is the case), fix that.

CI: make bitbake write less output

See if it is possible, preferably with some default options or commands, to reduce output from bitbake a bit.

Besides making that file huge, perhaps it also causes a speed-reduction in the builds? They seem to take longer than when executed straight from the command line.

Small copy & paste from the huge log:

NOTE: recipe mk2vsc-1.06-r0: task do_install: Started
NOTE: recipe vecan-mk2-2.24-r6: task do_install: Succeeded
NOTE: Running task 2906 of 3454 (ID: 2650, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/meta-ccgx/recipes-bpp3/vecan-mk2/vecan-mk2_2.24.bb, do_populate_sysroot)
NOTE: recipe vecan-dbus-2.13-r3: task do_install: Succeeded
NOTE: Running task 2907 of 3454 (ID: 3148, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/meta-victronenergy-private/recipes-ve/vecan-dbus_2.13.bb, do_populate_sysroot)
NOTE: recipe glib-2.0-1_2.44.1-r0: task do_compile: Succeeded
NOTE: Running task 2908 of 3454 (ID: 2071, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/openembedded-core/meta/recipes-core/glib-2.0/glib-2.0_2.44.1.bb, do_install)
NOTE: recipe vecan-mk2-2.24-r6: task do_populate_sysroot: Started
NOTE: recipe vecan-dbus-2.13-r3: task do_populate_sysroot: Started
NOTE: recipe mk2vsc-1.06-r0: task do_install: Succeeded
NOTE: Running task 2909 of 3454 (ID: 2559, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/meta-victronenergy-private/recipes-ve/mk2vsc_1.06.bb, do_populate_sysroot)
NOTE: recipe glib-2.0-1_2.44.1-r0: task do_install: Started
NOTE: recipe vecan-mk2-2.24-r6: task do_populate_sysroot: Succeeded
NOTE: recipe vecan-dbus-2.13-r3: task do_populate_sysroot: Succeeded
NOTE: recipe mk2vsc-1.06-r0: task do_populate_sysroot: Started
NOTE: recipe mk2vsc-1.06-r0: task do_populate_sysroot: Succeeded
NOTE: recipe dbus-1.8.20-r0: task do_package: Succeeded
NOTE: Running task 2910 of 3454 (ID: 1251, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/openembedded-core/meta/recipes-core/dbus/dbus_1.8.20.bb, do_packagedata)
NOTE: recipe dbus-1.8.20-r0: task do_packagedata: Started
NOTE: recipe dbus-1.8.20-r0: task do_packagedata: Succeeded
NOTE: Running task 2911 of 3454 (ID: 3104, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/meta-victronenergy-private/recipes-ve/gps-dbus_1.01.bb, do_package)
NOTE: Running task 2912 of 3454 (ID: 1255, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/openembedded-core/meta/recipes-core/dbus/dbus_1.8.20.bb, do_package_write_ipk)
NOTE: Running task 2913 of 3454 (ID: 2658, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/meta-ccgx/recipes-bpp3/vecan-mk2/vecan-mk2_2.24.bb, do_package)
NOTE: Running task 2914 of 3454 (ID: 3195, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/meta-victronenergy-private/recipes-ve/vedirect-interface_3.08.bb, do_package)
NOTE: Running task 2915 of 3454 (ID: 2580, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/meta-ccgx/recipes-bpp3/vecan-mk2/mk2selftest_2.21.bb, do_package)
NOTE: Running task 2916 of 3454 (ID: 3156, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/meta-victronenergy-private/recipes-ve/vecan-dbus_2.13.bb, do_package)
NOTE: Running task 2917 of 3454 (ID: 3430, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/meta-victronenergy-private/recipes-lg/lg-resu-interface_0.06.bb, do_package)
NOTE: Running task 2918 of 3454 (ID: 3417, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/meta-victronenergy-private/recipes-oceanvolt/dbus-valence_1.9.bb, do_package)
NOTE: Running task 2919 of 3454 (ID: 3390, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/meta-victronenergy-private/recipes-oceanvolt/dbus-motordrive_1.6.bb, do_package)
NOTE: Running task 2920 of 3454 (ID: 2567, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/meta-victronenergy-private/recipes-ve/mk2vsc_1.06.bb, do_package)
NOTE: Running task 2921 of 3454 (ID: 1252, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/openembedded-core/meta/recipes-core/dbus/dbus_1.8.20.bb, do_package_qa)
NOTE: recipe gps-dbus-1.01-r1: task do_package: Started
NOTE: recipe vedirect-interface-3.08-r2: task do_package: Started
NOTE: recipe vecan-mk2-2.24-r6: task do_package: Started
NOTE: recipe dbus-1.8.20-r0: task do_package_write_ipk: Started
NOTE: recipe mk2selftest-2.21-r2: task do_package: Started
NOTE: recipe lg-resu-interface-0.06-r9: task do_package: Started
NOTE: recipe vecan-dbus-2.13-r3: task do_package: Started
NOTE: recipe dbus-valence-1.9-r2: task do_package: Started
NOTE: recipe mk2vsc-1.06-r0: task do_package: Started
NOTE: recipe dbus-motordrive-1.6-r2: task do_package: Started
NOTE: recipe dbus-1.8.20-r0: task do_package_qa: Started
NOTE: recipe dbus-1.8.20-r0: task do_package_qa: Succeeded
NOTE: recipe mk2vsc-1.06-r0: task do_package: Succeeded
NOTE: Running task 2922 of 3454 (ID: 2564, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/meta-victronenergy-private/recipes-ve/mk2vsc_1.06.bb, do_packagedata)
NOTE: recipe mk2vsc-1.06-r0: task do_packagedata: Started
NOTE: recipe vecan-dbus-2.13-r3: task do_package: Succeeded
NOTE: Running task 2923 of 3454 (ID: 3153, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/meta-victronenergy-private/recipes-ve/vecan-dbus_2.13.bb, do_packagedata)
NOTE: recipe openssh-7.1p2-r0: task do_compile: Succeeded
NOTE: recipe gps-dbus-1.01-r1: task do_package: Succeeded
NOTE: Running task 2924 of 3454 (ID: 1349, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/openembedded-core/meta/recipes-connectivity/openssh/openssh_7.1p2.bb, do_install)
NOTE: Running task 2925 of 3454 (ID: 3101, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/meta-victronenergy-private/recipes-ve/gps-dbus_1.01.bb, do_packagedata)
NOTE: recipe vecan-dbus-2.13-r3: task do_packagedata: Started
NOTE: recipe dbus-valence-1.9-r2: task do_package: Succeeded
NOTE: recipe mk2selftest-2.21-r2: task do_package: Succeeded
NOTE: Running task 2926 of 3454 (ID: 3414, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/meta-victronenergy-private/recipes-oceanvolt/dbus-valence_1.9.bb, do_packagedata)
NOTE: Running task 2927 of 3454 (ID: 2577, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/meta-ccgx/recipes-bpp3/vecan-mk2/mk2selftest_2.21.bb, do_packagedata)
NOTE: recipe gps-dbus-1.01-r1: task do_packagedata: Started
NOTE: recipe openssh-7.1p2-r0: task do_install: Started
NOTE: recipe vedirect-interface-3.08-r2: task do_package: Succeeded
NOTE: recipe mk2vsc-1.06-r0: task do_packagedata: Succeeded
NOTE: Running task 2928 of 3454 (ID: 2568, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/meta-victronenergy-private/recipes-ve/mk2vsc_1.06.bb, do_package_write_ipk)
NOTE: Running task 2929 of 3454 (ID: 3192, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/meta-victronenergy-private/recipes-ve/vedirect-interface_3.08.bb, do_packagedata)
NOTE: Running task 2930 of 3454 (ID: 2565, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/meta-victronenergy-private/recipes-ve/mk2vsc_1.06.bb, do_package_qa)
NOTE: recipe dbus-valence-1.9-r2: task do_packagedata: Started
NOTE: recipe mk2selftest-2.21-r2: task do_packagedata: Started
NOTE: recipe lg-resu-interface-0.06-r9: task do_package: Succeeded
NOTE: recipe mk2vsc-1.06-r0: task do_package_write_ipk: Started
NOTE: recipe vedirect-interface-3.08-r2: task do_packagedata: Started
NOTE: Running task 2931 of 3454 (ID: 3427, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/meta-victronenergy-private/recipes-lg/lg-resu-interface_0.06.bb, do_packagedata)
NOTE: recipe mk2vsc-1.06-r0: task do_package_qa: Started
NOTE: recipe dbus-motordrive-1.6-r2: task do_package: Succeeded
NOTE: Running task 2932 of 3454 (ID: 3387, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/meta-victronenergy-private/recipes-oceanvolt/dbus-motordrive_1.6.bb, do_packagedata)
NOTE: recipe vecan-mk2-2.24-r6: task do_package: Succeeded
NOTE: Running task 2933 of 3454 (ID: 2655, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/meta-ccgx/recipes-bpp3/vecan-mk2/vecan-mk2_2.24.bb, do_packagedata)
NOTE: recipe lg-resu-interface-0.06-r9: task do_packagedata: Started
NOTE: recipe findutils-4.5.14-r0: task do_install: Succeeded
NOTE: Running task 2934 of 3454 (ID: 1641, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/openembedded-core/meta/recipes-extended/findutils/findutils_4.5.14.bb, do_package)
NOTE: Running task 2935 of 3454 (ID: 1633, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/openembedded-core/meta/recipes-extended/findutils/findutils_4.5.14.bb, do_populate_sysroot)
NOTE: recipe dbus-motordrive-1.6-r2: task do_packagedata: Started
NOTE: recipe vecan-mk2-2.24-r6: task do_packagedata: Started
NOTE: recipe mk2vsc-1.06-r0: task do_package_qa: Succeeded
NOTE: recipe findutils-4.5.14-r0: task do_package: Started
NOTE: recipe findutils-4.5.14-r0: task do_populate_sysroot: Started
NOTE: recipe findutils-4.5.14-r0: task do_populate_sysroot: Succeeded
NOTE: recipe openssh-7.1p2-r0: task do_install: Succeeded
NOTE: Running task 2936 of 3454 (ID: 1358, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/openembedded-core/meta/recipes-connectivity/openssh/openssh_7.1p2.bb, do_package)
NOTE: Running task 2937 of 3454 (ID: 1350, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/openembedded-core/meta/recipes-connectivity/openssh/openssh_7.1p2.bb, do_populate_sysroot)
NOTE: recipe openssh-7.1p2-r0: task do_package: Started
NOTE: recipe openssh-7.1p2-r0: task do_populate_sysroot: Started
NOTE: recipe mk2selftest-2.21-r2: task do_packagedata: Succeeded
NOTE: recipe lttng-ust-2_2.6.2+gitAUTOINC+c49ee9040a-r0: task do_configure: Succeeded
NOTE: Running task 2938 of 3454 (ID: 1992, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/openembedded-core/meta/recipes-kernel/lttng/lttng-ust_git.bb, do_compile)
NOTE: recipe vecan-dbus-2.13-r3: task do_packagedata: Succeeded
NOTE: Running task 2939 of 3454 (ID: 2581, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/meta-ccgx/recipes-bpp3/vecan-mk2/mk2selftest_2.21.bb, do_package_write_ipk)
NOTE: Running task 2940 of 3454 (ID: 3157, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/meta-victronenergy-private/recipes-ve/vecan-dbus_2.13.bb, do_package_write_ipk)
NOTE: recipe vedirect-interface-3.08-r2: task do_packagedata: Succeeded
NOTE: Running task 2941 of 3454 (ID: 3154, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/meta-victronenergy-private/recipes-ve/vecan-dbus_2.13.bb, do_package_qa)
NOTE: recipe gps-dbus-1.01-r1: task do_packagedata: Succeeded
NOTE: Running task 2942 of 3454 (ID: 3196, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/meta-victronenergy-private/recipes-ve/vedirect-interface_3.08.bb, do_package_write_ipk)
NOTE: recipe vecan-mk2-2.24-r6: task do_packagedata: Succeeded
NOTE: Running task 2943 of 3454 (ID: 3105, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/meta-victronenergy-private/recipes-ve/gps-dbus_1.01.bb, do_package_write_ipk)
NOTE: Running task 2944 of 3454 (ID: 2659, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/meta-ccgx/recipes-bpp3/vecan-mk2/vecan-mk2_2.24.bb, do_package_write_ipk)
NOTE: recipe dbus-motordrive-1.6-r2: task do_packagedata: Succeeded
NOTE: Running task 2945 of 3454 (ID: 2620, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/meta-ccgx/recipes-bpp3/serial-starter/serial-starter_0.0.bb, do_package_write_ipk)
NOTE: Running task 2946 of 3454 (ID: 3391, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/meta-victronenergy-private/recipes-oceanvolt/dbus-motordrive_1.6.bb, do_package_write_ipk)
NOTE: Running task 2947 of 3454 (ID: 3388, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/meta-victronenergy-private/recipes-oceanvolt/dbus-motordrive_1.6.bb, do_package_qa)
NOTE: Running task 2948 of 3454 (ID: 3193, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/meta-victronenergy-private/recipes-ve/vedirect-interface_3.08.bb, do_package_qa)
NOTE: Running task 2949 of 3454 (ID: 3102, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/meta-victronenergy-private/recipes-ve/gps-dbus_1.01.bb, do_package_qa)
NOTE: Running task 2950 of 3454 (ID: 2656, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/meta-ccgx/recipes-bpp3/vecan-mk2/vecan-mk2_2.24.bb, do_package_qa)
NOTE: recipe lg-resu-interface-0.06-r9: task do_packagedata: Succeeded
NOTE: recipe dbus-valence-1.9-r2: task do_packagedata: Succeeded
NOTE: Running task 2951 of 3454 (ID: 3431, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/meta-victronenergy-private/recipes-lg/lg-resu-interface_0.06.bb, do_package_write_ipk)
NOTE: recipe vecan-dbus-2.13-r3: task do_package_qa: Started
NOTE: recipe lttng-ust-2_2.6.2+gitAUTOINC+c49ee9040a-r0: task do_compile: Started
NOTE: recipe vecan-dbus-2.13-r3: task do_package_write_ipk: Started
NOTE: recipe mk2selftest-2.21-r2: task do_package_write_ipk: Started
NOTE: Running task 2952 of 3454 (ID: 3418, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/meta-victronenergy-private/recipes-oceanvolt/dbus-valence_1.9.bb, do_package_write_ipk)
NOTE: recipe serial-starter-0.0-r23: task do_package_write_ipk: Started
NOTE: recipe vedirect-interface-3.08-r2: task do_package_write_ipk: Started
NOTE: recipe dbus-motordrive-1.6-r2: task do_package_write_ipk: Started
NOTE: recipe gps-dbus-1.01-r1: task do_package_write_ipk: Started
NOTE: recipe vecan-mk2-2.24-r6: task do_package_write_ipk: Started
NOTE: recipe dbus-motordrive-1.6-r2: task do_package_qa: Started
NOTE: recipe vedirect-interface-3.08-r2: task do_package_qa: Started
NOTE: recipe gps-dbus-1.01-r1: task do_package_qa: Started
NOTE: recipe vecan-mk2-2.24-r6: task do_package_qa: Started
NOTE: recipe dbus-valence-1.9-r2: task do_package_write_ipk: Started
NOTE: recipe lg-resu-interface-0.06-r9: task do_package_write_ipk: Started
NOTE: recipe openssh-7.1p2-r0: task do_populate_sysroot: Succeeded
NOTE: Running task 2953 of 3454 (ID: 3428, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/meta-victronenergy-private/recipes-lg/lg-resu-interface_0.06.bb, do_package_qa)
NOTE: recipe vecan-dbus-2.13-r3: task do_package_qa: Succeeded
NOTE: Running task 2954 of 3454 (ID: 3415, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/meta-victronenergy-private/recipes-oceanvolt/dbus-valence_1.9.bb, do_package_qa)
NOTE: recipe vedirect-interface-3.08-r2: task do_package_qa: Succeeded
NOTE: recipe vecan-mk2-2.24-r6: task do_package_qa: Succeeded
NOTE: recipe dbus-motordrive-1.6-r2: task do_package_qa: Succeeded
NOTE: recipe gps-dbus-1.01-r1: task do_package_qa: Succeeded
NOTE: Running task 2955 of 3454 (ID: 2617, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/meta-ccgx/recipes-bpp3/serial-starter/serial-starter_0.0.bb, do_package_qa)
NOTE: Running task 2956 of 3454 (ID: 2578, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/meta-ccgx/recipes-bpp3/vecan-mk2/mk2selftest_2.21.bb, do_package_qa)
NOTE: recipe lg-resu-interface-0.06-r9: task do_package_qa: Started
NOTE: recipe mk2selftest-2.21-r2: task do_package_qa: Started
NOTE: recipe dbus-valence-1.9-r2: task do_package_qa: Started
NOTE: recipe serial-starter-0.0-r23: task do_package_qa: Started
NOTE: recipe mk2vsc-1.06-r0: task do_package_write_ipk: Succeeded
NOTE: recipe lg-resu-interface-0.06-r9: task do_package_qa: Succeeded
NOTE: recipe dbus-valence-1.9-r2: task do_package_qa: Succeeded
NOTE: recipe dbus-1.8.20-r0: task do_package_write_ipk: Succeeded
NOTE: recipe serial-starter-0.0-r23: task do_package_qa: Succeeded
NOTE: recipe mk2selftest-2.21-r2: task do_package_qa: Succeeded
NOTE: recipe e2fsprogs-1.42.9-r0: task do_compile: Succeeded
NOTE: Running task 2957 of 3454 (ID: 789, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/openembedded-core/meta/recipes-devtools/e2fsprogs/e2fsprogs_1.42.9.bb, do_install)
NOTE: recipe e2fsprogs-1.42.9-r0: task do_install: Started
NOTE: recipe tiff-4.0.4-r0: task do_package: Succeeded
NOTE: Running task 2958 of 3454 (ID: 2920, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/openembedded-core/meta/recipes-multimedia/libtiff/tiff_4.0.4.bb, do_packagedata)
NOTE: recipe tiff-4.0.4-r0: task do_packagedata: Started
NOTE: recipe vedirect-interface-3.08-r2: task do_package_write_ipk: Succeeded
NOTE: recipe tiff-4.0.4-r0: task do_packagedata: Succeeded
NOTE: Running task 2959 of 3454 (ID: 2924, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/openembedded-core/meta/recipes-multimedia/libtiff/tiff_4.0.4.bb, do_package_write_ipk)
NOTE: Running task 2960 of 3454 (ID: 2921, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/openembedded-core/meta/recipes-multimedia/libtiff/tiff_4.0.4.bb, do_package_qa)
NOTE: recipe vecan-dbus-2.13-r3: task do_package_write_ipk: Succeeded
NOTE: recipe dbus-motordrive-1.6-r2: task do_package_write_ipk: Succeeded
NOTE: recipe mk2selftest-2.21-r2: task do_package_write_ipk: Succeeded
NOTE: recipe tiff-4.0.4-r0: task do_package_qa: Started
NOTE: recipe tiff-4.0.4-r0: task do_package_write_ipk: Started
NOTE: recipe dbus-valence-1.9-r2: task do_package_write_ipk: Succeeded
NOTE: recipe serial-starter-0.0-r23: task do_package_write_ipk: Succeeded
NOTE: recipe gps-dbus-1.01-r1: task do_package_write_ipk: Succeeded
NOTE: recipe vecan-mk2-2.24-r6: task do_package_write_ipk: Succeeded
NOTE: recipe lg-resu-interface-0.06-r9: task do_package_write_ipk: Succeeded
NOTE: recipe tiff-4.0.4-r0: task do_package_qa: Succeeded
NOTE: recipe findutils-4.5.14-r0: task do_package: Succeeded
NOTE: Running task 2961 of 3454 (ID: 1638, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/openembedded-core/meta/recipes-extended/findutils/findutils_4.5.14.bb, do_packagedata)
NOTE: recipe findutils-4.5.14-r0: task do_packagedata: Started
NOTE: recipe findutils-4.5.14-r0: task do_packagedata: Succeeded
NOTE: Running task 2962 of 3454 (ID: 1642, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/openembedded-core/meta/recipes-extended/findutils/findutils_4.5.14.bb, do_package_write_ipk)
NOTE: Running task 2963 of 3454 (ID: 1639, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/openembedded-core/meta/recipes-extended/findutils/findutils_4.5.14.bb, do_package_qa)
NOTE: recipe findutils-4.5.14-r0: task do_package_write_ipk: Started
NOTE: recipe findutils-4.5.14-r0: task do_package_qa: Started
NOTE: recipe findutils-4.5.14-r0: task do_package_qa: Succeeded
NOTE: recipe gnutls-3.3.17.1-r0: task do_configure: Succeeded
NOTE: Running task 2964 of 3454 (ID: 2145, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/openembedded-core/meta/recipes-support/gnutls/gnutls_3.3.17.1.bb, do_compile)
NOTE: recipe gnutls-3.3.17.1-r0: task do_compile: Started
NOTE: recipe openssh-7.1p2-r0: task do_package: Succeeded
NOTE: Running task 2965 of 3454 (ID: 1355, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/openembedded-core/meta/recipes-connectivity/openssh/openssh_7.1p2.bb, do_packagedata)
NOTE: recipe openssh-7.1p2-r0: task do_packagedata: Started
NOTE: recipe openssh-7.1p2-r0: task do_packagedata: Succeeded
NOTE: Running task 2966 of 3454 (ID: 1359, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/openembedded-core/meta/recipes-connectivity/openssh/openssh_7.1p2.bb, do_package_write_ipk)
NOTE: Running task 2967 of 3454 (ID: 3118, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/meta-ccgx/recipes-bpp3/ssh-tunnel/ssh-tunnel_0.0.bb, do_package_write_ipk)
NOTE: Running task 2968 of 3454 (ID: 256, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/openembedded-core/meta/recipes-core/packagegroups/packagegroup-core-ssh-openssh.bb, do_package_write_ipk)
NOTE: Running task 2969 of 3454 (ID: 3115, /media/ram/builds/a9bbdc1a/0/mvader/venus/sources/meta-ccgx/recipes-bpp3/ssh-tunnel/ssh-tunnel_0.0.bb, do_package_qa)

bbb: /data, /log and /conf

(moved it from the mailing to here to keep a better overview)

/data needs to be a separate partition, which it is now. good.

Which leaves /conf and /log, they need to be symlinks. Or, even better: we now decide to remove them entirely. All applications should use /data/conf/ directly. ccgx-gui run script already uses that. bbb now doesn't , please change that so it is the same. And the only other program would be localsettings, which is easy enough to change, I'll do so in a few minutes.

And lets remove /log too. Jeroen and myself are quite happy with logs in /var/log instead of /data/log. Less work any way, changing all kinds of standard scripts.. But then do symlink /var/log to /data/log/ instead of /var/volatile which it is now. Logs shouldn't get lost during an image upgrade, and neither during a reboot.

/db is not used by anythin, so just remove that symlink.

From CCGX:

root@ccgx:/# ls -al
drwxr-xr-x   20 root     root          1512 Dec  9 19:13 .
drwxr-xr-x   20 root     root          1512 Dec  9 19:13 ..
drwxr-xr-x    2 root     root           232 Dec  9 19:13 Settings
drwxr-xr-x    2 root     root          5968 Dec  4 18:45 bin
drwxr-xr-x    2 root     root           224 Dec  9 19:13 boot
lrwxrwxrwx    1 root     root            10 Dec  9 19:13 conf -> /data/conf
drwxr-xr-x    9 root     root           624 Oct 17 19:34 data
lrwxrwxrwx    1 root     root             8 Dec  9 19:13 db -> /data/db
drwxr-xr-x   11 root     root          3480 Dec 11 12:58 dev
drwxr-xr-x   36 root     root          5864 Dec  9 19:13 etc
drwxr-sr-x    4 root     root           288 Dec  1 13:44 home
drwxr-xr-x    7 root     root          4544 Dec  4 18:45 lib
lrwxrwxrwx    1 root     root             9 Dec  9 19:13 log -> /data/log
drwxr-xr-x   10 root     root           680 Dec  4 18:45 media
drwxr-xr-x    2 root     root           416 Dec  4 18:45 mnt
drwxr-xr-x    3 root     root           232 Dec  1 14:13 opt
dr-xr-xr-x  187 root     root             0 Jan  1  1970 proc
drwxr-xr-x    2 root     root          5552 Dec  4 18:45 sbin
drwxr-xr-x    2 root     root          2880 Dec 11 12:59 service
dr-xr-xr-x   12 root     root             0 Dec 11 12:58 sys
drwxrwxrwt    4 root     root          1240 Dec 11 12:58 tmp
drwxr-xr-x   11 root     root           736 Dec  1 14:17 usr
drwxr-xr-x    8 root     root           944 Dec  9 19:13 var

dummy issue to hold pictures

in case you want to add a picture to the wiki, just paste it here and grab the url and use that in the wiki

connman: revisit patches and (again) try upstream the wifi patch

Currently used version in Venus is v1.25, and we have 4 patches on it. Besides upgrading connman to a newer version, see #52, the patches need to be revisited.

At a minimum better document those patches in the meta-victronenergy repository. Even better is to get rid of some or all. Most importantly the wifi patch, that one would be best to get into upstream.

1) add hwclock when time is decoded

0001-Add-set-hwclock-when-time-is-decoded.patch

I'm not certain what it is for. Perhaps to make sure NTP time updates are also stored into the RTC? Makes me wonder if it is really necessary to do that with a patch like this. Is there no more common way to do that? The, only?, other way to change the time on a Venus device is via the gui, and that also relates to connman: gui/connman/clockmodel.cpp

2) ntp max poll set to 16

0001-ntp-max-poll-set-to-16.patch

I'm not certain what it is for. Perhaps to reduce generated network traffic?

3) gweb multi-header work around

gweb-multi-header-workaround.patch

I don't know what this is for.

4) ignore invalid key on AR failure

connman_IgnoreInvalidKey_Arfailure.patch

Fixes some issues we have with the way connman handles wifi.

This patch was made by Thiemo. Thiemo: problem with the wifi is the following:
Setting up a Wifi connection consists of a number of phases. When wpa_supplicant makes a certain state transition (G_SUPPLICANT_STATE_4WAY_HANDSHAKE -> G_SUPPLICANT_STATE_DISCONNECTED), connman draws the conclusion that the wifi password was wrong. This is a correct conclusion when Wifi reception is good, but this can also occur on marginal wifi reception. Stock connman will not try to reconnect once it thinks that the password has changed. The reason is that on some networks the account/network card is blocked after to many retries.
See this mail: http://connman.connman.narkive.com/AkqCDM7z/connman-behavior-with-marginally-stabe-wifi

This also describes the way that they want the issue to be resolved: Wpa supplicant will report a wifi network gone when it is not seen for 2 minutes. Connman performs regularly a scan for networks when it is not connected. The interval time is doubled with each scan. So when the interval gets more than 2 minutes, wpa_supplicant removes the network and when the next scan rediscovers the network, connman will try to reconnect with the stored passphrase. (at least it was like this in version 1.25... things have changed lately in this area but I don't know what the end result is). So it can take upto something like 6 minutes before connman tries to reconnect to the network (which also has a relative large chance of failing due to the marginal wifi recception).

Besides this, there is also an issue when the gui is in the Wifi menu. When it is in this menu, a Wifi scan is started every 10 seconds or so, so that the user gets fast updates on the available networks. But this also means that the network is continuously seen by wpa_supplicant which will not remove it from the list so connman will keep the error set and will not try to reconnect.

To work around these problems, the following changes are made in the patch:

It adds a plugin called "arfailure", which can reset a failure on a service after a certain amount of time.
Normally, when a service has a failure (like when connman thinks the Wifi password is wrong) it will not automatically try to reconnect to that service.
When enabled, this plugin will reset the failure after a configurable number of seconds and starts an auto connect (but only when in the past there has been a succesfull connection attempt).
It adds a setting IgnoreInvalidKey.
Normally, when a service has the CONNMAN_SERVICE_ERROR_INVALID_KEY error set, a call to service_connect will not try to connect and return with an error.
When the IgnoreInvalidKey is set, it will ignore this error and still try to connect.

I know that these patches do not apply any more with the latest connman. The plugin part might still apply but this alone is probably not enough to solve the issue.

Maybe interesting commits between 1.25 and 1.32:

2015-04-15 - service: Do not clear passphrase or identity on error - Patrik Flykt
2015-03-23 - service: Let service error indicate invalid WiFi PSK passphrase - Patrik Flykt
2014-10-10 - service: When connecting, ignore previous invalid key errors - Patrik Flykt
2014-10-10 - service: Don't save or load reason for failure - Patrik Flykt
2014-09-05 - service: Use proper function when setting service Passphrase attribute - Patrik Flykt
2014-09-05 - service: Remove __connman_service_add_passphrase() - Patrik Flykt
2014-09-05 - service: Clean up __connman_service_set_passphrase() - Patrik Flykt
2014-09-05 - service: Make a specifig function for clearing a passphrase on error - Patrik Flykt

bbb: make image so that it auto-installs to emmc

ccgx sometimes fails to reboot, when rebooting early in the startup process

below is from a test where an init scripts calls reboot, so it continuously boots. An USB device is connected. After a while it get stuck...

Rebooting... [ 26.920593] Restarting syste
U-Boot SPL 2013.01.01 (Jul 27 2016 - 08:52:55)
[ 0.000000] Booting Linux on physical CPU 0
[ 0.000000] Linux version 3.7.1 (jeroen@jeroen-VirtualBox) (gcc version 4.7.2 (GCC) ) #1 Wed Jun 29 16:44:40 CEST 2016
[ 0.000000] CPU: ARMv7 Processor [411fc087] revision 7 (ARMv7), cr=10c53c7d
[ 0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT nonaliasing instruction cache
[ 0.000000] Machine: Victron BPP3
[ 0.000000] Reserving 2097152 bytes SDRAM for VRAM
[ 0.000000] Memory policy: ECC disabled, Data cache writeback
[ 0.000000] AM3517 ES1.1 (l2cache sgx neon )
[ 0.000000] Clocking rate (Crystal/Core/MPU): 26.0/332/600 MHz
[ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 64256
[ 0.000000] Kernel command line: console=ttyO0,115200 root=ubi0:rootfs rootfstype=ubifs ubi.mtd=9 mtdparts=omap2-nand.0:512k(MLO),1m(u-boot),256k(env1),256k(env2),1m(u-boot2),256k(bootparms),768k(splash),6m(kernel),200m(data),-(rootfs) omapdss.def_disp=lcd omapfb.vram=0:2M vram=2M
[ 0.000000] PID hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.000000] Dentry cache hash table entries: 32768 (order: 5, 131072 bytes)
[ 0.000000] Inode-cache hash table entries: 16384 (order: 4, 65536 bytes)
[ 0.000000] Memory: 253MB = 253MB total
[ 0.000000] Memory: 246112k/246112k available, 16032k reserved, 0K highmem
[ 0.000000] Virtual kernel memory layout:
[ 0.000000] vector : 0xffff0000 - 0xffff1000 ( 4 kB)
[ 0.000000] fixmap : 0xfff00000 - 0xfffe0000 ( 896 kB)
[ 0.000000] vmalloc : 0xd0800000 - 0xff000000 ( 744 MB)
[ 0.000000] lowmem : 0xc0000000 - 0xd0000000 ( 256 MB)
[ 0.000000] pkmap : 0xbfe00000 - 0xc0000000 ( 2 MB)
[ 0.000000] modules : 0xbf000000 - 0xbfe00000 ( 14 MB)
[ 0.000000] .text : 0xc0008000 - 0xc04df310 (4957 kB)
[ 0.000000] .init : 0xc04e0000 - 0xc050caf8 ( 179 kB)
[ 0.000000] .data : 0xc050e000 - 0xc05513a0 ( 269 kB)
[ 0.000000] .bss : 0xc05513c4 - 0xc0a67f2c (5211 kB)
[ 0.000000] NR_IRQS:16 nr_irqs:16 16
[ 0.000000] IRQ: Found an INTC at 0xfa200000 (revision 4.0) with 96 interrupts
[ 0.000000] Total of 96 interrupts on 1 active controller
[ 0.000000] OMAP clockevent source: GPTIMER1 at 32768 Hz
[ 0.000000] sched_clock: 32 bits at 32kHz, resolution 30517ns, wraps every 131071999ms
[ 0.000000] OMAP clocksource: 32k_counter at 32768 Hz
[ 0.000000] Console: colour dummy device 80x30
[ 0.000000] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar
[ 0.000000] ... MAX_LOCKDEP_SUBCLASSES: 8
[ 0.000000] ... MAX_LOCK_DEPTH: 48
[ 0.000000] ... MAX_LOCKDEP_KEYS: 8191
[ 0.000000] ... CLASSHASH_SIZE: 4096
[ 0.000000] ... MAX_LOCKDEP_ENTRIES: 16384
[ 0.000000] ... MAX_LOCKDEP_CHAINS: 32768
[ 0.000000] ... CHAINHASH_SIZE: 16384
[ 0.000000] memory used by lock dependency info: 3695 kB
[ 0.000000] per task-struct memory footprint: 1152 bytes
[ 0.000823] Calibrating delay loop... 397.57 BogoMIPS (lpj=1554432)
[ 0.109313] pid_max: default: 32768 minimum: 301
[ 0.109771] Mount-cache hash table entries: 512
[ 0.113830] CPU: Testing write buffer coherency: ok
[ 0.114807] Setting up static identity map for 0x80396248 - 0x803962a0
[ 0.118896] devtmpfs: initialized
[ 0.180816] pinctrl core: initialized pinctrl subsystem
[ 0.186340] regulator-dummy: no parameters
[ 0.188232] NET: Registered protocol family 16
[ 0.189727] DMA: preallocated 256 KiB pool for atomic coherent allocations
[ 0.191589] omap-gpmc omap-gpmc: GPMC revision 5.0
[ 0.202087] OMAP GPIO hardware version 2.5
[ 0.218292] omap_mux_init: Add partition: #1: core, flags: 0
[ 0.241546] Found NAND on CS0
[ 0.241577] Registering NAND on CS0
[ 0.243682] _omap_mux_init_gpio: Multiple gpio paths (3) for gpio126
[ 0.245849] _omap_mux_init_gpio: Multiple gpio paths (2) for gpio99
[ 0.245880] _omap_mux_init_gpio: Multiple gpio paths (2) for gpio104
[ 0.250183] hw-breakpoint: debug architecture 0x4 unsupported.
[ 0.259887] OMAP DMA hardware revision 4.0
[ 0.264007] arm-pmu: alias fck already exists
[ 0.309509] bio: create slab at 0
[ 0.391174] omap-dma-engine omap-dma-engine: OMAP DMA engine driver
[ 0.392272] display: 1800 mV normal
[ 0.395965] SCSI subsystem initialized
[ 0.398284] usbcore: registered new interface driver usbfs
[ 0.398925] usbcore: registered new interface driver hub
[ 0.399566] usbcore: registered new device driver usb
[ 0.407867] VDCDC1: 1200 mV normal
[ 0.409271] VDCDC2: 3300 mV normal
[ 0.410339] VDCDC3: 1800 mV normal
[ 0.411315] LDO1: 1800 mV normal
[ 0.412384] LDO2: 3300 mV normal
[ 0.413909] omap_i2c omap_i2c.1: bus 1 rev1.3.12 at 400 kHz
[ 0.416107] omap_i2c omap_i2c.3: bus 3 rev1.3.12 at 400 kHz
[ 0.425567] Switching to clocksource 32k_counter
[ 0.486114] NET: Registered protocol family 2
[ 0.488128] TCP established hash table entries: 8192 (order: 4, 65536 bytes)
[ 0.488677] TCP bind hash table entries: 8192 (order: 6, 294912 bytes)
[ 0.493164] TCP: Hash tables configured (established 8192 bind 8192)
[ 0.493438] TCP: reno registered
[ 0.493469] UDP hash table entries: 256 (order: 2, 20480 bytes)
[ 0.493804] UDP-Lite hash table entries: 256 (order: 2, 20480 bytes)
[ 0.494689] NET: Registered protocol family 1
[ 0.495178] usbhs_omap usbhs_omap: xclk60mhsp1_ck set parentfailed error:-22
[ 0.499420] CPU PMU: probing PMU on CPU 0
[ 0.499572] hw perfevents: enabled with ARMv7 Cortex-A8 PMU driver, 5 counters available
[ 0.512420] msgmni has been set to 480
[ 0.517089] io scheduler noop registered
[ 0.517120] io scheduler deadline registered
[ 0.517181] io scheduler cfq registered (default)
[ 0.519653] OMAP DSS rev 2.0
[ 0.530822] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[ 0.538116] omap_uart.0: ttyO0 at MMIO 0x4806a000 (irq = 88) is a OMAP UART0
[ 1.099334] console [ttyO0] enabled
[ 1.105163] omap_uart.1: ttyO1 at MMIO 0x4806c000 (irq = 89) is a OMAP UART1
[ 1.114196] omap_uart.2: ttyO2 at MMIO 0x49020000 (irq = 90) is a OMAP UART2
[ 1.126190] brd: module loaded
[ 1.146179] loop: module loaded
[ 1.149627] at24 1-0050: 256 byte 24c02 EEPROM, writable, 1 bytes/write
[ 1.160766] mtdoops: mtd device (mtddev=name/number) must be supplied
[ 1.167572] Generic platform RAM MTD, (c) 2004 Simtec Electronics
[ 1.175262] NAND device: Manufacturer ID: 0x2c, Chip ID: 0xbc (Micron NAND 512MiB 1,8V 16-bit), page size: 2048, OOB size: 64
[ 1.187316] 10 cmdlinepart partitions found on MTD device omap2-nand.0
[ 1.194183] Creating 10 MTD partitions on "omap2-nand.0":
[ 1.199890] 0x000000000000-0x000000080000 : "MLO"
[ 1.211914] 0x000000080000-0x000000180000 : "u-boot"
[ 1.222167] 0x000000180000-0x0000001c0000 : "env1"
[ 1.231811] 0x0000001c0000-0x000000200000 : "env2"
[ 1.241210] 0x000000200000-0x000000300000 : "u-boot2"
[ 1.251586] 0x000000300000-0x000000340000 : "bootparms"
[ 1.261718] 0x000000340000-0x000000400000 : "splash"
[ 1.271728] 0x000000400000-0x000000a00000 : "kernel"
[ 1.286163] 0x000000a00000-0x00000d200000 : "data"
[ 1.466278] 0x00000d200000-0x000020000000 : "rootfs"
[ 1.734130] OneNAND driver initializing
[ 1.738922] tun: Universal TUN/TAP device driver, 1.6
[ 1.744232] tun: (C) 1999-2004 Max Krasnyansky [email protected]
[ 1.751953] CAN device driver interface
[ 1.759063] ti_hecc ti_hecc: device registered (reg_base=d0810000, irq=40)
[ 1.808624] davinci_mdio davinci_mdio.0: davinci mdio revision 1.5
[ 1.815124] davinci_mdio davinci_mdio.0: detected phy mask fffffffe
[ 1.824096] libphy: davinci_mdio.0: probed
[ 1.828491] davinci_mdio davinci_mdio.0: phy[0]: device davinci_mdio-0:00, driver unknown
[ 1.839172] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[ 1.846343] ehci-omap.0 supply hsusb0 not found, using dummy regulator
[ 1.853637] ehci-omap ehci-omap.0: OMAP-EHCI Host Controller
[ 1.862243] ehci-omap ehci-omap.0: new USB bus registered, assigned bus number 1
[ 2.902465] ehci-omap ehci-omap.0: irq 93, io mem 0x48064800
[ 2.918029] ehci-omap ehci-omap.0: USB 2.0 started, EHCI 1.00
[ 2.924865] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
[ 2.932037] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 2.939636] usb usb1: Product: OMAP-EHCI Host Controller
[ 2.945251] usb usb1: Manufacturer: Linux 3.7.1 ehci_hcd
[ 2.950866] usb usb1: SerialNumber: ehci-omap.0
[ 2.960113] hub 1-0:1.0: USB hub found
[ 2.964233] hub 1-0:1.0: 3 ports detected
[ 2.971710] usbcore: registered new interface driver usbtmc
[ 2.977630] Initializing USB Mass Storage driver...
[ 2.983428] usbcore: registered new interface driver usb-storage
[ 2.989807] USB Mass Storage support registered.
[ 2.995300] musb-hdrc: version 6.0, ?dma?, otg (peripheral+host)
[ 3.004913] 6Waiting for PHY clock good...
[ 3.028320] rtc-ds1307 3-0068: rtc core: registered ds1307 as rtc0
[ 3.034851] rtc-ds1307 3-0068: 56 bytes nvram
[ 3.040039] i2c /dev entries driver
[ 3.049072] omap_wdt: OMAP Watchdog Timer Rev 0x31: initial timeout 60 sec
[ 3.057189] cpu0 supply vcc not found, using dummy regulator
[ 3.063293] omap_cpufreq_init: physical regulator not present for MPU
[ 3.071594] cpuidle: using governor ladder
[ 3.075988] cpuidle: using governor menu
[ 3.082305] omap_hsmmc omap_hsmmc.0: Failed to get debounce clk
[ 3.088623] omap-dma-engine omap-dma-engine: allocating channel for 62
[ 3.095611] omap-dma-engine omap-dma-engine: allocating channel for 61
[ 3.151977] usbcore: registered new interface driver usbhid
[ 3.157897] usbhid: USB HID core driver
[ 3.162811] TCP: cubic registered
[ 3.166290] Initializing XFRM netlink socket
[ 3.171203] NET: Registered protocol family 10
[ 3.178436] sit: IPv6 over IPv4 tunneling driver
[ 3.187652] NET: Registered protocol family 17
[ 3.192718] NET: Registered protocol family 15
[ 3.197387] can: controller area network core (rev 20120528 abi 9)
[ 3.204101] NET: Registered protocol family 29
[ 3.208892] can: raw protocol (rev 20120528)
[ 3.213470] lib80211: common routines for IEEE802.11 drivers
[ 3.222198] Key type dns_resolver registered
[ 3.226867] VFP support v0.3: implementor 41 architecture 3 part 30 variant c rev 1
[ 3.238128] pwm: omap_dm_timer_request_specific
[ 3.243103] pwm: omap_dm_timer_set_pwm
[ 3.249542] voltdm_scale: No voltage scale API registered for vdd_mpu_iva
[ 3.256774] voltdm_scale: No voltage scale API registered for vdd_core
[ 3.279998] LDO2: incomplete constraints, leaving on
[ 3.285552] LDO1: incomplete constraints, leaving on
[ 3.293395] UBI: attaching mtd9 to ubi0
[ 3.310028] mmc0: host does not support reading read-only switch. assuming write-enable.
[ 3.324401] usb 1-1: new high-speed USB device number 2 using ehci-omap
[ 3.340087] mmc0: new high speed SDHC card at address 0007
[ 3.357208] mmcblk0: mmc0:0007 SD8GB 7.42 GiB
[ 3.367828] mmcblk0: p1
[ 3.482574] usb 1-1: New USB device found, idVendor=0424, idProduct=2412
[ 3.489685] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[ 3.527923] hub 1-1:1.0: USB hub found
[ 3.532104] hub 1-1:1.0: 2 ports detected
[ 3.832702] usb 1-1.1: new full-speed USB device number 3 using ehci-omap
[ 3.968780] usb 1-1.1: New USB device found, idVendor=067b, idProduct=2303
[ 3.976074] usb 1-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[ 3.983825] usb 1-1.1: Product: USB-Serial Controller
[ 3.989166] usb 1-1.1: Manufacturer: Prolific Technology Inc.
[ 4.154632] UBI: scanning is finished
[ 4.178009] UBI: attached mtd9 (name "rootfs", size 302 MiB) to ubi0
[ 4.184783] UBI: PEB size: 131072 bytes (128 KiB), LEB size: 129024 bytes
[ 4.191925] UBI: min./max. I/O unit sizes: 2048/2048, sub-page size 512
[ 4.198852] UBI: VID header offset: 512 (aligned 512), data offset: 2048
[ 4.205932] UBI: good PEBs: 2416, bad PEBs: 0, corrupted PEBs: 0
[ 4.212249] UBI: user volume: 1, internal volumes: 1, max. volumes count: 128
[ 4.219757] UBI: max/mean erase counter: 4/1, WL threshold: 4096, image sequence number: 1998749053
[ 4.229278] UBI: available PEBs: 0, total reserved PEBs: 2416, PEBs reserved for bad PEB handling: 80
[ 4.240905] UBI: background thread "ubi_bgt0d" started, PID 651
[ 4.252624] input: gpio-keys as /devices/platform/gpio-keys/input/input0
[ 4.264221] rtc-ds1307 3-0068: setting system clock to 2016-07-29 10:55:35 UTC (1469789735)
[ 4.298156] UBIFS: background thread "ubifs_bgt0_0" started, PID 659
[ 4.364532] UBIFS: mounted UBI device 0, volume 0, name "rootfs"(null)
[ 4.371490] UBIFS: LEB size: 129024 bytes (126 KiB), min./max. I/O unit sizes: 2048 bytes/2048 bytes
[ 4.381103] UBIFS: FS size: 299464704 bytes (285 MiB, 2321 LEBs), journal size 9033728 bytes (8 MiB, 71 LEBs)
[ 4.391540] UBIFS: reserved for root: 0 bytes (0 KiB)
[ 4.396881] UBIFS: media format: w4/r0 (latest is w4/r0), UUID 2F4D3F3E-9359-43BA-B30D-8C33F81C7D7A, small LPT model
[ 4.409912] VFS: Mounted root (ubifs filesystem) on device 0:11.
[ 4.416870] Freeing init memory: 176K
INIT: version 2.88 booting
Starting udev
[ 8.861938] usbcore: registered new interface driver usbserial
[ 8.868347] usbcore: registered new interface driver usbserial_generic
[ 8.876007] usbserial: USB Serial support registered for generic
[ 9.671722] usbcore: registered new interface driver pl2303
[ 9.678009] usbserial: USB Serial support registered for pl2303
[ 9.684539] pl2303 1-1.1:1.0: pl2303 converter detected
[ 9.829467] usb 1-1.1: pl2303 converter now attached to ttyUSB0
Starting Bootlog daemon: bootlogd.
Mounting local filesystems...
Remounting rw...
Attaching data partition...
[ 11.384796] UBI: attaching mtd8 to ubi1
[ 11.879852] UBI: scanning is finished
[ 11.905792] UBI: attached mtd8 (name "data", size 200 MiB) to ubi1
[ 11.912414] UBI: PEB size: 131072 bytes (128 KiB), LEB size: 129024 bytes
[ 11.919555] UBI: min./max. I/O unit sizes: 2048/2048, sub-page size 512
[ 11.926513] UBI: VID header offset: 512 (aligned 512), data offset: 2048
[ 11.933563] UBI: good PEBs: 1600, bad PEBs: 0, corrupted PEBs: 0
[ 11.939849] UBI: user volume: 1, internal volumes: 1, max. volumes count: 128
[ 11.947387] UBI: max/mean erase counter: 7/3, WL threshold: 4096, image sequence number: 850092237
[ 11.956787] UBI: available PEBs: 0, total reserved PEBs: 1600, PEBs reserved for bad PEB handling: 80
[ 11.966522] UBI: background thread "ubi_bgt1d" started, PID 1066
UBI device number 1, total 1600 LEBs (206438400 bytes, 196.9 MiB), available 0 LEBs (0 bytes), LEB size 129024 bytes (126.0 KiB)
[ 12.015930] UBIFS: background thread "ubifs_bgt1_0" started, PID 1068
[ 12.074554] UBIFS: mounted UBI device 1, volume 0, name "data"(null)
[ 12.081298] UBIFS: LEB size: 129024 bytes (126 KiB), min./max. I/O unit sizes: 2048 bytes/2048 bytes
[ 12.090911] UBIFS: FS size: 194052096 bytes (185 MiB, 1504 LEBs), journal size 9676800 bytes (9 MiB, 75 LEBs)
[ 12.101318] UBIFS: reserved for root: 4952683 bytes (4836 KiB)
[ 12.107482] UBIFS: media format: w4/r0 (latest is w4/r0), UUID C2CA3B9D-D9ED-4148-A5E0-781B6EA72C84, small LPT model
Set alignment to 3
kernel.print-fatal-signals = 1
Starting watchdog
INIT: Entering runlevel: 5
Starting system message bus: Setting up watches.
Watches established.
/var/run/dbus/ CREATE system_bus_socket
Starting syslogd/klogd: done
Setting hostname
Starting php-fpm done
Configuring network interfaces... [ 14.034057] davinci_mdio davinci_mdio.0: resetting idled controller
[ 14.041381] net eth0: attached PHY driver [Generic PHY](mii_bus:phy_addr=davinci_mdio-0:00, id=7c0f1)
[ 14.051391] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[ 14.224273] turning CAN driver on
[ 14.227905] ti_hecc ti_hecc can0: setting CANBTC=0x30049
done.
Starting Connection Manager
Starting Hiawatha Web Server: hiawatha.
Starting crond: OK
****** Prepare & Starting Application meta-bpp3 ******
dbus[1090]: [system] Activating service name='fi.w1.wpa_supplicant1' (using servicehelper)
dbus[1090]: [system] Successfully activated service 'fi.w1.wpa_supplicant1'
[ 16.099090] libphy: davinci_mdio-0:00 - Link is Up - 100/Full
[ 16.105224] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
INIT: Switching to runlevel: 6
INIT: Sending processes the TERM signal
[ 18.956970] [sched_delayed] sched: RT throttling activated

test Raspbian and Debian packages

Quite a while ago @jhofstee has made it possible to create packages out of Venus which can be installed on Raspbian and Debian.

See this email on the dev-venus mailinglist for an introduction.

Current status of this is that we can easily build them, but don't have time to test them and therefore nobody uses them.

A waste, as there are many people that I know would like to run these drivers. Especially as it is quite easy to have the data available not only on d-bus but also on mqtt. See this repo for the mqtt work:
https://github.com/victronenergy/dbus-mqtt

official feeds are here:
http://updates.victronenergy.com/feeds/debian/
http://updates.victronenergy.com/feeds/raspbian/

a feed containing more recent build is here:
https://build-feeds.victronenergy.com/mvader/venus.git/WIP-bbb4/118ee417/

Besides testing it, part of this issue is also to make documentation. So people don't have to go through the mailing list to get started. Preferably on a page in the venus wiki

boot type is always 0

Seeing watchdog reboots is valuable for quality purposes.

For the CCGX, we have a mechanism to read the boot type, using IOCTL with WDIOC_GETBOOTSTATUS:
https://git.victronenergy.com/ccgx/meta-ccgx/blob/master/recipes-extended/watchdog/files/get_boot_type.c

Output (/tmp/last_boot_type) is then read by vrmlogger.py. Which then overwrittes it with a result code, and sends it to vrmportal.

On bbb the contents of /tmp/last_boot_type is 0, both on normal power up and after a reboot.

Definition of the numbers written to /tmp/last_boot_type are:
0=Old kernel, no boottype support;
1=Normal boot;
17=Watchdog reboot;
-1=Reading watchdog register failed (generated by get_boot_type);
-2=Reading tmp file failed (generated by vrmlogger.py)
-3=Reading tmp file succeeded (generated by vrmlogger.py, and used to make sure that boottype is only sent to the portal once)

gui: d-bus errors during startup

Errors on the bbb during startup. Not further looked into it yet.

root@beaglebone:~# cat /var/log/gui/current | tai64nlocal
2016-05-26 18:16:23.711451500 *** Starting gui ***
2016-05-26 18:16:23.711460500 *** headless device=1
2016-05-26 18:16:31.409428500 *** Localsettings is up, continuing...
2016-05-26 18:16:31.504323500 method return sender=:1.14 -> dest=:1.17 reply_serial=2
2016-05-26 18:16:31.504334500    int32 0
2016-05-26 18:16:31.610194500 *** Starting gui, with VNC enabled (VncLocal=1 VncInternet=0)
2016-05-26 18:16:32.355827500 QVNCServer created on port 5900
2016-05-26 18:16:32.418710500 Cannot open keyboard input device '/dev/input/by-path/platform-gpio-keys-event': No such file or directory
2016-05-26 18:16:32.475155500 GUI v2.8.4 started
2016-05-26 18:16:32.475166500 Built with Qt 4.8.7 running on 4.8.7
2016-05-26 18:16:32.685841500 LocalThemeDaemonClient: Looking for assets in "/opt/color-control/themes/ccgx"
2016-05-26 18:16:32.703881500 Connecting to deprecated signal QDBusConnectionInterface::serviceOwnerChanged(QString,QString,QString)
2016-05-26 18:16:32.743833500 TRACE [Qwacs::qwacsRegisterd]  "com.victronenergy.qwacs"
2016-05-26 18:16:33.465020500 INFO  CCGX version "v1.40" build on  "20160526173423"
2016-05-26 18:16:33.465032500 INFO  GUI built on "May 25 2016" at "13:22:23"
2016-05-26 18:16:33.489336500 TRACE [Service] marking "/service/dbus-motordrive" as a conflict of "/service/lg-resu-interface"
2016-05-26 18:16:33.489349500 TRACE [Service] marking "/service/lg-resu-interface" as a conflict of "/service/dbus-motordrive"
2016-05-26 18:16:33.489354500 TRACE [Service] marking "/service/dbus-valence" as a conflict of "/service/lg-resu-interface"
2016-05-26 18:16:33.489360500 TRACE [Service] marking "/service/lg-resu-interface" as a conflict of "/service/dbus-valence"
2016-05-26 18:16:33.489365500 TRACE [Service] marking "/service/vecan-dbus" as a conflict of "/service/lg-resu-interface"
2016-05-26 18:16:33.489503500 TRACE [Service] marking "/service/lg-resu-interface" as a conflict of "/service/vecan-dbus"
2016-05-26 18:16:33.574395500 INFO  [CmManager] Add technology "/net/connman/technology/ethernet"
2016-05-26 18:16:33.574407500 INFO  [CmManager] Add technology "/net/connman/technology/wifi"
2016-05-26 18:16:33.606140500 TRACE ColorControl::setDemoMode stop
2016-05-26 18:16:36.155872500 QDBusConnection: name 'com.victronenergy.system' had owner '' but we thought it was ':1.20'
2016-05-26 18:16:36.157883500 TRACE ColorControl::cmdlineFinished
2016-05-26 18:16:36.161452500 TRACE ColorControl::dbusDisplayOffTimeChanged:  600
2016-05-26 18:16:36.171944500 TRACE ColorControl::dbusReleaseTypeChanged:  0
2016-05-26 18:16:36.171955500 TRACE TimeZone::valueChanged:  "/UTC"
2016-05-26 18:16:36.171959500 TRACE ColorControl::dbusAccessLevelChanged:  1
2016-05-26 18:16:36.171963500 TRACE ColorControl::setAccessLevel:  1
2016-05-26 18:16:36.171966500 INFO  [Service] DBus service "/service/ssh-tunnel" changed: "com.victronenergy.settings/Settings/System/RemoteSupport" - "0"
2016-05-26 18:16:36.178899500 INFO  [Service] DBus service "/service/ssh-tunnel" changed: "com.victronenergy.settings/Settings/System/VncInternet" - ""
2016-05-26 18:16:36.178912500 INFO  [Service] DBus service "/service/sshd" changed: "com.victronenergy.settings/Settings/System/RemoteSupport" - "0"
2016-05-26 18:16:36.189358500 INFO  [Service] DBus service "/service/dbus-generator-starter" changed: "com.victronenergy.settings/Settings/Relay/Function" - "0"
2016-05-26 18:16:36.189371500 TRACE [BusItemCons::PropertiesChanged]
2016-05-26 18:16:36.189375500 TRACE Item:  "Text" : ""
2016-05-26 18:16:36.189378500 TRACE Item:  "Value" : ""
2016-05-26 18:16:36.189381500 TRACE [BusItemCons::PropertiesChanged]
2016-05-26 18:16:36.189385500 TRACE Item:  "Text" : ""
2016-05-26 18:16:36.189388500 TRACE Item:  "Value" : ""
2016-05-26 18:16:36.189391500 TRACE [BusItemCons::PropertiesChanged]
2016-05-26 18:16:36.189527500 TRACE Item:  "Text" : ""
2016-05-26 18:16:36.189531500 TRACE Item:  "Value" : ""
2016-05-26 18:16:36.189534500 TRACE [BusItemCons::PropertiesChanged]
2016-05-26 18:16:36.189538500 TRACE Item:  "Text" : ""
2016-05-26 18:16:36.189541500 TRACE Item:  "Value" : ""
2016-05-26 18:16:36.189544500 TRACE [BusItemCons::PropertiesChanged]
2016-05-26 18:16:36.189548500 TRACE Item:  "Text" : ""
2016-05-26 18:16:36.189550500 TRACE Item:  "Value" : ""
2016-05-26 18:16:36.189676500 TRACE [BusItemCons::PropertiesChanged]
2016-05-26 18:16:36.189680500 TRACE Item:  "Text" : ""
2016-05-26 18:16:36.189683500 TRACE Item:  "Value" : ""
2016-05-26 18:16:36.199356500 TRACE [BusItemCons::PropertiesChanged]
2016-05-26 18:16:36.199367500 TRACE Item:  "Text" : ""
2016-05-26 18:16:36.199370500 TRACE Item:  "Value" : ""
2016-05-26 18:16:36.199373500 TRACE [BusItemCons::PropertiesChanged]
2016-05-26 18:16:36.199377500 TRACE Item:  "Text" : ""
2016-05-26 18:16:36.199380500 TRACE Item:  "Value" : ""
2016-05-26 18:16:36.199383500 TRACE [BusItemCons::PropertiesChanged]
2016-05-26 18:16:36.199386500 TRACE Item:  "Text" : ""
2016-05-26 18:16:36.199389500 TRACE Item:  "Value" : ""
2016-05-26 18:16:36.199519500 INFO  [Service] DBus service "/service/dbus-modbustcp" changed: "com.victronenergy.settings/Settings/Services/Modbus" - "0"
2016-05-26 18:16:36.199528500 INFO  [Service] DBus service "/service/vegetty" changed: "com.victronenergy.settings/Settings/Services/Console" - "0"
2016-05-26 18:16:36.199534500 INFO  [Service] DBus service "/service/lg-resu-interface" changed: "com.victronenergy.settings/Settings/Services/LgResu" - "0"
2016-05-26 18:16:36.199540500 INFO  [Service] DBus service "/service/vrmpubnub" changed: "com.victronenergy.settings/Settings/Services/Vrmpubnub" - "0"
2016-05-26 18:16:36.199614500 INFO  [Service] DBus service "/service/dbus-motordrive" changed: "com.victronenergy.settings/Settings/Services/OceanvoltMotorDrive" - "0"
2016-05-26 18:16:36.199621500 INFO  [Service] DBus service "/service/dbus-valence" changed: "com.victronenergy.settings/Settings/Services/OceanvoltValence" - "0"
2016-05-26 18:16:36.199687500 INFO  [Service] DBus service "/service/vecan-dbus" changed: "com.victronenergy.settings/Settings/Services/VeCan" - "1"
2016-05-26 18:16:36.199693500 INFO  [Service] DBus service "/service/ssh-tunnel" changed: "com.victronenergy.settings/Settings/System/RemoteSupport" - "0"
2016-05-26 18:16:36.199698500 INFO  [Service] DBus service "/service/ssh-tunnel" changed: "com.victronenergy.settings/Settings/System/VncInternet" - "0"
2016-05-26 18:16:36.204694500 INFO  [Service] DBus service "/service/websockify-c" changed: "com.victronenergy.settings/Settings/System/VncLocal" - "1"
2016-05-26 18:16:36.204703500 INFO  [Service] Start "/service/websockify-c"
2016-05-26 18:16:36.272274500 TRACE serviceOwnerChanged "com.victronenergy.system" "" ":1.20"
2016-05-26 18:16:36.289373500 unknown service com.victronenergy.system
2016-05-26 18:16:36.290822500 Error value: QDBusError("org.freedesktop.DBus.Error.LimitsExceeded", "The maximum number of pending replies per connection has been reached") "com.victronenergy.system"
2016-05-26 18:16:36.331274500 Error value: QDBusError("org.freedesktop.DBus.Error.LimitsExceeded", "The maximum number of pending replies per connection has been reached") "com.victronenergy.system"
2016-05-26 18:16:36.332977500 Error value: QDBusError("org.freedesktop.DBus.Error.LimitsExceeded", "The maximum number of pending replies per connection has been reached") "com.victronenergy.system"
2016-05-26 18:16:36.334540500 Error value: QDBusError("org.freedesktop.DBus.Error.LimitsExceeded", "The maximum number of pending replies per connection has been reached") "com.victronenergy.system"
2016-05-26 18:16:36.337684500 Error value: QDBusError("org.freedesktop.DBus.Error.LimitsExceeded", "The maximum number of pending replies per connection has been reached") "com.victronenergy.system"
2016-05-26 18:16:36.339488500 Error value: QDBusError("org.freedesktop.DBus.Error.LimitsExceeded", "The maximum number of pending replies per connection has been reached") "com.victronenergy.system"
2016-05-26 18:16:36.351100500 Error value: QDBusError("org.freedesktop.DBus.Error.LimitsExceeded", "The maximum number of pending replies per connection has been reached") "com.victronenergy.system"
2016-05-26 18:16:36.353114500 Error value: QDBusError("org.freedesktop.DBus.Error.LimitsExceeded", "The maximum number of pending replies per connection has been reached") "com.victronenergy.system"
2016-05-26 18:16:36.359992500 Error value: QDBusError("org.freedesktop.DBus.Error.LimitsExceeded", "The maximum number of pending replies per connection has been reached") "com.victronenergy.system"
2016-05-26 18:16:36.361815500 Error value: QDBusError("org.freedesktop.DBus.Error.LimitsExceeded", "The maximum number of pending replies per connection has been reached") "com.victronenergy.system"
2016-05-26 18:16:36.363370500 Error value: QDBusError("org.freedesktop.DBus.Error.LimitsExceeded", "The maximum number of pending replies per connection has been reached") "com.victronenergy.system"
2016-05-26 18:16:36.366256500 Error value: QDBusError("org.freedesktop.DBus.Error.LimitsExceeded", "The maximum number of pending replies per connection has been reached") "com.victronenergy.system"
2016-05-26 18:16:36.370932500 Error value: QDBusError("org.freedesktop.DBus.Error.LimitsExceeded", "The maximum number of pending replies per connection has been reached") "com.victronenergy.system"
2016-05-26 18:16:36.373738500 Error value: QDBusError("org.freedesktop.DBus.Error.LimitsExceeded", "The maximum number of pending replies per connection has been reached") "com.victronenergy.system"
2016-05-26 18:16:36.381114500 Error value: QDBusError("org.freedesktop.DBus.Error.LimitsExceeded", "The maximum number of pending replies per connection has been reached") "com.victronenergy.system"
2016-05-26 18:16:36.388008500 Error value: QDBusError("org.freedesktop.DBus.Error.LimitsExceeded", "The maximum number of pending replies per connection has been reached") "com.victronenergy.system"
2016-05-26 18:16:36.391024500 Error value: QDBusError("org.freedesktop.DBus.Error.LimitsExceeded", "The maximum number of pending replies per connection has been reached") "com.victronenergy.system"
2016-05-26 18:16:36.392643500 Error value: QDBusError("org.freedesktop.DBus.Error.LimitsExceeded", "The maximum number of pending replies per connection has been reached") "com.victronenergy.system"
2016-05-26 18:16:36.397087500 Error value: QDBusError("org.freedesktop.DBus.Error.LimitsExceeded", "The maximum number of pending replies per connection has been reached") "com.victronenergy.system"
2016-05-26 18:16:36.401776500 Error value: QDBusError("org.freedesktop.DBus.Error.LimitsExceeded", "The maximum number of pending replies per connection has been reached") "com.victronenergy.system"
2016-05-26 18:16:36.406656500 Error value: QDBusError("org.freedesktop.DBus.Error.LimitsExceeded", "The maximum number of pending replies per connection has been reached") "com.victronenergy.system"
2016-05-26 18:16:36.408386500 Error value: QDBusError("org.freedesktop.DBus.Error.LimitsExceeded", "The maximum number of pending replies per connection has been reached") "com.victronenergy.system"
2016-05-26 18:16:36.410716500 Error value: QDBusError("org.freedesktop.DBus.Error.LimitsExceeded", "The maximum number of pending replies per connection has been reached") "com.victronenergy.system"
2016-05-26 18:16:36.431338500 Error value: QDBusError("org.freedesktop.DBus.Error.LimitsExceeded", "The maximum number of pending replies per connection has been reached") "com.victronenergy.system"
2016-05-26 18:16:36.433085500 Error value: QDBusError("org.freedesktop.DBus.Error.LimitsExceeded", "The maximum number of pending replies per connection has been reached") "com.victronenergy.system"
2016-05-26 18:16:36.441184500 Error value: QDBusError("org.freedesktop.DBus.Error.LimitsExceeded", "The maximum number of pending replies per connection has been reached") "com.victronenergy.system"
2016-05-26 18:16:36.442849500 Error value: QDBusError("org.freedesktop.DBus.Error.LimitsExceeded", "The maximum number of pending replies per connection has been reached") "com.victronenergy.system"
2016-05-26 18:16:36.444391500 Error value: QDBusError("org.freedesktop.DBus.Error.LimitsExceeded", "The maximum number of pending replies per connection has been reached") "com.victronenergy.system"
2016-05-26 18:16:36.450039500 Error value: QDBusError("org.freedesktop.DBus.Error.LimitsExceeded", "The maximum number of pending replies per connection has been reached") "com.victronenergy.system"
2016-05-26 18:16:36.451691500 Error value: QDBusError("org.freedesktop.DBus.Error.LimitsExceeded", "The maximum number of pending replies per connection has been reached") "com.victronenergy.system"
2016-05-26 18:16:36.453538500 Error value: QDBusError("org.freedesktop.DBus.Error.LimitsExceeded", "The maximum number of pending replies per connection has been reached") "com.victronenergy.system"
2016-05-26 18:16:36.455112500 Error value: QDBusError("org.freedesktop.DBus.Error.LimitsExceeded", "The maximum number of pending replies per connection has been reached") "com.victronenergy.system"
2016-05-26 18:16:36.456598500 Error value: QDBusError("org.freedesktop.DBus.Error.LimitsExceeded", "The maximum number of pending replies per connection has been reached") "com.victronenergy.system"
2016-05-26 18:16:36.461294500 Error value: QDBusError("org.freedesktop.DBus.Error.LimitsExceeded", "The maximum number of pending replies per connection has been reached") "com.victronenergy.system"
2016-05-26 18:16:36.462935500 Error value: QDBusError("org.freedesktop.DBus.Error.LimitsExceeded", "The maximum number of pending replies per connection has been reached") "com.victronenergy.system"
2016-05-26 18:16:36.464523500 Error value: QDBusError("org.freedesktop.DBus.Error.LimitsExceeded", "The maximum number of pending replies per connection has been reached") "com.victronenergy.system"
2016-05-26 18:16:36.466609500 Error value: QDBusError("org.freedesktop.DBus.Error.LimitsExceeded", "The maximum number of pending replies per connection has been reached") "com.victronenergy.system"
2016-05-26 18:16:36.468681500 Error value: QDBusError("org.freedesktop.DBus.Error.LimitsExceeded", "The maximum number of pending replies per connection has been reached") "com.victronenergy.system"
2016-05-26 18:16:36.470642500 Error value: QDBusError("org.freedesktop.DBus.Error.LimitsExceeded", "The maximum number of pending replies per connection has been reached") "com.victronenergy.system"
2016-05-26 18:16:36.581969500 TRACE serviceOwnerChanged "com.victronenergy.logger" "" ":1.21"
2016-05-26 18:16:38.801888500 TRACE serviceOwnerChanged "com.victronenergy.fronius" "" ":1.22"
2016-05-26 18:16:43.531894500 TRACE Updater::runCheckUpdateScript:
2016-05-26 18:16:50.339613500 TRACE ColorControl::opkgUpdated:  "/tmp/opkg-updates"
2016-05-26 18:16:50.339625500 TRACE ColorControl::opkgUpdated: numOfUpdates =  0
2016-05-26 18:16:53.738724500 TRACE ColorControl::opkgUpdated:  "/tmp/opkg-updates"
2016-05-26 18:16:53.738731500 TRACE ColorControl::opkgUpdated: numOfUpdates =  0
2016-05-26 18:16:53.738735500 TRACE Updater::opkgCheckingForUpdatesFinished:
root@beaglebone:~#

verify ADC inputs on ve-cape and add to venus bbb image

There are 7 analogue inputs on the cape. Details document on the bbb adc page in venus/wiki.

Objectives of this issue are:

Hardware on the ve-cape needs to be verified.
bbb kernel/image need to be configured such that they work
commandline config and readout documented on the bbb adc page in venus/wiki.

Later we'll develop proper executables (can be C code, python, etc.) that reads those inputs and publishes the tanklevels on the D-Bus according to Victron D-Bus spec.

wpa_supplicant fails to start 2

The error:

May 29 19:59:17 beaglebone daemon.notice dbus[902]: [system] Activated service 'fi.w1.wpa_supplicant1' failed: The permission of the setuid helper is not correct

Seems that somehow xuser has entered the scene in the latest builds (WIP-bbb4):

root@beaglebone:/usr# ls -al ./lib/dbus/
drwxr-xr-x    2 xuser    xuser         1024 May 29 19:59 .
drwxr-xr-x   15 root     root          4096 May 29 19:59 ..
-rwsr-xr-x    1 xuser    xuser       236948 May 29 09:05 dbus-daemon-launch-helper

Old image, which worked:

root@beaglebone:~# ls -al /usr/lib/dbus/
drwxr-xr-x    2 root     root          1024 May 26 17:37 .
drwxr-xr-x   15 root     root          4096 May 26 17:37 ..
-rwsr-xr-x    1 root     messageb    236948 May 20 23:04 dbus-daemon-launch-helper

vrmlogger pulling cpu to 100% when no internet

vrmlogger is keeping the bbb really busy, because the http calls fail immediately, and there is no delay: it will retry immediately.

This is probably also happening on the CCGX, since I can't see any sleep or delay in the code (http_endpoint.py).

I found this on a bbb with only the console connected. No ethernet/internet.

I'll have a look at it myself, since I am the one that wrote http_endpoint.py... Adding a one second delay in case of result==False already reduces vrmlogger cpu usage to 0%.

# cat /log/vrmlogger/current | tai64nlocal
2015-12-12 15:14:28.594017500 HTTPThread-http_endpoint: result: False, id: 11
2015-12-12 15:14:28.612410500 HTTPThread-vrmhttp: ('Connection aborted.', gaierror(-3, 'Temporary failure in name resolution'))
2015-12-12 15:14:28.613819500 HTTPThread-http_endpoint: result: False, id: 11
2015-12-12 15:14:28.632399500 HTTPThread-vrmhttp: ('Connection aborted.', gaierror(-3, 'Temporary failure in name resolution'))
2015-12-12 15:14:28.633813500 HTTPThread-http_endpoint: result: False, id: 11
2015-12-12 15:14:28.651970500 HTTPThread-vrmhttp: ('Connection aborted.', gaierror(-3, 'Temporary failure in name resolution'))
2015-12-12 15:14:28.653262500 HTTPThread-http_endpoint: result: False, id: 11
2015-12-12 15:14:28.671746500 HTTPThread-vrmhttp: ('Connection aborted.', gaierror(-3, 'Temporary failure in name resolution'))
2015-12-12 15:14:28.673033500 HTTPThread-http_endpoint: result: False, id: 11
2015-12-12 15:14:28.691228500 HTTPThread-vrmhttp: ('Connection aborted.', gaierror(-3, 'Temporary failure in name resolution'))
2015-12-12 15:14:28.692518500 HTTPThread-http_endpoint: result: False, id: 11
2015-12-12 15:14:28.710999500 HTTPThread-vrmhttp: ('Connection aborted.', gaierror(-3, 'Temporary failure in name resolution'))
2015-12-12 15:14:28.712272500 HTTPThread-http_endpoint: result: False, id: 11

root@venus:~# top
Mem: 139508K used, 364852K free, 832K shrd, 11708K buff, 63072K cached
CPU:  73% usr  24% sys   0% nic   0% idle   0% io   0% irq   1% sirq
Load average: 1.47 1.18 0.67 2/150 1066
  PID  PPID USER     STAT   VSZ %VSZ %CPU COMMAND
 1045   880 root     S    25740   5%  85% {vrmlogger.py} /usr/bin/python -u /opt
  827     1 root     S     6708   1%  11% /usr/sbin/connmand
  899   881 root     S     1720   0%   2% multilog t s99999 n8 /var/log/vrmlogge
  874   854 root     S     3192   1%   0% {vecan-dbus} /opt/victronenergy/vecan-
 1066   970 root     R     2964   1%   0% top

umount fails when taking sdcard out without ejecting from gui

Reproducable with an sdcard not containing any swu or other files that make vrmlogger ignore it.

1st insert OK
1st removal fails to unmount
2nd insert fails
2nd removal OK to unmount
3rd insert OK
3rd removal fail
etc

I expect the reason for the first unmount to fail is that vrmlogger still has the file open. The umount fails because of that. But still vrmlogger will receive the dbus-signal from mount.sh that it is time to unmount. So, a few seconds later the device is no longer busy, and it would be possible to umount. Doesn't happen though: udev doesn't try again a few seconds later. Also there is no -l (Lazy unmount) flag used. Yet (?).

Then next insert fails or doesn't happen, because the device with that name is still mounted.

The next umount succeeds: vrmlogger does not have a file open.

etc.

One solution would be to use the -l flag:

-l Lazy unmount. Detach the filesystem from the filesystem hierarchy now, and cleanup all references to the filesystem as soon as it is not busy anymore. This option allows a "busy" filesystem to be unmounted. (Requires kernel 2.4.11 or later.)

It would be applied here:

diff --git a/recipes-bpp3/recipes-core/udev/files/mount.sh b/recipes-bpp3/recipes-core/udev/files/mount.sh
index 5c4bfa2..2a6dd54 100644
--- a/recipes-bpp3/recipes-core/udev/files/mount.sh
+++ b/recipes-bpp3/recipes-core/udev/files/mount.sh
@@ -59,7 +59,7 @@ fi
 if [ "$ACTION" = "remove" ] && [ -x "$UMOUNT" ] && [ -n "$DEVNAME" ]; then
    for mnt in `cat /proc/mounts | grep "$DEVNAME" | cut -f 2 -d " " `
    do
-       $UMOUNT $mnt
+       $UMOUNT -l $mnt
    done

    # Remove empty directories from auto-mounter

Which actually has been discussed on the openembedded-devel mailinglist. But somehow that was never applied.