USB camera device fails to be created

I have a USB camera attached to my BeagleBone Black, but the video device (/dev/video0) only sometimes succeeds in being created.
I havent figured out exactly what conditions cause a success or failure, however…

This is dmesg output

[ 8.990115] CAUTION: musb: Babble Interrupt Occurred

[ 9.392009] gspca_main: v2.14.0 registered
[ 9.418727] ov519 1-1:1.0: usb_probe_interface
[ 9.418754] ov519 1-1:1.0: usb_probe_interface - got id
[ 9.418775] gspca_main: ov519-2.14.0 probing 05a9:8519
[ 16.530220] gspca_ov519: Can’t determine sensor slave IDs

[ 16.553335] ov519: probe of 1-1:1.0 failed with error -22

I have found that sometimes the following will cause the device to be created, but not often

modprobe -vr gspca_ov519; modprobe -v gspca_ov519

otherwise, I have to disconnect/reconnect the camera a dozen times before it is successfully registered.

I have read this on a forum about an eye-toy camera on a completely different system, but I wonder if it’s related…
"Resolved!

Not a software bug as such, turned out to be the Intel xHCI mode setting in the bios for USB for my Asus Z87M-PLUS motherboard. Default setting was Smart Mode. This made all usb ports usb 3.0 and/or “better” than 2.0, meaning my webcmas wouldn’t play nicely. Changing the bios setting to Disabled saw the camera fire up instantly, along with routine connection of my DVB USB stick.

Other option to try is Auto, and EHCI Hand Off to Enabled"

Does anybody have any clues?

Ok, so I have gotten a little further.

It seems getting the camera working has nothing to do with my reset. It appears that the camera will ONLY function if the kernel driver is initialized AFTER EXACTLY 600 seconds (10 minutes).
I rebooted the device with the camera attached, and reinitializing the GSPCA kernel module before, and after the 10 minute mark, showed that it would always fail before 10 minutes, then immediately 10 minutes after boot, the reinitialize succeeds…

[ 591.648935] snd-usb-audio 1-1:1.1: forced unbind

[ 591.655606] snd-usb-audio 1-1:1.2: forced unbind
[ 591.706987] hub 1-0:1.0: state 7 ports 1 chg 0000 evt 0002
[ 591.759675] usb 1-1: reset full-speed USB device number 2 using musb-hdrc
[ 591.812430] hub 1-0:1.0: state 7 ports 1 chg 0000 evt 0002
[ 591.878783] usb 1-1: ep0 maxpacket = 8
[ 591.879631] ov519 1-1:1.2: usb_probe_interface
[ 591.879648] ov519 1-1:1.2: usb_probe_interface - got id
[ 591.879755] snd-usb-audio 1-1:1.1: usb_probe_interface
[ 591.879777] snd-usb-audio 1-1:1.1: usb_probe_interface - got id
[ 591.910741] usbcore: deregistering interface driver ov519
[ 591.939709] gspca_main: v2.14.0 registered
[ 591.944158] ov519 1-1:1.0: usb_probe_interface
[ 591.944184] ov519 1-1:1.0: usb_probe_interface - got id
[ 591.944207] gspca_main: ov519-2.14.0 probing 05a9:8519
[ 598.996452] gspca_ov519: Can’t determine sensor slave IDs
[ 599.002905] ov519: probe of 1-1:1.0 failed with error -22
[ 599.006279] usbcore: registered new interface driver ov519

[ 601.212615] snd-usb-audio 1-1:1.1: forced unbind
[ 601.228667] snd-usb-audio 1-1:1.2: forced unbind
[ 601.281380] hub 1-0:1.0: state 7 ports 1 chg 0000 evt 0002
[ 601.334145] usb 1-1: reset full-speed USB device number 2 using musb-hdrc
[ 601.386819] hub 1-0:1.0: state 7 ports 1 chg 0000 evt 0002
[ 601.453113] usb 1-1: ep0 maxpacket = 8
[ 601.454579] ov519 1-1:1.2: usb_probe_interface
[ 601.454632] ov519 1-1:1.2: usb_probe_interface - got id
[ 601.455073] snd-usb-audio 1-1:1.1: usb_probe_interface
[ 601.455149] snd-usb-audio 1-1:1.1: usb_probe_interface - got id
[ 601.540469] usbcore: deregistering interface driver ov519
[ 601.611502] gspca_main: v2.14.0 registered
[ 601.626470] ov519 1-1:1.0: usb_probe_interface
[ 601.626533] ov519 1-1:1.0: usb_probe_interface - got id
[ 601.626588] gspca_main: ov519-2.14.0 probing 05a9:8519
[ 601.824312] input: ov519 as /devices/ocp.2/47400000.usb/musb-hdrc.1.auto/usb1/1-1/input/input1
[ 601.826001] usbcore: registered new interface driver ov519

I performed a further test, I rebooted the beaglebone WITHOUT the camera attached, waited until the uptime was 600 seconds, then connected the camera. Auto-initialization succeeds perfectly every time (See full log output below)

So, it seems there is nothing wrong the camera - the beaglebone black is simply not ready to initialize the camera driver until it has been up for 600 seconds.
So… what is conflicting with the driver, which is being ‘fixed’ ath the 600 second mark?
I expect there must be a more verbose log somewhere, where I can see an entry at the 600 second mark… potentially incriminating another area of the system.
Is there, for example… some kind of USB power cycling that happens every 10 minutes of USB inactivity?

Ideas?
Thanks

[ 0.000000] Booting Linux on physical CPU 0x0
[ 0.000000] Initializing cgroup subsys cpu
[ 0.000000] Linux version 3.8.13 (koen@rrMBP) (gcc version 4.7.3 20130205 (prerelease) (Linaro GCC 4.7-2013.02-01) ) #1 SMP Tue Jun 18 02:11:09 EDT 2013
[ 0.000000] CPU: ARMv7 Processor [413fc082] revision 2 (ARMv7), cr=50c5387d
[ 0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
[ 0.000000] Machine: Generic AM33XX (Flattened Device Tree), model: TI AM335x BeagleBone
[ 0.000000] Memory policy: ECC disabled, Data cache writeback
[ 0.000000] On node 0 totalpages: 130816
[ 0.000000] free_area_init_node: node 0, pgdat c0688d80, node_mem_map c06e4000
[ 0.000000] Normal zone: 1024 pages used for memmap
[ 0.000000] Normal zone: 0 pages reserved
[ 0.000000] Normal zone: 129792 pages, LIFO batch:31
[ 0.000000] AM335X ES1.0 (neon )
[ 0.000000] PERCPU: Embedded 8 pages/cpu @c0af3000 s9408 r8192 d15168 u32768
[ 0.000000] pcpu-alloc: s9408 r8192 d15168 u32768 alloc=84096
[ 0.000000] pcpu-alloc: [0] 0
[ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 129792
[ 0.000000] Kernel command line: console=ttyO0,115200n8 run_hardware_tests quiet root=/dev/mmcblk0p2 ro rootfstype=ext4 rootwait
[ 0.000000] PID hash table entries: 2048 (order: 1, 8192 bytes)
[ 0.000000] Dentry cache hash table entries: 65536 (order: 6, 262144 bytes)
[ 0.000000] Inode-cache hash table entries: 32768 (order: 5, 131072 bytes)
[ 0.000000] __ex_table already sorted, skipping sort
[ 0.000000] allocated 1048576 bytes of page_cgroup
[ 0.000000] please try ‘cgroup_disable=memory’ option if you don’t want memory cgroups
[ 0.000000] Memory: 511MB = 511MB total
[ 0.000000] Memory: 510560k/510560k available, 13728k reserved, 0K highmem
[ 0.000000] Virtual kernel memory layout:
[ 0.000000] vector : 0xffff0000 - 0xffff1000 ( 4 kB)
[ 0.000000] fixmap : 0xfff00000 - 0xfffe0000 ( 896 kB)
[ 0.000000] vmalloc : 0xe0800000 - 0xff000000 ( 488 MB)
[ 0.000000] lowmem : 0xc0000000 - 0xe0000000 ( 512 MB)
[ 0.000000] pkmap : 0xbfe00000 - 0xc0000000 ( 2 MB)
[ 0.000000] modules : 0xbf800000 - 0xbfe00000 ( 6 MB)
[ 0.000000] .text : 0xc0008000 - 0xc05d5914 (5943 kB)
[ 0.000000] .init : 0xc05d6000 - 0xc06174c0 ( 262 kB)
[ 0.000000] .data : 0xc0618000 - 0xc0689ca0 ( 456 kB)
[ 0.000000] .bss : 0xc0689ca0 - 0xc06e36bc ( 359 kB)
[ 0.000000] Hierarchical RCU implementation.
[ 0.000000] RCU restricting CPUs from NR_CPUS=4 to nr_cpu_ids=1.
[ 0.000000] NR_IRQS:16 nr_irqs:16 16
[ 0.000000] IRQ: Found an INTC at 0xfa200000 (revision 5.0) with 128 interrupts
[ 0.000000] Total of 128 interrupts on 1 active controller
[ 0.000000] OMAP clockevent source: GPTIMER1 at 24000000 Hz
[ 0.000000] sched_clock: 32 bits at 24MHz, resolution 41ns, wraps every 178956ms
[ 0.000000] OMAP clocksource: GPTIMER2 at 24000000 Hz
[ 0.000000] Console: colour dummy device 80x30
[ 0.000353] Calibrating delay loop… 545.07 BogoMIPS (lpj=531968)
[ 0.015425] pid_max: default: 32768 minimum: 301
[ 0.015657] Security Framework initialized
[ 0.015754] Mount-cache hash table entries: 512
[ 0.025834] Initializing cgroup subsys cpuacct
[ 0.025867] Initializing cgroup subsys memory
[ 0.025931] Initializing cgroup subsys blkio
[ 0.026066] CPU: Testing write buffer coherency: ok
[ 0.026601] CPU0: thread -1, cpu 0, socket -1, mpidr 0
[ 0.026674] Setting up static identity map for 0x80370ee0 - 0x80370f2c
[ 0.028074] Brought up 1 CPUs
[ 0.028098] SMP: Total of 1 processors activated (545.07 BogoMIPS).
[ 0.029395] devtmpfs: initialized
[ 0.094250] pinctrl core: initialized pinctrl subsystem
[ 0.094465] rstctl core: initialized rstctl subsystem
[ 0.094950] regulator-dummy: no parameters
[ 0.095461] NET: Registered protocol family 16
[ 0.096414] DMA: preallocated 256 KiB pool for atomic coherent allocations
[ 0.106360] pinctrl-single 44e10800.pinmux: 142 pins at pa f9e10800 size 568
[ 0.107127] platform 49000000.edma: alias fck already exists
[ 0.107159] platform 49000000.edma: alias fck already exists
[ 0.107186] platform 49000000.edma: alias fck already exists
[ 0.108377] gpiochip_add: registered GPIOs 0 to 31 on device: gpio
[ 0.108564] OMAP GPIO hardware version 0.1
[ 0.109960] gpiochip_add: registered GPIOs 32 to 63 on device: gpio
[ 0.111260] gpiochip_add: registered GPIOs 64 to 95 on device: gpio
[ 0.112556] gpiochip_add: registered GPIOs 96 to 127 on device: gpio
[ 0.113047] of_get_named_gpio_flags exited with status 52
[ 0.113225] gpio-rctrl rstctl.3: loaded OK
[ 0.118550] hw-breakpoint: debug architecture 0x4 unsupported.
[ 0.120691] cpsw.0: No hwaddr in dt. Using c8:a0:30:c4:56:be from efuse
[ 0.120726] cpsw.1: No hwaddr in dt. Using c8:a0:30:c4:56:c0 from efuse
[ 0.135251] bio: create slab at 0
[ 0.147214] edma-dma-engine edma-dma-engine.0: TI EDMA DMA engine driver
[ 0.147441] of_get_named_gpio_flags: can’t parse gpios property
[ 0.147668] vmmcsd_fixed: 3300 mV
[ 0.150545] SCSI subsystem initialized
[ 0.150963] usbcore: registered new interface driver usbfs
[ 0.151076] usbcore: registered new interface driver hub
[ 0.151360] usbcore: registered new device driver usb
[ 0.153424] omap_i2c 44e0b000.i2c: bus 0 rev0.11 at 400 kHz
[ 0.154941] input: tps65217_pwr_but as /devices/ocp.2/44e0b000.i2c/i2c-0/0-0024/input/input0
[ 0.157137] DCDC1: at 1500 mV
[ 0.158359] vdd_mpu: 925 <–> 1325 mV at 1100 mV
[ 0.159462] vdd_core: 925 <–> 1150 mV at 1100 mV
[ 0.160616] LDO1: at 1800 mV
[ 0.161733] LDO2: at 3300 mV
[ 0.163702] LDO3: 1800 mV
[ 0.164828] LDO4: at 3300 mV
[ 0.165864] tps65217 0-0024: TPS65217 ID 0xe version 1.2
[ 0.166557] omap_i2c 44e0b000.i2c: unable to select pin group
[ 0.167240] omap_i2c 4819c000.i2c: bus 1 rev0.11 at 100 kHz
[ 0.169595] omap_i2c 4819c000.i2c: unable to select pin group
[ 0.169879] media: Linux media interface: v0.10
[ 0.169982] Linux video capture interface: v2.00
[ 0.170659] Advanced Linux Sound Architecture Driver Initialized.
[ 0.172033] Switching to clocksource gp_timer
[ 0.190150] NET: Registered protocol family 2
[ 0.191153] TCP established hash table entries: 4096 (order: 3, 32768 bytes)
[ 0.191298] TCP bind hash table entries: 4096 (order: 4, 81920 bytes)
[ 0.191461] TCP: Hash tables configured (established 4096 bind 4096)
[ 0.191555] TCP: reno registered
[ 0.191581] UDP hash table entries: 256 (order: 1, 12288 bytes)
[ 0.191627] UDP-Lite hash table entries: 256 (order: 1, 12288 bytes)
[ 0.192109] NET: Registered protocol family 1
[ 0.192690] RPC: Registered named UNIX socket transport module.
[ 0.192712] RPC: Registered udp transport module.
[ 0.192726] RPC: Registered tcp transport module.
[ 0.192740] RPC: Registered tcp NFSv4.1 backchannel transport module.
[ 0.193950] CPU PMU: probing PMU on CPU 0
[ 0.193980] hw perfevents: enabled with ARMv7 Cortex-A8 PMU driver, 5 counters available
[ 0.194432] omap2_mbox_probe: platform not supported
[ 0.198280] VFS: Disk quotas dquot_6.5.2
[ 0.198515] Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[ 0.199766] NFS: Registering the id_resolver key type
[ 0.199871] Key type id_resolver registered
[ 0.199888] Key type id_legacy registered
[ 0.199955] jffs2: version 2.2. (NAND) (SUMMARY) \xffffffc2\xffffffa9 2001-2006 Red Hat, Inc.
[ 0.200407] msgmni has been set to 997
[ 0.203226] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250)
[ 0.203251] io scheduler noop registered
[ 0.203267] io scheduler deadline registered
[ 0.203308] io scheduler cfq registered (default)
[ 0.205014] tps65217-bl tps65217-bl: no platform data provided
[ 0.205055] tps65217-bl: probe of tps65217-bl failed with error -22
[ 0.206034] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[ 0.208549] omap_uart 44e09000.serial: did not get pins for uart0 error: -19
[ 0.208837] 44e09000.serial: ttyO0 at MMIO 0x44e09000 (irq = 88) is a OMAP UART0
[ 0.221141] console [ttyO0] enabled
[ 0.222133] [drm] Initialized drm 1.1.0 20060810
[ 0.235953] brd: module loaded
[ 0.243042] loop: module loaded
[ 0.243169] at24 0-0050: 32768 byte 24c256 EEPROM, writable, 1 bytes/write
[ 0.243245] at24 1-0054: 32768 byte 24c256 EEPROM, writable, 1 bytes/write
[ 0.243328] at24 1-0055: 32768 byte 24c256 EEPROM, writable, 1 bytes/write
[ 0.243396] at24 1-0056: 32768 byte 24c256 EEPROM, writable, 1 bytes/write
[ 0.243464] at24 1-0057: 32768 byte 24c256 EEPROM, writable, 1 bytes/write
[ 0.250530] bone-capemgr bone_capemgr.8: Baseboard: ‘A335BNLT,0A5C,3313BBBK3287’
[ 0.250573] bone-capemgr bone_capemgr.8: compatible-baseboard=ti,beaglebone-black
[ 0.281531] bone-capemgr bone_capemgr.8: slot #0: No cape found
[ 0.318640] bone-capemgr bone_capemgr.8: slot #1: No cape found
[ 0.355746] bone-capemgr bone_capemgr.8: slot #2: No cape found
[ 0.392855] bone-capemgr bone_capemgr.8: slot #3: No cape found
[ 0.399112] bone-capemgr bone_capemgr.8: slot #4: specific override
[ 0.399155] bone-capemgr bone_capemgr.8: bone: Using override eeprom data at slot 4
[ 0.399185] bone-capemgr bone_capemgr.8: slot #4: ‘Bone-LT-eMMC-2G,00A0,Texas Instrument,BB-BONE-EMMC-2G’
[ 0.399325] bone-capemgr bone_capemgr.8: slot #5: specific override
[ 0.399362] bone-capemgr bone_capemgr.8: bone: Using override eeprom data at slot 5
[ 0.399390] bone-capemgr bone_capemgr.8: slot #5: ‘Bone-Black-HDMI,00A0,Texas Instrument,BB-BONELT-HDMI’
[ 0.399529] bone-capemgr bone_capemgr.8: slot #6: specific override
[ 0.399565] bone-capemgr bone_capemgr.8: bone: Using override eeprom data at slot 6
[ 0.399593] bone-capemgr bone_capemgr.8: slot #6: ‘Bone-Black-HDMIN,00A0,Texas Instrument,BB-BONELT-HDMIN’
[ 0.400104] bone-capemgr bone_capemgr.8: loader: before slot-4 BB-BONE-EMMC-2G:00A0 (prio 1)
[ 0.400131] bone-capemgr bone_capemgr.8: loader: check slot-4 BB-BONE-EMMC-2G:00A0 (prio 1)
[ 0.400265] bone-capemgr bone_capemgr.8: loader: before slot-5 BB-BONELT-HDMI:00A0 (prio 1)
[ 0.400290] bone-capemgr bone_capemgr.8: loader: check slot-5 BB-BONELT-HDMI:00A0 (prio 1)
[ 0.400351] bone-capemgr bone_capemgr.8: initialized OK.
[ 0.402325] OneNAND driver initializing
[ 0.402796] bone-capemgr bone_capemgr.8: loader: after slot-4 BB-BONE-EMMC-2G:00A0 (prio 1)
[ 0.402836] bone-capemgr bone_capemgr.8: slot #4: Requesting firmware ‘cape-bone-2g-emmc1.dtbo’ for board-name ‘Bone-LT-eMMC-2G’, version ‘00A0’
[ 0.402866] bone-capemgr bone_capemgr.8: slot #4: dtbo ‘cape-bone-2g-emmc1.dtbo’ loaded; converting to live tree
[ 0.403175] bone-capemgr bone_capemgr.8: slot #4: #2 overlays
[ 0.404032] bone-capemgr bone_capemgr.8: slot #4: Applied #2 overlays.
[ 0.404062] bone-capemgr bone_capemgr.8: loader: done slot-4 BB-BONE-EMMC-2G:00A0 (prio 1)
[ 0.404220] bone-capemgr bone_capemgr.8: loader: after slot-5 BB-BONELT-HDMI:00A0 (prio 1)
[ 0.404260] bone-capemgr bone_capemgr.8: slot #5: Requesting firmware ‘cape-boneblack-hdmi-00A0.dtbo’ for board-name ‘Bone-Black-HDMI’, version ‘00A0’
[ 0.404300] bone-capemgr bone_capemgr.8: slot #5: dtbo ‘cape-boneblack-hdmi-00A0.dtbo’ loaded; converting to live tree
[ 0.405525] bone-capemgr bone_capemgr.8: slot #5: #4 overlays
[ 0.407807] usbcore: registered new interface driver asix
[ 0.407932] usbcore: registered new interface driver cdc_ether
[ 0.408064] usbcore: registered new interface driver smsc95xx
[ 0.408154] usbcore: registered new interface driver net1080
[ 0.408243] usbcore: registered new interface driver cdc_subset
[ 0.408330] usbcore: registered new interface driver zaurus
[ 0.408590] usbcore: registered new interface driver cdc_ncm
[ 0.409203] bone-capemgr bone_capemgr.8: loader: before slot-6 BB-BONELT-HDMIN:00A0 (prio 2)
[ 0.409230] bone-capemgr bone_capemgr.8: loader: check slot-6 BB-BONELT-HDMIN:00A0 (prio 2)
[ 0.409259] bone-capemgr bone_capemgr.8: loader: check slot-6 BB-BONELT-HDMIN:00A0 (prio 2)
[ 0.410664] platform 4830e000.fb: alias fck already exists
[ 0.412230] bone-capemgr bone_capemgr.8: slot #5: Applied #4 overlays.
[ 0.412257] bone-capemgr bone_capemgr.8: loader: done slot-5 BB-BONELT-HDMI:00A0 (prio 1)
[ 0.412324] bone-capemgr bone_capemgr.8: loader: check slot-6 BB-BONELT-HDMIN:00A0 (prio 2)
[ 0.412448] bone-capemgr bone_capemgr.8: loader: after slot-6 BB-BONELT-HDMIN:00A0 (prio 2)
[ 0.412491] bone-capemgr bone_capemgr.8: slot #6: Requesting firmware ‘cape-boneblack-hdmin-00A0.dtbo’ for board-name ‘Bone-Black-HDMIN’, version ‘00A0’
[ 0.412532] bone-capemgr bone_capemgr.8: slot #6: dtbo ‘cape-boneblack-hdmin-00A0.dtbo’ loaded; converting to live tree
[ 0.412873] bone-capemgr bone_capemgr.8: slot #6: BB-BONELT-HDMIN conflict P8.45 (#5:BB-BONELT-HDMI)
[ 0.422493] bone-capemgr bone_capemgr.8: slot #6: Failed verification
[ 0.429269] bone-capemgr bone_capemgr.8: loader: failed to load slot-6 BB-BONELT-HDMIN:00A0 (prio 2)
[ 0.439139] usbcore: registered new interface driver cdc_acm
[ 0.439156] cdc_acm: USB Abstract Control Model driver for USB modems and ISDN adapters
[ 0.439169] Initializing USB Mass Storage driver…
[ 0.439308] usbcore: registered new interface driver usb-storage
[ 0.439322] USB Mass Storage support registered.
[ 0.439545] musb-hdrc: version 6.0, ?dma?, otg (peripheral+host)
[ 0.440000] musb-hdrc musb-hdrc.0.auto: pdev->id = 0
[ 0.440029] musb-hdrc musb-hdrc.0.auto: drivers/usb/musb/musb_dsps.c:468 dsps_musb_init: OK
[ 0.440067] musb-hdrc: ConfigData=0xde (UTMI-8, dyn FIFOs, bulk combine, bulk split, HB-ISO Rx, HB-ISO Tx, SoftConn)
[ 0.440087] musb-hdrc: MHDRC RTL version 2.0
[ 0.440101] musb-hdrc: setup fifo_mode 4
[ 0.440129] musb-hdrc: 28/31 max ep, 16384/16384 memory
[ 0.440269] musb-hdrc musb-hdrc.0.auto: *** mode=3
[ 0.440290] musb-hdrc musb-hdrc.0.auto: *** power=250
[ 0.441155] musb-hdrc musb-hdrc.1.auto: pdev->id = 1
[ 0.441184] musb-hdrc musb-hdrc.1.auto: drivers/usb/musb/musb_dsps.c:468 dsps_musb_init: OK
[ 0.441220] musb-hdrc: ConfigData=0xde (UTMI-8, dyn FIFOs, bulk combine, bulk split, HB-ISO Rx, HB-ISO Tx, SoftConn)
[ 0.441238] musb-hdrc: MHDRC RTL version 2.0
[ 0.441252] musb-hdrc: setup fifo_mode 4
[ 0.441274] musb-hdrc: 28/31 max ep, 16384/16384 memory
[ 0.441403] musb-hdrc musb-hdrc.1.auto: *** mode=1
[ 0.441423] musb-hdrc musb-hdrc.1.auto: *** power=250
[ 0.441444] musb-hdrc musb-hdrc.1.auto: MUSB HDRC host driver
[ 0.441941] musb-hdrc musb-hdrc.1.auto: new USB bus registered, assigned bus number 1
[ 0.441983] musb-hdrc musb-hdrc.1.auto: supports USB remote wakeup
[ 0.442101] usb usb1: default language 0x0409
[ 0.442160] usb usb1: udev 1, busnum 1, minor = 0
[ 0.442183] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
[ 0.442206] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 0.442226] usb usb1: Product: MUSB HDRC host driver
[ 0.442246] usb usb1: Manufacturer: Linux 3.8.13 musb-hcd
[ 0.442264] usb usb1: SerialNumber: musb-hdrc.1.auto
[ 0.443030] usb usb1: usb_probe_device
[ 0.443059] usb usb1: configuration #1 chosen from 1 choice
[ 0.443122] usb usb1: adding 1-0:1.0 (config #1, interface 0)
[ 0.443330] hub 1-0:1.0: usb_probe_interface
[ 0.443352] hub 1-0:1.0: usb_probe_interface - got id
[ 0.443381] hub 1-0:1.0: USB hub found
[ 0.443421] hub 1-0:1.0: 1 port detected
[ 0.443441] hub 1-0:1.0: standalone hub
[ 0.443458] hub 1-0:1.0: individual port power switching
[ 0.443476] hub 1-0:1.0: no over-current protection
[ 0.443493] hub 1-0:1.0: Single TT
[ 0.443514] hub 1-0:1.0: TT requires at most 8 FS bit times (666 ns)
[ 0.443533] hub 1-0:1.0: power on to power good time: 10ms
[ 0.443573] hub 1-0:1.0: local power source is good
[ 0.443813] hub 1-0:1.0: enabling power on all ports
[ 0.444874] mousedev: PS/2 mouse device common for all mice
[ 0.447259] omap_rtc 44e3e000.rtc: rtc core: registered 44e3e000.rtc as rtc0
[ 0.447753] i2c /dev entries driver
[ 0.449417] Driver for 1-wire Dallas network protocol.
[ 0.450980] omap_wdt: OMAP Watchdog Timer Rev 0x01: initial timeout 60 sec
[ 0.451334] cpuidle: using governor ladder
[ 0.451353] cpuidle: using governor menu
[ 0.451833] of_get_named_gpio_flags: can’t parse gpios property
[ 0.451853] of_get_named_gpio_flags: can’t parse gpios property
[ 0.451869] of_get_named_gpio_flags: can’t parse gpios property
[ 0.451904] omap_hsmmc mmc.4: of_parse_phandle_with_args of ‘reset’ failed
[ 0.459143] omap_hsmmc mmc.4: Failed to get rstctl; not using any
[ 0.459202] omap_hsmmc mmc.4: unable to select pin group
[ 0.459514] edma-dma-engine edma-dma-engine.0: allocated channel for 0:25
[ 0.459599] edma-dma-engine edma-dma-engine.0: allocated channel for 0:24
[ 0.459836] mmc.4 supply vmmc_aux not found, using dummy regulator
[ 0.459972] omap_hsmmc mmc.4: pins are not configured from the driver
[ 0.486862] of_get_named_gpio_flags: can’t parse gpios property
[ 0.486885] of_get_named_gpio_flags: can’t parse gpios property
[ 0.486902] of_get_named_gpio_flags: can’t parse gpios property
[ 0.486956] gpio-rctrl rstctl.3: gpio_rctrl_request eMMC_RSTn
[ 0.487043] omap_hsmmc mmc.10: Got rstctl (gpio:#0 name eMMC_RSTn) label:eMMC_RSTn
[ 0.487065] gpio-rctrl rstctl.3: gpio_rctrl_deassert eMMC_RSTn
[ 0.487369] edma-dma-engine edma-dma-engine.0: allocated channel for 0:3
[ 0.487450] edma-dma-engine edma-dma-engine.0: allocated channel for 0:2
[ 0.487978] mmc.10 supply vmmc_aux not found, using dummy regulator
[ 0.488194] omap_hsmmc mmc.10: pins are not configured from the driver
[ 0.516315] pinctrl-single 44e10800.pinmux: pin 44e10854 already requested by 44e10800.pinmux; cannot claim for gpio-leds.7
[ 0.528030] pinctrl-single 44e10800.pinmux: pin-21 (gpio-leds.7) status -22
[ 0.535345] pinctrl-single 44e10800.pinmux: could not request pin 21 on device pinctrl-single
[ 0.544328] leds-gpio gpio-leds.7: pins are not configured from the driver
[ 0.544368] of_get_named_gpio_flags exited with status 53
[ 0.544391] of_get_named_gpio_flags exited with status 54
[ 0.544413] of_get_named_gpio_flags exited with status 55
[ 0.544434] of_get_named_gpio_flags exited with status 56
[ 0.544459] of_get_named_gpio_flags exited with status 53
[ 0.544714] of_get_named_gpio_flags exited with status 54
[ 0.544927] of_get_named_gpio_flags exited with status 55
[ 0.545126] of_get_named_gpio_flags exited with status 56
[ 0.545312] hub 1-0:1.0: state 7 ports 1 chg 0000 evt 0000
[ 0.545382] hub 1-0:1.0: hub_suspend
[ 0.545423] usb usb1: bus auto-suspend, wakeup 1
[ 0.545949] ledtrig-cpu: registered to indicate activity on CPUs
[ 0.546398] edma-dma-engine edma-dma-engine.0: allocated channel for 0:36
[ 0.546478] omap-sham 53100000.sham: hw accel on OMAP rev 4.3
[ 0.548662] omap-aes 53500000.aes: OMAP AES hw accel rev: 3.2
[ 0.548792] edma-dma-engine edma-dma-engine.0: allocated channel for 0:5
[ 0.548870] edma-dma-engine edma-dma-engine.0: allocated channel for 0:6
[ 0.550216] usbcore: registered new interface driver usbhid
[ 0.550236] usbhid: USB HID core driver
[ 0.553320] of_get_named_gpio_flags exited with status 59
[ 0.554653] davinci_evm sound.13: nxp-hdmi-hifi <-> 48038000.mcasp mapping ok
[ 0.557014] mmc0: host does not support reading read-only switch. assuming write-enable.
[ 0.558624] TCP: cubic registered
[ 0.558645] Initializing XFRM netlink socket
[ 0.558690] NET: Registered protocol family 17
[ 0.558767] NET: Registered protocol family 15
[ 0.559079] mmc0: new high speed SDHC card at address 59b4
[ 0.559903] Key type dns_resolver registered
[ 0.560231] VFP support v0.3: implementor 41 architecture 3 part 30 variant c rev 3
[ 0.560270] ThumbEE CPU extension supported.
[ 0.560315] Registering SWP/SWPB emulation handler
[ 0.561357] registered taskstats version 1
[ 0.562712] slave hdmi.12: modes-blacklisted #0 → 1920x1080@25
[ 0.562912] mmcblk0: mmc0:59b4 SD 30.0 GiB
[ 0.563980] slave hdmi.12: modes-blacklisted #1 → 832x624@75
[ 0.565267] mmcblk0: p1 p2
[ 0.567547] of_get_named_gpio_flags: can’t parse gpios property
[ 0.567577] tilcdc 4830e000.fb: No power control GPIO
[ 0.619050] mmc1: BKOPS_EN bit is not set
[ 0.621866] mmc1: new high speed MMC card at address 0001
[ 0.622656] mmcblk1: mmc1:0001 MMC02G 1.78 GiB
[ 0.623005] mmcblk1boot0: mmc1:0001 MMC02G partition 1 1.00 MiB
[ 0.623411] mmcblk1boot1: mmc1:0001 MMC02G partition 2 1.00 MiB
[ 0.625579] mmcblk1: p1 p2
[ 0.628435] mmcblk1boot1: unknown partition table
[ 0.630399] mmcblk1boot0: unknown partition table
[ 0.677190] tilcdc 4830e000.fb: found TDA19988
[ 0.678045] [drm] Supports vblank timestamp caching Rev 1 (10.10.2010).
[ 0.678062] [drm] No driver support for vblank timestamp query.
[ 0.678473] tilcdc 4830e000.fb: No connectors reported connected with modes
[ 0.678501] [drm] Cannot find any crtc or sizes - going 1024x768
[ 0.695814] Console: switching to colour frame buffer device 128x48
[ 0.710474] tilcdc 4830e000.fb: fb0: frame buffer device
[ 0.710495] tilcdc 4830e000.fb: registered panic notifier
[ 0.710534] [drm] Initialized tilcdc 1.0.0 20121205 on minor 0
[ 0.760066] davinci_mdio 4a101000.mdio: davinci mdio revision 1.6
[ 0.760097] davinci_mdio 4a101000.mdio: detected phy mask fffffffe
[ 0.761105] libphy: 4a101000.mdio: probed
[ 0.761135] davinci_mdio 4a101000.mdio: phy[0]: device 4a101000.mdio:00, driver SMSC LAN8710/LAN8720
[ 0.761364] Detected MACID = c8:a0:30:c4:56:be
[ 0.761477] cpsw 4a100000.ethernet: NAPI disabled
[ 0.763062] omap_rtc 44e3e000.rtc: setting system clock to 2000-01-01 00:00:01 UTC (946684801)
[ 0.770297] ALSA device list:
[ 0.770321] #0: TI BeagleBone Black
[ 0.774888] EXT4-fs (mmcblk0p2): INFO: recovery required on readonly filesystem
[ 0.774905] EXT4-fs (mmcblk0p2): write access will be enabled during recovery
[ 2.412705] EXT4-fs (mmcblk0p2): orphan cleanup on readonly fs
[ 2.412756] EXT4-fs (mmcblk0p2): ext4_orphan_cleanup: deleting unreferenced inode 37689
[ 2.413077] EXT4-fs (mmcblk0p2): ext4_orphan_cleanup: deleting unreferenced inode 37652
[ 2.413161] EXT4-fs (mmcblk0p2): 2 orphan inodes deleted
[ 2.413172] EXT4-fs (mmcblk0p2): recovery complete
[ 2.488710] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
[ 2.488779] VFS: Mounted root (ext4 filesystem) readonly on device 179:2.
[ 2.491709] devtmpfs: mounted
[ 2.491983] Freeing init memory: 260K
[ 3.265626] systemd[1]: systemd 196 running in system mode. (+PAM -LIBWRAP -AUDIT -SELINUX +IMA +SYSVINIT -LIBCRYPTSETUP +GCRYPT +ACL +XZ; angstrom)
[ 3.352584] systemd[1]: Inserted module ‘autofs4’
[ 3.354542] systemd[1]: Set hostname to .
[ 3.358684] systemd[1]: Initializing machine ID from random generator.
[ 3.358981] systemd[1]: Installed transient /etc/machine-id file.
[ 3.639292] systemd[1]: Starting Forward Password Requests to Wall Directory Watch.
[ 3.639755] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[ 3.639864] systemd[1]: Expecting device dev-ttyGS0.device…
[ 3.639952] systemd[1]: Expecting device dev-ttyO0.device…
[ 3.640030] systemd[1]: Starting Remote File Systems.
[ 3.640100] systemd[1]: Reached target Remote File Systems.
[ 3.640173] systemd[1]: Starting Delayed Shutdown Socket.
[ 3.640367] systemd[1]: Listening on Delayed Shutdown Socket.
[ 3.640432] systemd[1]: Starting /dev/initctl Compatibility Named Pipe.
[ 3.640580] systemd[1]: Listening on /dev/initctl Compatibility Named Pipe.
[ 3.640673] systemd[1]: Starting Dispatch Password Requests to Console Directory Watch.
[ 3.641079] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
[ 3.641335] systemd[1]: Starting Arbitrary Executable File Formats File System Automount Point.
[ 3.642025] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
[ 3.642553] systemd[1]: Starting udev Kernel Socket.
[ 3.642771] systemd[1]: Listening on udev Kernel Socket.
[ 3.643315] systemd[1]: Starting udev Control Socket.
[ 3.643554] systemd[1]: Listening on udev Control Socket.
[ 3.643624] systemd[1]: Starting Swap.
[ 3.643692] systemd[1]: Reached target Swap.
[ 3.643788] systemd[1]: Starting Journal Socket.
[ 3.644126] systemd[1]: Listening on Journal Socket.
[ 3.644288] systemd[1]: Starting File System Check on Root Device…
[ 3.651094] systemd[1]: Starting udev Coldplug all Devices…
[ 3.658833] systemd[1]: Mounting Debug File System…
[ 3.692113] systemd[1]: Started Set Up Additional Binary Formats.
[ 3.697629] systemd[1]: Starting Apply Kernel Variables…
[ 3.713242] systemd[1]: Starting Load Kernel Modules…
[ 3.718374] systemd[1]: Mounted Huge Pages File System.
[ 3.718510] systemd[1]: Started Machine ID first boot configure.
[ 3.718625] systemd[1]: Mounting POSIX Message Queue File System…
[ 3.727138] systemd[1]: Starting udev Kernel Device Manager…
[ 3.734163] systemd[1]: Starting Journal Service…
[ 3.740261] systemd[1]: Started Journal Service.
[ 3.960516] Bluetooth: Core ver 2.16
[ 3.960613] NET: Registered protocol family 31
[ 3.960622] Bluetooth: HCI device and connection manager initialized
[ 3.960662] Bluetooth: HCI socket layer initialized
[ 3.960678] Bluetooth: L2CAP socket layer initialized
[ 3.960716] Bluetooth: SCO socket layer initialized
[ 3.986624] Bluetooth: HIDP (Human Interface Emulation) ver 1.2
[ 3.986676] Bluetooth: HIDP socket layer initialized
[ 4.171859] systemd-udevd[91]: starting version 196
[ 4.303906] NET: Registered protocol family 10
[ 4.353313] NET: Registered protocol family 23
[ 4.361953] IrCOMM protocol (Dag Brattli)
[ 4.418830] Bluetooth: RFCOMM TTY layer initialized
[ 4.418908] Bluetooth: RFCOMM socket layer initialized
[ 4.418919] Bluetooth: RFCOMM ver 1.11
[ 4.754970] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
[ 5.110494] systemd-journald[92]: Received SIGUSR1
[ 7.008757] udc musb-hdrc.0.auto: registering UDC driver [g_multi]
[ 7.008866] gadget: using random self ethernet address
[ 7.009618] usb0: MAC 6e:79:6d:c3:cb:10
[ 7.009632] usb0: HOST MAC c8:a0:30:c4:56:c0
[ 7.016088] gserial_setup: registered 1 ttyGS
device
[ 7.016242] lun0: open backing file: /dev/mmcblk0p1
[ 7.019315] gadget: Mass Storage Function, version: 2009/09/11
[ 7.019333] gadget: Number of LUNs=1
[ 7.019355] lun0: LUN: removable file: /dev/mmcblk0p1
[ 7.019366] gadget: I/O thread pid: 268
[ 7.019386] gadget: adding config #1 ‘Multifunction with RNDIS’/bf8ee59c
[ 7.019452] gadget: adding ‘rndis’/dcd5af00 to config ‘Multifunction with RNDIS’/bf8ee59c
[ 7.019479] rndis_register: configNr = 0
[ 7.019488] rndis_set_param_medium: 0 0
[ 7.019499] gadget: RNDIS: dual speed IN/ep1in OUT/ep1out NOTIFY/ep2in
[ 7.019513] gadget: adding ‘acm’/dcd5ae40 to config ‘Multifunction with RNDIS’/bf8ee59c
[ 7.019534] gadget: acm ttyGS0: dual speed IN/ep3in OUT/ep2out NOTIFY/ep4in
[ 7.019546] gadget: adding ‘Mass Storage Function’/dcd81a40 to config ‘Multifunction with RNDIS’/bf8ee59c
[ 7.019564] gadget: cfg 1/bf8ee59c speeds: high full
[ 7.019574] gadget: interface 0 = rndis/dcd5af00
[ 7.019584] gadget: interface 1 = rndis/dcd5af00
[ 7.019593] gadget: interface 2 = acm/dcd5ae40
[ 7.019602] gadget: interface 3 = acm/dcd5ae40
[ 7.019612] gadget: interface 4 = Mass Storage Function/dcd81a40
[ 7.019626] gadget: Multifunction Composite Gadget
[ 7.019650] gadget: g_multi ready
[ 7.019692] musb-hdrc musb-hdrc.0.auto: MUSB HDRC host driver
[ 7.024252] musb-hdrc musb-hdrc.0.auto: new USB bus registered, assigned bus number 2
[ 7.024288] musb-hdrc musb-hdrc.0.auto: supports USB remote wakeup
[ 7.024360] usb usb2: default language 0x0409
[ 7.024395] usb usb2: udev 1, busnum 2, minor = 128
[ 7.024408] usb usb2: New USB device found, idVendor=1d6b, idProduct=0002
[ 7.024421] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 7.024433] usb usb2: Product: MUSB HDRC host driver
[ 7.024444] usb usb2: Manufacturer: Linux 3.8.13 musb-hcd
[ 7.024454] usb usb2: SerialNumber: musb-hdrc.0.auto
[ 7.024924] usb usb2: usb_probe_device
[ 7.024941] usb usb2: configuration #1 chosen from 1 choice
[ 7.024985] usb usb2: adding 2-0:1.0 (config #1, interface 0)
[ 7.025110] hub 2-0:1.0: usb_probe_interface
[ 7.025123] hub 2-0:1.0: usb_probe_interface - got id
[ 7.025142] hub 2-0:1.0: USB hub found
[ 7.025167] hub 2-0:1.0: 1 port detected
[ 7.025177] hub 2-0:1.0: standalone hub
[ 7.025187] hub 2-0:1.0: individual port power switching
[ 7.025197] hub 2-0:1.0: no over-current protection
[ 7.025207] hub 2-0:1.0: Single TT
[ 7.025219] hub 2-0:1.0: TT requires at most 8 FS bit times (666 ns)
[ 7.025230] hub 2-0:1.0: power on to power good time: 10ms
[ 7.025254] hub 2-0:1.0: local power source is good
[ 7.025313] hub 2-0:1.0: enabling power on all ports
[ 7.125453] hub 2-0:1.0: state 7 ports 1 chg 0000 evt 0000
[ 7.125513] hub 2-0:1.0: hub_suspend
[ 7.125540] usb usb2: bus auto-suspend, wakeup 1
[ 7.258438] CAUTION: musb: Babble Interrupt Occurred
[ 7.349436] CAUTION: musb: Babble Interrupt Occurred
[ 8.238674] gs_open: ttyGS0 (dcd5b800,dca7f180)
[ 8.238810] gs_close: ttyGS0 (dcd5b800,dca7f180) …
[ 8.238823] gs_close: ttyGS0 (dcd5b800,dca7f180) done!
[ 8.239060] gs_open: ttyGS0 (dcd5b800,dc99a740)
[ 8.239141] gs_close: ttyGS0 (dcd5b800,dc99a740) …
[ 8.239153] gs_close: ttyGS0 (dcd5b800,dc99a740) done!
[ 8.267960] gs_open: ttyGS0 (dcd5b800,df7a2bc0)
[ 8.268097] gs_close: ttyGS0 (dcd5b800,df7a2bc0) …
[ 8.268111] gs_close: ttyGS0 (dcd5b800,df7a2bc0) done!
[ 8.270099] gs_open: ttyGS0 (dcd5b800,df5990c0)
[ 8.977427] usb0: eth_open
[ 8.977633] IPv6: ADDRCONF(NETDEV_UP): usb0: link is not ready
[ 10.233884] usb0: stop stats: rx/tx 0/0, errs 0/0
[ 10.300058] ip_tables: © 2000-2006 Netfilter Core Team
[ 10.547006] net eth0: initializing cpsw version 1.12 (0)
[ 10.551585] net eth0: phy found : id is : 0x7c0f1
[ 10.551611] libphy: PHY 4a101000.mdio:01 not found
[ 10.556700] net eth0: phy 4a101000.mdio:01 not found on slave 1
[ 10.620791] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[ 13.629740] libphy: 4a101000.mdio:00 - Link is Up - 100/Full
[ 13.629805] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[ 26.933459] EXT4-fs (mmcblk0p2): re-mounted. Opts: commit=0
[ 38.460725] fuse init (API version 7.20)
[ 46.781812] EXT4-fs (mmcblk1p2): recovery complete
[ 46.781851] EXT4-fs (mmcblk1p2): mounted filesystem with ordered data mode. Opts: (null)
[ 685.178669] usb usb1: usb wakeup-resume
[ 685.178768] usb usb1: usb auto-resume
[ 685.178820] hub 1-0:1.0: hub_resume
[ 685.178929] hub 1-0:1.0: port 1: status 0101 change 0001
[ 685.281726] hub 1-0:1.0: state 7 ports 1 chg 0002 evt 0000
[ 685.281847] hub 1-0:1.0: port 1, status 0101, change 0000, 12 Mb/s
[ 685.387113] usb 1-1: new full-speed USB device number 2 using musb-hdrc
[ 685.506210] usb 1-1: ep0 maxpacket = 8
[ 685.507400] usb 1-1: skipped 3 descriptors after interface
[ 685.507446] usb 1-1: skipped 2 descriptors after interface
[ 685.507488] usb 1-1: skipped 1 descriptor after endpoint
[ 685.507696] usb 1-1: default language 0x0409
[ 685.508348] usb 1-1: udev 2, busnum 1, minor = 1
[ 685.508396] usb 1-1: New USB device found, idVendor=05a9, idProduct=8519
[ 685.508438] usb 1-1: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[ 685.508475] usb 1-1: Product: USB Camera
[ 685.508511] usb 1-1: Manufacturer: OmniVision Technologies, Inc.
[ 685.509774] usb 1-1: usb_probe_device
[ 685.509828] usb 1-1: configuration #1 chosen from 1 choice
[ 685.510216] usb 1-1: adding 1-1:1.0 (config #1, interface 0)
[ 685.511076] usb 1-1: adding 1-1:1.1 (config #1, interface 1)
[ 685.511641] usb 1-1: adding 1-1:1.2 (config #1, interface 2)
[ 685.514425] hub 1-0:1.0: state 7 ports 1 chg 0000 evt 0002
[ 685.514524] hub 1-0:1.0: port 1 enable change, status 00000103
[ 685.688842] gspca_main: v2.14.0 registered
[ 685.710469] ov519 1-1:1.0: usb_probe_interface
[ 685.710534] ov519 1-1:1.0: usb_probe_interface - got id
[ 685.710594] gspca_main: ov519-2.14.0 probing 05a9:8519
[ 685.912569] input: ov519 as /devices/ocp.2/47400000.usb/musb-hdrc.1.auto/usb1/1-1/input/input1
[ 685.920611] ov519 1-1:1.1: usb_probe_interface
[ 685.920672] ov519 1-1:1.1: usb_probe_interface - got id
[ 685.920831] ov519 1-1:1.2: usb_probe_interface
[ 685.920873] ov519 1-1:1.2: usb_probe_interface - got id
[ 685.930554] usbcore: registered new interface driver ov519
[ 686.080828] snd-usb-audio 1-1:1.1: usb_probe_interface
[ 686.080924] snd-usb-audio 1-1:1.1: usb_probe_interface - got id
[ 686.131761] usbcore: registered new interface driver snd-usb-audio

Well,
Made some progress. Thanks to David, the issue has been tracked down to the cpu ‘performance’ profile.
One switching to ‘ondemand’, resetting the camera driver loads perfectly.
The driver reset would previously only work after 10 minutes, because the Angstrom distro is set by default boot in the problematic performance profile, then switch to ‘ondemand’ at 10 minutes.

So…

The total resolution, if you are having the same problem, is to run the script after reboot…

cpufreq-set -c 0 -g ondemand
modprobe -vr gspca_ov519; modprobe -v gspca_ov519;
cpufreq-set -c 0 -g performance

The next question… is, how do I change the default boot cpu governor profile?