ORxPi2 2.0.00 issues

I have a brand new Pi2, right out of the box, with a 16G SD card. Here are the issues I have seen so far:

  1. Pi will not boot with Tuner plugged in. I am using one of the Outernet HDStar Tuners, purchased from the store. I believe there are a couple of hardware versions? Do you need details on the hardware?

If I leave the Pi to boot wit no tuner, it boots fine, and has “NO TUNER” shown as expected. When I plug the tuner in (leaving the Pi powered on), and set the tuner to Galaxy, N.A. LNB, I get a signal lock and good download speed.

  1. Files not being sent to Library. I saw some files being downloaded on the settings/tuner page, but they all had “(skipping)” after the file name. I also see that the “files” section shows an error “error Code 500”. The library doesn’t seem to be updating, presumably since all files are marked as skipped.

Let me know if you would like any more info on the system, or if you want me to try something.

Here is what comes up in the files section of librarian:

Traceback (most recent call last):
File “/usr/bin/bottle.py”, line 862, in _handle
return route.call(**args)
File “/usr/lib/python2.7/site-packages/librarian_core/contrib/databases/plugins.py”, line 11, in wrapper
return callback(*args, **kwargs)
File “/usr/lib/python2.7/site-packages/librarian_core/contrib/sessions/plugins.py”, line 37, in wrapper
return callback(*args, **kwargs)
File “/usr/lib/python2.7/site-packages/librarian_core/contrib/auth/plugins.py”, line 32, in wrapper
return callback(*args, **kwargs)
File “/usr/lib/python2.7/site-packages/bottle_utils/i18n.py”, line 387, in wrapper
return callback(*args, **kwargs)
File “/usr/bin/bottle.py”, line 1729, in wrapper
rv = callback(*a, **ka)
File “/usr/lib/python2.7/site-packages/librarian_setup/plugins.py”, line 25, in wrapper
return callback(*args, **kwargs)
File “/usr/lib/python2.7/site-packages/librarian_captive/plugins.py”, line 26, in wrapper
return callback(*args, **kwargs)
File “/usr/lib/python2.7/site-packages/librarian_library/plugins.py”, line 39, in wrapper
return callback(*args, **kwargs)
File “/usr/lib/python2.7/site-packages/bottle_utils/ajax.py”, line 68, in wrapper
result = func(*args, **kwargs)
File “/usr/lib/python2.7/site-packages/librarian_filemanager/routes.py”, line 66, in show_file_list
(is_successful, dirs, files, meta) = manager.list(query)
File “/usr/lib/python2.7/site-packages/librarian_filemanager/manager.py”, line 53, in list
(success, dirs, files) = self.fsal_client.list_dir(path)
File “/usr/lib/python2.7/site-packages/fsal/client.py”, line 60, in wrapper
response = self._send_request(tostring(request_xml))
File “/usr/lib/python2.7/site-packages/fsal/client.py”, line 96, in _send_request
raise RuntimeError(‘FSAL could not connect to FSAL server’)
RuntimeError: FSAL could not connect to FSAL server

Here is the application log:


[2015-12-05 23:02:57 (+0000)] DEBUG LOADED: /usr/lib/python2.7/site-packages/librarian_core/contrib/system::initialize
[2015-12-05 23:02:57 (+0000)] DEBUG LOADED: /usr/lib/python2.7/site-packages/librarian_core/contrib/commands::initialize
[2015-12-05 23:02:57 (+0000)] DEBUG LOADED: /usr/lib/python2.7/site-packages/librarian_core/contrib/assets::initialize
[2015-12-05 23:02:57 (+0000)] DEBUG LOADED: /usr/lib/python2.7/site-packages/librarian_core/contrib/assets::init_complete
[2015-12-05 23:02:57 (+0000)] DEBUG LOADED: /usr/lib/python2.7/site-packages/librarian_core/contrib/assets::component_member_loaded
[2015-12-05 23:02:57 (+0000)] DEBUG LOADED: /usr/lib/python2.7/site-packages/librarian_core/contrib/assets::routes
[2015-12-05 23:02:57 (+0000)] DEBUG LOADED: /usr/lib/python2.7/site-packages/librarian_twitter::routes
[2015-12-05 23:02:57 (+0000)] DEBUG LOADED: /usr/lib/python2.7/site-packages/librarian_library::routes
[2015-12-05 23:02:57 (+0000)] DEBUG LOADED: /usr/lib/python2.7/site-packages/librarian_filemanager::routes
[2015-12-05 23:02:57 (+0000)] DEBUG LOADED: /usr/lib/python2.7/site-packages/librarian_ui::routes
[2015-12-05 23:02:57 (+0000)] DEBUG LOADED: /usr/lib/python2.7/site-packages/librarian_core/contrib/system::routes
[2015-12-05 23:02:57 (+0000)] DEBUG LOADED: /usr/lib/python2.7/site-packages/librarian_core/contrib/databases::init_complete
[2015-12-05 23:02:57 (+0000)] DEBUG LOADED: /usr/lib/python2.7/site-packages/librarian_core/contrib/commands::init_complete
[2015-12-05 23:02:57 (+0000)] DEBUG LOADED: /usr/lib/python2.7/site-packages/librarian_core/contrib/databases::initialize
[2015-12-05 23:02:57 (+0000)] DEBUG LOADED: /usr/lib/python2.7/site-packages/librarian_core/contrib/databases::immediate_shutdown
[2015-12-05 23:02:57 (+0000)] DEBUG LOADED: /usr/lib/python2.7/site-packages/librarian_core/contrib/databases::component_member_loaded
[2015-12-05 23:02:57 (+0000)] DEBUG LOADED: /usr/lib/python2.7/site-packages/librarian_core/contrib/databases::shutdown
[2015-12-05 23:02:57 (+0000)] DEBUG LOADED: /usr/lib/python2.7/site-packages/librarian_core/contrib/databases::plugin
[2015-12-05 23:02:57 (+0000)] DEBUG LOADED: /usr/lib/python2.7/site-packages/librarian_core/contrib/sessions::session_plugin
[2015-12-05 23:02:57 (+0000)] DEBUG LOADED: /usr/lib/python2.7/site-packages/librarian_core/contrib/auth::initialize
[2015-12-05 23:02:57 (+0000)] DEBUG LOADED: /usr/lib/python2.7/site-packages/librarian_core/contrib/auth::user_plugin
[2015-12-05 23:02:57 (+0000)] DEBUG LOADED: /usr/lib/python2.7/site-packages/librarian_core/contrib/i18n::component_member_loaded
[2015-12-05 23:02:57 (+0000)] DEBUG LOADED: /usr/lib/python2.7/site-packages/librarian_core/contrib/i18n::initialize
[2015-12-05 23:02:57 (+0000)] DEBUG LOADED: /usr/lib/python2.7/site-packages/librarian_core/contrib/cache::initialize
[2015-12-05 23:02:57 (+0000)] DEBUG LOADED: /usr/lib/python2.7/site-packages/librarian_core/contrib/tasks::initialize
[2015-12-05 23:02:57 (+0000)] DEBUG LOADED: /usr/lib/python2.7/site-packages/librarian_core/contrib/templates::component_member_loaded
[2015-12-05 23:02:57 (+0000)] DEBUG LOADED: /usr/lib/python2.7/site-packages/librarian_core/contrib/templates::initialize
[2015-12-05 23:02:57 (+0000)] DEBUG LOADED: /usr/lib/python2.7/site-packages/librarian_ui::initialize
[2015-12-05 23:02:57 (+0000)] DEBUG LOADED: /usr/lib/python2.7/site-packages/librarian_auth::initialize
[2015-12-05 23:02:57 (+0000)] DEBUG LOADED: /usr/lib/python2.7/site-packages/librarian_auth::routes
[2015-12-05 23:02:57 (+0000)] DEBUG LOADED: /usr/lib/python2.7/site-packages/librarian_setup::component_member_loaded
[2015-12-05 23:02:57 (+0000)] DEBUG LOADED: /usr/lib/python2.7/site-packages/librarian_setup::initialize
[2015-12-05 23:02:57 (+0000)] DEBUG LOADED: /usr/lib/python2.7/site-packages/librarian_setup::plugin
[2015-12-05 23:02:57 (+0000)] DEBUG LOADED: /usr/lib/python2.7/site-packages/librarian_setup::routes
[2015-12-05 23:02:57 (+0000)] DEBUG LOADED: /usr/lib/python2.7/site-packages/librarian_menu::init_complete
[2015-12-05 23:02:57 (+0000)] DEBUG LOADED: /usr/lib/python2.7/site-packages/librarian_menu::initialize
[2015-12-05 23:02:57 (+0000)] DEBUG LOADED: /usr/lib/python2.7/site-packages/librarian_captive::captive_portal_plugin
[2015-12-05 23:02:57 (+0000)] DEBUG LOADED: /usr/lib/python2.7/site-packages/librarian_notifications::initialize
[2015-12-05 23:02:57 (+0000)] DEBUG LOADED: /usr/lib/python2.7/site-packages/librarian_notifications::routes
[2015-12-05 23:02:57 (+0000)] DEBUG LOADED: /usr/lib/python2.7/site-packages/librarian_content::post_start
[2015-12-05 23:02:57 (+0000)] DEBUG LOADED: /usr/lib/python2.7/site-packages/librarian_content::initialize
[2015-12-05 23:02:57 (+0000)] DEBUG LOADED: /usr/lib/python2.7/site-packages/librarian_dashboard::init_complete
[2015-12-05 23:02:57 (+0000)] DEBUG LOADED: /usr/lib/python2.7/site-packages/librarian_dashboard::initialize
[2015-12-05 23:02:57 (+0000)] DEBUG LOADED: /usr/lib/python2.7/site-packages/librarian_dashboard::routes
[2015-12-05 23:02:57 (+0000)] DEBUG LOADED: /usr/lib/python2.7/site-packages/librarian_logs::initialize
[2015-12-05 23:02:57 (+0000)] DEBUG LOADED: /usr/lib/python2.7/site-packages/librarian_logs::routes
[2015-12-05 23:02:57 (+0000)] DEBUG LOADED: /usr/lib/python2.7/site-packages/librarian_diskspace::initialize
[2015-12-05 23:02:57 (+0000)] DEBUG LOADED: /usr/lib/python2.7/site-packages/librarian_netinterfaces::initialize
[2015-12-05 23:02:57 (+0000)] DEBUG LOADED: /usr/lib/python2.7/site-packages/librarian_ondd::initialize
[2015-12-05 23:02:57 (+0000)] DEBUG LOADED: /usr/lib/python2.7/site-packages/librarian_ondd::routes
[2015-12-05 23:02:57 (+0000)] DEBUG LOADED: /usr/lib/python2.7/site-packages/librarian_filemanager::initialize
[2015-12-05 23:02:57 (+0000)] DEBUG LOADED: /usr/lib/python2.7/site-packages/librarian_library::initialize
[2015-12-05 23:02:57 (+0000)] DEBUG LOADED: /usr/lib/python2.7/site-packages/librarian_library::content_resolver_plugin
[2015-12-05 23:02:57 (+0000)] DEBUG LOADED: /usr/lib/python2.7/site-packages/librarian_twitter::post_start
[2015-12-05 23:02:57 (+0000)] DEBUG LOADED: /usr/lib/python2.7/site-packages/librarian_twitter::initialize
[2015-12-05 23:02:57 (+0000)] DEBUG LOADED: /usr/lib/python2.7/site-packages/librarian_opener_html::initialize
[2015-12-05 23:02:57 (+0000)] DEBUG LOADED: /usr/lib/python2.7/site-packages/librarian_opener_video::initialize
[2015-12-05 23:03:00 (+0000)] DEBUG Migration version for librarian_filemanager.migrations.files is 0.0
[2015-12-05 23:03:00 (+0000)] DEBUG Finished migrating to 00_01_add_dirinfo_table
[2015-12-05 23:03:01 (+0000)] DEBUG Migration version for librarian_core.contrib.sessions.migrations.sessions is 0.0
[2015-12-05 23:03:01 (+0000)] DEBUG Finished migrating to 01_01_add_sessions_table
[2015-12-05 23:03:01 (+0000)] DEBUG Migration version for librarian_twitter.migrations.twitter is 0.0
[2015-12-05 23:03:01 (+0000)] DEBUG Finished migrating to 00_01_init_twitter_database
[2015-12-05 23:03:02 (+0000)] DEBUG Migration version for librarian_core.contrib.auth.migrations.auth is 0.0
[2015-12-05 23:03:02 (+0000)] DEBUG Finished migrating to 00_01_add_users_table
[2015-12-05 23:03:02 (+0000)] DEBUG Finished migrating to 00_02_add_groups
[2015-12-05 23:03:02 (+0000)] DEBUG Finished migrating to 00_03_add_permissions
[2015-12-05 23:03:03 (+0000)] DEBUG Migration version for librarian_content.migrations.content is 0.0
[2015-12-05 23:03:03 (+0000)] DEBUG Finished migrating to 00_01_cleanup
[2015-12-05 23:03:03 (+0000)] DEBUG Finished migrating to 00_02_add_content_table
[2015-12-05 23:03:03 (+0000)] DEBUG Finished migrating to 00_03_add_generic_table
[2015-12-05 23:03:03 (+0000)] DEBUG Finished migrating to 00_04_add_html_table
[2015-12-05 23:03:03 (+0000)] DEBUG Finished migrating to 00_05_add_image_table
[2015-12-05 23:03:03 (+0000)] DEBUG Finished migrating to 00_06_add_audio_table
[2015-12-05 23:03:03 (+0000)] DEBUG Finished migrating to 00_07_add_video_table
[2015-12-05 23:03:03 (+0000)] DEBUG Finished migrating to 00_08_add_app_table
[2015-12-05 23:03:04 (+0000)] DEBUG Migration version for librarian_notifications.migrations.notifications is 0.0
[2015-12-05 23:03:04 (+0000)] DEBUG Finished migrating to 00_01_add_notifications_table
[2015-12-05 23:03:04 (+0000)] DEBUG Started server on http://127.0.0.1:8000/
[2015-12-05 23:04:04 (+0000)] ERROR Task execution failed.
Traceback (most recent call last):
File “/usr/lib/python2.7/site-packages/librarian_core/contrib/tasks/scheduler.py”, line 35, in _execute
fn(*args, **kwargs)
File “/usr/lib/python2.7/site-packages/librarian_content/tasks.py”, line 25, in check_new_content
for event in supervisor.exts.fsal.get_changes():
File “/usr/lib/python2.7/site-packages/fsal/client.py”, line 227, in get_changes
for e in self._get_changes_helper(limit):
File “/usr/lib/python2.7/site-packages/fsal/client.py”, line 60, in wrapper
response = self._send_request(tostring(request_xml))
File “/usr/lib/python2.7/site-packages/fsal/client.py”, line 96, in _send_request
raise RuntimeError(‘FSAL could not connect to FSAL server’)
RuntimeError: FSAL could not connect to FSAL server
[2015-12-05 23:05:29 (+0000)] ERROR Unhandled error ‘[Errno 2] No such file or directory: ‘/mnt/data/librarian/librarian.json’’ at POST /setup/:

Traceback (most recent call last):
File “/usr/bin/bottle.py”, line 862, in _handle
return route.call(**args)
File “/usr/lib/python2.7/site-packages/librarian_core/contrib/databases/plugins.py”, line 11, in wrapper
return callback(*args, **kwargs)
File “/usr/lib/python2.7/site-packages/librarian_core/contrib/sessions/plugins.py”, line 37, in wrapper
return callback(*args, **kwargs)
File “/usr/lib/python2.7/site-packages/librarian_core/contrib/auth/plugins.py”, line 32, in wrapper
return callback(*args, **kwargs)
File “/usr/lib/python2.7/site-packages/bottle_utils/i18n.py”, line 387, in wrapper
return callback(*args, **kwargs)
File “/usr/bin/bottle.py”, line 1729, in wrapper
rv = callback(*a, **ka)
File “/usr/lib/python2.7/site-packages/librarian_setup/plugins.py”, line 25, in wrapper
return callback(*args, **kwargs)
File “/usr/lib/python2.7/site-packages/librarian_captive/plugins.py”, line 26, in wrapper
return callback(*args, **kwargs)
File “/usr/lib/python2.7/site-packages/librarian_library/plugins.py”, line 39, in wrapper
return callback(*args, **kwargs)
File “/usr/lib/python2.7/site-packages/librarian_setup/routes.py”, line 36, in enter_wizard
return request.app.supervisor.exts.setup_wizard()
File “/usr/lib/python2.7/site-packages/librarian_setup/wizard.py”, line 39, in call
return instance.dispatch()
File “/usr/lib/python2.7/site-packages/librarian_setup/wizard.py”, line 61, in dispatch
return self.process_current_step()
File “/usr/lib/python2.7/site-packages/librarian_setup/wizard.py”, line 164, in process_current_step
step_result = step’handler’
File “/usr/lib/python2.7/site-packages/librarian_library/setup.py”, line 37, in setup_language
request.app.supervisor.exts.setup.append({‘language’: lang})
File “/usr/lib/python2.7/site-packages/librarian_setup/setup.py”, line 59, in append
with open(self.setup_file, ‘w’) as s_file:
IOError: [Errno 2] No such file or directory: ‘/mnt/data/librarian/librarian.json’

[2015-12-05 23:05:45 (+0000)] ERROR Unhandled error ‘[Errno 2] No such file or directory: ‘/mnt/data/librarian/librarian.json’’ at GET /setup/:

Traceback (most recent call last):
File “/usr/bin/bottle.py”, line 862, in _handle
return route.call(**args)
File “/usr/lib/python2.7/site-packages/librarian_core/contrib/databases/plugins.py”, line 11, in wrapper
return callback(*args, **kwargs)
File “/usr/lib/python2.7/site-packages/librarian_core/contrib/sessions/plugins.py”, line 37, in wrapper
return callback(*args, **kwargs)
File “/usr/lib/python2.7/site-packages/librarian_core/contrib/auth/plugins.py”, line 32, in wrapper
return callback(*args, **kwargs)
File “/usr/lib/python2.7/site-packages/bottle_utils/i18n.py”, line 387, in wrapper
return callback(*args, **kwargs)
File “/usr/bin/bottle.py”, line 1729, in wrapper
rv = callback(*a, **ka)
File “/usr/lib/python2.7/site-packages/librarian_setup/plugins.py”, line 25, in wrapper
return callback(*args, **kwargs)
File “/usr/lib/python2.7/site-packages/librarian_captive/plugins.py”, line 26, in wrapper
return callback(*args, **kwargs)
File “/usr/lib/python2.7/site-packages/librarian_library/plugins.py”, line 39, in wrapper
return callback(*args, **kwargs)
File “/usr/lib/python2.7/site-packages/librarian_setup/routes.py”, line 36, in enter_wizard
return request.app.supervisor.exts.setup_wizard()
File “/usr/lib/python2.7/site-packages/librarian_setup/wizard.py”, line 39, in call
return instance.dispatch()
File “/usr/lib/python2.7/site-packages/librarian_setup/wizard.py”, line 63, in dispatch
return self.start_next_step()
File “/usr/lib/python2.7/site-packages/librarian_setup/wizard.py”, line 139, in start_next_step
return self.wizard_finished(self.state[‘data’])
File “/usr/lib/python2.7/site-packages/librarian_setup/setup.py”, line 91, in wizard_finished
setup.append(setup_data)
File “/usr/lib/python2.7/site-packages/librarian_setup/setup.py”, line 59, in append
with open(self.setup_file, ‘w’) as s_file:
IOError: [Errno 2] No such file or directory: ‘/mnt/data/librarian/librarian.json’

[2015-12-05 23:05:48 (+0000)] ERROR Unhandled error ‘FSAL could not connect to FSAL server’ at GET /files/:

Traceback (most recent call last):
File “/usr/bin/bottle.py”, line 862, in _handle
return route.call(**args)
File “/usr/lib/python2.7/site-packages/librarian_core/contrib/databases/plugins.py”, line 11, in wrapper
return callback(*args, **kwargs)
File “/usr/lib/python2.7/site-packages/librarian_core/contrib/sessions/plugins.py”, line 37, in wrapper
return callback(*args, **kwargs)
File “/usr/lib/python2.7/site-packages/librarian_core/contrib/auth/plugins.py”, line 32, in wrapper
return callback(*args, **kwargs)
File “/usr/lib/python2.7/site-packages/bottle_utils/i18n.py”, line 387, in wrapper
return callback(*args, **kwargs)
File “/usr/bin/bottle.py”, line 1729, in wrapper
rv = callback(*a, **ka)
File “/usr/lib/python2.7/site-packages/librarian_setup/plugins.py”, line 25, in wrapper
return callback(*args, **kwargs)
File “/usr/lib/python2.7/site-packages/librarian_captive/plugins.py”, line 26, in wrapper
return callback(*args, **kwargs)
File “/usr/lib/python2.7/site-packages/librarian_library/plugins.py”, line 39, in wrapper
return callback(*args, **kwargs)
File “/usr/lib/python2.7/site-packages/bottle_utils/ajax.py”, line 68, in wrapper
result = func(*args, **kwargs)
File “/usr/lib/python2.7/site-packages/librarian_filemanager/routes.py”, line 66, in show_file_list
(is_successful, dirs, files, meta) = manager.list(query)
File “/usr/lib/python2.7/site-packages/librarian_filemanager/manager.py”, line 53, in list
(success, dirs, files) = self.fsal_client.list_dir(path)
File “/usr/lib/python2.7/site-packages/fsal/client.py”, line 60, in wrapper
response = self._send_request(tostring(request_xml))
File “/usr/lib/python2.7/site-packages/fsal/client.py”, line 96, in _send_request
raise RuntimeError(‘FSAL could not connect to FSAL server’)
RuntimeError: FSAL could not connect to FSAL server

[2015-12-05 23:06:08 (+0000)] ERROR Plugin rendering failed: diskspace
Traceback (most recent call last):
File “/usr/lib/python2.7/site-packages/librarian_dashboard/dashboard.py”, line 88, in render
context.update(self.get_context())
File “/usr/lib/python2.7/site-packages/librarian_diskspace/dashboard_plugin.py”, line 38, in get_context
usage = sdev.stat
AttributeError: ‘NoneType’ object has no attribute ‘stat’
[2015-12-05 23:09:29 (+0000)] DEBUG ONDD: sending payload:
[2015-12-05 23:09:29 (+0000)] DEBUG ONDD: sending payload:
[2015-12-05 23:09:29 (+0000)] DEBUG ONDD: received data: <?xml version="1.0" encoding="UTF-8"?>
[2015-12-05 23:09:29 (+0000)] DEBUG ONDD: received data: <?xml version="1.0" encoding="UTF-8"?>no20.00
[2015-12-05 23:09:32 (+0000)] DEBUG ONDD: sending payload:
[2015-12-05 23:09:32 (+0000)] DEBUG ONDD: sending payload:

And, the system log, part 1


Jan 1 00:00:07 orxpi2 syslog.info syslogd started: BusyBox v1.24.1
Jan 1 00:00:07 orxpi2 user.notice kernel: klogd started: BusyBox v1.24.1 (2015-12-01 16:46:29 CET)
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.000000] Booting Linux on physical CPU 0xf00
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.000000] Initializing cgroup subsys cpuset
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.000000] Initializing cgroup subsys cpu
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.000000] Initializing cgroup subsys cpuacct
Jan 1 00:00:07 orxpi2 user.notice kernel: [ 0.000000] Linux version 4.1.12-v7 ([email protected]) (gcc version 4.9.3 (Buildroot 2015.11-git-00951-g07de512) ) #12 SMP PREEMPT Thu Dec 3 21:25:37 CET 2015
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.000000] CPU: ARMv7 Processor [410fc075] revision 5 (ARMv7), cr=10c5387d
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.000000] Machine: BCM2709
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.000000] cma: Reserved 8 MiB at 0x38400000
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.000000] Memory policy: Data cache writealloc
Jan 1 00:00:07 orxpi2 user.debug kernel: [ 0.000000] On node 0 totalpages: 232448
Jan 1 00:00:07 orxpi2 user.debug kernel: [ 0.000000] free_area_init_node: node 0, pgdat 8378aec0, node_mem_map b7bf7000
Jan 1 00:00:07 orxpi2 user.debug kernel: [ 0.000000] Normal zone: 2043 pages used for memmap
Jan 1 00:00:07 orxpi2 user.debug kernel: [ 0.000000] Normal zone: 0 pages reserved
Jan 1 00:00:07 orxpi2 user.debug kernel: [ 0.000000] Normal zone: 232448 pages, LIFO batch:31
Jan 1 00:00:07 orxpi2 user.warn kernel: [ 0.000000] [bcm2709_smp_init_cpus] enter (9420->f3003010)
Jan 1 00:00:07 orxpi2 user.warn kernel: [ 0.000000] [bcm2709_smp_init_cpus] ncores=4
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.000000] PERCPU: Embedded 13 pages/cpu @b7bbe000 s20544 r8192 d24512 u53248
Jan 1 00:00:07 orxpi2 user.debug kernel: [ 0.000000] pcpu-alloc: s20544 r8192 d24512 u53248 alloc=13*4096
Jan 1 00:00:07 orxpi2 user.debug kernel: [ 0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 230405
Jan 1 00:00:07 orxpi2 user.notice kernel: [ 0.000000] Kernel command line: dma.dmachans=0x7f35 bcm2708_fb.fbwidth=720 bcm2708_fb.fbheight=480 bcm2709.boardrev=0xa21041 bcm2709.serial=0xbea164a9 smsc95xx.macaddr=B8:27:EB:A1:64:A9 bcm2708_fb.fbswap=1 bcm2709.disk_led_
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.000000] PID hash table entries: 4096 (order: 2, 16384 bytes)
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes)
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.000000] Memory: 854708K/929792K available (5876K kernel code, 515K rwdata, 1628K rodata, 48852K init, 757K bss, 66892K reserved, 8192K cma-reserved)
Jan 1 00:00:07 orxpi2 user.notice kernel: [ 0.000000] Virtual kernel memory layout:
Jan 1 00:00:07 orxpi2 user.notice kernel: [ 0.000000] vector : 0xffff0000 - 0xffff1000 ( 4 kB)
Jan 1 00:00:07 orxpi2 user.notice kernel: [ 0.000000] fixmap : 0xffc00000 - 0xfff00000 (3072 kB)
Jan 1 00:00:07 orxpi2 user.notice kernel: [ 0.000000] vmalloc : 0xb9000000 - 0xff000000 (1120 MB)
Jan 1 00:00:07 orxpi2 user.notice kernel: [ 0.000000] lowmem : 0x80000000 - 0xb8c00000 ( 908 MB)
Jan 1 00:00:07 orxpi2 user.notice kernel: [ 0.000000] modules : 0x7f000000 - 0x80000000 ( 16 MB)
Jan 1 00:00:07 orxpi2 user.notice kernel: [ 0.000000] .text : 0x80008000 - 0x8075c244 (7505 kB)
Jan 1 00:00:07 orxpi2 user.notice kernel: [ 0.000000] .init : 0x8075d000 - 0x83712000 (48852 kB)
Jan 1 00:00:07 orxpi2 user.notice kernel: [ 0.000000] .data : 0x83712000 - 0x83792dd4 ( 516 kB)
Jan 1 00:00:07 orxpi2 user.notice kernel: [ 0.000000] .bss : 0x83795000 - 0x838527c4 ( 758 kB)
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.000000] Preemptible hierarchical RCU implementation.
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.000000] Additional per-CPU info printed with stalls.
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.000000] NR_IRQS:608
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.000000] Architected cp15 timer(s) running at 19.20MHz (virt).
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.000000] clocksource arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.000010] sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.000033] Switching to timer-based delay loop, resolution 52ns
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.000320] Console: colour dummy device 80x30
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.002042] console [tty1] enabled
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.002109] Calibrating delay loop (skipped), value calculated using timer frequency… 38.40 BogoMIPS (lpj=192000)
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.002212] pid_max: default: 32768 minimum: 301
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.002611] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes)
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.002677] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes)
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.004010] Initializing cgroup subsys blkio
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.004092] Initializing cgroup subsys memory
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.004159] Initializing cgroup subsys devices
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.004223] Initializing cgroup subsys freezer
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.004297] Initializing cgroup subsys net_cls
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.004407] CPU: Testing write buffer coherency: ok
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.004529] ftrace: allocating 19988 entries in 59 pages
Jan 1 00:00:07 orxpi2 user.err kernel: [ 0.052925] missing device node for CPU 0
Jan 1 00:00:07 orxpi2 user.err kernel: [ 0.053009] missing device node for CPU 1
Jan 1 00:00:07 orxpi2 user.err kernel: [ 0.053048] missing device node for CPU 2
Jan 1 00:00:07 orxpi2 user.err kernel: [ 0.053085] missing device node for CPU 3
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.053133] CPU0: thread -1, cpu 0, socket 15, mpidr 80000f00
Jan 1 00:00:07 orxpi2 user.warn kernel: [ 0.053179] [bcm2709_smp_prepare_cpus] enter
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.053337] Setting up static identity map for 0x8240 - 0x8274
Jan 1 00:00:07 orxpi2 user.warn kernel: [ 0.112889] [bcm2709_boot_secondary] cpu:1 started (0) 17
Jan 1 00:00:07 orxpi2 user.warn kernel: [ 0.113331] [bcm2709_secondary_init] enter cpu:1
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.113388] CPU1: thread -1, cpu 1, socket 15, mpidr 80000f01
Jan 1 00:00:07 orxpi2 user.warn kernel: [ 0.132880] [bcm2709_boot_secondary] cpu:2 started (0) 18
Jan 1 00:00:07 orxpi2 user.warn kernel: [ 0.133242] [bcm2709_secondary_init] enter cpu:2
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.133277] CPU2: thread -1, cpu 2, socket 15, mpidr 80000f02
Jan 1 00:00:07 orxpi2 user.warn kernel: [ 0.152927] [bcm2709_boot_secondary] cpu:3 started (0) 18
Jan 1 00:00:07 orxpi2 user.warn kernel: [ 0.153184] [bcm2709_secondary_init] enter cpu:3
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.153216] CPU3: thread -1, cpu 3, socket 15, mpidr 80000f03
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.153312] Brought up 4 CPUs
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.153419] SMP: Total of 4 processors activated (153.60 BogoMIPS).
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.153462] CPU: All CPU(s) started in SVC mode.
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.154464] devtmpfs: initialized
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.174164] VFP support v0.3: implementor 41 architecture 2 part 30 variant 7 rev 5
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.174526] clocksource jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.175674] pinctrl core: initialized pinctrl subsystem
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.176480] NET: Registered protocol family 16
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.182177] DMA: preallocated 4096 KiB pool for atomic coherent allocations
Jan 1 00:00:07 orxpi2 user.warn kernel: [ 0.183391] bcm2709.uart_clock = 3000000
Jan 1 00:00:07 orxpi2 user.err kernel: [ 0.183469] of_platform_populate failed: -22
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.185043] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.185112] hw-breakpoint: maximum watchpoint size is 8 bytes.
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.185282] Serial: AMBA PL011 UART driver
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.185460] dev:f1: ttyAMA0 at MMIO 0x3f201000 (irq = 83, base_baud = 0) is a PL011 rev3
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.680881] console [ttyAMA0] enabled
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.685080] bcm2835-mbox bcm2835-mbox: mailbox enabled
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.755329] bcm2708-dmaengine bcm2708-dmaengine: DMA legacy API manager at f3007000, dmachans=0x7f35
Jan 1 00:00:07 orxpi2 user.err kernel: [ 0.764613] bcm2708-dmaengine bcm2708-dmaengine: failed to get irq for DMA channel 13
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.772495] bcm2708-dmaengine bcm2708-dmaengine: Initialized 8 DMA channels (+ 1 legacy)
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.781363] bcm2708-dmaengine bcm2708-dmaengine: Load BCM2835 DMA engine driver
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.788716] bcm2708-dmaengine bcm2708-dmaengine: dma_debug:0
Jan 1 00:00:07 orxpi2 user.notice kernel: [ 0.795052] SCSI subsystem initialized
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.799061] usbcore: registered new interface driver usbfs
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.804688] usbcore: registered new interface driver hub
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.810156] usbcore: registered new device driver usb
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.815953] raspberrypi-firmware raspberrypi-firmware.0: Attached to firmware from 2015-08-13 00:03
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.852198] Switched to clocksource arch_sys_counter
Jan 1 00:00:07 orxpi2 user.notice kernel: [ 0.892478] FS-Cache: Loaded
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.895767] CacheFiles: Loaded
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.910080] NET: Registered protocol family 2
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.915835] TCP established hash table entries: 8192 (order: 3, 32768 bytes)
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.923086] TCP bind hash table entries: 8192 (order: 4, 65536 bytes)
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.929731] TCP: Hash tables configured (established 8192 bind 8192)
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.936275] UDP hash table entries: 512 (order: 2, 16384 bytes)
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.942303] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.949026] NET: Registered protocol family 1
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.953867] RPC: Registered named UNIX socket transport module.
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.959817] RPC: Registered udp transport module.
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.964613] RPC: Registered tcp transport module.
Jan 1 00:00:07 orxpi2 user.info kernel: [ 0.969345] RPC: Registered tcp NFSv4.1 backchannel transport module.
Jan 1 00:00:07 orxpi2 user.info kernel: [ 6.097562] bcm2708_gpio: bcm2708_gpio_probe 83736c28
Jan 1 00:00:07 orxpi2 user.info kernel: [ 6.104639] futex hash table entries: 1024 (order: 4, 65536 bytes)
Jan 1 00:00:07 orxpi2 user.notice kernel: [ 6.129567] FS-Cache: Netfs ‘nfs’ registered for caching
Jan 1 00:00:07 orxpi2 user.notice kernel: [ 6.136175] NFS: Registering the id_resolver key type
Jan 1 00:00:07 orxpi2 user.notice kernel: [ 6.141337] Key type id_resolver registered
Jan 1 00:00:07 orxpi2 user.notice kernel: [ 6.145579] Key type id_legacy registered
Jan 1 00:00:07 orxpi2 user.info kernel: [ 6.151473] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252)
Jan 1 00:00:07 orxpi2 user.info kernel: [ 6.159151] io scheduler noop registered
Jan 1 00:00:07 orxpi2 user.info kernel: [ 6.163141] io scheduler deadline registered
Jan 1 00:00:07 orxpi2 user.info kernel: [ 6.167782] io scheduler cfq registered (default)
Jan 1 00:00:07 orxpi2 user.info kernel: [ 6.174251] BCM2708FB: allocated DMA memory f8800000
Jan 1 00:00:07 orxpi2 user.info kernel: [ 6.179280] BCM2708FB: allocated DMA channel 0 @ f3007000
Jan 1 00:00:07 orxpi2 user.info kernel: [ 6.191517] Console: switching to colour frame buffer device 90x30
Jan 1 00:00:07 orxpi2 user.info kernel: [ 6.203964] Serial: 8250/16550 driver, 0 ports, IRQ sharing disabled
Jan 1 00:00:07 orxpi2 user.info kernel: [ 6.212623] KGDB: Registered I/O driver kgdboc
Jan 1 00:00:07 orxpi2 user.info kernel: [ 6.242959] vc-cma: Videocore CMA driver
Jan 1 00:00:07 orxpi2 user.info kernel: [ 6.248703] vc-cma: vc_cma_base = 0x00000000
Jan 1 00:00:07 orxpi2 user.info kernel: [ 6.255218] vc-cma: vc_cma_size = 0x00000000 (0 MiB)
Jan 1 00:00:07 orxpi2 user.info kernel: [ 6.262382] vc-cma: vc_cma_initial = 0x00000000 (0 MiB)
Jan 1 00:00:07 orxpi2 user.info kernel: [ 6.269707] vc-mem: phys_addr:0x00000000 mem_base=0x3dc00000 mem_size:0x3f000000(1008 MiB)
Jan 1 00:00:07 orxpi2 user.info kernel: [ 6.283948] loop: module loaded
Jan 1 00:00:07 orxpi2 user.info kernel: [ 6.289844] vchiq: vchiq_init_state: slot_zero = 0xb8880000, is_master = 0
Jan 1 00:00:07 orxpi2 user.info kernel: [ 6.300364] Loading iSCSI transport class v2.0-870.
Jan 1 00:00:07 orxpi2 user.info kernel: [ 6.308084] usbcore: registered new interface driver smsc95xx
Jan 1 00:00:07 orxpi2 user.info kernel: [ 6.315798] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
Jan 1 00:00:07 orxpi2 user.warn kernel: [ 6.523739] Core Release: 2.80a
Jan 1 00:00:07 orxpi2 user.warn kernel: [ 6.528705] Setting default values for core params
Jan 1 00:00:07 orxpi2 user.warn kernel: [ 6.535411] Finished setting default values for core params
Jan 1 00:00:07 orxpi2 user.warn kernel: [ 6.743309] Using Buffer DMA mode
Jan 1 00:00:07 orxpi2 user.warn kernel: [ 6.748450] Periodic Transfer Interrupt Enhancement - disabled
Jan 1 00:00:07 orxpi2 user.warn kernel: [ 6.756128] Multiprocessor Interrupt Enhancement - disabled
Jan 1 00:00:07 orxpi2 user.warn kernel: [ 6.763571] OTG VER PARAM: 0, OTG VER FLAG: 0
Jan 1 00:00:07 orxpi2 user.warn kernel: [ 6.769787] Dedicated Tx FIFOs mode
Jan 1 00:00:07 orxpi2 user.warn kernel: [ 6.775448] WARN::dwc_otg_hcd_init:1047: FIQ DMA bounce buffers: virt = 0xb8814000 dma = 0xf8814000 len=9024
Jan 1 00:00:07 orxpi2 user.warn kernel: [ 6.789245] FIQ FSM acceleration enabled for :
Jan 1 00:00:07 orxpi2 user.warn kernel: [ 6.789245] Non-periodic Split Transactions
Jan 1 00:00:07 orxpi2 user.warn kernel: [ 6.789245] Periodic Split Transactions
Jan 1 00:00:07 orxpi2 user.warn kernel: [ 6.789245] High-Speed Isochronous Endpoints
Jan 1 00:00:07 orxpi2 user.debug kernel: [ 6.813636] dwc_otg: Microframe scheduler enabled
Jan 1 00:00:07 orxpi2 user.warn kernel: [ 6.813717] WARN::hcd_init_fiq:412: FIQ on core 1 at 0x803dddc8
Jan 1 00:00:07 orxpi2 user.warn kernel: [ 6.821602] WARN::hcd_init_fiq:413: FIQ ASM at 0x803de138 length 36
Jan 1 00:00:07 orxpi2 user.warn kernel: [ 6.829852] WARN::hcd_init_fiq:438: MPHI regs_base at 0xb9006000
Jan 1 00:00:07 orxpi2 user.info kernel: [ 6.837876] dwc_otg bcm2708_usb: DWC OTG Controller
Jan 1 00:00:07 orxpi2 user.info kernel: [ 6.844788] dwc_otg bcm2708_usb: new USB bus registered, assigned bus number 1
Jan 1 00:00:07 orxpi2 user.info kernel: [ 6.854048] dwc_otg bcm2708_usb: irq 32, io mem 0x00000000
Jan 1 00:00:07 orxpi2 user.warn kernel: [ 6.861526] Init: Port Power? op_state=1
Jan 1 00:00:07 orxpi2 user.warn kernel: [ 6.867380] Init: Power Port (0)
Jan 1 00:00:07 orxpi2 user.info kernel: [ 6.872753] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
Jan 1 00:00:07 orxpi2 user.info kernel: [ 6.881464] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
Jan 1 00:00:07 orxpi2 user.info kernel: [ 6.890623] usb usb1: Product: DWC OTG Controller
Jan 1 00:00:07 orxpi2 user.info kernel: [ 6.897232] usb usb1: Manufacturer: Linux 4.1.12-v7 dwc_otg_hcd
Jan 1 00:00:07 orxpi2 user.info kernel: [ 6.905075] usb usb1: SerialNumber: bcm2708_usb
Jan 1 00:00:07 orxpi2 user.info kernel: [ 6.912449] hub 1-0:1.0: USB hub found
Jan 1 00:00:07 orxpi2 user.info kernel: [ 6.918104] hub 1-0:1.0: 1 port detected
Jan 1 00:00:07 orxpi2 user.debug kernel: [ 6.924317] dwc_otg: FIQ enabled
Jan 1 00:00:07 orxpi2 user.debug kernel: [ 6.924332] dwc_otg: NAK holdoff enabled
Jan 1 00:00:07 orxpi2 user.debug kernel: [ 6.924342] dwc_otg: FIQ split-transaction FSM enabled
Jan 1 00:00:07 orxpi2 user.debug kernel: [ 6.924382] Module dwc_common_port init
Jan 1 00:00:07 orxpi2 user.info kernel: [ 6.924755] usbcore: registered new interface driver usb-storage
Jan 1 00:00:07 orxpi2 user.info kernel: [ 6.932928] mousedev: PS/2 mouse device common for all mice
Jan 1 00:00:07 orxpi2 user.info kernel: [ 6.941399] bcm2835-cpufreq: min=600000 max=900000
Jan 1 00:00:07 orxpi2 user.info kernel: [ 6.948393] sdhci: Secure Digital Host Controller Interface driver
Jan 1 00:00:07 orxpi2 user.info kernel: [ 6.956435] sdhci: Copyright© Pierre Ossman
Jan 1 00:00:07 orxpi2 user.info kernel: [ 6.962837] mmc-bcm2835 mmc-bcm2835.0: mmc_debug:0 mmc_debug2:0
Jan 1 00:00:07 orxpi2 user.info kernel: [ 6.970551] mmc-bcm2835 mmc-bcm2835.0: DMA channels allocated
Jan 1 00:00:07 orxpi2 user.info kernel: [ 7.012505] sdhci-pltfm: SDHCI platform and OF driver helper
Jan 1 00:00:07 orxpi2 user.info kernel: [ 7.023677] ledtrig-cpu: registered to indicate activity on CPUs
Jan 1 00:00:07 orxpi2 user.info kernel: [ 7.033966] hidraw: raw HID events driver © Jiri Kosina
Jan 1 00:00:07 orxpi2 user.info kernel: [ 7.042539] usbcore: registered new interface driver usbhid
Jan 1 00:00:07 orxpi2 user.info kernel: [ 7.051058] usbhid: USB HID core driver
Jan 1 00:00:07 orxpi2 user.info kernel: [ 7.059321] ip_tables: © 2000-2006 Netfilter Core Team
Jan 1 00:00:07 orxpi2 user.info kernel: [ 7.068093] Initializing XFRM netlink socket
Jan 1 00:00:07 orxpi2 user.info kernel: [ 7.076454] NET: Registered protocol family 17
Jan 1 00:00:07 orxpi2 user.notice kernel: [ 7.082998] Key type dns_resolver registered
Jan 1 00:00:07 orxpi2 user.notice kernel: [ 7.089619] Registering SWP/SWPB emulation handler
Jan 1 00:00:07 orxpi2 user.info kernel: [ 7.097247] registered taskstats version 1
Jan 1 00:00:07 orxpi2 user.info kernel: [ 7.103436] vc-sm: Videocore shared memory driver
Jan 1 00:00:07 orxpi2 user.warn kernel: [ 7.109756] mmc0: host does not support reading read-only switch, assuming write-enable

System Log part 2

Jan 1 00:00:07 orxpi2 user.info kernel: [ 7.111905] mmc0: new high speed SDHC card at address 59b4
Jan 1 00:00:07 orxpi2 user.info kernel: [ 7.112608] mmcblk0: mmc0:59b4 SD 14.7 GiB
Jan 1 00:00:07 orxpi2 user.info kernel: [ 7.121067] mmcblk0: p1 p2 p3 p4
Jan 1 00:00:07 orxpi2 user.warn kernel: [ 7.138909] Indeed it is in host mode hprt0 = 00021501
Jan 1 00:00:07 orxpi2 user.info kernel: [ 7.142232] [vc_sm_connected_init]: start
Jan 1 00:00:07 orxpi2 user.info kernel: [ 7.142945] [vc_sm_connected_init]: end - returning 0
Jan 1 00:00:07 orxpi2 user.info kernel: [ 7.239672] Freeing unused kernel memory: 48852K (8075d000 - 83712000)
Jan 1 00:00:07 orxpi2 user.warn kernel: [ 7.297480] FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck.
Jan 1 00:00:07 orxpi2 user.info kernel: [ 7.332308] usb 1-1: new high-speed USB device number 2 using dwc_otg
Jan 1 00:00:07 orxpi2 user.warn kernel: [ 7.341310] Indeed it is in host mode hprt0 = 00001101
Jan 1 00:00:07 orxpi2 user.info kernel: [ 7.552766] usb 1-1: New USB device found, idVendor=0424, idProduct=9514
Jan 1 00:00:07 orxpi2 user.info kernel: [ 7.561962] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Jan 1 00:00:07 orxpi2 user.info kernel: [ 7.572606] hub 1-1:1.0: USB hub found
Jan 1 00:00:07 orxpi2 user.info kernel: [ 7.579053] hub 1-1:1.0: 5 ports detected
Jan 1 00:00:07 orxpi2 user.info kernel: [ 7.862336] usb 1-1.1: new high-speed USB device number 3 using dwc_otg
Jan 1 00:00:07 orxpi2 user.info kernel: [ 7.982874] usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00
Jan 1 00:00:07 orxpi2 user.info kernel: [ 7.991909] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Jan 1 00:00:07 orxpi2 user.info kernel: [ 8.012935] smsc95xx v1.0.4
Jan 1 00:00:07 orxpi2 user.info kernel: [ 8.077673] smsc95xx 1-1.1:1.0 eth0: register ‘smsc95xx’ at usb-bcm2708_usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:a1:64:a9
Jan 1 00:00:07 orxpi2 daemon.info kernel: [ 8.105002] udevd[197]: starting version 3.1.2
Jan 1 00:00:07 orxpi2 user.notice kernel: [ 8.113331] random: udevd urandom read with 65 bits of entropy available
Jan 1 00:00:08 orxpi2 user.warn kernel: [ 8.241557] bcm2708_i2c_init_pinmode(1,2)
Jan 1 00:00:08 orxpi2 user.warn kernel: [ 8.252388] bcm2708_i2c_init_pinmode(1,3)
Jan 1 00:00:08 orxpi2 user.info kernel: [ 8.259032] bcm2708_i2c bcm2708_i2c.1: BSC1 Controller at 0x3f804000 (irq 79) (baudrate 100000)
Jan 1 00:00:08 orxpi2 user.info kernel: [ 8.272971] bcm2708_spi bcm2708_spi.0: master is unqueued, this is deprecated
Jan 1 00:00:08 orxpi2 user.info kernel: [ 8.284043] bcm2708_spi bcm2708_spi.0: SPI Controller at 0x3f204000 (irq 80)
Jan 1 00:00:08 orxpi2 daemon.info udhcpc[236]: udhcpc (v1.24.1) started
Jan 1 00:00:08 orxpi2 user.info kernel: [ 8.794968] smsc95xx 1-1.1:1.0 eth0: hardware isn’t capable of remote wakeup
Jan 1 00:00:08 orxpi2 daemon.info udhcpc[236]: Sending discover…
Jan 1 00:00:11 orxpi2 user.info kernel: [ 11.743056] smsc95xx 1-1.1:1.0 eth0: link up, 100Mbps, full-duplex, lpa 0x41E1
Jan 1 00:00:11 orxpi2 daemon.info udhcpc[236]: Sending discover…
Jan 1 00:00:14 orxpi2 daemon.info udhcpc[236]: Sending discover…
Jan 1 00:00:14 orxpi2 daemon.info udhcpc[236]: Sending select for 192.168.250.202…
Jan 1 00:00:14 orxpi2 daemon.info udhcpc[236]: Lease of 192.168.250.202 obtained, lease time 86400
Jan 1 00:00:15 orxpi2 daemon.err ifplugd(eth0): started: BusyBox v1.24.1 (2015-12-01 16:46:29 CET)
Jan 1 00:00:15 orxpi2 daemon.err ifplugd(eth0): using SIOCETHTOOL detection mode
Jan 1 00:00:15 orxpi2 daemon.err ifplugd(eth0): link is up
Jan 1 00:00:15 orxpi2 daemon.err ifplugd(eth0): executing '/etc/ifplugd.action eth0 up’
Jan 1 00:00:15 orxpi2 daemon.err ifplugd(eth0): exit code: 0
Jan 1 00:00:15 orxpi2 daemon.notice ntpd[278]: ntpd [email protected] Tue Dec 1 15:56:37 UTC 2015 (1): Starting
Jan 1 00:00:15 orxpi2 daemon.info ntpd[278]: Command line: /usr/sbin/ntpd -g
Jan 1 00:00:15 orxpi2 user.info kernel: [ 15.478666] NET: Registered protocol family 10
Jan 1 00:00:15 orxpi2 daemon.info ntpd[288]: proto: precision = 2.083 usec (-19)
Jan 1 00:00:15 orxpi2 daemon.info ntpd[288]: Listen and drop on 0 v6wildcard [::]:123
Jan 1 00:00:15 orxpi2 daemon.info ntpd[288]: Listen and drop on 1 v4wildcard 0.0.0.0:123
Jan 1 00:00:15 orxpi2 daemon.info ntpd[288]: Listen normally on 2 lo 127.0.0.1:123
Jan 1 00:00:15 orxpi2 daemon.info ntpd[288]: Listen normally on 3 eth0 192.168.250.202:123
Jan 1 00:00:15 orxpi2 daemon.info ntpd[288]: Listen normally on 4 lo [::1]:123
Jan 1 00:00:15 orxpi2 daemon.err ntpd[288]: bind(21) AF_INET6 fe80::ba27:ebff:fea1:64a9%2#123 flags 0x11 failed: Cannot assign requested address
Jan 1 00:00:15 orxpi2 daemon.err ntpd[288]: unable to create socket on eth0 (5) for fe80::ba27:ebff:fea1:64a9%2#123
Jan 1 00:00:15 orxpi2 daemon.info ntpd[288]: failed to init interface for address fe80::ba27:ebff:fea1:64a9%2
Jan 1 00:00:15 orxpi2 daemon.info ntpd[288]: Listening on routing socket on fd #21 for interface updates
Jan 1 00:00:16 orxpi2 daemon.err ntpd[288]: bind(24) AF_INET6 fe80::ba27:ebff:fea1:64a9%2#123 flags 0x11 failed: Cannot assign requested address
Jan 1 00:00:16 orxpi2 daemon.err ntpd[288]: unable to create socket on eth0 (6) for fe80::ba27:ebff:fea1:64a9%2#123
Jan 1 00:00:16 orxpi2 daemon.info ntpd[288]: failed to init interface for address fe80::ba27:ebff:fea1:64a9%2
Jan 1 00:00:18 orxpi2 daemon.info ntpd[288]: Listen normally on 7 eth0 [fe80::ba27:ebff:fea1:64a9%2]:123
Jan 1 00:00:18 orxpi2 daemon.debug ntpd[288]: new interface(s) found: waking up resolver
Dec 5 23:02:21 orxpi2 daemon.info ntpd[288]: receive: Unexpected origin timestamp from 192.99.34.87
Dec 5 23:02:24 orxpi2 user.notice kernel: [ 20.846909] random: nonblocking pool is initialized
Dec 5 23:02:27 orxpi2 authpriv.warn dropbear[295]: Failed loading /etc/dropbear/dropbear_rsa_host_key
Dec 5 23:02:27 orxpi2 authpriv.warn dropbear[295]: Failed loading /etc/dropbear/dropbear_dss_host_key
Dec 5 23:02:27 orxpi2 authpriv.warn dropbear[295]: Failed loading /etc/dropbear/dropbear_ecdsa_host_key
Dec 5 23:02:27 orxpi2 authpriv.info dropbear[296]: Running in background
Dec 5 23:02:27 orxpi2 auth.notice su: + /dev/console root:postgres
Dec 5 23:02:46 orxpi2 auth.notice su: + /dev/console root:postgres
Dec 5 23:02:48 orxpi2 daemon.info dnsmasq[371]: started, version 2.75 cachesize 150
Dec 5 23:02:48 orxpi2 daemon.info dnsmasq[371]: compile time options: IPv6 GNU-getopt no-DBus no-i18n no-IDN DHCP DHCPv6 no-Lua TFTP no-conntrack ipset auth no-DNSSEC loop-detect inotify
Dec 5 23:02:48 orxpi2 daemon.warn dnsmasq[371]: warning: interface wlan0 does not currently exist
Dec 5 23:02:48 orxpi2 daemon.info dnsmasq-dhcp[371]: DHCP, IP range 10.0.0.10 – 10.0.0.254, lease time 1h
Dec 5 23:02:48 orxpi2 daemon.info dnsmasq[371]: cleared cache
Dec 5 23:02:48 orxpi2 user.info kernel: [ 45.074509] cfg80211: Calling CRDA to update world regulatory domain
Dec 5 23:02:48 orxpi2 daemon.info init: starting pid 400, tty ‘/dev/tty1’: '/sbin/getty -L tty1 0 vt100 '
Dec 5 23:02:50 orxpi2 user.info outernet.monitor: Collecting data
Dec 5 23:02:50 orxpi2 user.info outernet.monitor: Could not connect to socket. Pausing for next attempt
Dec 5 23:02:51 orxpi2 user.info kernel: [ 48.232377] cfg80211: Calling CRDA to update world regulatory domain
Dec 5 23:02:54 orxpi2 user.info kernel: [ 51.382356] cfg80211: Calling CRDA to update world regulatory domain
Dec 5 23:02:55 orxpi2 user.info outernet.monitor: Could not connect to socket. Pausing for next attempt
Dec 5 23:02:57 orxpi2 user.info kernel: [ 54.532347] cfg80211: Calling CRDA to update world regulatory domain
Dec 5 23:03:00 orxpi2 user.info kernel: [ 57.682364] cfg80211: Calling CRDA to update world regulatory domain
Dec 5 23:03:00 orxpi2 user.info outernet.monitor: Could not connect to socket. Pausing for next attempt
Dec 5 23:03:04 orxpi2 user.info kernel: [ 60.832339] cfg80211: Calling CRDA to update world regulatory domain
Dec 5 23:03:05 orxpi2 user.info outernet.monitor: Could not connect to socket. Pausing for next attempt
Dec 5 23:03:07 orxpi2 user.info kernel: [ 63.982269] cfg80211: Calling CRDA to update world regulatory domain
Dec 5 23:03:10 orxpi2 user.info kernel: [ 67.132260] cfg80211: Calling CRDA to update world regulatory domain
Dec 5 23:03:10 orxpi2 user.info outernet.monitor: Could not connect to socket. Pausing for next attempt
Dec 5 23:03:13 orxpi2 user.info kernel: [ 70.282256] cfg80211: Calling CRDA to update world regulatory domain
Dec 5 23:03:15 orxpi2 user.info outernet.monitor: Could not connect to socket. Pausing for next attempt
Dec 5 23:03:16 orxpi2 user.info kernel: [ 73.432260] cfg80211: Calling CRDA to update world regulatory domain
Dec 5 23:03:19 orxpi2 user.info kernel: [ 76.582266] cfg80211: Calling CRDA to update world regulatory domain
Dec 5 23:03:20 orxpi2 user.info outernet.monitor: Could not connect to socket. Pausing for next attempt
Dec 5 23:03:22 orxpi2 user.info kernel: [ 79.732262] cfg80211: Exceeded CRDA call max attempts. Not calling CRDA
Dec 5 23:03:25 orxpi2 user.info outernet.monitor: Could not connect to socket. Pausing for next attempt

Dec 5 23:09:16 orxpi2 user.info outernet.monitor: Could not connect to socket. Pausing for next attempt
Dec 5 23:09:21 orxpi2 user.info outernet.monitor: Could not connect to socket. Pausing for next attempt
Dec 5 23:09:26 orxpi2 user.info outernet.monitor: Could not connect to socket. Pausing for next attempt
Dec 5 23:09:26 orxpi2 user.info kernel: [ 443.786699] usb 1-1.4: new high-speed USB device number 4 using dwc_otg
Dec 5 23:09:27 orxpi2 user.info kernel: [ 443.887808] usb 1-1.4: New USB device found, idVendor=1f4d, idProduct=3000
Dec 5 23:09:27 orxpi2 user.info kernel: [ 443.896119] usb 1-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Dec 5 23:09:27 orxpi2 user.info kernel: [ 443.904887] usb 1-1.4: Product: USB Stick
Dec 5 23:09:27 orxpi2 user.info kernel: [ 443.910575] usb 1-1.4: Manufacturer: Max
Dec 5 23:09:27 orxpi2 user.info kernel: [ 443.915942] usb 1-1.4: SerialNumber: 080116
Dec 5 23:09:27 orxpi2 user.info kernel: [ 443.940346] dw2102: su3000_identify_state
Dec 5 23:09:27 orxpi2 user.info kernel: [ 443.940346]
Dec 5 23:09:27 orxpi2 user.info kernel: [ 443.948535] dvb-usb: found a ‘SU3000HD DVB-S USB2.0’ in warm state.
Dec 5 23:09:27 orxpi2 user.info kernel: [ 443.956199] dw2102: su3000_power_ctrl: 1, initialized 0
Dec 5 23:09:27 orxpi2 user.info kernel: [ 443.956199]
Dec 5 23:09:27 orxpi2 user.info kernel: [ 443.966148] dvb-usb: will pass the complete MPEG2 transport stream to the software demuxer.
Dec 5 23:09:27 orxpi2 user.info kernel: [ 443.977886] DVB: registering new adapter (SU3000HD DVB-S USB2.0)
Dec 5 23:09:27 orxpi2 user.info kernel: [ 443.989910] dvb-usb: MAC address: 00:0d:fe:87:b1:d1
Dec 5 23:09:27 orxpi2 user.info kernel: [ 444.328967] DS3000 chip version: 0.208 attached.
Dec 5 23:09:27 orxpi2 user.warn kernel: [ 444.337047] Chip ID = [DS3103]!
Dec 5 23:09:27 orxpi2 user.info kernel: [ 444.342961] dw2102: Attached DS3000/TS2020!
Dec 5 23:09:27 orxpi2 user.info kernel: [ 444.342961]
Dec 5 23:09:27 orxpi2 user.info kernel: [ 444.352334] usb 1-1.4: DVB: registering adapter 0 frontend 0 (Montage Technology DS3000/TS2020)…
Dec 5 23:09:27 orxpi2 user.notice hotplug.dvb: DVB frontend /dev/dvb/adapter0/frontend0 added, restarting ONDD
Dec 5 23:09:27 orxpi2 user.info kernel: [ 444.396778] Registered IR keymap rc-su3000
Dec 5 23:09:27 orxpi2 user.info kernel: [ 444.404143] input: IR-receiver inside an USB DVB receiver as /devices/platform/bcm2708_usb/usb1/1-1/1-1.4/rc/rc0/input0
Dec 5 23:09:27 orxpi2 user.info kernel: [ 444.420801] rc0: IR-receiver inside an USB DVB receiver as /devices/platform/bcm2708_usb/usb1/1-1/1-1.4/rc/rc0
Dec 5 23:09:27 orxpi2 user.info kernel: [ 444.435871] dvb-usb: schedule remote query interval to 150 msecs.
Dec 5 23:09:27 orxpi2 user.info kernel: [ 444.444248] dw2102: su3000_power_ctrl: 0, initialized 1
Dec 5 23:09:27 orxpi2 user.info kernel: [ 444.444248]
Dec 5 23:09:27 orxpi2 user.info kernel: [ 444.455486] dvb-usb: SU3000HD DVB-S USB2.0 successfully initialized and connected.
Dec 5 23:09:27 orxpi2 user.info kernel: [ 444.465610] usbcore: registered new interface driver dw2102
Dec 5 23:09:28 orxpi2 user.info ondd[568]: [main] v2.1.0
Dec 5 23:09:28 orxpi2 user.info kernel: [ 445.469841] dw2102: su3000_power_ctrl: 1, initialized 1
Dec 5 23:09:28 orxpi2 user.info kernel: [ 445.469841]
Dec 5 23:09:28 orxpi2 user.info kernel: [ 445.539447] ds3000_firmware_ondemand: Waiting for firmware upload (dvb-fe-ds3103.fw)…
Dec 5 23:09:28 orxpi2 user.info kernel: [ 445.549977] ds3000_firmware_ondemand: Waiting for firmware upload(2)…
Dec 5 23:09:29 orxpi2 user.info ondd[568]: [main] tuning to 0 Mhz 0 KSym
Dec 5 23:09:29 orxpi2 user.warn ondd[568]: [main] ioctl( FE_SET_PROPERTY ) failed: (22) Invalid argument
Dec 5 23:09:29 orxpi2 user.warn kernel: [ 446.614112] usb 1-1.4: DVB: adapter 0 frontend 0 frequency 0 out of range (950000…2150000)
Dec 5 23:09:31 orxpi2 user.info outernet.monitor: Connected to socket
Dec 5 23:09:31 orxpi2 user.info outernet.monitor: Connected to socket
Dec 5 23:09:31 orxpi2 user.info outernet.monitor: Finished collecting data in 400.460427999 seconds
Dec 5 23:09:31 orxpi2 user.info outernet.monitor: Transmitting buffered data
Dec 5 23:09:31 orxpi2 user.info outernet.monitor: Transmission complete, claring local buffer
Dec 5 23:10:11 orxpi2 user.info ondd[568]: [main] tuning to 1179 Mhz 22000 KSym
Dec 5 23:10:31 orxpi2 user.info outernet.monitor: Collecting data
Dec 5 23:10:31 orxpi2 user.info outernet.monitor: Connected to socket
Dec 5 23:10:31 orxpi2 user.info outernet.monitor: Connected to socket
Dec 5 23:10:31 orxpi2 user.info outernet.monitor: Connected to socket
Dec 5 23:10:31 orxpi2 user.info outernet.monitor: Finished collecting data in 0.0345969200134 seconds
Dec 5 23:11:31 orxpi2 user.info outernet.monitor: Collecting data
Dec 5 23:11:31 orxpi2 user.info outernet.monitor: Connected to socket
Dec 5 23:11:31 orxpi2 user.info outernet.monitor: Connected to socket
Dec 5 23:11:31 orxpi2 user.info outernet.monitor: Connected to socket
Dec 5 23:11:31 orxpi2 user.info outernet.monitor: Finished collecting data in 0.0235149860382 seconds
Dec 5 23:12:31 orxpi2 user.info outernet.monitor: Collecting data
Dec 5 23:12:31 orxpi2 user.info outernet.monitor: Connected to socket
Dec 5 23:12:31 orxpi2 user.info outernet.monitor: Connected to socket
Dec 5 23:12:31 orxpi2 user.info outernet.monitor: Connected to socket
Dec 5 23:12:31 orxpi2 user.info outernet.monitor: Finished collecting data in 0.024001121521 seconds
Dec 5 23:13:31 orxpi2 user.info outernet.monitor: Collecting data
Dec 5 23:13:31 orxpi2 user.info outernet.monitor: Connected to socket
Dec 5 23:13:31 orxpi2 user.info outernet.monitor: Connected to socket
Dec 5 23:13:31 orxpi2 user.info outernet.monitor: Connected to socket
Dec 5 23:13:31 orxpi2 user.info outernet.monitor: Finished collecting data in 0.0256969928741 seconds
Dec 5 23:14:31 orxpi2 user.info outernet.monitor: Collecting data
Dec 5 23:14:31 orxpi2 user.info outernet.monitor: Connected to socket
Dec 5 23:14:37 orxpi2 user.err ondd[568]: [ipc] send() failed: (32) Broken pipe
Dec 5 23:14:37 orxpi2 user.err ondd[568]: [ipc] send() failed: (32) Broken pipe
Dec 5 23:14:37 orxpi2 user.err ondd[568]: [ipc] send() failed: (32) Broken pipe
Dec 5 23:14:37 orxpi2 user.err ondd[568]: [ipc] send() failed: (32) Broken pipe
Dec 5 23:14:37 orxpi2 user.err ondd[568]: [ipc] send() failed: (32) Broken pipe
Dec 5 23:14:37 orxpi2 user.err ondd[568]: [ipc] send() failed: (32) Broken pipe
Dec 5 23:14:37 orxpi2 user.info outernet.monitor: Connected to socket
Dec 5 23:14:37 orxpi2 user.info outernet.monitor: Connected to socket
Dec 5 23:14:37 orxpi2 user.info outernet.monitor: Finished collecting data in 5.68659710884 seconds
Dec 5 23:15:37 orxpi2 user.info outernet.monitor: Collecting data
Dec 5 23:15:37 orxpi2 user.info outernet.monitor: Connected to socket
Dec 5 23:15:37 orxpi2 user.info outernet.monitor: Connected to socket
Dec 5 23:15:37 orxpi2 user.info outernet.monitor: Connected to socket
Dec 5 23:15:37 orxpi2 user.info outernet.monitor: Finished collecting data in 0.0261268615723 seconds
Dec 5 23:15:37 orxpi2 user.info outernet.monitor: Transmitting buffered data
Dec 5 23:15:37 orxpi2 user.info outernet.monitor: Transmission complete, claring local buffer


Flagging this for the team @branko

OK - Thanks, Syed.

I’m also having this ‘tuner’ related issue. Hot plug works.

Do you have an HDMI display that you can connect to Pi and tell us more about what’s going on during boot?

Can you log into Pi and do a few things?

  1. copy the output of mount command
  2. extract /mnt/data/log/fsal.log (if it exists) and post it here (or on pastebin
  3. copy the output of ps ax | grep python
  4. copy the output of ps ax | grep postgres

branko:

There was no /mnt/data/log/ directory, and as such, no fsal.log.

Here are the results of the queries:


ORxPi2 v2.0.000 | [email protected]:~> mount
rootfs on / type rootfs (rw)
devtmpfs on /dev type devtmpfs (rw,relatime,size=427352k,nr_inodes=106838,mode=755)
proc on /proc type proc (rw,relatime)
devpts on /dev/pts type devpts (rw,relatime,gid=5,mode=620,ptmxmode=000)
tmpfs on /dev/shm type tmpfs (rw,relatime,mode=777)
tmpfs on /tmp type tmpfs (rw,relatime)
tmpfs on /run type tmpfs (rw,nosuid,nodev,relatime,mode=755)
sysfs on /sys type sysfs (rw,relatime)
/dev/mmcblk0p1 on /boot type vfat (rw,noatime,fmask=0022,dmask=0022,codepage=437,iocharset=iso8859-1,shortname=mixed,errors=remount-ro)
ORxPi2 v2.0.000 | [email protected]:~>

ORxPi2 v2.0.000 | [email protected]:~> ps ax | grep python
396 root {librarian} /usr/bin/python /usr/bin/librarian --conf /etc/librar ian.ini
12224 outernet grep python
ORxPi2 v2.0.000 | [email protected]:~>


ORxPi2 v2.0.000 | [email protected]:~> ps ax | grep postgres
360 postgres /usr/bin/postgres -D /mnt/data/pgsql
362 postgres postgres: checkpointer process
363 postgres postgres: writer process
364 postgres postgres: wal writer process
365 postgres postgres: autovacuum launcher process
366 postgres postgres: stats collector process
455 postgres postgres: postgres files 127.0.0.1(58223) idle
456 postgres postgres: postgres files 127.0.0.1(58224) idle
461 postgres postgres: postgres sessions 127.0.0.1(58228) idle
462 postgres postgres: postgres sessions 127.0.0.1(58229) idle
466 postgres postgres: postgres twitter 127.0.0.1(58233) idle
467 postgres postgres: postgres twitter 127.0.0.1(58234) idle
471 postgres postgres: postgres auth 127.0.0.1(58238) idle
472 postgres postgres: postgres auth 127.0.0.1(58239) idle
476 postgres postgres: postgres content 127.0.0.1(58243) idle
477 postgres postgres: postgres content 127.0.0.1(58244) idle
482 postgres postgres: postgres notifications 127.0.0.1(58248) idle
484 postgres postgres: postgres notifications 127.0.0.1(58249) idle
12234 outernet grep postgres
ORxPi2 v2.0.000 | [email protected]:~>

Ok, that explains a lot of things. Is there a file at /tmp/setup? If so, can you post it online?

Another thing to try is, whether everything will work with a fresh card and no tuner attached at any point.

Here is the output from the setup file:


ORxPi2 v2.0.000 | [email protected]:/tmp> vi setup
[Thu Jan 1 00:00:07 UTC 1970] Setting up persistent configuration
[Thu Jan 1 00:00:07 UTC 1970] Copying /etc/shadow -> /mnt/persist/shadow
[Thu Jan 1 00:00:07 UTC 1970] Removing /etc/shadow
[Thu Jan 1 00:00:07 UTC 1970] Linked /mnt/persist/shadow -> /etc/shadow
[Thu Jan 1 00:00:07 UTC 1970] Copying /etc/group -> /mnt/persist/group
[Thu Jan 1 00:00:07 UTC 1970] Removing /etc/group
[Thu Jan 1 00:00:07 UTC 1970] Linked /mnt/persist/group -> /etc/group
[Thu Jan 1 00:00:07 UTC 1970] Copying /etc/profile -> /mnt/persist/profile
[Thu Jan 1 00:00:07 UTC 1970] Removing /etc/profile
[Thu Jan 1 00:00:07 UTC 1970] Linked /mnt/persist/profile -> /etc/profile
[Thu Jan 1 00:00:07 UTC 1970] Copying /etc/hostapd.conf -> /mnt/persist/hostapd.conf
[Thu Jan 1 00:00:07 UTC 1970] Removing /etc/hostapd.conf
[Thu Jan 1 00:00:07 UTC 1970] Linked /mnt/persist/hostapd.conf -> /etc/hostapd.conf
[Thu Jan 1 00:00:07 UTC 1970] Copying /etc/dnsmasq.conf -> /mnt/persist/dnsmasq.conf
[Thu Jan 1 00:00:07 UTC 1970] Removing /etc/dnsmasq.conf
[Thu Jan 1 00:00:07 UTC 1970] Linked /mnt/persist/dnsmasq.conf -> /etc/dnsmasq.conf
[Thu Jan 1 00:00:07 UTC 1970] Copying /etc/fsal-user.ini -> /mnt/persist/fsal-user.ini
[Thu Jan 1 00:00:07 UTC 1970] Removing /etc/fsal-user.ini
[Thu Jan 1 00:00:07 UTC 1970] Linked /mnt/persist/fsal-user.ini -> /etc/fsal-user.ini
[Thu Jan 1 00:00:07 UTC 1970] Copying /etc/librarian-user.ini -> /mnt/persist/librarian-user.ini
[Thu Jan 1 00:00:07 UTC 1970] Removing /etc/librarian-user.ini
[Thu Jan 1 00:00:07 UTC 1970] Linked /mnt/persist/librarian-user.ini -> /etc/librarian-user.ini
[Thu Jan 1 00:00:07 UTC 1970] Copying /etc/ondd.conf -> /mnt/persist/ondd.conf
[Thu Jan 1 00:00:07 UTC 1970] Removing /etc/ondd.conf
[Thu Jan 1 00:00:07 UTC 1970] Linked /mnt/persist/ondd.conf -> /etc/ondd.conf
[Thu Jan 1 00:00:07 UTC 1970] Copying /etc/fstab -> /mnt/persist/fstab
[Thu Jan 1 00:00:07 UTC 1970] Removing /etc/fstab
[Thu Jan 1 00:00:07 UTC 1970] Linked /mnt/persist/fstab -> /etc/fstab
[Thu Jan 1 00:00:07 UTC 1970] Copying /etc/sudoers -> /mnt/persist/sudoers
[Thu Jan 1 00:00:07 UTC 1970] Removing /etc/sudoers
[Thu Jan 1 00:00:07 UTC 1970] Linked /mnt/persist/sudoers -> /etc/sudoers
[Thu Jan 1 00:00:07 UTC 1970] Removing /etc/profile
[Thu Jan 1 00:00:07 UTC 1970] Linked /mnt/persist/profile -> /etc/profile
[Thu Jan 1 00:00:07 UTC 1970] Copying /etc/dropbear -> /mnt/persist/dropbear
[Thu Jan 1 00:00:07 UTC 1970] Removing /etc/dropbear
[2]+ Stopped vi setup
ORxPi2 v2.0.000 | [email protected]:/tmp>

I also saw the the thread that talked about the long boot process. I had tried to create a new SD card a coupe of times, with the same results. I will try to start from a clean boot, with no tuner, new SD card and let it boot up for 10 or 20 minutes before I touch it. I will watch it via a monitor attached to the Pi to see is I see any issues.

I will then connect the tuner, verify operation. Then, I will reboot with HDStar attached.

I may not have let the initial boot complete before I determined that it had failed, and pulled the power on it part way through initial boot - causing database issues. I should have been monitoring initial boot with a monitor.

I will let you know what happens later today …

Cheers,
Brian

Can you do cat /tmp/setup instead?

login as: outernet
[email protected]’s password:
ORxPi2 v2.0.000 | [email protected]:~> cat /tmp/setup
[Thu Jan 1 00:00:07 UTC 1970] Data partition /dev/mmcblk0p4 already initialized
[Thu Jan 1 00:00:07 UTC 1970] Setting up persistent configuration
[Thu Jan 1 00:00:07 UTC 1970] Copying /etc/shadow -> /mnt/persist/shadow
[Thu Jan 1 00:00:07 UTC 1970] Removing /etc/shadow
[Thu Jan 1 00:00:07 UTC 1970] Linked /mnt/persist/shadow -> /etc/shadow
[Thu Jan 1 00:00:07 UTC 1970] Copying /etc/group -> /mnt/persist/group
[Thu Jan 1 00:00:07 UTC 1970] Removing /etc/group
[Thu Jan 1 00:00:07 UTC 1970] Linked /mnt/persist/group -> /etc/group
[Thu Jan 1 00:00:07 UTC 1970] Copying /etc/profile -> /mnt/persist/profile
[Thu Jan 1 00:00:07 UTC 1970] Removing /etc/profile
[Thu Jan 1 00:00:07 UTC 1970] Linked /mnt/persist/profile -> /etc/profile
[Thu Jan 1 00:00:07 UTC 1970] Copying /etc/hostapd.conf -> /mnt/persist/hostapd.conf
[Thu Jan 1 00:00:07 UTC 1970] Removing /etc/hostapd.conf
[Thu Jan 1 00:00:07 UTC 1970] Linked /mnt/persist/hostapd.conf -> /etc/hostapd.conf
[Thu Jan 1 00:00:07 UTC 1970] Copying /etc/dnsmasq.conf -> /mnt/persist/dnsmasq.conf
[Thu Jan 1 00:00:07 UTC 1970] Removing /etc/dnsmasq.conf
[Thu Jan 1 00:00:07 UTC 1970] Linked /mnt/persist/dnsmasq.conf -> /etc/dnsmasq.conf
[Thu Jan 1 00:00:07 UTC 1970] Copying /etc/fsal-user.ini -> /mnt/persist/fsal-user.ini
[Thu Jan 1 00:00:07 UTC 1970] Removing /etc/fsal-user.ini
[Thu Jan 1 00:00:07 UTC 1970] Linked /mnt/persist/fsal-user.ini -> /etc/fsal-user.ini
[Thu Jan 1 00:00:07 UTC 1970] Copying /etc/librarian-user.ini -> /mnt/persist/librarian-user.ini
[Thu Jan 1 00:00:07 UTC 1970] Removing /etc/librarian-user.ini
[Thu Jan 1 00:00:07 UTC 1970] Linked /mnt/persist/librarian-user.ini -> /etc/librarian-user.ini
[Thu Jan 1 00:00:07 UTC 1970] Copying /etc/ondd.conf -> /mnt/persist/ondd.conf
[Thu Jan 1 00:00:07 UTC 1970] Removing /etc/ondd.conf
[Thu Jan 1 00:00:07 UTC 1970] Linked /mnt/persist/ondd.conf -> /etc/ondd.conf
[Thu Jan 1 00:00:07 UTC 1970] Copying /etc/fstab -> /mnt/persist/fstab
[Thu Jan 1 00:00:07 UTC 1970] Removing /etc/fstab
[Thu Jan 1 00:00:07 UTC 1970] Linked /mnt/persist/fstab -> /etc/fstab
[Thu Jan 1 00:00:07 UTC 1970] Copying /etc/sudoers -> /mnt/persist/sudoers
[Thu Jan 1 00:00:07 UTC 1970] Removing /etc/sudoers
[Thu Jan 1 00:00:07 UTC 1970] Linked /mnt/persist/sudoers -> /etc/sudoers
[Thu Jan 1 00:00:07 UTC 1970] Removing /etc/profile
[Thu Jan 1 00:00:07 UTC 1970] Linked /mnt/persist/profile -> /etc/profile
[Thu Jan 1 00:00:07 UTC 1970] Copying /etc/dropbear -> /mnt/persist/dropbear
[Thu Jan 1 00:00:07 UTC 1970] Removing /etc/dropbear
[Thu Jan 1 00:00:07 UTC 1970] Linked /mnt/persist/dropbear -> /etc/dropbear
[Thu Jan 1 00:00:07 UTC 1970] Copying /etc/network -> /mnt/persist/network
[Thu Jan 1 00:00:07 UTC 1970] Removing /etc/network
[Thu Jan 1 00:00:07 UTC 1970] Linked /mnt/persist/network -> /etc/network
ORxPi2 v2.0.000 | [email protected]:~>

Thanks, well, looks like there’s nothing useful there cause it’s the second run. What does mount say now?

login as: outernet
[email protected]’s password:
ORxPi2 v2.0.000 | [email protected]:~> mount
rootfs on / type rootfs (rw)
devtmpfs on /dev type devtmpfs (rw,relatime,size=427352k,nr_inodes=106838,mode=7 55)
proc on /proc type proc (rw,relatime)
devpts on /dev/pts type devpts (rw,relatime,gid=5,mode=620,ptmxmode=000)
tmpfs on /dev/shm type tmpfs (rw,relatime,mode=777)
tmpfs on /tmp type tmpfs (rw,relatime)
tmpfs on /run type tmpfs (rw,nosuid,nodev,relatime,mode=755)
sysfs on /sys type sysfs (rw,relatime)
/dev/mmcblk0p1 on /boot type vfat (rw,noatime,fmask=0022,dmask=0022,codepage=437 ,iocharset=iso8859-1,shortname=mixed,errors=remount-ro)
ORxPi2 v2.0.000 | [email protected]:~>

Hmm. So, I know this may be becoming a bit frustrating, but could you try one more thing? With the same card, flash the 2.0 image to it, boot it up, and if you get the same results as right now, copy the contents of /tmp/setup, and the output of dmesg command without restarting.

I will get to this after work today. No worries about this being frustrating. This is why I have an Outernet system in the first place … to do my small part in what I see as a great project!

OK - here is what happened:

  • fresh install onto the SD card.
  • forgot to disconnect tuner … it tried to boot a couple of times.
  • removed tuner, rebooted. The system started, but it was back to the same problem - all files say ‘skipping’.
  • reformatted and reloaded SD card, did a fresh install of Outernet as in the first step above.
  • did NOT have the tuner connected.
  • the system took a slightly longer time to start - I was watching a monitor and saw some mke2fs commands take a few seconds - presumably creating partitions.
  • logged into system, set passwords, etc. The interface looked a bit different … the files section came up with ‘currently no files or folders’. Before, there seemed to be some folders listed … but all empty. This is hard to explain without screen shots. My point is, the interface was a bit different this time.
  • hot-plugged the tuner, it recognized it OK, set satellite/lnb options and it began to download files. NO SKIPPING! (yay!)

When the boot fails with the tuner plugged in, it only took about 2 or 3 seconds or so for the boot sequence to hit the ‘magic’ command that caused the Pi to restart. I can recreate this, but not sure how to capture any logs as the reboot happens too fast. If the logs were written to a syslog during boot, I could capture the exact point where the boot fails with the tuner plugged in.

If there is anything else I could do to help troubleshoot this issue, let me know. I think a few guys out there are having the same issues.

Here is the summary (if you are getting all files shown as ‘skipping’)

  • format SD card and do a clean install
  • DO NOT CONNECT TUNER
  • let the Pi boot. Mine only took a couple of minutes. If you can connect a monitor to the Pi via HDMI, you can wait for the Outernet login screen (text) on the monitor.
  • connect to the Pi via the IP address as normally
  • I entered root password, then logged in and went to the tuner page.
  • then plugged in the tuner while leaving the tuner and Pi powered on
  • I saw the tuner get recognized on the HDMI monitor attached to the Pi
  • set proper LNB and Satellite, and away it went. After a few minutes, files began downloading!

Cheers,
Brian

OK - maybe another issue?

How long should I wait between downloaded files and when they appear in the Library. I have seen may 30 or 40 files complete (watching the download screen of the tuner), but Library still says empty.

Brian