User avatar
micksulley
Posts: 166
Joined: Sat Mar 03, 2012 11:48 am
Location: Melton Mowbray, England

Pi Lock-up

Fri Jul 05, 2013 2:02 pm

Hi, I now have 4 Pi's, 3 early ones and one new one. The old ones work fine, but the new one locks up for no apparent reason.

I am running the latest version of Raspian and access all of them in terminal via ssh or Nautilus file explorer from my laptop or desktop (which both run Debian). When I am using the new Pi it works fine and then just stops responding.

I realise that that is not going to be enough info to give me a definitive answer as to what is wrong, but could someone give me some clues on how to investigate further, logs to look for etc.

Thanks
Mick

User avatar
Davespice
Forum Moderator
Forum Moderator
Posts: 1665
Joined: Fri Oct 14, 2011 8:06 pm
Location: The Netherlands
Contact: Twitter

Re: Pi Lock-up

Fri Jul 05, 2013 2:20 pm

Hmm, maybe the syslog might give you something?
This command will get you last 200 lines, increase the number to go back further.

Code: Select all

tail -n 200 /var/log/syslog

User avatar
micksulley
Posts: 166
Joined: Sat Mar 03, 2012 11:48 am
Location: Melton Mowbray, England

Re: Pi Lock-up

Fri Jul 05, 2013 2:50 pm

OK it had just locked up when I posted the message, so I restarted and ran that command, here is the chunk of output that looks like it may contain the problem

Code: Select all

Jul  5 15:17:15 pi-b kernel: [   12.106335] bcm2835 ALSA card created!
Jul  5 15:17:15 pi-b kernel: [   12.119574] bcm2835 ALSA chip created!
Jul  5 15:17:15 pi-b kernel: [   12.132454] bcm2835 ALSA chip created!
Jul  5 15:17:15 pi-b kernel: [   12.141257] bcm2835 ALSA chip created!
Jul  5 15:17:15 pi-b kernel: [   12.151749] bcm2835 ALSA chip created!
Jul  5 15:17:15 pi-b kernel: [   12.159208] bcm2835 ALSA chip created!
Jul  5 15:17:15 pi-b kernel: [   12.168334] bcm2835 ALSA chip created!
Jul  5 15:17:15 pi-b kernel: [   12.176668] bcm2835 ALSA chip created!
Jul  5 15:17:15 pi-b kernel: [   20.953981] smsc95xx 1-1.1:1.0: eth0: link up, 100Mbps, full-duplex, lpa 0x45E1
Jul  5 15:17:18 pi-b /usr/sbin/cron[1940]: (CRON) INFO (pidfile fd = 3)
Jul  5 15:17:18 pi-b /usr/sbin/cron[1941]: (CRON) STARTUP (fork ok)
Jul  5 15:17:18 pi-b /usr/sbin/cron[1941]: (CRON) INFO (Running @reboot jobs)
Jul  5 15:17:19 pi-b kernel: [   26.436656] Adding 102396k swap on /var/swap.  Priority:-1 extents:1 across:102396k SS
Jul  5 15:17:22 pi-b mysqld_safe: Starting mysqld daemon with databases from /var/lib/mysql
Jul  5 15:17:22 pi-b mysqld: 130705 15:17:22 [Note] Plugin 'FEDERATED' is disabled.
Jul  5 15:17:22 pi-b mysqld: 130705 15:17:22 InnoDB: The InnoDB memory heap is disabled
Jul  5 15:17:22 pi-b mysqld: 130705 15:17:22 InnoDB: Mutexes and rw_locks use GCC atomic builtins
Jul  5 15:17:22 pi-b mysqld: 130705 15:17:22 InnoDB: Compressed tables use zlib 1.2.7
Jul  5 15:17:22 pi-b mysqld: 130705 15:17:22 InnoDB: Using Linux native AIO
Jul  5 15:17:22 pi-b mysqld: 130705 15:17:22 InnoDB: Initializing buffer pool, size = 128.0M
Jul  5 15:17:23 pi-b mysqld: 130705 15:17:23 InnoDB: Completed initialization of buffer pool
Jul  5 15:17:23 pi-b mysqld: 130705 15:17:23 InnoDB: highest supported file format is Barracuda.
Jul  5 15:17:23 pi-b mysqld: InnoDB: The log sequence number in ibdata files does not match
Jul  5 15:17:23 pi-b mysqld: InnoDB: the log sequence number in the ib_logfiles!
Jul  5 15:17:23 pi-b mysqld: 130705 15:17:23  InnoDB: Database was not shut down normally!
Jul  5 15:17:23 pi-b mysqld: InnoDB: Starting crash recovery.
Jul  5 15:17:23 pi-b mysqld: InnoDB: Reading tablespace information from the .ibd files...
Jul  5 15:17:23 pi-b mysqld: InnoDB: Restoring possible half-written data pages from the doublewrite
Jul  5 15:17:23 pi-b mysqld: InnoDB: buffer...
Jul  5 15:17:24 pi-b mysqld: 130705 15:17:24  InnoDB: Waiting for the background threads to start
That last entry was the last one before the restart. Does this indicate a mysql problem? If so any ideas how to fix it?

Thanks
Mick

User avatar
Davespice
Forum Moderator
Forum Moderator
Posts: 1665
Joined: Fri Oct 14, 2011 8:06 pm
Location: The Netherlands
Contact: Twitter

Re: Pi Lock-up

Fri Jul 05, 2013 3:12 pm

This section of the syslog is only 9 seconds long and looks like it's from the boot up after the crash.
The several instances "bcm2835 ALSA chip created!" only occur at boot time right? I think you need to go back further.

Unless... does it boot up and then lock-up after about 10 seconds then?

User avatar
micksulley
Posts: 166
Joined: Sat Mar 03, 2012 11:48 am
Location: Melton Mowbray, England

Re: Pi Lock-up

Fri Jul 05, 2013 4:01 pm

Here is all of it

Code: Select all

[email protected] ~ $ sudo tail -n 200 /var/log/syslog
Jul  5 15:17:15 pi-b kernel: [    0.074145] NET: Registered protocol family 16
Jul  5 15:17:15 pi-b kernel: [    0.080633] DMA: preallocated 4096 KiB pool for atomic coherent allocations
Jul  5 15:17:15 pi-b kernel: [    0.081731] bcm2708.uart_clock = 0
Jul  5 15:17:15 pi-b kernel: [    0.083097] hw-breakpoint: found 6 breakpoint and 1 watchpoint registers.
Jul  5 15:17:15 pi-b kernel: [    0.083153] hw-breakpoint: maximum watchpoint size is 4 bytes.
Jul  5 15:17:15 pi-b kernel: [    0.083189] mailbox: Broadcom VideoCore Mailbox driver
Jul  5 15:17:15 pi-b kernel: [    0.083281] bcm2708_vcio: mailbox at f200b880
Jul  5 15:17:15 pi-b kernel: [    0.083382] bcm_power: Broadcom power driver
Jul  5 15:17:15 pi-b kernel: [    0.083420] bcm_power_open() -> 0
Jul  5 15:17:15 pi-b kernel: [    0.083445] bcm_power_request(0, 8)
Jul  5 15:17:15 pi-b kernel: [    0.584143] bcm_mailbox_read -> 00000080, 0
Jul  5 15:17:15 pi-b kernel: [    0.584183] bcm_power_request -> 0
Jul  5 15:17:15 pi-b kernel: [    0.584210] Serial: AMBA PL011 UART driver
Jul  5 15:17:15 pi-b kernel: [    0.584354] dev:f1: ttyAMA0 at MMIO 0x20201000 (irq = 83) is a PL011 rev3
Jul  5 15:17:15 pi-b kernel: [    0.908086] console [ttyAMA0] enabled
Jul  5 15:17:15 pi-b kernel: [    0.931558] bio: create slab <bio-0> at 0
Jul  5 15:17:15 pi-b kernel: [    0.936438] SCSI subsystem initialized
Jul  5 15:17:15 pi-b kernel: [    0.940527] usbcore: registered new interface driver usbfs
Jul  5 15:17:15 pi-b kernel: [    0.946108] usbcore: registered new interface driver hub
Jul  5 15:17:15 pi-b kernel: [    0.951702] usbcore: registered new device driver usb
Jul  5 15:17:15 pi-b kernel: [    0.958039] Switching to clocksource stc
Jul  5 15:17:15 pi-b kernel: [    0.962284] FS-Cache: Loaded
Jul  5 15:17:15 pi-b kernel: [    0.965425] CacheFiles: Loaded
Jul  5 15:17:15 pi-b kernel: [    0.980234] NET: Registered protocol family 2
Jul  5 15:17:15 pi-b kernel: [    0.985517] TCP established hash table entries: 16384 (order: 5, 131072 bytes)
Jul  5 15:17:15 pi-b kernel: [    0.993254] TCP bind hash table entries: 16384 (order: 4, 65536 bytes)
Jul  5 15:17:15 pi-b kernel: [    1.000045] TCP: Hash tables configured (established 16384 bind 16384)
Jul  5 15:17:15 pi-b kernel: [    1.006649] TCP: reno registered
Jul  5 15:17:15 pi-b kernel: [    1.009903] UDP hash table entries: 256 (order: 0, 4096 bytes)
Jul  5 15:17:15 pi-b kernel: [    1.015801] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
Jul  5 15:17:15 pi-b kernel: [    1.022382] NET: Registered protocol family 1
Jul  5 15:17:15 pi-b kernel: [    1.027243] RPC: Registered named UNIX socket transport module.
Jul  5 15:17:15 pi-b kernel: [    1.033291] RPC: Registered udp transport module.
Jul  5 15:17:15 pi-b kernel: [    1.038014] RPC: Registered tcp transport module.
Jul  5 15:17:15 pi-b kernel: [    1.042760] RPC: Registered tcp NFSv4.1 backchannel transport module.
Jul  5 15:17:15 pi-b kernel: [    1.049870] bcm2708_dma: DMA manager at f2007000
Jul  5 15:17:15 pi-b kernel: [    1.054655] bcm2708_gpio: bcm2708_gpio_probe c0515d98
Jul  5 15:17:15 pi-b kernel: [    1.060113] vc-mem: phys_addr:0x00000000 mem_base=0x1ec00000 mem_size:0x20000000(512 MiB)
Jul  5 15:17:15 pi-b kernel: [    1.069264] audit: initializing netlink socket (disabled)
Jul  5 15:17:15 pi-b kernel: [    1.074861] type=2000 audit(0.930:1): initialized
Jul  5 15:17:15 pi-b kernel: [    1.198084] VFS: Disk quotas dquot_6.5.2
Jul  5 15:17:15 pi-b kernel: [    1.202230] Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
Jul  5 15:17:15 pi-b kernel: [    1.209187] FS-Cache: Netfs 'nfs' registered for caching
Jul  5 15:17:15 pi-b kernel: [    1.214931] NFS: Registering the id_resolver key type
Jul  5 15:17:15 pi-b kernel: [    1.220094] Key type id_resolver registered
Jul  5 15:17:15 pi-b kernel: [    1.224390] Key type id_legacy registered
Jul  5 15:17:15 pi-b kernel: [    1.228749] msgmni has been set to 876
Jul  5 15:17:15 pi-b kernel: [    1.234258] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252)
Jul  5 15:17:15 pi-b kernel: [    1.241965] io scheduler noop registered
Jul  5 15:17:15 pi-b kernel: [    1.246011] io scheduler deadline registered (default)
Jul  5 15:17:15 pi-b kernel: [    1.251193] io scheduler cfq registered
Jul  5 15:17:15 pi-b kernel: [    1.273778] Console: switching to colour frame buffer device 82x26
Jul  5 15:17:15 pi-b kernel: [    1.285199] kgdb: Registered I/O driver kgdboc.
Jul  5 15:17:15 pi-b kernel: [    1.292379] vc-cma: Videocore CMA driver
Jul  5 15:17:15 pi-b kernel: [    1.298189] vc-cma: vc_cma_base      = 0x00000000
Jul  5 15:17:15 pi-b kernel: [    1.304774] vc-cma: vc_cma_size      = 0x00000000 (0 MiB)
Jul  5 15:17:15 pi-b kernel: [    1.311941] vc-cma: vc_cma_initial   = 0x00000000 (0 MiB)
Jul  5 15:17:15 pi-b kernel: [    1.327898] brd: module loaded
Jul  5 15:17:15 pi-b kernel: [    1.337578] loop: module loaded
Jul  5 15:17:15 pi-b kernel: [    1.342801] vchiq: vchiq_init_state: slot_zero = 0xdb000000, is_master = 0
Jul  5 15:17:15 pi-b kernel: [    1.352173] Loading iSCSI transport class v2.0-870.
Jul  5 15:17:15 pi-b kernel: [    1.359774] usbcore: registered new interface driver smsc95xx
Jul  5 15:17:15 pi-b kernel: [    1.367408] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
Jul  5 15:17:15 pi-b kernel: [    1.579983] Core Release: 2.80a
Jul  5 15:17:15 pi-b kernel: [    1.584908] Setting default values for core params
Jul  5 15:17:15 pi-b kernel: [    1.591418] Finished setting default values for core params
Jul  5 15:17:15 pi-b kernel: [    1.803808] Using Buffer DMA mode
Jul  5 15:17:15 pi-b kernel: [    1.808814] Periodic Transfer Interrupt Enhancement - disabled
Jul  5 15:17:15 pi-b kernel: [    1.816332] Multiprocessor Interrupt Enhancement - disabled
Jul  5 15:17:15 pi-b kernel: [    1.823612] OTG VER PARAM: 0, OTG VER FLAG: 0
Jul  5 15:17:15 pi-b kernel: [    1.829657] Dedicated Tx FIFOs mode
Jul  5 15:17:15 pi-b kernel: [    1.835805] dwc_otg: Microframe scheduler enabled
Jul  5 15:17:15 pi-b kernel: [    1.836207] dwc_otg bcm2708_usb: DWC OTG Controller
Jul  5 15:17:15 pi-b kernel: [    1.842939] dwc_otg bcm2708_usb: new USB bus registered, assigned bus number 1
Jul  5 15:17:15 pi-b kernel: [    1.851969] dwc_otg bcm2708_usb: irq 32, io mem 0x00000000
Jul  5 15:17:15 pi-b kernel: [    1.859321] Init: Port Power? op_state=1
Jul  5 15:17:15 pi-b kernel: [    1.864974] Init: Power Port (0)
Jul  5 15:17:15 pi-b kernel: [    1.869987] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
Jul  5 15:17:15 pi-b kernel: [    1.878650] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
Jul  5 15:17:15 pi-b kernel: [    1.887738] usb usb1: Product: DWC OTG Controller
Jul  5 15:17:15 pi-b kernel: [    1.894317] usb usb1: Manufacturer: Linux 3.6.11+ dwc_otg_hcd
Jul  5 15:17:15 pi-b kernel: [    1.901889] usb usb1: SerialNumber: bcm2708_usb
Jul  5 15:17:15 pi-b kernel: [    1.908970] hub 1-0:1.0: USB hub found
Jul  5 15:17:15 pi-b kernel: [    1.914597] hub 1-0:1.0: 1 port detected
Jul  5 15:17:15 pi-b kernel: [    1.920562] dwc_otg: FIQ enabled
Jul  5 15:17:15 pi-b kernel: [    1.920579] dwc_otg: NAK holdoff enabled
Jul  5 15:17:15 pi-b kernel: [    1.920600] Module dwc_common_port init
Jul  5 15:17:15 pi-b kernel: [    1.920818] Initializing USB Mass Storage driver...
Jul  5 15:17:15 pi-b kernel: [    1.927717] usbcore: registered new interface driver usb-storage
Jul  5 15:17:15 pi-b kernel: [    1.935517] USB Mass Storage support registered.
Jul  5 15:17:15 pi-b kernel: [    1.941995] usbcore: registered new interface driver libusual
Jul  5 15:17:15 pi-b kernel: [    1.949818] mousedev: PS/2 mouse device common for all mice
Jul  5 15:17:15 pi-b kernel: [    1.957952] bcm2835-cpufreq: min=700000 max=700000 cur=700000
Jul  5 15:17:15 pi-b kernel: [    1.963815] bcm2835-cpufreq: switching to governor powersavebcm2835-cpufreq: switching to governor powersave
Jul  5 15:17:15 pi-b kernel: [    1.977196] cpuidle: using governor ladder
Jul  5 15:17:15 pi-b kernel: [    1.984952] cpuidle: using governor menu
Jul  5 15:17:15 pi-b kernel: [    1.990658] sdhci: Secure Digital Host Controller Interface driver
Jul  5 15:17:15 pi-b kernel: [    1.998633] sdhci: Copyright(c) Pierre Ossman
Jul  5 15:17:15 pi-b kernel: [    2.004848] sdhci: Enable low-latency mode
Jul  5 15:17:15 pi-b kernel: [    2.052369] mmc0: SDHCI controller on BCM2708_Arasan [platform] using platform's DMA
Jul  5 15:17:15 pi-b kernel: [    2.063922] mmc0: BCM2708 SDHC host at 0x20300000 DMA 2 IRQ 77
Jul  5 15:17:15 pi-b kernel: [    2.073843] sdhci-pltfm: SDHCI platform and OF driver helper
Jul  5 15:17:15 pi-b kernel: [    2.087165] usbcore: registered new interface driver usbhid
Jul  5 15:17:15 pi-b kernel: [    2.094765] usbhid: USB HID core driver
Jul  5 15:17:15 pi-b kernel: [    2.105269] TCP: cubic registered
Jul  5 15:17:15 pi-b kernel: [    2.112747] Indeed it is in host mode hprt0 = 00021501
Jul  5 15:17:15 pi-b kernel: [    2.121962] Initializing XFRM netlink socket
Jul  5 15:17:15 pi-b kernel: [    2.128267] NET: Registered protocol family 17
Jul  5 15:17:15 pi-b kernel: [    2.152314] Key type dns_resolver registered
Jul  5 15:17:15 pi-b kernel: [    2.158836] VFP support v0.3: implementor 41 architecture 1 part 20 variant b rev 5
Jul  5 15:17:15 pi-b kernel: [    2.193063] registered taskstats version 1
Jul  5 15:17:15 pi-b kernel: [    2.199221] mmc0: could read SD Status register (SSR) at the 2th attempt
Jul  5 15:17:15 pi-b kernel: [    2.212709] Waiting for root device /dev/mmcblk0p2...
Jul  5 15:17:15 pi-b kernel: [    2.236570] mmc0: new SDHC card at address aaaa
Jul  5 15:17:15 pi-b kernel: [    2.252012] mmcblk0: mmc0:aaaa SD32G 29.7 GiB 
Jul  5 15:17:15 pi-b kernel: [    2.263358]  mmcblk0: p1 p2
Jul  5 15:17:15 pi-b kernel: [    2.362200] usb 1-1: new high-speed USB device number 2 using dwc_otg
Jul  5 15:17:15 pi-b kernel: [    2.370879] Indeed it is in host mode hprt0 = 00001101
Jul  5 15:17:15 pi-b kernel: [    2.572506] usb 1-1: New USB device found, idVendor=0424, idProduct=9514
Jul  5 15:17:15 pi-b kernel: [    2.582357] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Jul  5 15:17:15 pi-b kernel: [    2.592300] hub 1-1:1.0: USB hub found
Jul  5 15:17:15 pi-b kernel: [    2.598120] hub 1-1:1.0: 5 ports detected
Jul  5 15:17:15 pi-b kernel: [    2.746391] EXT4-fs (mmcblk0p2): ext4_orphan_cleanup: deleting unreferenced inode 3835
Jul  5 15:17:15 pi-b kernel: [    2.769188] EXT4-fs (mmcblk0p2): ext4_orphan_cleanup: deleting unreferenced inode 1914
Jul  5 15:17:15 pi-b kernel: [    2.769340] EXT4-fs (mmcblk0p2): ext4_orphan_cleanup: deleting unreferenced inode 1861
Jul  5 15:17:15 pi-b kernel: [    2.782101] EXT4-fs (mmcblk0p2): ext4_orphan_cleanup: deleting unreferenced inode 1221
Jul  5 15:17:15 pi-b kernel: [    2.782285] EXT4-fs (mmcblk0p2): ext4_orphan_cleanup: deleting unreferenced inode 1171
Jul  5 15:17:15 pi-b kernel: [    2.782358] EXT4-fs (mmcblk0p2): 5 orphan inodes deleted
Jul  5 15:17:15 pi-b kernel: [    2.789606] EXT4-fs (mmcblk0p2): recovery complete
Jul  5 15:17:15 pi-b kernel: [    2.882361] usb 1-1.1: new high-speed USB device number 3 using dwc_otg
Jul  5 15:17:15 pi-b kernel: [    3.002787] usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00
Jul  5 15:17:15 pi-b kernel: [    3.011636] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Jul  5 15:17:15 pi-b kernel: [    3.023761] smsc95xx v1.0.4
Jul  5 15:17:15 pi-b kernel: [    3.086980] smsc95xx 1-1.1:1.0: eth0: register 'smsc95xx' at usb-bcm2708_usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:dc:e7:cc
Jul  5 15:17:15 pi-b kernel: [    3.517728] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
Jul  5 15:17:15 pi-b kernel: [    3.529986] VFS: Mounted root (ext4 filesystem) on device 179:2.
Jul  5 15:17:15 pi-b kernel: [    3.541442] devtmpfs: mounted
Jul  5 15:17:15 pi-b kernel: [    3.546968] Freeing init memory: 128K
Jul  5 15:17:15 pi-b kernel: [    6.362917] Registered led device: led0
Jul  5 15:17:15 pi-b kernel: [   11.350637] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
Jul  5 15:17:15 pi-b kernel: [   12.106335] bcm2835 ALSA card created!
Jul  5 15:17:15 pi-b kernel: [   12.119574] bcm2835 ALSA chip created!
Jul  5 15:17:15 pi-b kernel: [   12.132454] bcm2835 ALSA chip created!
Jul  5 15:17:15 pi-b kernel: [   12.141257] bcm2835 ALSA chip created!
Jul  5 15:17:15 pi-b kernel: [   12.151749] bcm2835 ALSA chip created!
Jul  5 15:17:15 pi-b kernel: [   12.159208] bcm2835 ALSA chip created!
Jul  5 15:17:15 pi-b kernel: [   12.168334] bcm2835 ALSA chip created!
Jul  5 15:17:15 pi-b kernel: [   12.176668] bcm2835 ALSA chip created!
Jul  5 15:17:15 pi-b kernel: [   20.953981] smsc95xx 1-1.1:1.0: eth0: link up, 100Mbps, full-duplex, lpa 0x45E1
Jul  5 15:17:18 pi-b /usr/sbin/cron[1940]: (CRON) INFO (pidfile fd = 3)
Jul  5 15:17:18 pi-b /usr/sbin/cron[1941]: (CRON) STARTUP (fork ok)
Jul  5 15:17:18 pi-b /usr/sbin/cron[1941]: (CRON) INFO (Running @reboot jobs)
Jul  5 15:17:19 pi-b kernel: [   26.436656] Adding 102396k swap on /var/swap.  Priority:-1 extents:1 across:102396k SS
Jul  5 15:17:22 pi-b mysqld_safe: Starting mysqld daemon with databases from /var/lib/mysql
Jul  5 15:17:22 pi-b mysqld: 130705 15:17:22 [Note] Plugin 'FEDERATED' is disabled.
Jul  5 15:17:22 pi-b mysqld: 130705 15:17:22 InnoDB: The InnoDB memory heap is disabled
Jul  5 15:17:22 pi-b mysqld: 130705 15:17:22 InnoDB: Mutexes and rw_locks use GCC atomic builtins
Jul  5 15:17:22 pi-b mysqld: 130705 15:17:22 InnoDB: Compressed tables use zlib 1.2.7
Jul  5 15:17:22 pi-b mysqld: 130705 15:17:22 InnoDB: Using Linux native AIO
Jul  5 15:17:22 pi-b mysqld: 130705 15:17:22 InnoDB: Initializing buffer pool, size = 128.0M
Jul  5 15:17:23 pi-b mysqld: 130705 15:17:23 InnoDB: Completed initialization of buffer pool
Jul  5 15:17:23 pi-b mysqld: 130705 15:17:23 InnoDB: highest supported file format is Barracuda.
Jul  5 15:17:23 pi-b mysqld: InnoDB: The log sequence number in ibdata files does not match
Jul  5 15:17:23 pi-b mysqld: InnoDB: the log sequence number in the ib_logfiles!
Jul  5 15:17:23 pi-b mysqld: 130705 15:17:23  InnoDB: Database was not shut down normally!
Jul  5 15:17:23 pi-b mysqld: InnoDB: Starting crash recovery.
Jul  5 15:17:23 pi-b mysqld: InnoDB: Reading tablespace information from the .ibd files...
Jul  5 15:17:23 pi-b mysqld: InnoDB: Restoring possible half-written data pages from the doublewrite
Jul  5 15:17:23 pi-b mysqld: InnoDB: buffer...
Jul  5 15:17:24 pi-b mysqld: 130705 15:17:24  InnoDB: Waiting for the background threads to start
Jul  5 15:42:00 pi-b ntpdate[1839]: step time server 77.246.126.132 offset 1475.187667 sec
Jul  5 15:42:00 pi-b ifplugd(eth0)[1568]: Program executed successfully.
Jul  5 15:42:00 pi-b mysqld: 130705 15:42:00 InnoDB: 5.5.30 started; log sequence number 2330606
Jul  5 15:42:00 pi-b mysqld: 130705 15:42:00 [Note] Server hostname (bind-address): '0.0.0.0'; port: 3306
Jul  5 15:42:00 pi-b mysqld: 130705 15:42:00 [Note]   - '0.0.0.0' resolves to '0.0.0.0';
Jul  5 15:42:00 pi-b mysqld: 130705 15:42:00 [Note] Server socket created on IP: '0.0.0.0'.
Jul  5 15:42:00 pi-b mysqld: 130705 15:42:00 [Note] Event Scheduler: Loaded 0 events
Jul  5 15:42:00 pi-b mysqld: 130705 15:42:00 [Note] /usr/sbin/mysqld: ready for connections.
Jul  5 15:42:00 pi-b mysqld: Version: '5.5.30-1.1'  socket: '/var/run/mysqld/mysqld.sock'  port: 3306  (Debian)
Jul  5 15:42:01 pi-b /etc/mysql/debian-start[2502]: Upgrading MySQL tables if necessary.
Jul  5 15:42:02 pi-b /etc/mysql/debian-start[2506]: /usr/bin/mysql_upgrade: the '--basedir' option is always ignored
Jul  5 15:42:02 pi-b /etc/mysql/debian-start[2506]: Looking for 'mysql' as: /usr/bin/mysql
Jul  5 15:42:02 pi-b /etc/mysql/debian-start[2506]: Looking for 'mysqlcheck' as: /usr/bin/mysqlcheck
Jul  5 15:42:02 pi-b /etc/mysql/debian-start[2506]: This installation of MySQL is already upgraded to 5.5.30, use --force if you still need to run mysql_upgrade
Jul  5 15:42:02 pi-b /etc/mysql/debian-start[2521]: Checking for insecure root accounts.
Jul  5 15:42:02 pi-b /etc/mysql/debian-start[2526]: Triggering myisam-recover for all MyISAM tables
Jul  5 15:42:05 pi-b dbus[1977]: [system] Activating service name='org.freedesktop.ConsoleKit' (using servicehelper)
Jul  5 15:42:05 pi-b dbus[1977]: [system] Activating service name='org.freedesktop.PolicyKit1' (using servicehelper)
Jul  5 15:42:05 pi-b polkitd[2666]: started daemon version 0.105 using authority implementation `local' version `0.105'
Jul  5 15:42:05 pi-b dbus[1977]: [system] Successfully activated service 'org.freedesktop.PolicyKit1'
Jul  5 15:42:05 pi-b dbus[1977]: [system] Successfully activated service 'org.freedesktop.ConsoleKit'
Jul  5 15:42:10 pi-b ntpd[2684]: ntpd [email protected] Fri May 18 20:30:57 UTC 2012 (1)
Jul  5 15:42:10 pi-b ntpd[2685]: proto: precision = 1.000 usec
Jul  5 15:42:10 pi-b ntpd[2685]: Listen and drop on 0 v4wildcard 0.0.0.0 UDP 123
Jul  5 15:42:10 pi-b ntpd[2685]: Listen normally on 1 lo 127.0.0.1 UDP 123
Jul  5 15:42:10 pi-b ntpd[2685]: Listen normally on 2 eth0 192.168.0.82 UDP 123
Jul  5 15:42:10 pi-b ntpd[2685]: peers refreshed
Jul  5 15:42:10 pi-b ntpd[2685]: Listening on routing socket on fd #19 for interface updates
Jul  5 15:42:10 pi-b ntpd[2685]: restrict: error in address '::' on line 38. Ignoring...
Jul  5 15:42:10 pi-b ntpd[2685]: restrict: error in address '::1' on line 42. Ignoring...
[email protected] ~ $ 
I was assuming that up to 15:17 was the problem and 15:42 on was from the re-boot.

User avatar
Davespice
Forum Moderator
Forum Moderator
Posts: 1665
Joined: Fri Oct 14, 2011 8:06 pm
Location: The Netherlands
Contact: Twitter

Re: Pi Lock-up

Fri Jul 05, 2013 4:18 pm

Ah okay, you're right.
I've just googled that last line and it looks like it's an actual bug;
https://bugs.launchpad.net/percona-server/+bug/923820
Not sure if that is the same system you're using but looks like other people have also had it.

User avatar
micksulley
Posts: 166
Joined: Sat Mar 03, 2012 11:48 am
Location: Melton Mowbray, England

Re: Pi Lock-up

Sat Jul 06, 2013 10:41 pm

OK, I have done a bit more investigation. I thought it may be a mysql problem so check for updates -
sudo apt-get update started but locked up after a few seconds. I tried this a few times but it always locked up. I then put the SD card into an old Pi and tried again, that worked fine. I then put the SD card back into the new Pi and I have lock up problems again, but now they are worse, it locks as soon as I log on. Plug it into the old Pi and it works fine!

Could the problem be caused by creating an SD card and initialising in an old Pi and then plugging it in to a new Pi? I know there are some small differences in the boards but I was assuming that I would be able to swap SD cards, is that not the case?

Thanks
Mick

Return to “General discussion”