Turris OS 3.11.3 is released!

release

#1

Dear Turris users,

We have just released a minor release of Turris OS 3.11.3 to everyone! It mostly contains security updates, some minor updates and also some bug fixes.

Release notes:

• Foris: unification of themes across all Turris products
• foris-controller-openvpn-module: compression logic updates (Gitlab issue)
• turris-netboot: initial support for booting Turris MOX over network
• openssh, keepalived, tar, dovecot, curl, prosody, openssh, openssl, bind, tor: security updates
• tvheadend, youtube-dl, wireguard, syslog-ng, unbound, netdata and various minor packages: update
• lua-cqueues: add a new package
• kernel: update
• logread: fix
• foris-pakon: fix for Safari

• fixed issue for Turris 1.x about smaller RAM than it should be (forum link)

Enjoy and if you encounter any issues, let us know.


Turris OS 3.11.3 in RC
#4

For me the update is hanging in updater for more than two hours. The pkgupdate process is fully using one CPU core since then.

Last entries in the /usr/share/updater/updater-log:

2019-03-26 19:05        TRANSACTION START
2019-03-26 19:05        install 1.0.2r-1        libopenssl
2019-03-26 19:05        install 1.0.2r-1        openssl-util
2019-03-26 19:05        install 7.64.0-1        libcurl
2019-03-26 19:05        install 61.1.5.2-3.6-1  updater-ng

Strace doesn’t reveal anything useful:

clock_gettime(CLOCK_MONOTONIC, {2607994, 250078115}) = 0
clock_gettime(CLOCK_REALTIME, {1553632215, 50635761}) = 0
poll([{fd=6, events=POLLIN}, {fd=17, events=POLLIN}, {fd=12, events=POLLIN}], 3, -1) = 2 ([{fd=17, revents=POLLNVAL}, {fd=12, revents=POLLNVAL}])

Any suggestions what might be wrong? (Obviously I can kill the pkgupdate and try restarting it, but this might lose some useful things to debug this…)

PS: Files in /tmp/update-state/:

# cat state 
planning
# cat log2 
D libcurl 7.64.0-1
D updater-ng 61.1.5.2-3.6-1
D openssl-util 1.0.2r-1
D libopenssl 1.0.2r-1
I libopenssl 1.0.2r-1
I openssl-util 1.0.2r-1
I libcurl 7.64.0-1
I updater-ng 61.1.5.2-3.6-1

#5

foris-controller-openvpn-module: compression logic updates (Gitlab issue )

Does this change not go against this security advice?
VORACLE attack and OpenVPN


#6

Turris 1.0, after the automatic update to 3.11.3 and manual restart:
Foris: Updater: Data collection is currently enabled. You can not disable updater without disabling the data collection first.
Foris: Data collection: The Updater is currently disabled. You must enable it first to enable data collection.

(Catch-22?)


#7

Same here on Omnia after troubles with updating to 3.11.2


#8

Firewall logs sending did not work after update, a second reboot solved it.


#9

Ucollect not work. After two restarts I can see problem with click on button Save “Enable data
collection”.

Edit: Even other repeated restarts did not fix the problem

    An unexpected error has occurred

    We are sorry, but your request raised an unexpected error. More information about this error may be found below.

    If you are willing to help us with fixing of the problem, download the following {error protocol} and send it to us with a short description of the steps that led to the error to our email address {tech.support@turris.cz} (the protocol contains only a copy of the following informations).
    ------------------------------------
    Při zpracování požadavku došlo k chybě

    Omlouváme se, ale během zpracování Vašeho požadavku došlo k nečekané chybě. Detailní informace naleznete níže.

    Pokud nám chcete pomoci s odstraněním chyby, stáhněte následující {protokol o chybě} a zašlete nám jej s krátkým popisem okolností vzniku chyby na adresu {tech.support@turris.cz} (protokol obsahuje pouze kopii informací uvedených na této stránce).
    ------------------------------------
    Remote Exception: Internal error Calling 'enable' for service 'ucollect' failed.('<class 'foris_controller.exceptions.ServiceCmdFailed'>')

    Remote request
    {"module": "data_collect", "action": "set", "kind": "request", "data": {"agreed": true}}
    Stack trace
    Traceback (most recent call last):
      File "/usr/lib/python3.6/site-packages/foris_controller/message_router.py", line 114, in process_message
        data = module_instance.perform_action(message["action"], message.get("data", {}))
      File "/usr/lib/python3.6/site-packages/foris_controller/module_base.py", line 60, in perform_action
        res = action_function(data)
      File "/usr/lib/python3.6/site-packages/foris_controller_modules/data_collect/__init__.py", line 56, in action_set
        res = self.handler.set_agreed(data["agreed"])
      File "/usr/lib/python3.6/site-packages/foris_controller/utils.py", line 111, in inner
        res = func(*args, **kwargs)
      File "/usr/lib/python3.6/site-packages/foris_controller_modules/data_collect/handlers/openwrt.py", line 69, in set_agreed
        return self.uci.set_agreed(agreed)
      File "/usr/lib/python3.6/site-packages/foris_controller_backends/data_collect/__init__.py", line 121, in set_agreed
        services.enable("ucollect")
      File "/usr/lib/python3.6/site-packages/foris_controller_backends/services/__init__.py", line 132, in enable
        self._run_service_command(service_name, "enable", fail_on_error)
      File "/usr/lib/python3.6/site-packages/foris_controller_backends/services/__init__.py", line 78, in _run_service_command
        raise ServiceCmdFailed(service_name, cmd)
    foris_controller.exceptions.ServiceCmdFailed: Calling 'enable' for service 'ucollect' failed.
    Environment
    {'CONTENT_LENGTH': '67',
     'CONTENT_TYPE': 'application/x-www-form-urlencoded',
     'DOCUMENT_ROOT': '/www',
     'GATEWAY_INTERFACE': 'CGI/1.1',
     'HTTP_ACCEPT': 'text/html,application/xhtml+xml,application/xml;q=0.9,image/webp,image/apng,*/*;q=0.8',
     'HTTP_ACCEPT_ENCODING': 'gzip, deflate',
     'HTTP_ACCEPT_LANGUAGE': 'cs,en-GB;q=0.9,en;q=0.8',
     'HTTP_CACHE_CONTROL': 'max-age=0',
     'HTTP_CONNECTION': 'keep-alive',
     'HTTP_CONTENT_LENGTH': '67',
     'HTTP_COOKIE': 'foris.session=5aa969445cd4417a637c02f30afe4c4d; '
                    'foris.ws.session=5125b1ecba0f38654fcaeedfec71d544',
     'HTTP_HOST': '192.168.2.1',
     'HTTP_ORIGIN': 'http://192.168.2.1',
     'HTTP_REFERER': 'http://192.168.2.1/foris/config/main/data_collect/',
     'HTTP_UPGRADE_INSECURE_REQUESTS': '1',
     'HTTP_USER_AGENT': 'Mozilla/5.0 (Windows NT 10.0; Win64; x64) '
                        'AppleWebKit/537.36 (KHTML, like Gecko) '
                        'Chrome/71.0.3578.98 Safari/537.36 OPR/58.0.3135.118',
     'PATH_INFO': '/main/data_collect/action/toggle_collecting',
     'PATH_TRANSLATED': '/www/main/data_collect/action/toggle_collecting',
     'QUERY_STRING': '',
     'REDIRECT_STATUS': '200',
     'REMOTE_ADDR': '192.168.2.120',
     'REMOTE_PORT': '64550',
     'REQUEST_METHOD': 'POST',
     'REQUEST_SCHEME': 'http',
     'REQUEST_URI': '/foris/config/main/data_collect/action/toggle_collecting',
     'SCRIPT_FILENAME': '/www/foris/config',
     'SCRIPT_NAME': '/foris/config',
     'SERVER_ADDR': '192.168.2.1',
     'SERVER_NAME': '192.168.2.1',
     'SERVER_PORT': '80',
     'SERVER_PROTOCOL': 'HTTP/1.1',
     'SERVER_SOFTWARE': 'lighttpd/1.4.50',
     'bottle.app': <bottle.Bottle object at 0x2a2d860>,
     'bottle.raw_path': '/data_collect/action/toggle_collecting',
     'bottle.request': <LocalRequest: POST http://192.168.2.1/foris/config/main/data_collect/action/toggle_collecting>,
     'bottle.request.body': <_io.BytesIO object at 0x2c9b4c0>,
     'bottle.request.headers': <bottle.WSGIHeaderDict object at 0x2bccbf0>,
     'bottle.request.post': {'csrf_token': 'otl4d9qiUoCY1pMm3y5vLz8mJtgMwlgh',
                             'enable': '1',
                             'send': ''},
     'bottle.request.urlparts': SplitResult(scheme='http', netloc='192.168.2.1', path='/foris/config/main/data_collect/action/toggle_collecting', query='', fragment=''),
     'bottle.route': <POST '/<page_name:re:.+>/action/<action:re:.+>' <function config_action_post at 0x2a2b2a0>>,
     'foris.backend': UbusSender('/var/run/ubus.sock'),
     'foris.language': 'cs',
     'foris.session': <foris.middleware.sessions.SessionForisProxy object at 0x2bdc3e0>,
     'foris.session.data': {'csrf_token': 'otl4d9qiUoCY1pMm3y5vLz8mJtgMwlgh',
                            'user_authenticated': True},
     'foris.session.id': '5aa969445cd4417a637c02f30afe4c4d',
     'foris.version': '99.7.1',
     'route.handle': <POST '/<page_name:re:.+>/action/<action:re:.+>' <function config_action_post at 0x2a2b2a0>>,
     'route.url_args': {'action': 'toggle_collecting', 'page_name': 'data_collect'},
     'wsgi.errors': <flup.server.fcgi_base.OutputStream object at 0x2bd4e00>,
     'wsgi.input': <_io.BytesIO object at 0x2c9b4c0>,
     'wsgi.multiprocess': False,
     'wsgi.multithread': True,
     'wsgi.run_once': False,
     'wsgi.url_scheme': 'http',
     'wsgi.version': (1, 0)}

root@Omnia:~# /etc/init.d/collectd stop
root@Omnia:~# /etc/init.d/collectd start
configfile: stat (/etc/collectd/conf.d) failed: No such file or directory
Could not find plugin disk.
Found a configuration for the `disk' plugin, but the plugin isn't loaded or didn't register a configuration callback.
Found a configuration for the `disk' plugin, but the plugin isn't loaded or didn't register a configuration callback.
Found a configuration for the `disk' plugin, but the plugin isn't loaded or didn't register a configuration callback.

root@Omnia:~# ^C
root@Omnia:~# /etc/init.d/collectd enable
root@Omnia:~# /etc/init.d/collectd start

root@Omnia:~# /etc/init.d/collectd stop
root@Omnia:~# /etc/init.d/collectd start
configfile: stat (/etc/collectd/conf.d) failed: No such file or directory
Could not find plugin disk.
Found a configuration for the `disk' plugin, but the plugin isn't loaded or didn't register a configuration callback.
Found a configuration for the `disk' plugin, but the plugin isn't loaded or didn't register a configuration callback.
Found a configuration for the `disk' plugin, but the plugin isn't loaded or didn't register a configuration callback.

root@Omnia:~# /etc/init.d/collectd enable
root@Omnia:~# /etc/init.d/collectd start
root@Omnia:~#

================

2019-03-27 12:22:38 notice syslog-ng[5240]: syslog-ng starting up; version='3.20.1'
2019-03-27 12:22:38 info kernel[]: [   14.562379] ip_set: protocol 6
2019-03-27 12:22:38 info kernel[]: [   17.632226] cryptodev: driver 1.8 loaded.
2019-03-27 12:22:38 info kernel[]: [   18.892354] Loading modules backported from Linux version wt-2017-01-31-0-ge882dff19e7f
2019-03-27 12:22:38 info kernel[]: [   18.900384] Backport generated by backports.git backports-20160324-13-g24da7d3c
2019-03-27 12:22:38 info kernel[]: [   19.312366] pci 0000:00:02.0: enabling device (0140 -> 0142)
2019-03-27 12:22:38 info kernel[]: [   19.318226] ath10k_pci 0000:02:00.0: pci irq msi oper_irq_mode 2 irq_mode 0 reset_mode 0
2019-03-27 12:22:38 warning kernel[]: [   19.493732] ath10k_pci 0000:02:00.0: Direct firmware load for ath10k/pre-cal-pci-0000:02:00.0.bin failed with error -2
2019-03-27 12:22:38 warning kernel[]: [   19.504465] ath10k_pci 0000:02:00.0: Falling back to user helper
2019-03-27 12:22:38 err kernel[]: [   19.514553] firmware ath10k!pre-cal-pci-0000:02:00.0.bin: firmware_loading_store: map pages failed
2019-03-27 12:22:38 warning kernel[]: [   19.523681] ath10k_pci 0000:02:00.0: Direct firmware load for ath10k/cal-pci-0000:02:00.0.bin failed with error -2
2019-03-27 12:22:38 warning kernel[]: [   19.534070] ath10k_pci 0000:02:00.0: Falling back to user helper
2019-03-27 12:22:38 err kernel[]: [   19.543972] firmware ath10k!cal-pci-0000:02:00.0.bin: firmware_loading_store: map pages failed
2019-03-27 12:22:38 info kernel[]: [   19.561362] ath10k_pci 0000:02:00.0: qca988x hw2.0 target 0x4100016c chip_id 0x043202ff sub 0000:0000
2019-03-27 12:22:38 info kernel[]: [   19.570609] ath10k_pci 0000:02:00.0: kconfig debug 0 debugfs 1 tracing 0 dfs 1 testmode 1
2019-03-27 12:22:38 info kernel[]: [   19.580233] ath10k_pci 0000:02:00.0: firmware ver 10.2.4-1.0-00041 api 5 features no-p2p,raw-mode,mfp,allows-mesh-bcast crc32 f43fa422
2019-03-27 12:22:38 warning kernel[]: [   19.624985] ath10k_pci 0000:02:00.0: board id is not exist in otp, ignore it
2019-03-27 12:22:38 warning kernel[]: [   19.632100] ath10k_pci 0000:02:00.0: Direct firmware load for ath10k/QCA988X/hw2.0/board-2.bin failed with error -2
2019-03-27 12:22:38 warning kernel[]: [   19.642564] ath10k_pci 0000:02:00.0: Falling back to user helper
2019-03-27 12:22:38 err kernel[]: [   19.652781] firmware ath10k!QCA988X!hw2.0!board-2.bin: firmware_loading_store: map pages failed
2019-03-27 12:22:38 info kernel[]: [   19.662833] ath10k_pci 0000:02:00.0: board_file api 1 bmi_id N/A crc32 bebc7c08
2019-03-27 12:22:38 info kernel[]: [   20.798773] ath10k_pci 0000:02:00.0: htt-ver 2.1 wmi-op 5 htt-op 2 cal otp max-sta 128 raw 0 hwcrypto 1
2019-03-27 12:22:38 debug kernel[]: [   20.892351] ath: EEPROM regdomain: 0x0
2019-03-27 12:22:38 debug kernel[]: [   20.892356] ath: EEPROM indicates default country code should be used
2019-03-27 12:22:38 debug kernel[]: [   20.892358] ath: doing EEPROM country->regdmn map search
2019-03-27 12:22:38 debug kernel[]: [   20.892363] ath: country maps to regdmn code: 0x3a
2019-03-27 12:22:38 debug kernel[]: [   20.892366] ath: Country alpha2 being used: US
2019-03-27 12:22:38 debug kernel[]: [   20.892368] ath: Regpair used: 0x3a
2019-03-27 12:22:38 info kernel[]: [   22.181375] u32 classifier
2019-03-27 12:22:38 info kernel[]: [   22.184091]     input device check on
2019-03-27 12:22:38 info kernel[]: [   22.187758]     Actions configured
2019-03-27 12:22:38 info kernel[]: [   22.551339] Mirror/redirect action on
2019-03-27 12:22:38 info kernel[]: [   23.251317] GACT probability on
2019-03-27 12:22:38 info kernel[]: [   26.201375] netem: version 1.3
2019-03-27 12:22:38 info kernel[]: [   27.594092] fuse init (API version 7.23)
2019-03-27 12:22:38 info kernel[]: [   27.772473] usbcore: registered new interface driver cdc_wdm
2019-03-27 12:22:38 info kernel[]: [   27.781958] Ebtables v2.0 registered
2019-03-27 12:22:38 info kernel[]: [   27.787466] ip_tables: (C) 2000-2006 Netfilter Core Team
2019-03-27 12:22:38 info kernel[]: [   27.874752] ctnetlink v0.93: registering with nfnetlink.
2019-03-27 12:22:38 info kernel[]: [   28.064855] nfnl_acct: registering with nfnetlink.
2019-03-27 12:22:38 info kernel[]: [   28.071125] PPP MPPE Compression module registered
2019-03-27 12:22:38 info kernel[]: [   28.076468] NET: Registered protocol family 24
2019-03-27 12:22:38 info kernel[]: [   28.083853] usbcore: registered new interface driver ums-alauda
2019-03-27 12:22:38 info kernel[]: [   28.090098] usbcore: registered new interface driver ums-cypress
2019-03-27 12:22:38 info kernel[]: [   28.096465] usbcore: registered new interface driver ums-datafab
2019-03-27 12:22:38 info kernel[]: [   28.102873] usbcore: registered new interface driver ums-freecom
2019-03-27 12:22:38 info kernel[]: [   28.109228] usbcore: registered new interface driver ums-isd200
2019-03-27 12:22:38 info kernel[]: [   28.115505] usbcore: registered new interface driver ums-jumpshot
2019-03-27 12:22:38 info kernel[]: [   28.121941] usbcore: registered new interface driver ums-karma
2019-03-27 12:22:38 info kernel[]: [   28.128162] usbcore: registered new interface driver ums-sddr09
2019-03-27 12:22:38 info kernel[]: [   28.134452] usbcore: registered new interface driver ums-sddr55
2019-03-27 12:22:38 info kernel[]: [   28.140736] usbcore: registered new interface driver ums-usbat
2019-03-27 12:22:38 info kernel[]: [   28.151313] usbcore: registered new interface driver usbserial
2019-03-27 12:22:38 info kernel[]: [   28.157197] usbcore: registered new interface driver usbserial_generic
2019-03-27 12:22:38 info kernel[]: [   28.163783] usbserial: USB Serial support registered for generic
2019-03-27 12:22:38 info kernel[]: [   28.182929] xt_time: kernel timezone is -0000
2019-03-27 12:22:38 info kernel[]: [   28.200913] usbcore: registered new interface driver cdc_ether
2019-03-27 12:22:38 info kernel[]: [   28.220173] usbcore: registered new interface driver qmi_wwan
2019-03-27 12:22:38 info kernel[]: [   28.226914] usbcore: registered new interface driver rndis_host
2019-03-27 12:22:38 warning kernel[]: [   28.240240] vmap allocation for size 102400 failed: use vmalloc=<size> to increase size.
2019-03-27 12:22:38 warning kernel[]: [   28.248377] vmalloc: allocation failure: 95849 bytes
2019-03-27 12:22:38 warning kernel[]: [   28.253361] kmodloader: page allocation failure: order:0, mode:0x24000c0
2019-03-27 12:22:38 warning kernel[]: [   28.260078] CPU: 1 PID: 1158 Comm: kmodloader Not tainted 4.4.176-7bc33afbb1b35f5830b2b1b42c9cd8a0-0 #1
2019-03-27 12:22:38 warning kernel[]: [   28.269492] Hardware name: Marvell Armada 380/385 (Device Tree)
2019-03-27 12:22:38 warning kernel[]: [   28.275423] Backtrace: 
2019-03-27 12:22:38 warning kernel[]: [   28.277889] [<c001c50c>] (dump_backtrace) from [<c001c74c>] (show_stack+0x18/0x1c)
2019-03-27 12:22:38 warning kernel[]: [   28.285474]  r6:00000000 r5:60000013 r4:c0770d68 r3:00000000
2019-03-27 12:22:38 warning kernel[]: [   28.291190] [<c001c734>] (show_stack) from [<c02ff974>] (dump_stack+0x98/0xac)
2019-03-27 12:22:38 warning kernel[]: [   28.298434] [<c02ff8dc>] (dump_stack) from [<c00af9d8>] (warn_alloc_failed+0xec/0x120)
2019-03-27 12:22:38 warning kernel[]: [   28.306366]  r6:00000001 r5:00000000 r4:024000c0 r3:c0769460
2019-03-27 12:22:38 warning kernel[]: [   28.312083] [<c00af8f0>] (warn_alloc_failed) from [<c00dc130>] (__vmalloc_node_range+0x170/0x214)
2019-03-27 12:22:38 warning kernel[]: [   28.320973]  r3:00017669 r2:c06550b4
2019-03-27 12:22:38 warning kernel[]: [   28.324573]  r6:c008f880 r5:00000000 r4:00017669
2019-03-27 12:22:38 warning kernel[]: [   28.329232] [<c00dbfc0>] (__vmalloc_node_range) from [<c001d0b0>] (module_alloc+0x5c/0xbc)
2019-03-27 12:22:38 warning kernel[]: [   28.337513]  r10:f09004c0 r9:f08eb000 r8:00017669 r7:ffffffff r6:c008f880 r5:024000c0
2019-03-27 12:22:38 warning kernel[]: [   28.345411]  r4:c078ef1c
2019-03-27 12:22:38 warning kernel[]: [   28.347962] [<c001d054>] (module_alloc) from [<c008f880>] (load_module+0x7b4/0x1d1c)
2019-03-27 12:22:38 warning kernel[]: [   28.355720]  r8:00017669 r7:00000aa0 r6:f0900ba4 r5:000002ea r4:edea5f34
2019-03-27 12:22:38 warning kernel[]: [   28.362488] [<c008f0cc>] (load_module) from [<c0090ec4>] (SyS_init_module+0xdc/0x14c)
2019-03-27 12:22:38 warning kernel[]: [   28.370334]  r10:00000051 r9:0001223f r8:edea4000 r7:f090b0ac r6:b66d30bc r5:00000000
2019-03-27 12:22:38 warning kernel[]: [   28.378228]  r4:000000ac
2019-03-27 12:22:38 warning kernel[]: [   28.380778] [<c0090de8>] (SyS_init_module) from [<c0009d00>] (ret_fast_syscall+0x0/0x48)
2019-03-27 12:22:38 warning kernel[]: [   28.388884]  r10:00000000 r9:edea4000 r8:c0009ec4 r7:00000080 r6:00000000 r5:00000000
2019-03-27 12:22:38 warning kernel[]: [   28.396780]  r4:00000000
2019-03-27 12:22:38 warning kernel[]: [   28.399336] Mem-Info:
2019-03-27 12:22:38 warning kernel[]: [   28.401632] active_anon:141 inactive_anon:2 isolated_anon:0
2019-03-27 12:22:38 warning kernel[]: [   28.401632]  active_file:2901 inactive_file:920 isolated_file:0
2019-03-27 12:22:38 warning kernel[]: [   28.401632]  unevictable:0 dirty:0 writeback:0 unstable:0
2019-03-27 12:22:38 warning kernel[]: [   28.401632]  slab_reclaimable:507 slab_unreclaimable:1612
2019-03-27 12:22:38 warning kernel[]: [   28.401632]  mapped:1852 shmem:2 pagetables:23 bounce:0
2019-03-27 12:22:38 warning kernel[]: [   28.401632]  free:246469 free_pcp:297 free_cma:0
2019-03-27 12:22:38 warning kernel[]: [   28.433786] Normal free:729252kB min:3504kB low:4380kB high:5256kB active_anon:388kB inactive_anon:8kB active_file:9156kB inactive_file:2936kB unevictable:0kB isolated(anon):0kB isolated(file):0kB present:786432kB managed:768588kB mlocked:0kB dirty:0kB writeback:0kB mapped:5492kB shmem:8kB slab_reclaimable:2028kB slab_unreclaimable:6448kB kernel_stack:1088kB pagetables:92kB unstable:0kB bounce:0kB free_pcp:692kB local_pcp:304kB free_cma:0kB writeback_tmp:0kB pages_scanned:0 all_unreclaimable? no
2019-03-27 12:22:38 warning kernel[]: [   28.477762] lowmem_reserve[]: 0 2048 2048
2019-03-27 12:22:38 warning kernel[]: [   28.481834] HighMem free:256624kB min:256kB low:552kB high:852kB active_anon:176kB inactive_anon:0kB active_file:2448kB inactive_file:744kB unevictable:0kB isolated(anon):0kB isolated(file):0kB present:262144kB managed:262144kB mlocked:0kB dirty:0kB writeback:0kB mapped:1916kB shmem:0kB slab_reclaimable:0kB slab_unreclaimable:0kB kernel_stack:0kB pagetables:0kB unstable:0kB bounce:0kB free_pcp:496kB local_pcp:324kB free_cma:0kB writeback_tmp:0kB pages_scanned:0 all_unreclaimable? no
2019-03-27 12:22:38 warning kernel[]: [   28.524676] lowmem_reserve[]: 0 0 0
2019-03-27 12:22:38 warning kernel[]: [   28.528210] Normal: 3*4kB (UME) 13*8kB (UM) 8*16kB (UME) 7*32kB (UM) 0*64kB 2*128kB (ME) 2*256kB (ME) 2*512kB (ME) 2*1024kB (UE) 0*2048kB 177*4096kB (M) = 729300kB
2019-03-27 12:22:38 warning kernel[]: [   28.543079] HighMem: 8*4kB (UM) 8*8kB (UM) 3*16kB (UM) 3*32kB (U) 2*64kB (UM) 0*128kB 1*256kB (M) 0*512kB 0*1024kB 1*2048kB (U) 62*4096kB (M) = 256624kB
2019-03-27 12:22:38 warning kernel[]: [   28.556967] 3826 total pagecache pages
2019-03-27 12:22:38 warning kernel[]: [   28.560723] 0 pages in swap cache
2019-03-27 12:22:38 warning kernel[]: [   28.564050] Swap cache stats: add 0, delete 0, find 0/0
2019-03-27 12:22:38 warning kernel[]: [   28.569284] Free swap  = 0kB
2019-03-27 12:22:38 warning kernel[]: [   28.572174] Total swap = 0kB
2019-03-27 12:22:38 warning kernel[]: [   28.575058] 262144 pages RAM
2019-03-27 12:22:38 warning kernel[]: [   28.577942] 65536 pages HighMem/MovableOnly
2019-03-27 12:22:38 warning kernel[]: [   28.582141] 4461 pages reserved
2019-03-27 12:22:38 info kernel[]: [   28.589493] pci 0000:00:01.0: enabling device (0140 -> 0142)
2019-03-27 12:22:38 debug kernel[]: [   28.681999] ath: EEPROM regdomain: 0x0
2019-03-27 12:22:38 debug kernel[]: [   28.682004] ath: EEPROM indicates default country code should be used
2019-03-27 12:22:38 debug kernel[]: [   28.682006] ath: doing EEPROM country->regdmn map search
2019-03-27 12:22:38 debug kernel[]: [   28.682011] ath: country maps to regdmn code: 0x3a
2019-03-27 12:22:38 debug kernel[]: [   28.682014] ath: Country alpha2 being used: US
2019-03-27 12:22:38 debug kernel[]: [   28.682016] ath: Regpair used: 0x3a
2019-03-27 12:22:38 debug kernel[]: [   28.693724] ieee80211 phy1: Selected rate control algorithm 'minstrel_ht'
2019-03-27 12:22:38 info kernel[]: [   28.695016] ieee80211 phy1: Atheros AR9287 Rev:2 mem=0xf0940000, irq=108
2019-03-27 12:22:38 info kernel[]: [   28.703821] usbcore: registered new interface driver option
2019-03-27 12:22:38 info kernel[]: [   28.709459] usbserial: USB Serial support registered for GSM modem (1-port)
2019-03-27 12:22:38 info kernel[]: [   28.716999] usbcore: registered new interface driver qcserial
2019-03-27 12:22:38 info kernel[]: [   28.722803] usbserial: USB Serial support registered for Qualcomm USB modem
2019-03-27 12:22:38 notice kernel[]: [   31.162210] random: nonblocking pool is initialized
2019-03-27 12:22:38 info kernel[]: [   34.071045] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
2019-03-27 12:22:38 info kernel[]: [   34.152734] IPv6: ADDRCONF(NETDEV_UP): eth1: link is not ready
2019-03-27 12:22:38 info kernel[]: [   34.156315] IPv6: ADDRCONF(NETDEV_UP): eth2: link is not ready
2019-03-27 12:22:38 info kernel[]: [   34.700538] BTRFS: device label srv devid 1 transid 311504 /dev/sda
2019-03-27 12:22:38 info kernel[]: [   36.061116] mvneta f1030000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off
2019-03-27 12:22:38 info kernel[]: [   36.061210] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
2019-03-27 12:22:38 info kernel[]: [   36.151096] mvneta f1070000.ethernet eth2: Link is Up - 1Gbps/Full - flow control off
2019-03-27 12:22:38 info kernel[]: [   36.151125] IPv6: ADDRCONF(NETDEV_CHANGE): eth2: link becomes ready
2019-03-27 12:22:38 info kernel[]: [   37.144623] IPv6: ADDRCONF(NETDEV_UP): br-guest_turris: link is not ready
2019-03-27 12:22:38 info kernel[]: [   37.178936] device eth0 entered promiscuous mode
2019-03-27 12:22:38 info kernel[]: [   37.180244] br-lan: port 1(eth0) entered forwarding state
2019-03-27 12:22:38 info kernel[]: [   37.180262] br-lan: port 1(eth0) entered forwarding state
2019-03-27 12:22:38 info kernel[]: [   37.208897] device eth2 entered promiscuous mode
2019-03-27 12:22:38 info kernel[]: [   37.208934] br-lan: port 2(eth2) entered forwarding state
2019-03-27 12:22:38 info kernel[]: [   37.208946] br-lan: port 2(eth2) entered forwarding state
2019-03-27 12:22:38 debug kernel[]: [   37.550112] ath: EEPROM regdomain: 0x80cb
2019-03-27 12:22:38 debug kernel[]: [   37.550119] ath: EEPROM indicates we should expect a country code
2019-03-27 12:22:38 debug kernel[]: [   37.550122] ath: doing EEPROM country->regdmn map search
2019-03-27 12:22:38 debug kernel[]: [   37.550125] ath: country maps to regdmn code: 0x36
2019-03-27 12:22:38 debug kernel[]: [   37.550127] ath: Country alpha2 being used: CZ
2019-03-27 12:22:38 debug kernel[]: [   37.550130] ath: Regpair used: 0x36
2019-03-27 12:22:38 debug kernel[]: [   37.550133] ath: regdomain 0x80cb dynamically updated by user
2019-03-27 12:22:38 debug kernel[]: [   37.550179] ath: EEPROM regdomain: 0x80cb
2019-03-27 12:22:38 debug kernel[]: [   37.550182] ath: EEPROM indicates we should expect a country code
2019-03-27 12:22:38 debug kernel[]: [   37.550184] ath: doing EEPROM country->regdmn map search
2019-03-27 12:22:38 debug kernel[]: [   37.550186] ath: country maps to regdmn code: 0x36
2019-03-27 12:22:38 debug kernel[]: [   37.550189] ath: Country alpha2 being used: CZ
2019-03-27 12:22:38 debug kernel[]: [   37.550191] ath: Regpair used: 0x36
2019-03-27 12:22:38 debug kernel[]: [   37.550194] ath: regdomain 0x80cb dynamically updated by user
2019-03-27 12:22:38 info kernel[]: [   37.683823] IPv6: ADDRCONF(NETDEV_UP): wlan1: link is not ready
2019-03-27 12:22:38 info kernel[]: [   37.685010] device wlan1 entered promiscuous mode
2019-03-27 12:22:38 info kernel[]: [   37.685047] br-lan: port 3(wlan1) entered forwarding state
2019-03-27 12:22:38 info kernel[]: [   37.685062] br-lan: port 3(wlan1) entered forwarding state
2019-03-27 12:22:38 info kernel[]: [   37.759465] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready
2019-03-27 12:22:38 info kernel[]: [   37.761672] device guest_turris_1 entered promiscuous mode
2019-03-27 12:22:38 info kernel[]: [   37.761938] IPv6: ADDRCONF(NETDEV_UP): guest_turris_1: link is not ready
2019-03-27 12:22:38 info kernel[]: [   37.761947] br-guest_turris: port 1(guest_turris_1) entered forwarding state
2019-03-27 12:22:38 info kernel[]: [   37.761965] br-guest_turris: port 1(guest_turris_1) entered forwarding state
2019-03-27 12:22:38 info kernel[]: [   37.762002] IPv6: ADDRCONF(NETDEV_CHANGE): br-guest_turris: link becomes ready
2019-03-27 12:22:38 info kernel[]: [   38.153488] br-lan: port 1(eth0) entered disabled state
2019-03-27 12:22:38 info kernel[]: [   38.153893] br-lan: port 2(eth2) entered disabled state
2019-03-27 12:22:38 info kernel[]: [   38.337104] BTRFS info (device sda): setting nodatacow, compression disabled
2019-03-27 12:22:38 info kernel[]: [   38.337113] BTRFS info (device sda): disk space caching is enabled
2019-03-27 12:22:38 info kernel[]: [   38.337116] BTRFS: has skinny extents
2019-03-27 12:22:38 info kernel[]: [   39.171069] mvneta f1030000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off
2019-03-27 12:22:38 info kernel[]: [   39.201107] mvneta f1070000.ethernet eth2: Link is Up - 1Gbps/Full - flow control off
2019-03-27 12:22:38 info kernel[]: [   39.681047] br-lan: port 3(wlan1) entered forwarding state
2019-03-27 12:22:38 info kernel[]: [   39.761047] br-guest_turris: port 1(guest_turris_1) entered forwarding state
2019-03-27 12:22:38 info kernel[]: [   39.977185] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
2019-03-27 12:22:38 info kernel[]: [   39.979262] device wlan0 entered promiscuous mode
2019-03-27 12:22:38 info kernel[]: [   39.979296] br-lan: port 4(wlan0) entered forwarding state
2019-03-27 12:22:38 info kernel[]: [   39.979311] br-lan: port 4(wlan0) entered forwarding state
2019-03-27 12:22:38 info kernel[]: [   39.980757] br-lan: port 1(eth0) entered forwarding state
2019-03-27 12:22:38 info kernel[]: [   39.980781] br-lan: port 1(eth0) entered forwarding state
2019-03-27 12:22:38 info kernel[]: [   39.980833] br-lan: port 2(eth2) entered forwarding state
2019-03-27 12:22:38 info kernel[]: [   39.980846] br-lan: port 2(eth2) entered forwarding state
2019-03-27 12:22:38 info kernel[]: [   39.981765] br-lan: port 4(wlan0) entered disabled state
2019-03-27 12:22:38 info kernel[]: [   40.056324] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
2019-03-27 12:22:38 info kernel[]: [   40.056412] br-lan: port 4(wlan0) entered forwarding state
2019-03-27 12:22:38 info kernel[]: [   40.056447] br-lan: port 4(wlan0) entered forwarding state
2019-03-27 12:22:38 info kernel[]: [   40.291652] mvneta f1034000.ethernet eth1: Link is Up - 100Mbps/Full - flow control off
2019-03-27 12:22:38 info kernel[]: [   41.971072] br-lan: port 2(eth2) entered forwarding state
2019-03-27 12:22:38 info kernel[]: [   41.971120] br-lan: port 1(eth0) entered forwarding state
2019-03-27 12:22:38 info kernel[]: [   42.051082] br-lan: port 4(wlan0) entered forwarding state
2019-03-27 12:22:39 emerg sfpswitch.py[3224]: uci: Entry not found
2019-03-27 12:22:39 info dhcp_host_domain_ng.py[]: Refresh kresd leases
2019-03-27 12:22:39 emerg sfpswitch.py[3224]: Called /etc/resolver/dhcp_host_domain_ng.py
2019-03-27 12:22:40 info sshd[5505]: Accepted password for root from 192.168.2.120 port 65275 ssh2
2019-03-27 12:22:40 emerg sfpswitch.py[3224]: configfile: stat (/etc/collectd/conf.d) failed: No such file or directory
2019-03-27 12:22:40 emerg sfpswitch.py[3224]: Could not find plugin disk.
2019-03-27 12:22:40 emerg sfpswitch.py[3224]: Found a configuration for the `disk' plugin, but the plugin isn't loaded or didn't register a configuration callback.
2019-03-27 12:22:41 emerg sfpswitch.py[5240]: Last message 'Found a configuratio' repeated 2 times, suppressed by syslog-ng on Omnia
2019-03-27 12:22:41 crit mountd[5605]: Starting OpenWrt (auto)mountd V1
2019-03-27 12:22:41 crit mountd[5605]: trying to mount /tmp/run/mountd/ as the autofs root
2019-03-27 12:22:41 info hostapd[]: wlan0: STA c0:11:73:18:3f:8d IEEE 802.11: authenticated
2019-03-27 12:22:41 info hostapd[]: wlan0: STA c0:11:73:18:3f:8d IEEE 802.11: associated (aid 1)
2019-03-27 12:22:41 info hostapd[]: wlan0: STA c0:11:73:18:3f:8d RADIUS: starting accounting session 8B8FD957BE07AEF7
2019-03-27 12:22:41 info hostapd[]: wlan0: STA c0:11:73:18:3f:8d WPA: pairwise key handshake completed (RSN)
2019-03-27 12:22:43 info dnsmasq[5681]: started, version 2.78 DNS disabled
2019-03-27 12:22:43 info dnsmasq[5681]: compile time options: IPv6 GNU-getopt no-DBus no-i18n no-IDN DHCP no-DHCPv6 no-Lua TFTP no-conntrack no-ipset no-auth no-DNSSEC loop-detect inotify
2019-03-27 12:22:43 info dnsmasq-dhcp[5681]: DHCP, IP range 10.111.222.200 -- 10.111.222.204, lease time 1h
2019-03-27 12:22:43 info dnsmasq-dhcp[5681]: DHCP, IP range 192.168.2.101 -- 192.168.2.120, lease time 1h
2019-03-27 12:22:43 info dnsmasq-dhcp[5681]: read /etc/ethers - 0 addresses
2019-03-27 12:22:43 info dnsmasq-dhcp[5681]: DHCPDISCOVER(br-lan) c0:11:73:18:3f:8d 
2019-03-27 12:22:43 info dnsmasq-dhcp[5681]: DHCPOFFER(br-lan) 192.168.2.115 c0:11:73:18:3f:8d 
2019-03-27 12:22:43 info dnsmasq-dhcp[5681]: DHCPREQUEST(br-lan) 192.168.2.115 c0:11:73:18:3f:8d 
2019-03-27 12:22:43 info dnsmasq-dhcp[5681]: DHCPACK(br-lan) 192.168.2.115 c0:11:73:18:3f:8d Tablet_Jirina
2019-03-27 12:22:44 info dhcp_host_domain_ng.py[]: DHCPv4 new lease
2019-03-27 12:22:44 warning dhcp_host_domain_ng.py[]: Add_lease, hostname check failed
2019-03-27 12:22:44 warning dhcp_host_domain_ng.py[5240]: Last message 'Add_lease, hostname ' repeated 1 times, suppressed by syslog-ng on Omnia
2019-03-27 12:22:44 info dhcp_host_domain_ng.py[]: DHCP add new hostname [Tablet_Jirina,192.168.2.115]
2019-03-27 12:22:44 notice ntpdate[5245]: step time server 217.31.202.100 offset 0.090761 sec
2019-03-27 12:22:44 debug dnsmasq-script[5681]: uci: Entry not found
2019-03-27 12:22:44 info dhcp_host_domain_ng.py[]: Refresh kresd leases
2019-03-27 12:22:45 info ucollect[5973]: Initializing emergency signal handlers
2019-03-27 12:22:45 info ucollect[5973]: Creating a main loop
2019-03-27 12:22:45 info ucollect[5973]: Creating uplink
2019-03-27 12:22:45 info ucollect[5973]: Initializing PCAP (in) on eth1
2019-03-27 12:22:45 info ucollect[5973]: Initializing PCAP (out) on eth1
2019-03-27 12:22:45 info ucollect[5973]: Initializing PCAP (in) on lo
2019-03-27 12:22:45 info ucollect[5973]: Initializing PCAP (out) on lo
2019-03-27 12:22:45 info ucollect[5973]: Loading plugin library libplugin_ucollect_badconf_25.so
2019-03-27 12:22:45 info ucollect[5973]: Installing plugin Badconf with api version 0
2019-03-27 12:22:45 info ucollect[5973]: Loading plugin library libplugin_ucollect_bandwidth_22.so
2019-03-27 12:22:45 info ucollect[5973]: Installing plugin Bandwidth with api version 0
2019-03-27 12:22:45 info ucollect[5973]: Loading plugin library libplugin_ucollect_count_28.so
2019-03-27 12:22:45 info ucollect[5973]: Installing plugin Count with api version 0
2019-03-27 12:22:45 err ucollect[5973]: Pluglibs for plugin with api version 0 Count
2019-03-27 12:22:45 info ucollect[5973]: Unloading plugin library
2019-03-27 12:22:45 info ucollect[5973]: Removing plugin Badconf
2019-03-27 12:22:45 info ucollect[5973]: Unloading plugin library
2019-03-27 12:22:45 info ucollect[5973]: Removing plugin Bandwidth
2019-03-27 12:22:45 info ucollect[5973]: Unloading plugin library
2019-03-27 12:22:45 info ucollect[5973]: Closing both PCAPs on eth1
2019-03-27 12:22:45 info ucollect[5973]: Closing both PCAPs on lo
2019-03-27 12:22:45 crit ucollect[5973]: No configuration available
2019-03-27 12:22:45 info /usr/lib/netdata/plugins.d/anonymous-statistics.sh[]: 'START' '-' '-'
2019-03-27 12:22:45 info smartd[6184]: smartd 6.5 2016-05-07 r4318 [armv7l-linux-4.4.176-7bc33afbb1b35f5830b2b1b42c9cd8a0-0] (localbuild)
2019-03-27 12:22:45 info smartd[6184]: Copyright (C) 2002-16, Bruce Allen, Christian Franke, www.smartmontools.org
2019-03-27 12:22:45 info smartd[6184]: Opened configuration file /etc/smartd.conf
2019-03-27 12:22:45 info smartd[6184]: Configuration file /etc/smartd.conf parsed.
2019-03-27 12:22:45 info smartd[6184]: Device: /dev/hdb, open() failed: No such device
2019-03-27 12:22:45 info smartd[6184]: Monitoring 0 ATA/SATA, 0 SCSI/SAS and 0 NVMe devices
2019-03-27 12:22:45 err child[]: pid 6159 exited with code 6.
2019-03-27 12:22:45 info netdata[6143]: resources control: allowed file descriptors: soft = 1024, max = 4096
2019-03-27 12:22:45 info netdata[6143]: Adjusted my Out-Of-Memory (OOM) score from 0 to 1000.
2019-03-27 12:22:45 err netdata[6143]: Cannot adjust netdata scheduling policy to idle (5), with priority 0. Falling back to nice.
2019-03-27 12:22:45 info smartd[6197]: smartd has fork()ed into background mode. New PID=6197.
2019-03-27 12:22:45 err netdata[6143]: Cannot get my current process scheduling policy.
2019-03-27 12:22:45 info netdata[6143]: netdata started on pid 6143.
2019-03-27 12:22:46 info netdata[6143]: CONFIG: cannot load user config '/etc/netdata/stream.conf'. Will try stock config.
2019-03-27 12:22:46 info netdata[6143]: Host 'Omnia' (at registry as 'Omnia') with guid 'a5552d24-5082-11e9-b645-d858d7003550' initialized, os 'linux', timezone 'GMT', tags '', program_name 'netdata', program_version 'v3.11.2-148-g97c156d', update every 2, memory mode ram, history entries 4034, streaming disabled (to '' with api key ''), health disabled, cache_dir '/var/cache/netdata', varlib_dir '/var/lib/netdata', health_log '/var/lib/netdata/health/health-log.db', alarms default handler '/usr/lib/netdata/plugins.d/alarm-notify.sh', alarms default recipient 'root'
2019-03-27 12:22:46 info netdata[6143]: netdata initialization completed. Enjoy real-time performance monitoring!
2019-03-27 12:22:46 info netdata[6143]: thread created with task id 6264
2019-03-27 12:22:46 info netdata[6143]: thread created with task id 6265
2019-03-27 12:22:46 info netdata[6143]: thread created with task id 6266
2019-03-27 12:22:46 err netdata[6143]: Cannot advise the kernel about the memory usage (MADV_MERGEABLE) of file '(null)'.
2019-03-27 12:22:46 info netdata[6143]: thread created with task id 6267
2019-03-27 12:22:46 info netdata[6143]: thread created with task id 6268
2019-03-27 12:22:46 info netdata[6143]: thread created with task id 6269
2019-03-27 12:22:46 info netdata[6143]: thread created with task id 6270
2019-03-27 12:22:46 info netdata[6143]: thread created with task id 6271
2019-03-27 12:22:46 info netdata[6143]: cleaning up...
2019-03-27 12:22:46 info netdata[6143]: thread with task id 6268 finished
2019-03-27 12:22:46 info netdata[6143]: starting worker 2
2019-03-27 12:22:46 info netdata[6143]: POLLFD: LISTENER: listening on 'tcp:0.0.0.0:19999'
2019-03-27 12:22:46 info netdata[6143]: POLLFD: LISTENER: listening on 'tcp:[::]:19999'
2019-03-27 12:22:46 info netdata[6143]: thread created with task id 6318
2019-03-27 12:22:46 info netdata[6143]: POLLFD: LISTENER: listening on 'tcp:0.0.0.0:19999'
2019-03-27 12:22:46 info netdata[6143]: POLLFD: LISTENER: listening on 'tcp:[::]:19999'
2019-03-27 12:22:46 info netdata[6143]: thread created with task id 6348
2019-03-27 12:22:46 info netdata[6143]: STATSD collector thread started with taskid 6348
2019-03-27 12:22:46 info netdata[6143]: POLLFD: LISTENER: listening on 'udp:127.0.0.1:8125'
2019-03-27 12:22:46 info netdata[6143]: POLLFD: LISTENER: listening on 'tcp:127.0.0.1:8125'
2019-03-27 12:22:46 info kernel[]: [   50.316805] device br-lan entered promiscuous mode
2019-03-27 12:22:47 info kernel[]: [   50.473861] device br-guest_turris entered promiscuous mode
2019-03-27 12:22:47 info netdata[6143]: cpufreq is using /sys/devices/system/cpu/cpu*/cpufreq/stats/time_in_state
2019-03-27 12:22:47 emerg turris[]: Router Turris successfully started.
2019-03-27 12:22:47 info procd[]: - init complete -
2019-03-27 12:22:47 info netdata[6143]: Using now_boottime_usec() for uptime (dt is 13 ms)
2019-03-27 12:22:48 err netdata[6143]: PROCFILE: Cannot open file '/proc/net/sctp/snmp'
2019-03-27 12:22:49 err netdata[6143]: DEVICE-MAPPER ('ram0', 1:0): Cannot open directory '/dev/disk/by-label'.
2019-03-27 12:22:49 err netdata[6143]: DEVICE-MAPPER ('ram0', 1:0): Cannot open directory '/dev/vx/dsk'.
2019-03-27 12:22:49 err netdata[6143]: DEVICE-MAPPER ('ram1', 1:1): Cannot open directory '/dev/disk/by-label'.
2019-03-27 12:22:49 err netdata[6143]: DEVICE-MAPPER ('ram1', 1:1): Cannot open directory '/dev/vx/dsk'.
2019-03-27 12:22:49 err netdata[6143]: DEVICE-MAPPER ('ram2', 1:2): Cannot open directory '/dev/disk/by-label'.
2019-03-27 12:22:49 err netdata[6143]: DEVICE-MAPPER ('ram2', 1:2): Cannot open directory '/dev/vx/dsk'.
2019-03-27 12:22:49 err netdata[6143]: DEVICE-MAPPER ('ram3', 1:3): Cannot open directory '/dev/disk/by-label'.
2019-03-27 12:22:49 err netdata[6143]: DEVICE-MAPPER ('ram3', 1:3): Cannot open directory '/dev/vx/dsk'.
2019-03-27 12:22:49 err netdata[6143]: DEVICE-MAPPER ('ram4', 1:4): Cannot open directory '/dev/disk/by-label'.
2019-03-27 12:22:49 err netdata[6143]: DEVICE-MAPPER ('ram4', 1:4): Cannot open directory '/dev/vx/dsk'.
2019-03-27 12:22:49 err netdata[6143]: DEVICE-MAPPER ('ram5', 1:5): Cannot open directory '/dev/disk/by-label'.
2019-03-27 12:22:49 err netdata[6143]: DEVICE-MAPPER ('ram5', 1:5): Cannot open directory '/dev/vx/dsk'.
2019-03-27 12:22:49 err netdata[6143]: DEVICE-MAPPER ('ram6', 1:6): Cannot open directory '/dev/disk/by-label'.
2019-03-27 12:22:49 err netdata[6143]: DEVICE-MAPPER ('ram6', 1:6): Cannot open directory '/dev/vx/dsk'.
2019-03-27 12:22:49 err netdata[6143]: DEVICE-MAPPER ('ram7', 1:7): Cannot open directory '/dev/disk/by-label'.
2019-03-27 12:22:49 err netdata[6143]: DEVICE-MAPPER ('ram7', 1:7): Cannot open directory '/dev/vx/dsk'.
2019-03-27 12:22:49 err netdata[6143]: DEVICE-MAPPER ('ram8', 1:8): Cannot open directory '/dev/disk/by-label'.
2019-03-27 12:22:49 err netdata[6143]: DEVICE-MAPPER ('ram8', 1:8): Cannot open directory '/dev/vx/dsk'.
2019-03-27 12:22:49 err netdata[6143]: DEVICE-MAPPER ('ram9', 1:9): Cannot open directory '/dev/disk/by-label'.
2019-03-27 12:22:49 err netdata[6143]: DEVICE-MAPPER ('ram9', 1:9): Cannot open directory '/dev/vx/dsk'.
2019-03-27 12:22:49 err netdata[6143]: DEVICE-MAPPER ('ram10', 1:10): Cannot open directory '/dev/disk/by-label'.
2019-03-27 12:22:49 err netdata[6143]: DEVICE-MAPPER ('ram10', 1:10): Cannot open directory '/dev/vx/dsk'.
2019-03-27 12:22:49 err netdata[6143]: DEVICE-MAPPER ('ram11', 1:11): Cannot open directory '/dev/disk/by-label'.
2019-03-27 12:22:49 err netdata[6143]: DEVICE-MAPPER ('ram11', 1:11): Cannot open directory '/dev/vx/dsk'.
2019-03-27 12:22:49 err netdata[6143]: DEVICE-MAPPER ('ram12', 1:12): Cannot open directory '/dev/disk/by-label'.
2019-03-27 12:22:49 err netdata[6143]: DEVICE-MAPPER ('ram12', 1:12): Cannot open directory '/dev/vx/dsk'.
2019-03-27 12:22:49 err netdata[6143]: DEVICE-MAPPER ('ram13', 1:13): Cannot open directory '/dev/disk/by-label'.
2019-03-27 12:22:49 err netdata[6143]: DEVICE-MAPPER ('ram13', 1:13): Cannot open directory '/dev/vx/dsk'.
2019-03-27 12:22:49 err netdata[6143]: DEVICE-MAPPER ('ram14', 1:14): Cannot open directory '/dev/disk/by-label'.
2019-03-27 12:22:49 err netdata[6143]: DEVICE-MAPPER ('ram14', 1:14): Cannot open directory '/dev/vx/dsk'.
2019-03-27 12:22:49 err netdata[6143]: DEVICE-MAPPER ('ram15', 1:15): Cannot open directory '/dev/disk/by-label'.
2019-03-27 12:22:49 err netdata[6143]: DEVICE-MAPPER ('ram15', 1:15): Cannot open directory '/dev/vx/dsk'.
2019-03-27 12:22:49 err netdata[6143]: DEVICE-MAPPER ('mtdblock0', 31:0): Cannot open directory '/dev/disk/by-label'.
2019-03-27 12:22:49 err netdata[6143]: DEVICE-MAPPER ('mtdblock0', 31:0): Cannot open directory '/dev/vx/dsk'.
2019-03-27 12:22:49 err netdata[6143]: DEVICE-MAPPER ('mtdblock1', 31:1): Cannot open directory '/dev/disk/by-label'.
2019-03-27 12:22:49 err netdata[6143]: DEVICE-MAPPER ('mtdblock1', 31:1): Cannot open directory '/dev/vx/dsk'.
2019-03-27 12:22:49 err netdata[6143]: DEVICE-MAPPER ('mmcblk0', 179:0): Cannot open directory '/dev/disk/by-label'.
2019-03-27 12:22:49 err netdata[6143]: DEVICE-MAPPER ('mmcblk0', 179:0): Cannot open directory '/dev/vx/dsk'.
2019-03-27 12:22:49 err netdata[6143]: DEVICE-MAPPER ('mmcblk0p1', 179:1): Cannot open directory '/dev/disk/by-label'.
2019-03-27 12:22:49 err netdata[6143]: DEVICE-MAPPER ('mmcblk0p1', 179:1): Cannot open directory '/dev/vx/dsk'.
2019-03-27 12:22:49 err netdata[6143]: DEVICE-MAPPER ('mmcblk0rpmb', 179:24): Cannot open directory '/dev/disk/by-label'.
2019-03-27 12:22:49 err netdata[6143]: DEVICE-MAPPER ('mmcblk0rpmb', 179:24): Cannot open directory '/dev/vx/dsk'.
2019-03-27 12:22:49 err netdata[6143]: DEVICE-MAPPER ('mmcblk0boot1', 179:16): Cannot open directory '/dev/disk/by-label'.
2019-03-27 12:22:49 err netdata[6143]: DEVICE-MAPPER ('mmcblk0boot1', 179:16): Cannot open directory '/dev/vx/dsk'.
2019-03-27 12:22:49 err netdata[6143]: DEVICE-MAPPER ('mmcblk0boot0', 179:8): Cannot open directory '/dev/disk/by-label'.
2019-03-27 12:22:49 err netdata[6143]: DEVICE-MAPPER ('mmcblk0boot0', 179:8): Cannot open directory '/dev/vx/dsk'.
2019-03-27 12:22:49 err netdata[6143]: DEVICE-MAPPER ('sda', 8:0): Cannot open directory '/dev/disk/by-label'.
2019-03-27 12:22:49 err netdata[6143]: DEVICE-MAPPER ('sda', 8:0): Cannot open directory '/dev/vx/dsk'.
2019-03-27 12:22:52 notice firewall[]: Reloading firewall due to ifup of lan (br-lan)
2019-03-27 12:22:52 info turris-firewall-rules[]: (v63) IPv4 WAN interface used - 'eth1'
2019-03-27 12:22:52 info turris-firewall-rules[]: (v63) IPv6 WAN interface used - 'lo'
2019-03-27 12:22:52 err netdata[6143]: heartbeat missed 3998804 monotonic microseconds
2019-03-27 12:22:53 info turris-firewall-rules[]: (v63) 3524 ipv4 address(es) and 3 ipv6 address(es) were loaded (c83b72d5b03454292e5000b3967bc350), 0 rule(s) overriden, 0 rule(s) skipped
2019-03-27 12:22:54 info adblock-[3.5.1]: blocklist with overall 30842 domains loaded successfully (Turris Omnia, OpenWrt omnia 15.05/3.11.3)
2019-03-27 12:22:58 err netdata[6143]: Cannot refresh interface guest_turris_1 speed by reading '/sys/class/net/guest_turris_1/speed'. Will not update its speed anymore.
2019-03-27 12:22:58 err netdata[6143]: Cannot refresh interface wlan0 speed by reading '/sys/class/net/wlan0/speed'. Will not update its speed anymore.
2019-03-27 12:22:58 err netdata[6143]: Cannot refresh interface wlan1 speed by reading '/sys/class/net/wlan1/speed'. Will not update its speed anymore.
2019-03-27 12:22:58 notice SQM[]: Stopping SQM on eth1
2019-03-27 12:22:58 notice SQM[]: Starting SQM script: nxt_routed_hfsc.qos on eth1, in: 30000 Kbps, out: 30000 Kbps
2019-03-27 12:22:59 notice SQM[]: nxt_routed_hfsc.qos was started on eth1 successfully
2019-03-27 12:22:59 notice firewall[]: Reloading firewall due to ifup of wan (eth1)
2019-03-27 12:22:59 info turris-firewall-rules[]: (v63) IPv4 WAN interface used - 'eth1'
2019-03-27 12:22:59 info turris-firewall-rules[]: (v63) IPv6 WAN interface used - 'lo'
2019-03-27 12:23:00 info turris-firewall-rules[]: (v63) 3524 ipv4 address(es) and 3 ipv6 address(es) were loaded (c83b72d5b03454292e5000b3967bc350), 0 rule(s) overriden, 0 rule(s) skipped
2019-03-27 12:23:01 info /usr/sbin/cron[9565]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
2019-03-27 12:23:01 info /usr/sbin/cron[9566]: (root) CMD ((cat /tmp/nethist.stats | sort ; echo "uptime = $(cat /proc/uptime | cut -d. -f1)" ) | logger -p info -t nethist ; rm /tmp/nethist.stats)
2019-03-27 12:23:01 info nethist[]: uptime = 64
2019-03-27 12:23:03 notice SQM[]: Stopping SQM on eth1
2019-03-27 12:23:03 notice SQM[]: Starting SQM script: nxt_routed_hfsc.qos on eth1, in: 30000 Kbps, out: 30000 Kbps
2019-03-27 12:23:04 notice SQM[]: nxt_routed_hfsc.qos was started on eth1 successfully
2019-03-27 12:23:04 notice firewall[]: Reloading firewall due to ifup of wan6 (eth1)
2019-03-27 12:23:04 info turris-firewall-rules[]: (v63) IPv4 WAN interface used - 'eth1'
2019-03-27 12:23:04 info turris-firewall-rules[]: (v63) IPv6 WAN interface used - 'lo'
2019-03-27 12:23:05 info turris-firewall-rules[]: (v63) 3524 ipv4 address(es) and 3 ipv6 address(es) were loaded (c83b72d5b03454292e5000b3967bc350), 0 rule(s) overriden, 0 rule(s) skipped
2019-03-27 12:23:45 info ucollect[12115]: Initializing emergency signal handlers
2019-03-27 12:23:45 info ucollect[12115]: Creating a main loop
2019-03-27 12:23:45 info ucollect[12115]: Creating uplink
2019-03-27 12:23:45 info ucollect[12115]: Initializing PCAP (in) on eth1
2019-03-27 12:23:45 info ucollect[12115]: Initializing PCAP (out) on eth1
2019-03-27 12:23:45 info ucollect[12115]: Initializing PCAP (in) on lo
2019-03-27 12:23:45 info ucollect[12115]: Initializing PCAP (out) on lo
2019-03-27 12:23:45 info ucollect[12226]: Initializing emergency signal handlers
2019-03-27 12:23:45 info ucollect[12226]: Creating a main loop
2019-03-27 12:23:45 info ucollect[12226]: Creating uplink
2019-03-27 12:23:45 info ucollect[12226]: Initializing PCAP (in) on eth1
2019-03-27 12:23:45 info ucollect[12115]: Loading plugin library libplugin_ucollect_badconf_25.so
2019-03-27 12:23:45 info ucollect[12115]: Installing plugin Badconf with api version 0
2019-03-27 12:23:45 info ucollect[12115]: Loading plugin library libplugin_ucollect_bandwidth_22.so
2019-03-27 12:23:45 info ucollect[12115]: Installing plugin Bandwidth with api version 0
2019-03-27 12:23:45 info ucollect[12115]: Loading plugin library libplugin_ucollect_count_28.so
2019-03-27 12:23:45 info ucollect[12115]: Installing plugin Count with api version 0
2019-03-27 12:23:45 info ucollect[12115]: Loading plugin library libplugin_ucollect_fake_23.so
2019-03-27 12:23:45 info ucollect[12115]: Installing plugin Fake with api version 0
2019-03-27 12:23:45 info ucollect[12115]: Loading plugin library libplugin_ucollect_flow_33.so
2019-03-27 12:23:45 info ucollect[12115]: Installing plugin Flow with api version 2
2019-03-27 12:23:45 info ucollect[12115]: Loading plugin library libpluglib_ucollect_diffstore_1.2.so
2019-03-27 12:23:45 info ucollect[12115]: Loading plugin library libplugin_ucollect_fwup_12.so
2019-03-27 12:23:45 info ucollect[12115]: Installing plugin Fwup with api version 2
2019-03-27 12:23:45 info ucollect[12115]: Loading plugin library libpluglib_ucollect_diffstore_1.2.so
2019-03-27 12:23:45 info ucollect[12115]: Loading plugin library libplugin_ucollect_refused_13.so
2019-03-27 12:23:45 info ucollect[12115]: Installing plugin Refused with api version 0
2019-03-27 12:23:45 info ucollect[12115]: Loading plugin library libplugin_ucollect_sniff_19.so
2019-03-27 12:23:45 info ucollect[12115]: Installing plugin Sniff with api version 0
2019-03-27 12:23:45 info ucollect[12115]: Loading plugin library libplugin_ucollect_spoof_8.so
2019-03-27 12:23:45 info ucollect[12115]: Installing plugin Spoof with api version 0
2019-03-27 12:23:45 info ucollect[12115]: Changing remote uplink address to api.turris.cz:5679
2019-03-27 12:23:45 info ucollect[12115]: Sending login credentials enabled
2019-03-27 12:23:45 info ucollect[5240]: Last message 'Sending login creden' repeated 5 times, suppressed by syslog-ng on Omnia
2019-03-27 12:23:45 info ucollect[12115]: Unloading plugin library
2019-03-27 12:23:45 info ucollect[12115]: Running the main loop
2019-03-27 12:23:45 info ucollect[12115]: Destroying uplink to api.turris.cz:5679
2019-03-27 12:23:45 info ucollect[12115]: Releasing the main loop
2019-03-27 12:23:45 info ucollect[12115]: Closing both PCAPs on eth1
2019-03-27 12:23:45 info ucollect[12226]: Initializing PCAP (out) on eth1
2019-03-27 12:23:45 info ucollect[12226]: Initializing PCAP (in) on lo
2019-03-27 12:23:45 info ucollect[12115]: Closing both PCAPs on lo
2019-03-27 12:23:45 info ucollect[12226]: Initializing PCAP (out) on lo
2019-03-27 12:23:45 info ucollect[12226]: Loading plugin library libplugin_ucollect_badconf_25.so
2019-03-27 12:23:45 info ucollect[12226]: Installing plugin Badconf with api version 0
2019-03-27 12:23:45 info ucollect[12226]: Loading plugin library libplugin_ucollect_bandwidth_22.so
2019-03-27 12:23:45 info ucollect[12226]: Installing plugin Bandwidth with api version 0
2019-03-27 12:23:45 info ucollect[12226]: Loading plugin library libplugin_ucollect_count_28.so
2019-03-27 12:23:45 info ucollect[12226]: Installing plugin Count with api version 0
2019-03-27 12:23:45 info ucollect[12226]: Loading plugin library libplugin_ucollect_fake_23.so
2019-03-27 12:23:45 info ucollect[12226]: Installing plugin Fake with api version 0
2019-03-27 12:23:45 err ucollect[12226]: Couldn't bind fake server telnet socket 18 to port 1392: Address in use
2019-03-27 12:23:45 err ucollect[12226]: Couldn't bind fake server telnet_alt socket 18 to port 3692: Address in use
2019-03-27 12:23:45 err ucollect[12226]: Couldn't bind fake server http socket 18 to port 1449: Address in use
2019-03-27 12:23:45 err ucollect[12226]: Couldn't bind fake server squid_http_proxy socket 18 to port 4497: Address in use
2019-03-27 12:23:45 err ucollect[12226]: Couldn't bind fake server http_proxy socket 18 to port 9449: Address in use
2019-03-27 12:23:45 err ucollect[12226]: Couldn't bind fake server polipo_http_proxy socket 18 to port 9492: Address in use
2019-03-27 12:23:45 info ucollect[12226]: Loading plugin library libplugin_ucollect_flow_33.so
2019-03-27 12:23:45 info ucollect[12226]: Installing plugin Flow with api version 2
2019-03-27 12:23:45 info ucollect[12226]: Loading plugin library libpluglib_ucollect_diffstore_1.2.so
2019-03-27 12:23:45 info ucollect[12226]: Loading plugin library libplugin_ucollect_fwup_12.so
2019-03-27 12:23:45 info ucollect[12226]: Installing plugin Fwup with api version 2
2019-03-27 12:23:45 info ucollect[12226]: Loading plugin library libpluglib_ucollect_diffstore_1.2.so
2019-03-27 12:23:45 info ucollect[12226]: Loading plugin library libplugin_ucollect_refused_13.so
2019-03-27 12:23:45 info ucollect[12226]: Installing plugin Refused with api version 0
2019-03-27 12:23:45 info ucollect[12226]: Loading plugin library libplugin_ucollect_sniff_19.so
2019-03-27 12:23:45 info ucollect[12226]: Installing plugin Sniff with api version 0
2019-03-27 12:23:45 info ucollect[12226]: Loading plugin library libplugin_ucollect_spoof_8.so
2019-03-27 12:23:45 info ucollect[12226]: Installing plugin Spoof with api version 0
2019-03-27 12:23:45 info ucollect[12226]: Changing remote uplink address to api.turris.cz:5679
2019-03-27 12:23:45 info ucollect[12226]: Sending login credentials enabled
2019-03-27 12:23:45 info ucollect[5240]: Last message 'Sending login creden' repeated 5 times, suppressed by syslog-ng on Omnia
2019-03-27 12:23:45 info ucollect[12226]: Unloading plugin library
2019-03-27 12:23:45 info ucollect[12226]: Running the main loop
2019-03-27 12:23:45 info ucollect[12226]: Reconnecting to api.turris.cz:5679 now
2019-03-27 12:23:45 info ucollect[12226]: Socat started
2019-03-27 12:23:45 info ucollect[12115]: Removing plugin Badconf
2019-03-27 12:23:45 info ucollect[12115]: Unloading plugin library
2019-03-27 12:23:45 info ucollect[12115]: Removing plugin Bandwidth
2019-03-27 12:23:45 info ucollect[12115]: Unloading plugin library
2019-03-27 12:23:45 info ucollect[12115]: Removing plugin Count
2019-03-27 12:23:45 info ucollect[12115]: Unloading plugin library
2019-03-27 12:23:45 info ucollect[12115]: Removing plugin Fake
2019-03-27 12:23:45 info ucollect[12115]: Unloading plugin library
2019-03-27 12:23:45 info ucollect[12115]: Removing plugin Flow
2019-03-27 12:23:45 info ucollect[12115]: Unloading plugin library
2019-03-27 12:23:45 info ucollect[12115]: Removing plugin Fwup
2019-03-27 12:23:45 info ucollect[12115]: Unloading plugin library
2019-03-27 12:23:45 info ucollect[12115]: Removing plugin Refused
2019-03-27 12:23:45 info ucollect[12115]: Unloading plugin library
2019-03-27 12:23:45 info ucollect[12115]: Removing plugin Sniff
2019-03-27 12:23:45 info ucollect[12115]: Unloading plugin library
2019-03-27 12:23:45 info ucollect[12115]: Removing plugin Spoof
2019-03-27 12:23:45 info ucollect[12115]: Unloading plugin library
2019-03-27 12:23:45 err ucollect[12226]: Error from socat: 2019/03/27 12:23:45 socat[12230] E connect(5, AF=10 [2001:1488:ac15:ff80:0000:0000:0000:0101]:5679, 28): Permission denied
2019-03-27 12:23:45 warning ucollect[12226]: Remote closed the uplink api.turris.cz:5679, reconnecting
2019-03-27 12:23:45 warning ucollect[12226]: epoll_wait on 4 interrupted, retry
2019-03-27 12:23:45 info ucollect[12226]: Reconnecting to api.turris.cz:5679 now
2019-03-27 12:23:45 warning ucollect[12226]: Reconnecting too often, waiting a little while
2019-03-27 12:23:45 info ucollect[12226]: Going to reconnect to api.turris.cz:5679 after 2 seconds
2019-03-27 12:23:47 info ucollect[12226]: Reconnecting to api.turris.cz:5679 now
2019-03-27 12:23:47 info ucollect[12226]: Socat started
2019-03-27 12:23:48 info ucollect[12226]: Activating plugin Count
2019-03-27 12:23:48 info ucollect[12226]: Activating plugin Sniff
2019-03-27 12:23:48 info ucollect[12226]: Activating plugin Flow
2019-03-27 12:23:48 info ucollect[12226]: Activating plugin Refused
2019-03-27 12:23:48 info ucollect[12226]: Activating plugin Bandwidth
2019-03-27 12:23:48 info ucollect[12226]: Activating plugin Fake
2019-03-27 12:23:48 info ucollect[12226]: Activating plugin Fwup
2019-03-27 12:23:48 info ucollect[12226]: Activating plugin Spoof
2019-03-27 12:23:48 info ucollect[12226]: Received configuration 49828441 (max. 5000 flows, 1800000 ms timeout)
2019-03-27 12:23:48 info ucollect[12226]: Received Refused config version 3
2019-03-27 12:23:48 info ucollect[12226]: Fake configuration version 1
2019-03-27 12:23:48 info ucollect[12226]: FWUp config 48834208 with 2 sets
2019-03-27 12:23:48 warning ucollect[12226]: epoll_wait on 4 interrupted, retry
2019-03-27 12:23:48 info ucollect[12226]: Updating filter addresses from version 0 to version 51789493 (epoch 4)
2019-03-27 12:23:48 info ucollect[12226]: Updating ipset turris_100FA4E0_lb_a_4_X from version 0 to version 51789493 (epoch 4)
2019-03-27 12:23:49 warning ucollect[12226]: epoll_wait on 4 interrupted, retry
2019-03-27 12:23:58 warning ucollect[5240]: Last message 'epoll_wait on 4 inte' repeated 1 times, suppressed by syslog-ng on Omnia
2019-03-27 12:24:01 info /usr/sbin/cron[12245]: (root) CMD (/root/connCheck.sh > /dev/null 2>&1)
2019-03-27 12:24:01 info /usr/sbin/cron[12246]: (root) CMD (nethist_stats.lua)
2019-03-27 12:24:01 info /usr/sbin/cron[12247]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
2019-03-27 12:24:12 err foris-controller[5570]: WARNING:foris_controller_backends.data_collect:Failed to read file '/tmp/firewall-turris-status.txt'.
2019-03-27 12:24:16 err foris-controller[5240]: Last message 'WARNING:foris_contro' repeated 1 times, suppressed by syslog-ng on Omnia
2019-03-27 12:24:22 err foris-controller[5570]: WARNING:foris_controller_backends.data_collect:Failed to read file '/tmp/firewall-turris-status.txt'.
2019-03-27 12:24:27 err foris-controller[5240]: Last message 'WARNING:foris_contro' repeated 3 times, suppressed by syslog-ng on Omnia
2019-03-27 12:24:31 err foris-controller[5570]: WARNING:foris_controller_backends.data_collect:Failed to read file '/tmp/firewall-turris-status.txt'.
2019-03-27 12:24:36 err foris-controller[5240]: Last message 'WARNING:foris_contro' repeated 1 times, suppressed by syslog-ng on Omnia

Powered by LuCI 96366054565006474c39e02dca00c9d45dcb9e15 branch (git-18.328.59464-9636605) / OpenWrt omnia 15.05 r47055

Until now it has calmet to a normal state. It was Managed by self-restarts was about seven thruu two days.

image



#11

Solved! After update to 3.11.3 firewall logs sending did not work (reboot not solved it)


#13

Update went smooth on 2 Omnias :slight_smile: , everything works as expected although I see some errors related to ath10k in logs on system startup (EDIT: Looks similar to @JardaB’s output):

2019-03-27 11:23:29 notice syslog-ng[3629]: syslog-ng starting up; version='3.20.1'
2019-03-27 11:23:29 notice dnsmasq[]: Allowing 127.0.0.0/8 responses
2019-03-27 11:23:29 info kernel[]: [   15.362436] tun: (C) 1999-2004 Max Krasnyansky <maxk@qualcomm.com>
2019-03-27 11:23:29 info kernel[]: [   15.372623] sit: IPv6 over IPv4 tunneling driver
2019-03-27 11:23:29 info kernel[]: [   15.379307] gre: GRE over IPv4 demultiplexor driver
2019-03-27 11:23:29 info kernel[]: [   15.385022] ip_gre: GRE over IPv4 tunneling driver
2019-03-27 11:23:29 info kernel[]: [   15.404967] Installing knfsd (copyright (C) 1996 okir@monad.swb.de).
2019-03-27 11:23:29 info kernel[]: [   15.413750] PPP generic driver version 2.4.2
2019-03-27 11:23:29 info kernel[]: [   15.419674] ip6_tables: (C) 2000-2006 Netfilter Core Team
2019-03-27 11:23:29 info kernel[]: [   15.432238] nf_conntrack version 0.5.0 (16105 buckets, 64420 max)
2019-03-27 11:23:29 info kernel[]: [   16.153168] ip_set: protocol 6
2019-03-27 11:23:29 info kernel[]: [   19.232226] cryptodev: driver 1.8 loaded.
2019-03-27 11:23:29 info kernel[]: [   20.502372] Loading modules backported from Linux version wt-2017-01-31-0-ge882dff19e7f
2019-03-27 11:23:29 info kernel[]: [   20.510401] Backport generated by backports.git backports-20160324-13-g24da7d3c
2019-03-27 11:23:29 info kernel[]: [   20.922347] pci 0000:00:02.0: enabling device (0140 -> 0142)
2019-03-27 11:23:29 info kernel[]: [   20.928210] ath10k_pci 0000:02:00.0: pci irq msi oper_irq_mode 2 irq_mode 0 reset_mode 0
2019-03-27 11:23:29 notice kernel[]: [   21.103755] random: nonblocking pool is initialized
2019-03-27 11:23:29 warning kernel[]: [   21.108804] ath10k_pci 0000:02:00.0: Direct firmware load for ath10k/pre-cal-pci-0000:02:00.0.bin failed with error -2
2019-03-27 11:23:29 warning kernel[]: [   21.119536] ath10k_pci 0000:02:00.0: Falling back to user helper
2019-03-27 11:23:29 err kernel[]: [   21.129607] firmware ath10k!pre-cal-pci-0000:02:00.0.bin: firmware_loading_store: map pages failed
2019-03-27 11:23:29 warning kernel[]: [   21.138722] ath10k_pci 0000:02:00.0: Direct firmware load for ath10k/cal-pci-0000:02:00.0.bin failed with error -2
2019-03-27 11:23:29 warning kernel[]: [   21.149112] ath10k_pci 0000:02:00.0: Falling back to user helper
2019-03-27 11:23:29 err kernel[]: [   21.158991] firmware ath10k!cal-pci-0000:02:00.0.bin: firmware_loading_store: map pages failed
2019-03-27 11:23:29 info kernel[]: [   21.176199] ath10k_pci 0000:02:00.0: qca988x hw2.0 target 0x4100016c chip_id 0x043202ff sub 0000:0000
2019-03-27 11:23:29 info kernel[]: [   21.185462] ath10k_pci 0000:02:00.0: kconfig debug 0 debugfs 1 tracing 0 dfs 1 testmode 1
2019-03-27 11:23:29 info kernel[]: [   21.195076] ath10k_pci 0000:02:00.0: firmware ver 10.2.4-1.0-00041 api 5 features no-p2p,raw-mode,mfp,allows-mesh-bcast crc32 f43fa422
2019-03-27 11:23:29 warning kernel[]: [   21.239801] ath10k_pci 0000:02:00.0: board id is not exist in otp, ignore it
2019-03-27 11:23:29 warning kernel[]: [   21.246915] ath10k_pci 0000:02:00.0: Direct firmware load for ath10k/QCA988X/hw2.0/board-2.bin failed with error -2
2019-03-27 11:23:29 warning kernel[]: [   21.257378] ath10k_pci 0000:02:00.0: Falling back to user helper
2019-03-27 11:23:29 err kernel[]: [   21.267461] firmware ath10k!QCA988X!hw2.0!board-2.bin: firmware_loading_store: map pages failed
2019-03-27 11:23:29 info kernel[]: [   21.277117] ath10k_pci 0000:02:00.0: board_file api 1 bmi_id N/A crc32 bebc7c08
2019-03-27 11:23:29 info kernel[]: [   22.413073] ath10k_pci 0000:02:00.0: htt-ver 2.1 wmi-op 5 htt-op 2 cal otp max-sta 128 raw 0 hwcrypto 1
2019-03-27 11:23:29 debug kernel[]: [   22.502351] ath: EEPROM regdomain: 0x0
2019-03-27 11:23:29 debug kernel[]: [   22.502356] ath: EEPROM indicates default country code should be used
2019-03-27 11:23:29 debug kernel[]: [   22.502359] ath: doing EEPROM country->regdmn map search
2019-03-27 11:23:29 debug kernel[]: [   22.502364] ath: country maps to regdmn code: 0x3a
2019-03-27 11:23:29 debug kernel[]: [   22.502367] ath: Country alpha2 being used: US
2019-03-27 11:23:29 debug kernel[]: [   22.502369] ath: Regpair used: 0x3a
2019-03-27 11:23:29 info kernel[]: [   23.731380] u32 classifier
2019-03-27 11:23:29 info kernel[]: [   23.734095]     input device check on
2019-03-27 11:23:29 info kernel[]: [   23.737761]     Actions configured
2019-03-27 11:23:29 info kernel[]: [   24.111341] Mirror/redirect action on
2019-03-27 11:23:29 info kernel[]: [   24.831316] GACT probability on
2019-03-27 11:23:29 info kernel[]: [   27.831382] netem: version 1.3
2019-03-27 11:23:29 info kernel[]: [   29.024276] fuse init (API version 7.23)
2019-03-27 11:23:29 info kernel[]: [   29.212390] usbcore: registered new interface driver cdc_wdm
2019-03-27 11:23:29 info kernel[]: [   29.221844] Ebtables v2.0 registered
2019-03-27 11:23:29 info kernel[]: [   29.227346] ip_tables: (C) 2000-2006 Netfilter Core Team
2019-03-27 11:23:29 info kernel[]: [   29.314766] ctnetlink v0.93: registering with nfnetlink.
2019-03-27 11:23:29 info kernel[]: [   29.504862] nfnl_acct: registering with nfnetlink.
2019-03-27 11:23:29 info kernel[]: [   29.510547] PPP MPPE Compression module registered
2019-03-27 11:23:29 info kernel[]: [   29.515898] NET: Registered protocol family 24
2019-03-27 11:23:29 info kernel[]: [   29.523290] usbcore: registered new interface driver ums-alauda
2019-03-27 11:23:29 info kernel[]: [   29.529530] usbcore: registered new interface driver ums-cypress
2019-03-27 11:23:29 info kernel[]: [   29.535893] usbcore: registered new interface driver ums-datafab
2019-03-27 11:23:29 info kernel[]: [   29.542230] usbcore: registered new interface driver ums-freecom
2019-03-27 11:23:29 info kernel[]: [   29.548584] usbcore: registered new interface driver ums-isd200
2019-03-27 11:23:29 info kernel[]: [   29.554841] usbcore: registered new interface driver ums-jumpshot
2019-03-27 11:23:29 info kernel[]: [   29.561270] usbcore: registered new interface driver ums-karma
2019-03-27 11:23:29 info kernel[]: [   29.567488] usbcore: registered new interface driver ums-sddr09
2019-03-27 11:23:29 info kernel[]: [   29.573851] usbcore: registered new interface driver ums-sddr55
2019-03-27 11:23:29 info kernel[]: [   29.580134] usbcore: registered new interface driver ums-usbat
2019-03-27 11:23:29 info kernel[]: [   29.590647] usbcore: registered new interface driver usbserial
2019-03-27 11:23:29 info kernel[]: [   29.596543] usbcore: registered new interface driver usbserial_generic
2019-03-27 11:23:29 info kernel[]: [   29.603116] usbserial: USB Serial support registered for generic
2019-03-27 11:23:29 info kernel[]: [   29.621749] xt_time: kernel timezone is -0000
2019-03-27 11:23:29 info kernel[]: [   29.639717] usbcore: registered new interface driver cdc_ether
2019-03-27 11:23:29 info kernel[]: [   29.655218] usbcore: registered new interface driver qmi_wwan
2019-03-27 11:23:29 info kernel[]: [   29.665872] usbcore: registered new interface driver rndis_host
2019-03-27 11:23:29 warning kernel[]: [   29.675594] vmap allocation for size 102400 failed: use vmalloc=<size> to increase size.
2019-03-27 11:23:29 warning kernel[]: [   29.683731] vmalloc: allocation failure: 95849 bytes
2019-03-27 11:23:29 warning kernel[]: [   29.688707] kmodloader: page allocation failure: order:0, mode:0x24000c0
2019-03-27 11:23:29 warning kernel[]: [   29.695433] CPU: 1 PID: 1144 Comm: kmodloader Not tainted 4.4.176-7bc33afbb1b35f5830b2b1b42c9cd8a0-0 #1
2019-03-27 11:23:29 warning kernel[]: [   29.704848] Hardware name: Marvell Armada 380/385 (Device Tree)
2019-03-27 11:23:29 warning kernel[]: [   29.710778] Backtrace: 
2019-03-27 11:23:29 warning kernel[]: [   29.713245] [<c001c50c>] (dump_backtrace) from [<c001c74c>] (show_stack+0x18/0x1c)
2019-03-27 11:23:29 warning kernel[]: [   29.720830]  r6:00000000 r5:60000013 r4:c0770d68 r3:00000000
2019-03-27 11:23:29 warning kernel[]: [   29.726548] [<c001c734>] (show_stack) from [<c02ff974>] (dump_stack+0x98/0xac)
2019-03-27 11:23:29 warning kernel[]: [   29.733790] [<c02ff8dc>] (dump_stack) from [<c00af9d8>] (warn_alloc_failed+0xec/0x120)
2019-03-27 11:23:29 warning kernel[]: [   29.741722]  r6:00000001 r5:00000000 r4:024000c0 r3:c0769460
2019-03-27 11:23:29 warning kernel[]: [   29.747438] [<c00af8f0>] (warn_alloc_failed) from [<c00dc130>] (__vmalloc_node_range+0x170/0x214)
2019-03-27 11:23:29 warning kernel[]: [   29.756327]  r3:00017669 r2:c06550b4
2019-03-27 11:23:29 warning kernel[]: [   29.759926]  r6:c008f880 r5:00000000 r4:00017669
2019-03-27 11:23:29 warning kernel[]: [   29.764584] [<c00dbfc0>] (__vmalloc_node_range) from [<c001d0b0>] (module_alloc+0x5c/0xbc)
2019-03-27 11:23:29 warning kernel[]: [   29.772865]  r10:f08f14c0 r9:f08dc000 r8:00017669 r7:ffffffff r6:c008f880 r5:024000c0
2019-03-27 11:23:29 warning kernel[]: [   29.780762]  r4:c078ef1c
2019-03-27 11:23:29 warning kernel[]: [   29.783313] [<c001d054>] (module_alloc) from [<c008f880>] (load_module+0x7b4/0x1d1c)
2019-03-27 11:23:29 warning kernel[]: [   29.791072]  r8:00017669 r7:00000aa0 r6:f08f1ba4 r5:000002ea r4:ed63ff34
2019-03-27 11:23:29 warning kernel[]: [   29.797840] [<c008f0cc>] (load_module) from [<c0090ec4>] (SyS_init_module+0xdc/0x14c)
2019-03-27 11:23:29 warning kernel[]: [   29.805685]  r10:00000051 r9:0001224b r8:ed63e000 r7:f08fc0ac r6:b66ff0bc r5:00000000
2019-03-27 11:23:29 warning kernel[]: [   29.813581]  r4:000000ac
2019-03-27 11:23:29 warning kernel[]: [   29.816130] [<c0090de8>] (SyS_init_module) from [<c0009d00>] (ret_fast_syscall+0x0/0x48)
2019-03-27 11:23:29 warning kernel[]: [   29.824237]  r10:00000000 r9:ed63e000 r8:c0009ec4 r7:00000080 r6:00000000 r5:00000000
2019-03-27 11:23:29 warning kernel[]: [   29.832134]  r4:00000000
2019-03-27 11:23:29 warning kernel[]: [   29.834688] Mem-Info:
2019-03-27 11:23:29 warning kernel[]: [   29.836971] active_anon:149 inactive_anon:2 isolated_anon:0
2019-03-27 11:23:29 warning kernel[]: [   29.836971]  active_file:4553 inactive_file:1235 isolated_file:0
2019-03-27 11:23:29 warning kernel[]: [   29.836971]  unevictable:0 dirty:1 writeback:0 unstable:0
2019-03-27 11:23:29 warning kernel[]: [   29.836971]  slab_reclaimable:720 slab_unreclaimable:1654
2019-03-27 11:23:29 warning kernel[]: [   29.836971]  mapped:1838 shmem:2 pagetables:22 bounce:0
2019-03-27 11:23:29 warning kernel[]: [   29.836971]  free:244136 free_pcp:402 free_cma:0
2019-03-27 11:23:29 warning kernel[]: [   29.869207] Normal free:721360kB min:3504kB low:4380kB high:5256kB active_anon:376kB inactive_anon:0kB active_file:15260kB inactive_file:3656kB unevictable:0kB isolated(anon):0kB isolated(file):0kB present:786432kB managed:768588kB mlocked:0kB dirty:4kB writeback:0kB mapped:4748kB shmem:0kB slab_reclaimable:2880kB slab_unreclaimable:6616kB kernel_stack:1112kB pagetables:88kB unstable:0kB bounce:0kB free_pcp:1176kB local_pcp:520kB free_cma:0kB writeback_tmp:0kB pages_scanned:0 all_unreclaimable? no
2019-03-27 11:23:29 warning kernel[]: [   29.913352] lowmem_reserve[]: 0 2048 2048
2019-03-27 11:23:29 warning kernel[]: [   29.917417] HighMem free:255184kB min:256kB low:552kB high:852kB active_anon:220kB inactive_anon:8kB active_file:2952kB inactive_file:1284kB unevictable:0kB isolated(anon):0kB isolated(file):0kB present:262144kB managed:262144kB mlocked:0kB dirty:0kB writeback:0kB mapped:2604kB shmem:8kB slab_reclaimable:0kB slab_unreclaimable:0kB kernel_stack:0kB pagetables:0kB unstable:0kB bounce:0kB free_pcp:432kB local_pcp:172kB free_cma:0kB writeback_tmp:0kB pages_scanned:0 all_unreclaimable? no
2019-03-27 11:23:29 warning kernel[]: [   29.960343] lowmem_reserve[]: 0 0 0
2019-03-27 11:23:29 warning kernel[]: [   29.963881] Normal: 2*4kB (ME) 7*8kB (UM) 8*16kB (UM) 4*32kB (U) 2*64kB (UM) 0*128kB 0*256kB 2*512kB (UM) 3*1024kB (UME) 0*2048kB 175*4096kB (M) = 721344kB
2019-03-27 11:23:29 warning kernel[]: [   29.978028] HighMem: 2*4kB (UM) 1*8kB (U) 0*16kB 0*32kB 1*64kB (M) 1*128kB (M) 2*256kB (UM) 1*512kB (U) 2*1024kB (UM) 1*2048kB (M) 61*4096kB (M) = 255184kB
2019-03-27 11:23:29 warning kernel[]: [   29.992185] 5803 total pagecache pages
2019-03-27 11:23:29 warning kernel[]: [   29.995941] 0 pages in swap cache
2019-03-27 11:23:29 warning kernel[]: [   29.999261] Swap cache stats: add 0, delete 0, find 0/0
2019-03-27 11:23:29 warning kernel[]: [   30.004500] Free swap  = 0kB
2019-03-27 11:23:29 warning kernel[]: [   30.007384] Total swap = 0kB
2019-03-27 11:23:29 warning kernel[]: [   30.010268] 262144 pages RAM
2019-03-27 11:23:29 warning kernel[]: [   30.013156] 65536 pages HighMem/MovableOnly
2019-03-27 11:23:29 warning kernel[]: [   30.017345] 4461 pages reserved
2019-03-27 11:23:29 info kernel[]: [   30.024635] pci 0000:00:03.0: enabling device (0140 -> 0142)
2019-03-27 11:23:29 debug kernel[]: [   30.117062] ath: EEPROM regdomain: 0x0
2019-03-27 11:23:29 debug kernel[]: [   30.117067] ath: EEPROM indicates default country code should be used
2019-03-27 11:23:29 debug kernel[]: [   30.117070] ath: doing EEPROM country->regdmn map search
2019-03-27 11:23:29 debug kernel[]: [   30.117075] ath: country maps to regdmn code: 0x3a
2019-03-27 11:23:29 debug kernel[]: [   30.117078] ath: Country alpha2 being used: US
2019-03-27 11:23:29 debug kernel[]: [   30.117080] ath: Regpair used: 0x3a
2019-03-27 11:23:29 debug kernel[]: [   30.128824] ieee80211 phy1: Selected rate control algorithm 'minstrel_ht'
2019-03-27 11:23:29 info kernel[]: [   30.130120] ieee80211 phy1: Atheros AR9287 Rev:2 mem=0xf0920000, irq=109
2019-03-27 11:23:29 info kernel[]: [   30.138990] usbcore: registered new interface driver option
2019-03-27 11:23:29 info kernel[]: [   30.144642] usbserial: USB Serial support registered for GSM modem (1-port)
2019-03-27 11:23:29 info kernel[]: [   30.152147] usbcore: registered new interface driver qcserial
2019-03-27 11:23:29 info kernel[]: [   30.157941] usbserial: USB Serial support registered for Qualcomm USB modem
2019-03-27 11:23:29 info kernel[]: [   35.894621] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
2019-03-27 11:23:29 info kernel[]: [   35.972731] IPv6: ADDRCONF(NETDEV_UP): eth1: link is not ready
2019-03-27 11:23:29 info kernel[]: [   35.976246] IPv6: ADDRCONF(NETDEV_UP): eth2: link is not ready
2019-03-27 11:23:29 info kernel[]: [   37.891104] mvneta f1030000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off
2019-03-27 11:23:29 info kernel[]: [   37.891132] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
2019-03-27 11:23:29 info kernel[]: [   37.971188] mvneta f1070000.ethernet eth2: Link is Up - 1Gbps/Full - flow control off
2019-03-27 11:23:29 info kernel[]: [   37.971209] IPv6: ADDRCONF(NETDEV_CHANGE): eth2: link becomes ready
2019-03-27 11:23:29 info kernel[]: [   39.068559] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
2019-03-27 11:23:29 info kernel[]: [   39.071101] device eth0.3 entered promiscuous mode
2019-03-27 11:23:29 info kernel[]: [   39.071108] device eth0 entered promiscuous mode
2019-03-27 11:23:29 info kernel[]: [   39.072842] IPv6: ADDRCONF(NETDEV_UP): br-dmz: link is not ready
2019-03-27 11:23:29 info kernel[]: [   39.077335] IPv6: ADDRCONF(NETDEV_UP): br-guest_turris: link is not ready
2019-03-27 11:23:29 info kernel[]: [   39.101292] device eth0.4 entered promiscuous mode
2019-03-27 11:23:29 info kernel[]: [   39.102501] IPv6: ADDRCONF(NETDEV_UP): br-iot: link is not ready
2019-03-27 11:23:29 info kernel[]: [   39.117432] device eth0.1 entered promiscuous mode
2019-03-27 11:23:29 info kernel[]: [   39.118558] IPv6: ADDRCONF(NETDEV_UP): br-lan: link is not ready
2019-03-27 11:23:29 info kernel[]: [   39.203746] device eth1 entered promiscuous mode
2019-03-27 11:23:29 info kernel[]: [   39.223323] device eth2 entered promiscuous mode
2019-03-27 11:23:29 info kernel[]: [   39.223366] br-lan: port 3(eth2) entered forwarding state
2019-03-27 11:23:29 info kernel[]: [   39.223383] br-lan: port 3(eth2) entered forwarding state
2019-03-27 11:23:29 info kernel[]: [   39.223552] IPv6: ADDRCONF(NETDEV_CHANGE): br-lan: link becomes ready
2019-03-27 11:23:29 info kernel[]: [   39.227790] IPv6: ADDRCONF(NETDEV_UP): eth1.5: link is not ready
2019-03-27 11:23:29 debug kernel[]: [   39.582014] ath: EEPROM regdomain: 0x8114
2019-03-27 11:23:29 debug kernel[]: [   39.582022] ath: EEPROM indicates we should expect a country code
2019-03-27 11:23:29 debug kernel[]: [   39.582025] ath: doing EEPROM country->regdmn map search
2019-03-27 11:23:29 debug kernel[]: [   39.582028] ath: country maps to regdmn code: 0x37
2019-03-27 11:23:29 debug kernel[]: [   39.582031] ath: Country alpha2 being used: DE
2019-03-27 11:23:29 debug kernel[]: [   39.582033] ath: Regpair used: 0x37
2019-03-27 11:23:29 debug kernel[]: [   39.582037] ath: regdomain 0x8114 dynamically updated by user
2019-03-27 11:23:29 debug kernel[]: [   39.582085] ath: EEPROM regdomain: 0x8114
2019-03-27 11:23:29 debug kernel[]: [   39.582088] ath: EEPROM indicates we should expect a country code
2019-03-27 11:23:29 debug kernel[]: [   39.582090] ath: doing EEPROM country->regdmn map search
2019-03-27 11:23:29 debug kernel[]: [   39.582093] ath: country maps to regdmn code: 0x37
2019-03-27 11:23:29 debug kernel[]: [   39.582095] ath: Country alpha2 being used: DE
2019-03-27 11:23:29 debug kernel[]: [   39.582097] ath: Regpair used: 0x37
2019-03-27 11:23:29 debug kernel[]: [   39.582100] ath: regdomain 0x8114 dynamically updated by user
2019-03-27 11:23:29 info kernel[]: [   40.061198] br-lan: port 3(eth2) entered disabled state
2019-03-27 11:23:29 info kernel[]: [   40.714341] device guest_turris_1 entered promiscuous mode
2019-03-27 11:23:29 info kernel[]: [   40.714401] br-guest_turris: port 1(guest_turris_1) entered forwarding state
2019-03-27 11:23:29 info kernel[]: [   40.714422] br-guest_turris: port 1(guest_turris_1) entered forwarding state
2019-03-27 11:23:29 info kernel[]: [   40.714521] IPv6: ADDRCONF(NETDEV_CHANGE): br-guest_turris: link becomes ready
2019-03-27 11:23:29 info kernel[]: [   41.065175] mvneta f1030000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off
2019-03-27 11:23:29 info kernel[]: [   41.221067] mvneta f1070000.ethernet eth2: Link is Up - 1Gbps/Full - flow control off
2019-03-27 11:23:29 info kernel[]: [   42.113953] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
2019-03-27 11:23:29 info kernel[]: [   42.114393] br-guest_turris: port 1(guest_turris_1) entered disabled state
2019-03-27 11:23:29 info kernel[]: [   42.114447] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
2019-03-27 11:23:29 info kernel[]: [   42.114595] br-lan: port 3(eth2) entered forwarding state
2019-03-27 11:23:29 info kernel[]: [   42.114608] br-lan: port 3(eth2) entered forwarding state
2019-03-27 11:23:29 info kernel[]: [   42.114999] br-lan: port 1(eth0.1) entered forwarding state
2019-03-27 11:23:29 info kernel[]: [   42.115012] br-lan: port 1(eth0.1) entered forwarding state
2019-03-27 11:23:29 info kernel[]: [   42.115044] br-dmz: port 1(eth0.3) entered forwarding state
2019-03-27 11:23:29 info kernel[]: [   42.115055] br-dmz: port 1(eth0.3) entered forwarding state
2019-03-27 11:23:29 info kernel[]: [   42.115083] br-iot: port 1(eth0.4) entered forwarding state
2019-03-27 11:23:29 info kernel[]: [   42.115096] br-iot: port 1(eth0.4) entered forwarding state
2019-03-27 11:23:29 info kernel[]: [   42.115144] IPv6: ADDRCONF(NETDEV_CHANGE): br-dmz: link becomes ready
2019-03-27 11:23:29 info kernel[]: [   42.115193] IPv6: ADDRCONF(NETDEV_CHANGE): br-iot: link becomes ready
2019-03-27 11:23:29 info kernel[]: [   42.125670] br-guest_turris: port 1(guest_turris_1) entered disabled state
2019-03-27 11:23:29 info kernel[]: [   42.129967] device wlan0 entered promiscuous mode
2019-03-27 11:23:29 info kernel[]: [   42.543861] device guest_turris_0 entered promiscuous mode
2019-03-27 11:23:29 info kernel[]: [   42.545263] br-guest_turris: port 2(guest_turris_0) entered forwarding state
2019-03-27 11:23:29 info kernel[]: [   42.545283] br-guest_turris: port 2(guest_turris_0) entered forwarding state
2019-03-27 11:23:29 info kernel[]: [   42.547677] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
2019-03-27 11:23:29 info kernel[]: [   42.547764] br-lan: port 4(wlan0) entered forwarding state
2019-03-27 11:23:29 info kernel[]: [   42.547790] br-lan: port 4(wlan0) entered forwarding state
2019-03-27 11:23:29 info kernel[]: [   43.202624] mvneta f1034000.ethernet eth1: Link is Up - 1Gbps/Full - flow control off
2019-03-27 11:23:29 info kernel[]: [   43.202659] br-lan: port 2(eth1) entered forwarding state
2019-03-27 11:23:29 info kernel[]: [   43.202699] br-lan: port 2(eth1) entered forwarding state
2019-03-27 11:23:29 info kernel[]: [   43.202817] IPv6: ADDRCONF(NETDEV_CHANGE): eth1.5: link becomes ready
2019-03-27 11:23:29 info kernel[]: [   43.820119] br-guest_turris: port 1(guest_turris_1) entered forwarding state
2019-03-27 11:23:29 info kernel[]: [   43.820162] br-guest_turris: port 1(guest_turris_1) entered forwarding state
2019-03-27 11:23:29 info kernel[]: [   43.846662] device vethU6WHPR entered promiscuous mode
2019-03-27 11:23:29 info kernel[]: [   43.847169] IPv6: ADDRCONF(NETDEV_UP): vethU6WHPR: link is not ready
2019-03-27 11:23:29 info kernel[]: [   43.847180] br-lan: port 5(vethU6WHPR) entered forwarding state
2019-03-27 11:23:29 info kernel[]: [   43.847205] br-lan: port 5(vethU6WHPR) entered forwarding state
2019-03-27 11:23:29 info kernel[]: [   43.863937] device wlan1-1 entered promiscuous mode
2019-03-27 11:23:29 info kernel[]: [   43.864334] IPv6: ADDRCONF(NETDEV_UP): wlan1-1: link is not ready
2019-03-27 11:23:29 info kernel[]: [   43.864342] br-iot: port 2(wlan1-1) entered forwarding state
2019-03-27 11:23:29 info kernel[]: [   43.864362] br-iot: port 2(wlan1-1) entered forwarding state
2019-03-27 11:23:29 info kernel[]: [   43.963735] eth0: renamed from vethN5VD7K
2019-03-27 11:23:29 info kernel[]: [   44.002892] IPv6: ADDRCONF(NETDEV_CHANGE): vethU6WHPR: link becomes ready
2019-03-27 11:23:29 info kernel[]: [   44.030311] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1-1: link becomes ready
2019-03-27 11:23:29 info kernel[]: [   44.111135] br-iot: port 1(eth0.4) entered forwarding state
2019-03-27 11:23:29 info kernel[]: [   44.111223] br-dmz: port 1(eth0.3) entered forwarding state
2019-03-27 11:23:29 info kernel[]: [   44.111280] br-lan: port 1(eth0.1) entered forwarding state
2019-03-27 11:23:29 info kernel[]: [   44.111329] br-lan: port 3(eth2) entered forwarding state
2019-03-27 11:23:29 info kernel[]: [   44.541080] br-lan: port 4(wlan0) entered forwarding state
2019-03-27 11:23:29 info kernel[]: [   44.541169] br-guest_turris: port 2(guest_turris_0) entered forwarding state

EDIT: And this (Some errors regarding contract status and getting registration code):

2019-03-27 12:00:02 err server_uplink[]: Failed to download contract status
2019-03-27 12:00:03 info nikola[]: (v43.1) recognized WAN interfaces: eth1.5, lo
2019-03-27 12:00:40 info nikola[]: (v43.1) Establishing connection took 0.541031 seconds
2019-03-27 12:00:40 info nikola[]: (v43.1) Logrotate took 0.004464 seconds
2019-03-27 12:00:40 info nikola[]: (v43.1) Syslog parsing took 0.029609 seconds
2019-03-27 12:00:41 info nikola[]: (v43.1) Session init took 0.579619 seconds
2019-03-27 12:00:41 info nikola[]: (v43.1) Records parsed: 21
2019-03-27 12:00:41 info nikola[]: (v43.1) Records after filtering: 21
2019-03-27 12:00:41 info nikola[]: (v43.1) Records filtering took 0.012787 seconds
2019-03-27 12:00:41 info nikola[]: (v43.1) {'msg': 'Data were inserted into work queue.'}
2019-03-27 12:00:41 info nikola[]: (v43.1) Sending records took 0.026850 seconds
2019-03-27 12:00:47 info hostapd[]: wlan1-1: STA XXX group key handshake completed (RSN)
2019-03-27 12:01:01 info /usr/sbin/cron[10262]: (root) CMD ((cat /tmp/nethist.stats | sort ; echo "uptime = $(cat /proc/uptime | cut -d. -f1)" ) | logger -p info -t nethist ; rm /tmp/nethist.stats)
2019-03-27 12:01:01 info /usr/sbin/cron[10261]: (root) CMD (/usr/bin/rainbow_button_sync.sh)
2019-03-27 12:01:01 info nethist[]: fs_avg = 108122440.91429
2019-03-27 12:01:01 info nethist[]: fs_max = 108185616
2019-03-27 12:01:01 info nethist[]: fs_min = 108121928
2019-03-27 12:01:01 info nethist[]: fs_samples = 140
2019-03-27 12:01:01 info nethist[]: fs_sum = 15137141728
2019-03-27 12:01:01 info nethist[]: last_timestamp = 1553684387
2019-03-27 12:01:01 info nethist[]: load_avg = 0.23071428571429
2019-03-27 12:01:01 info nethist[]: load_max = 2.13
2019-03-27 12:01:01 info nethist[]: load_min = 0
2019-03-27 12:01:01 info nethist[]: load_samples = 140
2019-03-27 12:01:01 info nethist[]: load_sum = 32.3
2019-03-27 12:01:01 info nethist[]: mem_buffers_avg = 741335.62857143
2019-03-27 12:01:01 info nethist[]: mem_buffers_max = 775368
2019-03-27 12:01:01 info nethist[]: mem_buffers_min = 720808
2019-03-27 12:01:01 info nethist[]: mem_buffers_samples = 140
2019-03-27 12:01:01 info nethist[]: mem_buffers_sum = 103786988
2019-03-27 12:01:01 info nethist[]: mem_cached_avg = 12191.085714286
2019-03-27 12:01:01 info nethist[]: mem_cached_max = 12844
2019-03-27 12:01:01 info nethist[]: mem_cached_min = 9208
2019-03-27 12:01:01 info nethist[]: mem_cached_samples = 140
2019-03-27 12:01:01 info nethist[]: mem_cached_sum = 1706752
2019-03-27 12:01:01 info nethist[]: mem_free_avg = 451782.45714286
2019-03-27 12:01:01 info nethist[]: mem_free_max = 677020
2019-03-27 12:01:01 info nethist[]: mem_free_min = 429724
2019-03-27 12:01:01 info nethist[]: mem_free_samples = 140
2019-03-27 12:01:01 info nethist[]: mem_free_sum = 63249544
2019-03-27 12:01:01 info nethist[]: mem_total_avg = 1030732
2019-03-27 12:01:01 info nethist[]: mem_total_max = 1030732
2019-03-27 12:01:01 info nethist[]: mem_total_min = 1030732
2019-03-27 12:01:01 info nethist[]: mem_total_samples = 140
2019-03-27 12:01:01 info nethist[]: mem_total_sum = 144302480
2019-03-27 12:01:01 info nethist[]: temp_board_avg = nan
2019-03-27 12:01:01 info nethist[]: temp_board_max = 0
2019-03-27 12:01:01 info nethist[]: temp_board_samples = 0
2019-03-27 12:01:01 info nethist[]: temp_board_sum = 0
2019-03-27 12:01:01 info nethist[]: temp_cpu_avg = nan
2019-03-27 12:01:01 info nethist[]: temp_cpu_max = 0
2019-03-27 12:01:01 info nethist[]: temp_cpu_samples = 0
2019-03-27 12:01:01 info nethist[]: temp_cpu_sum = 0
2019-03-27 12:01:01 info nethist[]: uptime = 2295
2019-03-27 12:01:01 err server_uplink[]: Failed to get registration code

Sending firewall logs works fine here…


#14

mysql not work with php


#15

First, thank you guys for great work. Installation was flawless now. I found only minor warning so far

2019-03-27 07:06:31 notice stunnel[]: LOG5[ui]: Compiled with OpenSSL 1.0.2o 27 Mar 2018
2019-03-27 07:06:31 notice stunnel[]: LOG5[ui]: Running with OpenSSL 1.0.2r 26 Feb 2019

I will try recompile stunnel package backporting from upstream by myself tonight if my debian vagrant toolchain is not damaged by LXC upgrade.


#16

updated 2 omnias all went smooth, everything works without any issues


#17

Also, not sure why, but the 5G wifi issues seems to be gone.


#18

I’ve the same issue. Did not tried second restart yet.

turris ~ # nikola 
Traceback (most recent call last):
  File "/usr/lib/python3.6/logging/handlers.py", line 835, in _connect_unixsocket
    self.socket.connect(address)
FileNotFoundError: [Errno 2] No such file or directory

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/usr/bin/nikola", line 11, in <module>
    load_entry_point('nikola==43.1', 'console_scripts', 'nikola')()
  File "/usr/lib/python3.6/site-packages/nikola/__main__.py", line 139, in main
    logger = get_logger(options.debug)
  File "/usr/lib/python3.6/site-packages/nikola/logger.py", line 32, in get_logger
    sys_handler = logging.handlers.SysLogHandler(address="/dev/log")
  File "/usr/lib/python3.6/logging/handlers.py", line 818, in __init__
    self._connect_unixsocket(address)
  File "/usr/lib/python3.6/logging/handlers.py", line 846, in _connect_unixsocket
    self.socket.connect(address)
FileNotFoundError: [Errno 2] No such file or directory

Update: after restart - no change. Nikola does not work, firewall logs are not sent.

obrazek

There is no “/dev/log”

turris ~ # ls -la /dev/log
ls: /dev/log: No such file or directory

#19

Did you make any changes to the file /etc/syslog-ng.conf ?
If you have the file /etc/syslog-ng.conf-opkg change its name to /etc/syslog-ng.conf and run syslog /etc/init.d/syslog-ng restart


Turris 3.11.3 and syslog-ng
#20

Thanks, that’s it.

turris ~ # ls -la /etc/syslog-ng*
-rw-r--r--    1 root     root           863 Sep  7  2018 /etc/syslog-ng.conf
-rw-r--r--    1 root     root          1994 Mar 20 00:06 /etc/syslog-ng.conf-opkg

After renaming and syslog-ng restart it works now.

I’m not sure, if I did any changes there. Diff does not show any difference that I recognize.


#21

Well. Seems to works, but is this correct?
No WAN device was set

turris ~ # nikola
recognized WAN interfaces: 
Establishing connection took 0.626054 seconds
No WAN device was set. No data will be send to the server!
Session init took 0.640514 seconds
Records parsed: 0
Records after filtering: 0
Records filtering took 0.001307 seconds
{'msg': 'Server was notified that client has no data to send.'}
Sending records took 0.018552 seconds

#23

Aha. I know. This was a temporary solution for this issue:

2019-03-28 20:32:59 crit mountd[6979]: could not find a serial number for the device sdb

This was September 2018 - still not fixed :frowning:


#24

My openvpn issue posted a while back has been resolved as well. The only remaining issue is that the openvpn service isn’t default enabled after installation. But this could just as well be related to the medkit I used (last modified date 2019-03-20). In what project in Gitlab should I enter an issue for that?


#25

Guys, could we keep this thread clear and understandable to others, please? There are already 24 posts and if somebody has an issue, it doesn’t help when someone posts here 4 posts about the same issue or that he solved it within 2 days. If you do please share your solution or workaround in your post In that case, it’s better to use the EDIT button. I have noticed that somebody had some issue and he solved without writing the solution. If somebody wants to put here some output or even a snip, please use [code][/code]

To summarize it:

@nijel: Since yesterday @cynerd is in touch with you.

@JardaB: When you receive Foris error, don’t put it here and follow the instructions, which are there - short description of the steps which leads to that error together with that error to tech.support@turris.cz.

@karelf: That’s interesting finding! Would you please send us diagnostics, which you generate in Foris and send them to tech.support@turris.cz? So we can look what’s wrong.

@protree: This is not related to this release, however logs from ath10k says that it couldn’t find firmware files and fallbacking to other firmware files and in the end it found the firmware.

@Twinkie: It should be harmless, if not, we’ll look into it.

@FraX: If you’re not sure in which repository you should create the issue, I suggest to reach our support, which is available on email address tech.support@turris.cz and we will do it for you.