vcdbg log msg

root@airpi:~# vcdbg log msg
001635.470: brfs: File read: /mfs/sd/config.txt
001636.270: brfs: File read: 560 bytes
001687.602: HDMI0:EDID error reading EDID block 0 attempt 0
001688.870: HDMI0:EDID error reading EDID block 0 attempt 1
001690.134: HDMI0:EDID error reading EDID block 0 attempt 2
001691.400: HDMI0:EDID error reading EDID block 0 attempt 3
001692.665: HDMI0:EDID error reading EDID block 0 attempt 4
001693.931: HDMI0:EDID error reading EDID block 0 attempt 5
001695.195: HDMI0:EDID error reading EDID block 0 attempt 6
001696.461: HDMI0:EDID error reading EDID block 0 attempt 7
001697.726: HDMI0:EDID error reading EDID block 0 attempt 8
001698.992: HDMI0:EDID error reading EDID block 0 attempt 9
001700.014: HDMI0:EDID giving up on reading EDID block 0
001705.894: brfs: File read: /mfs/sd/config.txt
002552.937: gpioman: gpioman_get_pin_num: pin DISPLAY_DSI_PORT not defined
002555.023: *** Restart logging
002555.049: brfs: File read: 560 bytes
002557.008: hdmi: HDMI0:EDID error reading EDID block 0 attempt 0
002558.277: hdmi: HDMI0:EDID error reading EDID block 0 attempt 1
002559.545: hdmi: HDMI0:EDID error reading EDID block 0 attempt 2
002560.816: hdmi: HDMI0:EDID error reading EDID block 0 attempt 3
002562.083: hdmi: HDMI0:EDID error reading EDID block 0 attempt 4
002563.353: hdmi: HDMI0:EDID error reading EDID block 0 attempt 5
002564.622: hdmi: HDMI0:EDID error reading EDID block 0 attempt 6
002565.892: hdmi: HDMI0:EDID error reading EDID block 0 attempt 7
002567.160: hdmi: HDMI0:EDID error reading EDID block 0 attempt 8
002568.430: hdmi: HDMI0:EDID error reading EDID block 0 attempt 9
002569.457: hdmi: HDMI0:EDID giving up on reading EDID block 0
002569.791: hdmi: HDMI0:EDID error reading EDID block 0 attempt 0
002571.060: hdmi: HDMI0:EDID error reading EDID block 0 attempt 1
002572.328: hdmi: HDMI0:EDID error reading EDID block 0 attempt 2
002573.598: hdmi: HDMI0:EDID error reading EDID block 0 attempt 3
002574.865: hdmi: HDMI0:EDID error reading EDID block 0 attempt 4
002576.135: hdmi: HDMI0:EDID error reading EDID block 0 attempt 5
002577.403: hdmi: HDMI0:EDID error reading EDID block 0 attempt 6
002578.673: hdmi: HDMI0:EDID error reading EDID block 0 attempt 7
002579.940: hdmi: HDMI0:EDID error reading EDID block 0 attempt 8
002581.211: hdmi: HDMI0:EDID error reading EDID block 0 attempt 9
002582.237: hdmi: HDMI0:EDID giving up on reading EDID block 0
002582.281: hdmi: HDMI:hdmi_get_state is deprecated, use hdmi_get_display_state instead
002582.305: HDMI0: hdmi_pixel_encoding: 162000000
002582.555: vec: vec_middleware_power_on: vec_base: 0x7e806000 rev-id 0x00002708 @ vec: 0x7e806100 @ 0x00000420 enc: 0x7e806060 @ 0x00000220 cgmsae: 0x7e80605c @ 0x00000000
002588.095: dtb_file 'bcm2710-rpi-3-b-plus.dtb'
002588.112: Trying Device Tree file 'bcm2710-rpi-3-b-plus.dtb'
002592.847: brfs: File read: /mfs/sd/bcm2710-rpi-3-b-plus.dtb
002592.871: Loading 'bcm2710-rpi-3-b-plus.dtb' to 0x100 size 0x6a07
002602.874: dtdebug: delete_node(/__local_fixups__)
002605.857: brfs: File read: 27143 bytes
002619.168: dtdebug: using platform 'bcm2835'
002619.187: dtdebug: overlay map not loaded
002620.821: dtdebug: /aliases:i2c_vc=i2c0
002626.007: dtdebug: /__symbols__:i2c_vc=i2c0
002629.687: dtdebug: /__overrides__:i2c_vc=i2c0
002637.206: dtdebug: /__overrides__:i2c_vc_baudrate=i2c0_baudrate
002638.860: dtdebug: /aliases:i2c=i2c1
002644.157: dtdebug: /__symbols__:i2c=i2c1
002647.939: dtdebug: /__overrides__:i2c=i2c1
002649.621: dtdebug: /aliases:i2c_arm=i2c1
002655.002: dtdebug: /__symbols__:i2c_arm=i2c1
002658.810: dtdebug: /__overrides__:i2c_arm=i2c1
002666.430: dtdebug: /__overrides__:i2c_baudrate=i2c1_baudrate
002674.051: dtdebug: /__overrides__:i2c_arm_baudrate=i2c1_baudrate
002678.150: dtparam: uart0_clkrate=48000000
002680.992: dtdebug: /__overrides__ has no uart0_clkrate property
002681.006: Unknown dtparam 'uart0_clkrate' - ignored
002683.758: brfs: File read: /mfs/sd/config.txt
002683.818: brfs: File read: 560 bytes
002690.683: dtdebug: Opened overlay file 'overlays/pi3-disable-bt.dtbo'
002691.291: brfs: File read: /mfs/sd/overlays/pi3-disable-bt.dtbo
002704.256: Loaded overlay 'pi3-disable-bt'
002704.292: dtparam: spi=on
002704.499: dtdebug: /__overrides__ node not found
002707.161: dtdebug: found override spi
002707.199: dtdebug:   override spi: string target 'status'
002721.230: dtdebug: merge_fragment(/soc/serial@7e215040,/fragment@0/__overlay__)
002721.258: dtdebug:   +prop(status)
002721.814: dtdebug: merge_fragment() end
002727.147: dtdebug: merge_fragment(/soc/serial@7e201000,/fragment@1/__overlay__)
002727.175: dtdebug:   +prop(pinctrl-names)
002727.814: dtdebug:   +prop(pinctrl-0)
002728.452: dtdebug:   +prop(status)
002729.101: dtdebug: merge_fragment() end
002734.260: dtdebug: merge_fragment(/soc/gpio@7e200000/uart0_pins,/fragment@2/__overlay__)
002734.289: dtdebug:   +prop(brcm,pins)
002734.900: dtdebug:   +prop(brcm,function)
002735.509: dtdebug:   +prop(brcm,pull)
002736.131: dtdebug: merge_fragment() end
002741.253: dtdebug: merge_fragment(/soc/gpio@7e200000/bt_pins,/fragment@3/__overlay__)
002741.282: dtdebug:   +prop(brcm,pins)
002741.893: dtdebug:   +prop(brcm,function)
002742.504: dtdebug:   +prop(brcm,pull)
002743.125: dtdebug: merge_fragment() end
002743.444: dtdebug: merge_fragment(/aliases,/fragment@4/__overlay__)
002743.470: dtdebug:   +prop(serial0)
002744.304: dtdebug:   +prop(serial1)
002745.146: dtdebug: merge_fragment() end
002745.234: brfs: File read: 954 bytes
002754.950: dtdebug: Opened overlay file 'overlays/spi0-4cs.dtbo'
002756.472: brfs: File read: /mfs/sd/overlays/spi0-4cs.dtbo
002765.562: Loaded overlay 'spi0-4cs'
002771.641: dtdebug: merge_fragment(/soc/gpio@7e200000/spi0_cs_pins,/fragment@0/__overlay__)
002771.669: dtdebug:   +prop(brcm,pins)
002772.301: dtdebug: merge_fragment() end
002778.034: dtdebug: merge_fragment(/soc/spi@7e204000,/fragment@1/__overlay__)
002778.062: dtdebug:   +prop(#size-cells)
002778.677: dtdebug:   +prop(#address-cells)
002779.280: dtdebug:   +prop(cs-gpios)
002782.611: dtdebug: merge_fragment(/soc/spi@7e204000/spidev@2,/fragment@1/__overlay__/spidev@2)
002782.637: dtdebug:   +prop(compatible)
002783.211: dtdebug:   +prop(reg)
002783.823: dtdebug:   +prop(#address-cells)
002784.408: dtdebug:   +prop(#size-cells)
002785.000: dtdebug:   +prop(spi-max-frequency)
002785.681: dtdebug:   +prop(status)
002786.319: dtdebug:   +prop(phandle)
002786.970: dtdebug: merge_fragment() end
002789.748: dtdebug: merge_fragment(/soc/spi@7e204000/spidev@3,/fragment@1/__overlay__/spidev@3)
002789.775: dtdebug:   +prop(compatible)
002790.356: dtdebug:   +prop(reg)
002790.972: dtdebug:   +prop(#address-cells)
002791.560: dtdebug:   +prop(#size-cells)
002792.155: dtdebug:   +prop(spi-max-frequency)
002792.841: dtdebug:   +prop(status)
002793.483: dtdebug:   +prop(phandle)
002794.137: dtdebug: merge_fragment() end
002794.170: dtdebug: merge_fragment() end
002794.295: brfs: File read: 1144 bytes
002804.052: dtdebug: Opened overlay file 'overlays/spi1-1cs.dtbo'
002805.834: brfs: File read: /mfs/sd/overlays/spi1-1cs.dtbo
002817.038: Loaded overlay 'spi1-1cs'
002817.055: dtparam: cs0_pin=16
002817.250: dtdebug: found override cs0_pin
002817.300: dtdebug:   override cs0_pin: cell target brcm,pins @ offset 0 (size 4)
002817.526: dtdebug:   override cs0_pin: cell target cs-gpios @ offset 4 (size 4)
002817.864: dtparam: cs0_spidev=disabled
002818.064: dtdebug: found override cs0_spidev
002818.101: dtdebug:   override cs0_spidev: string target 'status'
002823.205: dtdebug: merge_fragment(/soc/gpio@7e200000,/fragment@0/__overlay__)
002827.055: dtdebug: merge_fragment(/soc/gpio@7e200000/spi1_pins,/fragment@0/__overlay__/spi1_pins)
002827.083: dtdebug:   +prop(brcm,pins)
002827.901: dtdebug:   +prop(brcm,function)
002828.726: dtdebug:   +prop(phandle)
002829.528: dtdebug: merge_fragment() end
002833.436: dtdebug: merge_fragment(/soc/gpio@7e200000/spi1_cs_pins,/fragment@0/__overlay__/spi1_cs_pins)
002833.461: dtdebug:   +prop(brcm,pins)
002834.285: dtdebug:   +prop(brcm,function)
002835.111: dtdebug:   +prop(phandle)
002835.919: dtdebug: merge_fragment() end
002835.946: dtdebug: merge_fragment() end
002842.986: dtdebug: merge_fragment(/soc/spi@7e215080,/fragment@1/__overlay__)
002843.013: dtdebug:   +prop(#address-cells)
002843.558: dtdebug:   +prop(#size-cells)
002844.100: dtdebug:   +prop(pinctrl-names)
002844.733: dtdebug:   +prop(pinctrl-0)
002845.393: dtdebug:   +prop(cs-gpios)
002846.059: dtdebug:   +prop(status)
002849.623: dtdebug: merge_fragment(/soc/spi@7e215080/spidev@0,/fragment@1/__overlay__/spidev@0)
002849.650: dtdebug:   +prop(compatible)
002850.175: dtdebug:   +prop(reg)
002850.733: dtdebug:   +prop(#address-cells)
002851.263: dtdebug:   +prop(#size-cells)
002851.801: dtdebug:   +prop(spi-max-frequency)
002852.429: dtdebug:   +prop(status)
002853.013: dtdebug:   +prop(phandle)
002853.609: dtdebug: merge_fragment() end
002853.642: dtdebug: merge_fragment() end
002860.548: dtdebug: merge_fragment(/soc/aux@7e215000,/fragment@2/__overlay__)
002860.576: dtdebug:   +prop(status)
002861.194: dtdebug: merge_fragment() end
002861.366: brfs: File read: 1523 bytes
002869.694: dtdebug: Opened overlay file 'overlays/rcio.dtbo'
002870.045: brfs: File read: /mfs/sd/overlays/rcio.dtbo
002875.966: Loaded overlay 'rcio'
002883.371: dtdebug: merge_fragment(/soc/spi@7e215080,/fragment@0/__overlay__)
002883.398: dtdebug:   +prop(#address-cells)
002883.968: dtdebug:   +prop(#size-cells)
002884.530: dtdebug:   +prop(status)
002888.038: dtdebug: merge_fragment(/soc/spi@7e215080/rcio@0,/fragment@0/__overlay__/rcio@0)
002888.064: dtdebug:   +prop(compatible)
002888.591: dtdebug:   +prop(spi-max-frequency)
002889.203: dtdebug:   +prop(reg)
002889.770: dtdebug:   +prop(status)
002890.354: dtdebug: merge_fragment() end
002890.382: dtdebug: merge_fragment() end
002890.478: brfs: File read: 431 bytes
002897.750: dtdebug: Opened overlay file 'overlays/navio-rgb.dtbo'
002898.346: brfs: File read: /mfs/sd/overlays/navio-rgb.dtbo
002907.003: Loaded overlay 'navio-rgb'
002907.039: dtparam: i2c1=on
002907.206: dtdebug: /__overrides__ node not found
002909.985: dtdebug: found override i2c1
002910.023: dtdebug:   override i2c1: string target 'status'
002915.901: dtparam: i2c1_baudrate=1000000
002916.070: dtdebug: /__overrides__ node not found
002918.874: dtdebug: found override i2c1_baudrate
002918.929: dtdebug:   override i2c1_baudrate: cell target clock-frequency @ offset 0 (size 4)
002924.429: dtparam: i2c_arm=on
002924.595: dtdebug: /__overrides__ node not found
002927.296: dtdebug: found override i2c_arm
002927.334: dtdebug:   override i2c_arm: string target 'status'
002933.198: dtparam: i2c_vc=on
002933.366: dtdebug: /__overrides__ node not found
002936.086: dtdebug: found override i2c_vc
002936.123: dtdebug:   override i2c_vc: string target 'status'
002954.780: dtdebug: merge_fragment(/leds,/fragment@0/__overlay__)
002958.863: dtdebug: merge_fragment(/leds/rgb_led0,/fragment@0/__overlay__/rgb_led0)
002958.890: dtdebug:   +prop(gpios)
002959.252: dtdebug:   +prop(default-state)
002959.708: dtdebug: merge_fragment() end
002959.733: dtdebug: merge_fragment() end
002971.961: dtdebug: merge_fragment(/leds,/fragment@1/__overlay__)
002976.137: dtdebug: merge_fragment(/leds/rgb_led1,/fragment@1/__overlay__/rgb_led1)
002976.163: dtdebug:   +prop(gpios)
002976.528: dtdebug:   +prop(default-state)
002976.985: dtdebug: merge_fragment() end
002977.010: dtdebug: merge_fragment() end
002989.290: dtdebug: merge_fragment(/leds,/fragment@2/__overlay__)
002993.568: dtdebug: merge_fragment(/leds/rgb_led2,/fragment@2/__overlay__/rgb_led2)
002993.595: dtdebug:   +prop(gpios)
002993.962: dtdebug:   +prop(default-state)
002994.420: dtdebug: merge_fragment() end
002994.446: dtdebug: merge_fragment() end
003001.233: brfs: File read: 728 bytes
003003.717: brfs: File read: /mfs/sd/cmdline.txt
003003.764: Read command line from file 'cmdline.txt':
003003.784: 'dwc_otg.lpm_enable=0 console=tty1 root=/dev/mmcblk0p2 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait'
004860.640: dtparam: cam0-pwdn-ctrl=49
004863.399: dtdebug: found override cam0-pwdn-ctrl
004863.423: dtparam: cam0-pwdn=5
004866.185: dtdebug: found override cam0-pwdn
004872.810: dtparam: cam0-led-ctrl=49
004875.576: dtdebug: found override cam0-led-ctrl
004875.600: dtparam: cam0-led=6
004878.372: dtdebug: found override cam0-led
004878.398: gpioman: gpioman_get_pin_num: pin EMMC_ENABLE not defined
004878.454: dtparam: arm_freq=1400000000
004881.261: dtdebug: found override arm_freq
004881.318: dtdebug:   override arm_freq: cell target clock-frequency @ offset 0 (size 4)
004889.568: dtdebug:   override arm_freq: cell target clock-frequency @ offset 0 (size 4)
004897.853: dtdebug:   override arm_freq: cell target clock-frequency @ offset 0 (size 4)
004906.229: dtdebug:   override arm_freq: cell target clock-frequency @ offset 0 (size 4)
004914.659: dtparam: core_freq=400000000
004917.658: dtdebug: /__overrides__ has no core_freq property
004917.672: Unknown dtparam 'core_freq' - ignored
004930.119: dtdebug: delete_node(/hat)
004934.209: brfs: File read: 113 bytes
005337.907: brfs: File read: /mfs/sd/kernel7.img
005337.930: Loading 'kernel7.img' to 0x8000 size 0x552128
005337.959: Device tree loaded to 0x2eff8e00 (size 0x71b3)
005340.878: gpioman: gpioman_get_pin_num: pin SDCARD_CONTROL_POWER not defined
007709.016: vchiq_core: vchiq_init_state: slot_zero = 0xf7980000, is_master = 1
011043.010: camsubs: Looking for camera 0: i2c_port = 0, led gpio = 134, power enable gpio = 133
011343.626: camsubs: Camera not found
011343.653: camsubs: Looking for camera 0: i2c_port = 0, led gpio = 134, power enable gpio = 133
011644.268: camsubs: Camera found OK