Jan 28 04:13:54 syslogd started: BusyBox v1.13.4 Jan 28 04:13:54 kernel: klogd started: BusyBox v1.13.4 (2009-08-28 11:24:40 PDT) Jan 28 04:13:54 kernel: Linux version 2.6.26.8-rt16-332-g5849bfa (parabuild@compute-0-0.local) (gcc version 4.3.3 (Sourcery G++ Lite 2009q1-203) ) #1 PREEMPT RT Tue Sep 8 04:13:47 PDT 2009 Jan 28 04:13:54 kernel: CPU: ARMv6-compatible processor [4117b363] revision 3 (ARMv6TEJ), cr=00c5387f Jan 28 04:13:54 kernel: Machine: Logitech Fab4 Board Jan 28 04:13:55 kernel: Memory policy: ECC disabled, Data cache writeback Jan 28 04:13:55 kernel: On node 0 totalpages: 32768 Jan 28 04:13:55 kernel: DMA zone: 48 pages used for memmap Jan 28 04:13:55 kernel: DMA zone: 0 pages reserved Jan 28 04:13:55 kernel: DMA zone: 6096 pages, LIFO batch:0 Jan 28 04:13:55 kernel: Normal zone: 208 pages used for memmap Jan 28 04:13:55 kernel: Normal zone: 26416 pages, LIFO batch:7 Jan 28 04:13:55 kernel: Movable zone: 0 pages used for memmap Jan 28 04:13:55 kernel: CPU0: D VIPT write-back cache Jan 28 04:13:55 kernel: CPU0: I cache: 16384 bytes, associativity 4, 32 byte lines, 128 sets Jan 28 04:13:55 kernel: CPU0: D cache: 16384 bytes, associativity 4, 32 byte lines, 128 sets Jan 28 04:13:55 kernel: Real-Time Preemption Support (C) 2004-2007 Ingo Molnar Jan 28 04:13:55 kernel: Built 1 zonelists in Zone order, mobility grouping on. Total pages: 32512 Jan 28 04:13:55 kernel: Kernel command line: console=ttymxc0,115200 noinitrd init=/linuxrc ubi.mtd=1 root=/dev/mtdblock:cramfs Jan 28 04:13:55 kernel: Preemptible RCU implementation. Jan 28 04:13:55 kernel: MXC IRQ initialized Jan 28 04:13:55 kernel: PID hash table entries: 512 (order: 9, 2048 bytes) Jan 28 04:13:55 kernel: MXC GPT timer initialized, rate = 16625000 Jan 28 04:13:55 kernel: WARNING: Clock divider has been truncated, clock error 150 [ps] per 60 [ns] Jan 28 04:13:55 kernel: Console: colour dummy device 80x30 Jan 28 04:13:55 kernel: Dentry cache hash table entries: 16384 (order: 4, 65536 bytes) Jan 28 04:13:55 kernel: Inode-cache hash table entries: 8192 (order: 3, 32768 bytes) Jan 28 04:13:55 kernel: Memory: 128MB = 128MB total Jan 28 04:13:55 kernel: Memory: 125644KB available (3768K code, 338K data, 152K init) Jan 28 04:13:55 kernel: Calibrating delay loop... 530.84 BogoMIPS (lpj=2654208) Jan 28 04:13:55 kernel: Mount-cache hash table entries: 512 Jan 28 04:13:55 kernel: CPU: Testing write buffer coherency: ok Jan 28 04:13:55 watchdog[982]: starting daemon (5.6): Jan 28 04:13:55 kernel: net_namespace: 276 bytes Jan 28 04:13:55 kernel: NET: Registered protocol family 16 Jan 28 04:13:55 kernel: L2X0 cache controller enabled Jan 28 04:13:55 kernel: CPU is i.MX0 Revision 0.5 Jan 28 04:13:55 kernel: Clock input source is 24000000 Jan 28 04:13:55 kernel: MXC GPIO hardware Jan 28 04:13:55 kernel: Reset status: Watchdog timeout Jan 28 04:13:55 kernel: Using SDMA I.API Jan 28 04:13:55 kernel: MXC DMA API initialized Jan 28 04:13:55 kernel: SCSI subsystem initialized Jan 28 04:13:55 watchdog[982]: int=5s realtime=yes sync=no soft=no mla=0 mem=0 semkey=1234 Jan 28 04:13:55 kernel: CSPI: mxc_spi-0 probed Jan 28 04:13:55 watchdog[982]: ping: no machine to check Jan 28 04:13:55 watchdog[982]: file: no file to check Jan 28 04:13:55 kernel: usbcore: registered new interface driver usbfs Jan 28 04:13:55 watchdog[982]: pidfile: /var/run/squeezeplay.pid Jan 28 04:13:55 kernel: usbcore: registered new interface driver hub Jan 28 04:13:55 watchdog[982]: interface: no interface to check Jan 28 04:13:55 watchdog[982]: test=none(0) repair=/usr/sbin/repair.sh alive=/dev/watchdog heartbeat=none temp=none to=noone no_act=no Jan 28 04:13:55 kernel: usbcore: registered new device driver usb Jan 28 04:13:55 kernel: MXC I2C driver Jan 28 04:13:55 kernel: clk: Unable to get requested clock: dfm_clk Jan 28 04:13:55 kernel: ASoC version 0.20 Jan 28 04:13:55 kernel: NET: Registered protocol family 2 Jan 28 04:13:55 kernel: IP route cache hash table entries: 1024 (order: 0, 4096 bytes) Jan 28 04:13:55 kernel: TCP established hash table entries: 4096 (order: 3, 32768 bytes) Jan 28 04:13:55 kernel: TCP bind hash table entries: 4096 (order: 4, 114688 bytes) Jan 28 04:13:55 kernel: TCP: Hash tables configured (established 4096 bind 4096) Jan 28 04:13:55 kernel: TCP reno registered Jan 28 04:13:55 kernel: NET: Registered protocol family 1 Jan 28 04:13:55 kernel: usb: DR host (utmi) registered Jan 28 04:13:55 kernel: krcupreemptd setsched 0 Jan 28 04:13:55 kernel: prio = 98 Jan 28 04:13:55 kernel: Registering unionfs 2.5.1 (for 2.6.26.8) Jan 28 04:13:55 kernel: NTFS driver 2.1.29 [Flags: R/O]. Jan 28 04:13:55 kernel: msgmni has been set to 245 Jan 28 04:13:55 kernel: io scheduler noop registered Jan 28 04:13:55 kernel: io scheduler anticipatory registered Jan 28 04:13:55 kernel: io scheduler deadline registered Jan 28 04:13:55 kernel: io scheduler cfq registered (default) Jan 28 04:13:55 kernel: mxc_ipu mxc_ipu: Channel already uninitialized 14 Jan 28 04:13:55 kernel: Console: switching to colour frame buffer device 60x34 Jan 28 04:13:55 kernel: mxc_ipu mxc_ipu: Channel already uninitialized 15 Jan 28 04:13:55 kernel: mxcfb: fb registered, using mode Jan 28 04:13:55 kernel: fsl_rngc fsl_rngc: FSL RNGC Registered. Jan 28 04:13:55 kernel: Serial: MXC Internal UART driver Jan 28 04:13:55 kernel: mxcintuart.0: ttymxc0 at MMIO 0x43f90000 (irq = 45) is a Freescale MXC Jan 28 04:13:55 kernel: console [ttymxc0] enabled Jan 28 04:13:55 kernel: mxcintuart.1: ttymxc1 at MMIO 0x43f94000 (irq = 32) is a Freescale MXC Jan 28 04:13:55 kernel: loop: module loaded Jan 28 04:13:55 kernel: FEC ENET Version 0.2 Jan 28 04:13:55 kernel: fec: PHY @ 0x1, ID 0x00008201 -- RTL8210CP Jan 28 04:13:55 kernel: eth0: ethernet 00:04:20:22:01:56 Jan 28 04:13:55 kernel: Driver 'sd' needs updating - please use bus_type methods Jan 28 04:13:55 kernel: MXC MTD nand Driver 2.5 Jan 28 04:13:55 kernel: NAND device: Manufacturer ID: 0xec, Chip ID: 0xf1 (Samsung NAND 128MiB 3,3V 8-bit) Jan 28 04:13:55 kernel: Bad block table found at page 65472, version 0x01 Jan 28 04:13:55 kernel: Bad block table found at page 65408, version 0x01 Jan 28 04:13:55 kernel: nand_read_bbt: Bad block at 0x00740000 Jan 28 04:13:55 kernel: nand_read_bbt: Bad block at 0x01ac0000 Jan 28 04:13:55 kernel: nand_read_bbt: Bad block at 0x063c0000 Jan 28 04:13:55 kernel: RedBoot partition parsing not available Jan 28 04:13:55 kernel: cmdlinepart partition parsing not available Jan 28 04:13:55 kernel: Creating 2 MTD partitions on "NAND 128MiB 3,3V 8-bit": Jan 28 04:13:55 kernel: 0x00000000-0x00080000 : "redboot" Jan 28 04:13:55 kernel: 0x00080000-0x07f40000 : "ubi" Jan 28 04:13:55 kernel: UBI: attaching mtd1 to ubi0 Jan 28 04:13:55 kernel: UBI: physical eraseblock size: 131072 bytes (128 KiB) Jan 28 04:13:55 kernel: UBI: logical eraseblock size: 129024 bytes Jan 28 04:13:55 kernel: UBI: smallest flash I/O unit: 2048 Jan 28 04:13:55 kernel: UBI: sub-page size: 512 Jan 28 04:13:55 kernel: UBI: VID header offset: 512 (aligned 512) Jan 28 04:13:55 kernel: UBI: data offset: 2048 Jan 28 04:13:55 kernel: UBI: attached mtd1 to ubi0 Jan 28 04:13:55 kernel: UBI: MTD device name: "ubi" Jan 28 04:13:55 kernel: UBI: MTD device size: 126 MiB Jan 28 04:13:55 kernel: UBI: number of good PEBs: 1011 Jan 28 04:13:55 kernel: UBI: number of bad PEBs: 3 Jan 28 04:13:55 kernel: UBI: max. allowed volumes: 128 Jan 28 04:13:55 kernel: UBI: wear-leveling threshold: 4096 Jan 28 04:13:55 kernel: UBI: number of internal volumes: 1 Jan 28 04:13:55 kernel: UBI: number of user volumes: 4 Jan 28 04:13:55 kernel: UBI: available PEBs: 560 Jan 28 04:13:55 kernel: UBI: total number of reserved PEBs: 451 Jan 28 04:13:55 kernel: UBI: number of PEBs reserved for bad PEB handling: 10 Jan 28 04:13:55 kernel: UBI: max/mean erase counter: 454/31 Jan 28 04:13:55 kernel: UBI: background thread "ubi_bgt0d" started, PID 295 Jan 28 04:13:55 kernel: fsl-ehci fsl-ehci.0: Freescale On-Chip EHCI Host Controller Jan 28 04:13:55 kernel: fsl-ehci fsl-ehci.0: new USB bus registered, assigned bus number 1 Jan 28 04:13:55 kernel: fsl-ehci fsl-ehci.0: irq 37, io mem 0x53ff4000 Jan 28 04:13:55 kernel: fsl-ehci fsl-ehci.0: USB 2.0 started, EHCI 1.00, driver 10 Dec 2004 Jan 28 04:13:55 kernel: usb usb1: configuration #1 chosen from 1 choice Jan 28 04:13:55 kernel: hub 1-0:1.0: USB hub found Jan 28 04:13:55 kernel: hub 1-0:1.0: 1 port detected Jan 28 04:13:55 kernel: Initializing USB Mass Storage driver... Jan 28 04:13:55 kernel: usbcore: registered new interface driver usb-storage Jan 28 04:13:55 kernel: USB Mass Storage support registered. Jan 28 04:13:55 kernel: usbcore: registered new interface driver usbserial Jan 28 04:13:55 kernel: usbserial: USB Serial support registered for generic Jan 28 04:13:55 kernel: usbcore: registered new interface driver usbserial_generic Jan 28 04:13:55 kernel: usbserial: USB Serial Driver core Jan 28 04:13:55 kernel: usbserial: USB Serial support registered for FTDI USB Serial Device Jan 28 04:13:55 kernel: usbcore: registered new interface driver ftdi_sio Jan 28 04:13:55 kernel: ftdi_sio: v1.4.3:USB FTDI Serial Converters Driver Jan 28 04:13:55 kernel: usbserial: USB Serial support registered for Keyspan - (without firmware) Jan 28 04:13:55 kernel: usbserial: USB Serial support registered for Keyspan 1 port adapter Jan 28 04:13:55 kernel: usbserial: USB Serial support registered for Keyspan 2 port adapter Jan 28 04:13:55 kernel: usbserial: USB Serial support registered for Keyspan 4 port adapter Jan 28 04:13:55 kernel: usbcore: registered new interface driver keyspan Jan 28 04:13:55 kernel: keyspan: v1.1.5:Keyspan USB to Serial Converter Driver Jan 28 04:13:55 kernel: usbserial: USB Serial support registered for pl2303 Jan 28 04:13:55 kernel: usbcore: registered new interface driver pl2303 Jan 28 04:13:55 kernel: pl2303: Prolific PL2303 USB to serial adaptor driver Jan 28 04:13:55 kernel: mice: PS/2 mouse device common for all mice Jan 28 04:13:55 kernel: i2c /dev entries driver Jan 28 04:13:55 kernel: Clearpad: TM1199Logitech (7552,4248) Jan 28 04:13:55 kernel: input: Synaptics ClearPad as /class/input/input0 Jan 28 04:13:55 kernel: tsl2569 0-0039: support ver. 1.0 enabled Jan 28 04:13:55 kernel: i2c-adapter i2c-0: ACK not received Jan 28 04:13:55 kernel: lm75: probe of 0-0048 failed with error -1 Jan 28 04:13:55 kernel: MXC WatchDog Driver 2.0 Jan 28 04:13:55 kernel: MXC Watchdog # 0 Timer: initial timeout 60 sec Jan 28 04:13:55 kernel: IPU Post-filter loading Jan 28 04:13:55 kernel: mxc_asrc registered Jan 28 04:13:55 kernel: input: FAB4 IR as /class/input/input1 Jan 28 04:13:55 kernel: firmware: requesting ir_controller_21323.hex Jan 28 04:13:55 kernel: mxsdhci: MXC Secure Digital Host Controller Interface driver Jan 28 04:13:55 kernel: mxsdhci: MXC SDHCI Controller Driver. Jan 28 04:13:55 kernel: mmc0: SDHCI detect irq 0 irq 7 INTERNAL DMA Jan 28 04:13:55 kernel: mxsdhci: MXC SDHCI Controller Driver. Jan 28 04:13:55 kernel: mmc1: SDHCI detect irq 102 irq 8 INTERNAL DMA Jan 28 04:13:55 kernel: usbcore: registered new interface driver usbhid Jan 28 04:13:55 kernel: usbhid: v2.6:USB HID core driver Jan 28 04:13:55 kernel: mmc0: new SDIO card at address 0001 Jan 28 04:13:55 kernel: Advanced Linux Sound Architecture Driver Version 1.0.16. Jan 28 04:13:55 kernel: MXC spdif support initialized Jan 28 04:13:55 kernel: usbcore: registered new interface driver snd-usb-audio Jan 28 04:13:55 kernel: mmc1: new high speed SD card at address b368 Jan 28 04:13:55 kernel: AK4420 Audio Codec 0.1<6>DMA Sound Buffers Allocated:UseIram=0 buf->addr=87ec0000 buf->area=fe003000 size=65536 Jan 28 04:13:55 kernel: asoc: ak4420-dai <-> imx-ssi-1 mapping ok Jan 28 04:13:55 kernel: mmcblk0: mmc1:b368 SD 999936KiB Jan 28 04:13:55 kernel: mmcblk0: p1 Jan 28 04:13:55 kernel: WM8974 Audio Codec 0.1 Jan 28 04:13:55 kernel: DMA Sound Buffers Allocated:UseIram=0 buf->addr=87ef0000 buf->area=fe013000 size=65536 Jan 28 04:13:55 kernel: DMA Sound Buffers Allocated:UseIram=0 buf->addr=87f00000 buf->area=fe023000 size=65536 Jan 28 04:13:55 kernel: asoc: wm8974-hifi-dai <-> imx-ssi-3 mapping ok Jan 28 04:13:55 kernel: fab4 WM8974 Audio Driver Jan 28 04:13:55 kernel: ALSA device list: Jan 28 04:13:55 kernel: #0: MXC Freescale with SPDIF Jan 28 04:13:55 kernel: #1: fab4 (ak4420) Jan 28 04:13:55 kernel: #2: fab4 (wm8974) Jan 28 04:13:55 kernel: oprofile: using arm/armv6 Jan 28 04:13:55 kernel: TCP cubic registered Jan 28 04:13:55 kernel: NET: Registered protocol family 17 Jan 28 04:13:55 kernel: RPC: Registered udp transport module. Jan 28 04:13:55 kernel: RPC: Registered tcp transport module. Jan 28 04:13:55 kernel: ieee80211: 802.11 data/management/control stack, git-1.1.13 Jan 28 04:13:55 kernel: ieee80211: Copyright (C) 2004-2005 Intel Corporation Jan 28 04:13:55 kernel: ieee80211_crypt: registered algorithm 'NULL' Jan 28 04:13:55 kernel: mxc_rtc mxc_rtc.0: rtc core: registered mxc_rtc as rtc0 Jan 28 04:13:55 kernel: No external RTC clock Jan 28 04:13:55 kernel: Real TIme clock Driver v1.0 Jan 28 04:13:55 kernel: mxc_rtc: probe of mxc_rtc.0 failed with error -2 Jan 28 04:13:55 kernel: Static Power Management for Freescale i.MX35 Jan 28 04:13:55 kernel: VFP support v0.3: implementor 41 architecture 1 part 20 variant b rev 3 Jan 28 04:13:55 kernel: MXC Backlight Device mxc_ipu_bl.0 Initialized. Jan 28 04:13:55 kernel: platform mxc_rtc.0: setting system clock to 1970-01-28 04:13:34 UTC (2348014) Jan 28 04:13:55 kernel: VFS: Mounted root (cramfs filesystem) readonly. Jan 28 04:13:55 kernel: Freeing init memory: 152K Jan 28 04:13:55 kernel: UBIFS: recovery needed Jan 28 04:13:55 kernel: UBIFS: recovery completed Jan 28 04:13:55 kernel: UBIFS: mounted UBI device 0, volume 2, name "ubifs" Jan 28 04:13:55 kernel: UBIFS: file system size: 19869696 bytes (19404 KiB, 18 MiB, 154 LEBs) Jan 28 04:13:55 kernel: UBIFS: journal size: 1032193 bytes (1008 KiB, 0 MiB, 8 LEBs) Jan 28 04:13:55 kernel: UBIFS: media format: w4/r0 (latest is w4/r0) Jan 28 04:13:55 kernel: UBIFS: default compressor: lzo Jan 28 04:13:55 kernel: UBIFS: reserved for root: 938494 bytes (916 KiB) Jan 28 04:13:55 kernel: UBIFS: un-mount UBI device 0, volume 2 Jan 28 04:13:55 kernel: UBIFS: default file-system created Jan 28 04:13:55 kernel: UBIFS: mounted UBI device 0, volume 2, name "ubifs" Jan 28 04:13:55 kernel: UBIFS: file system size: 19869696 bytes (19404 KiB, 18 MiB, 154 LEBs) Jan 28 04:13:55 kernel: UBIFS: journal size: 1032193 bytes (1008 KiB, 0 MiB, 8 LEBs) Jan 28 04:13:55 kernel: UBIFS: media format: w4/r0 (latest is w4/r0) Jan 28 04:13:55 kernel: UBIFS: default compressor: lzo Jan 28 04:13:55 kernel: UBIFS: reserved for root: 938494 bytes (916 KiB) Jan 28 04:13:55 kernel: BUG: sleeping function called from invalid context firmware/ir_con(347) at kernel/rtmutex.c:743 Jan 28 04:13:55 kernel: in_atomic():0 [00000000], irqs_disabled():128 Jan 28 04:13:55 kernel: [] (dump_stack+0x0/0x14) from [] (__might_sleep+0x108/0x12c) Jan 28 04:13:55 kernel: [] (__might_sleep+0x0/0x12c) from [] (__rt_spin_lock+0x38/0x68) Jan 28 04:13:55 kernel: r4:c040c810 Jan 28 04:13:55 kernel: [] (__rt_spin_lock+0x0/0x68) from [] (rt_spin_lock+0x10/0x14) Jan 28 04:13:55 kernel: r5:c040c810 r4:c040c7f4 Jan 28 04:13:55 kernel: [] (rt_spin_lock+0x0/0x14) from [] (mxc_set_gpio_dataout+0x4c/0x70) Jan 28 04:13:55 kernel: [] (mxc_set_gpio_dataout+0x0/0x70) from [] (psoc_reset+0x20/0x44) Jan 28 04:13:55 kernel: r7:c7d1a860 r6:c7e13f8c r5:00418930 r4:c7d1a860 Jan 28 04:13:55 kernel: [] (psoc_reset+0x0/0x44) from [] (fab4_prog_firmware+0x224/0x474) Jan 28 04:13:55 kernel: r5:c7e13f88 r4:40000013 Jan 28 04:13:55 kernel: [] (fab4_prog_firmware+0x0/0x474) from [] (request_firmware_work_func+0x68/0x90) Jan 28 04:13:55 kernel: [] (request_firmware_work_func+0x0/0x90) from [] (kthread+0x54/0x80) Jan 28 04:13:55 kernel: r5:c01c3000 r4:c7e3a660 Jan 28 04:13:55 kernel: [] (kthread+0x0/0x80) from [] (do_exit+0x0/0x670) Jan 28 04:13:55 kernel: r5:00000000 r4:00000000 Jan 28 04:13:55 kernel: FAB4 IR: fw checksum ok aa33 Jan 28 04:13:56 root: Starting wlan Jan 28 04:13:56 kernel: firmware: requesting helper_sd.bin Jan 28 04:13:56 kernel: firmware: requesting sd8686.bin Jan 28 04:14:02 root: Starting wpa_supplicant Jan 28 04:14:02 root: Started wpa_supplicant Jan 28 04:14:02 root: wlan started Jan 28 04:14:03 kernel: eth0: config: auto-negotiation on, 100FDX, 100HDX, 10FDX, 10HDX. Jan 28 04:14:03 udhcpc[1062]: udhcpc (v1.13.4) started Jan 28 04:14:03 root: udhcpc_action eth0 deconfig ip= Jan 28 04:14:03 udhcpc[1062]: Sending discover... Jan 28 04:14:04 squeezeplay: INFO squeezeplay - JiveMain.lua:275 SqueezePlay version 7.4.0 r7467 Jan 28 04:14:04 squeezeplay: INFO squeezeplay.ui.draw - jiveL_initSDL:174 480,272 32 bits/pixel 4 bytes/pixel [R<<16 G<<8 B<<0] Jan 28 04:14:04 squeezeplay: INFO squeezeplay.ui.draw - jiveL_initSDL:175 Hardware acceleration is available Jan 28 04:14:05 squeezeplay: INFO squeezeplay.ui.draw - jive_surface_set_video_mode:70 Using a hardware double buffer Jan 28 04:14:05 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:91 User Path: /etc/squeezeplay/userpath Jan 28 04:14:05 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:314 Registering: SetupSoundEffects Jan 28 04:14:05 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:314 Registering: SqueezeboxFab4 Jan 28 04:14:06 kernel: Store Factor: 30 Jan 28 04:14:06 squeezeplay: debug_pagefaults:192 Pagefaults, Major:9 Minor:1665 Jan 28 04:14:06 squeezeplay: debug_pagefaults:192 Pagefaults, Major:0 Minor:1674 Jan 28 04:14:06 squeezeplay: audio_thread_execute:803 xrun (snd_pcm_wait) Jan 28 04:14:06 squeezeplay: audio_thread_execute:757 underrun!!! (at least 108.074 ms long) Jan 28 04:14:06 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:704 store settings: SqueezeboxFab4 Jan 28 04:14:06 udhcpc[1062]: Sending discover... Jan 28 04:14:06 squeezeplay: debug_pagefaults:192 Pagefaults, Major:0 Minor:37 Jan 28 04:14:06 squeezeplay: debug_pagefaults:192 Pagefaults, Major:0 Minor:40 Jan 28 04:14:07 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:314 Registering: Shortcuts Jan 28 04:14:07 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:314 Registering: AboutJive Jan 28 04:14:07 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:314 Registering: AlarmSnooze Jan 28 04:14:07 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:314 Registering: AutoSkin Jan 28 04:14:07 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:314 Registering: BlankScreen Jan 28 04:14:07 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:314 Registering: ChooseMusicSource Jan 28 04:14:07 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:314 Registering: Clock Jan 28 04:14:07 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:314 Registering: CrashLog Jan 28 04:14:07 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:314 Registering: CustomizeHomeMenu Jan 28 04:14:07 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:314 Registering: DebugSkin Jan 28 04:14:07 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:314 Registering: DebugTouch Jan 28 04:14:07 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:314 Registering: Diagnostics Jan 28 04:14:07 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:314 Registering: Experiments Jan 28 04:14:07 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:314 Registering: HttpAuth Jan 28 04:14:07 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:314 Registering: ImageViewer Jan 28 04:14:07 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:314 Registering: InfoBrowser Jan 28 04:14:07 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:314 Registering: LogSettings Jan 28 04:14:07 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:314 Registering: MacroPlay Jan 28 04:14:07 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:314 Registering: NowPlaying Jan 28 04:14:07 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:314 Registering: Playback Jan 28 04:14:07 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:314 Registering: ScreenSavers Jan 28 04:14:07 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:314 Registering: Screenshot Jan 28 04:14:07 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:314 Registering: SelectPlayer Jan 28 04:14:08 kernel: CMD_RESP: cmd 0x801f error, result=0x1 Jan 28 04:14:08 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:314 Registering: SelectSkin Jan 28 04:14:08 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:314 Registering: SetupAppletInstaller Jan 28 04:14:08 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:314 Registering: SetupDateTime Jan 28 04:14:08 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:314 Registering: SetupFactoryReset Jan 28 04:14:08 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:314 Registering: SetupFirmwareUpgrade Jan 28 04:14:08 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:314 Registering: SetupLanguage Jan 28 04:14:08 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:314 Registering: SetupNetTest Jan 28 04:14:08 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:314 Registering: SetupNetworking Jan 28 04:14:08 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:314 Registering: SetupSSH Jan 28 04:14:08 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:314 Registering: SetupSamba Jan 28 04:14:08 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:314 Registering: SetupSqueezebox Jan 28 04:14:08 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:314 Registering: SetupWallpaper Jan 28 04:14:09 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:314 Registering: SetupWelcome Jan 28 04:14:09 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:314 Registering: SlimBrowser Jan 28 04:14:09 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:314 Registering: SlimDiscovery Jan 28 04:14:09 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:314 Registering: SlimMenus Jan 28 04:14:09 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:314 Registering: SqueezeCenter Jan 28 04:14:09 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:314 Registering: SqueezeNetworkPIN Jan 28 04:14:09 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:314 Registering: TestAmbient Jan 28 04:14:09 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:314 Registering: TestDisplay Jan 28 04:14:09 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:314 Registering: TestProximity Jan 28 04:14:09 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:314 Registering: TestTones Jan 28 04:14:09 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:314 Registering: TestTouchscreen Jan 28 04:14:09 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:314 Registering: WQVGAlargeSkin Jan 28 04:14:09 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:314 Registering: WQVGAsmallSkin Jan 28 04:14:09 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:314 Registering: WirelessBridging Jan 28 04:14:09 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:314 Registering: Demo Jan 28 04:14:10 udhcpc[1062]: Sending discover... Jan 28 04:14:10 squeezeplay: INFO squeezeplay - JiveMain.lua:594 select skin: WQVGAsmallSkin Jan 28 04:14:10 squeezeplay: ERROR squeezeplay.ui.draw - jive_tile_load_tiles:133 Can't find image applets/WQVGAsmallSkin/images/Popup_Menu/button_cm_menu_press.png Jan 28 04:14:10 squeezeplay: ERROR squeezeplay.ui.draw - jive_tile_load_tiles:133 Can't find image applets/WQVGAsmallSkin/images/Popup_Menu/button_cm_menu_press_tl.png Jan 28 04:14:10 squeezeplay: ERROR squeezeplay.ui.draw - jive_tile_load_tiles:133 Can't find image applets/WQVGAsmallSkin/images/Popup_Menu/button_cm_menu_press_t.png Jan 28 04:14:10 squeezeplay: ERROR squeezeplay.ui.draw - jive_tile_load_tiles:133 Can't find image applets/WQVGAsmallSkin/images/Popup_Menu/button_cm_menu_press_tr.png Jan 28 04:14:10 squeezeplay: ERROR squeezeplay.ui.draw - jive_tile_load_tiles:133 Can't find image applets/WQVGAsmallSkin/images/Popup_Menu/button_cm_menu_press_r.png Jan 28 04:14:10 squeezeplay: ERROR squeezeplay.ui.draw - jive_tile_load_tiles:133 Can't find image applets/WQVGAsmallSkin/images/Popup_Menu/button_cm_menu_press_br.png Jan 28 04:14:10 squeezeplay: ERROR squeezeplay.ui.draw - jive_tile_load_tiles:133 Can't find image applets/WQVGAsmallSkin/images/Popup_Menu/button_cm_menu_press_b.png Jan 28 04:14:10 squeezeplay: ERROR squeezeplay.ui.draw - jive_tile_load_tiles:133 Can't find image applets/WQVGAsmallSkin/images/Popup_Menu/button_cm_menu_press_bl.png Jan 28 04:14:10 squeezeplay: ERROR squeezeplay.ui.draw - jive_tile_load_tiles:133 Can't find image applets/WQVGAsmallSkin/images/Popup_Menu/button_cm_menu_press_l.png Jan 28 04:14:13 root: udhcpc_action eth0 leasefail ip= Jan 28 04:14:13 udhcpc[1062]: No lease, forking to background Jan 28 04:14:13 squeezeplay: ERROR squeezeplay.ui.draw - jive_surface_load_image:195 Can't find image applets/WQVGAsmallSkin/images/IconsResized/icon_power_off2_touch.png Jan 28 04:14:14 squeezeplay: WARN lua.hooks - perf_hook:69 Func took 3430ms Jan 28 04:14:14 squeezeplay: stack traceback: Jan 28 04:14:14 squeezeplay: ...jive/applets/WQVGAsmallSkin/WQVGAsmallSkinApplet.lua:2661: in function '?' Jan 28 04:14:14 squeezeplay: /usr/share/jive/jive/JiveMain.lua:574: in function '_loadSkin' Jan 28 04:14:14 squeezeplay: /usr/share/jive/jive/JiveMain.lua:595: in function 'setSelectedSkin' Jan 28 04:14:14 squeezeplay: ...usr/share/jive/applets/SelectSkin/SelectSkinMeta.lua:52: in function 'configureApplet' Jan 28 04:14:14 squeezeplay: /usr/share/jive/jive/AppletManager.lua:322: in function Jan 28 04:14:14 squeezeplay: [C 0x5f760]: in function 'pcall' Jan 28 04:14:14 squeezeplay: /usr/share/jive/jive/Ap Jan 28 04:14:14 squeezeplay: INFO applet.SetupLanguage - SetupLanguageApplet.lua:65 locale currently is EN Jan 28 04:14:14 squeezeplay: INFO applet.SlimDiscovery - SlimDiscoveryMeta.lua:107 Setting local player as current player since no saved player found and a local player exists Jan 28 04:14:14 squeezeplay: INFO applet.SlimDiscovery - SlimDiscoveryApplet.lua:583 selected Squeezebox Touch Jan 28 04:14:14 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:704 store settings: SlimDiscovery Jan 28 04:14:14 squeezeplay: INFO applet.SlimMenus - SlimMenusApplet.lua:974 SlimMenusApplet:notify_playerCurrent(LocalPlayer {Squeezebox Touch}) Jan 28 04:14:14 squeezeplay: INFO applet.SlimMenus - SlimMenusApplet.lua:1000 player changed from:false to LocalPlayer {Squeezebox Touch} but server not yet present Jan 28 04:14:14 squeezeplay: INFO applet.SlimMenus - SlimMenusApplet.lua:1036 No server, Fetching initial server, nil Jan 28 04:14:14 squeezeplay: WARN net.socket - SocketUdp.lua:210 SocketUdp:writePump:Network is unreachable Jan 28 04:14:14 squeezeplay: WARN net.socket - SocketUdp.lua:210 SocketUdp:writePump:Network is unreachable Jan 28 04:14:14 squeezeplay: INFO net.comet - Comet.lua:625 Comet {mysqueezebox.com}: _handshake error: fab4.squeezenetwork.com Try again Jan 28 04:14:14 squeezeplay: INFO net.comet - Comet.lua:995 Comet {mysqueezebox.com}: handleAdvice state=CONNECTING Jan 28 04:14:14 squeezeplay: INFO squeezebox.server - SlimServer.lua:708 disconnected mysqueezebox.com idleTimeoutTriggered: nil Jan 28 04:14:14 squeezeplay: INFO net.comet - Comet.lua:1036 Comet {mysqueezebox.com}: advice is retry, connect in 2.236 seconds Jan 28 04:14:16 squeezeplay: INFO squeezeplay - JiveMain.lua:455 Calling postOnScreenInits callback Jan 28 04:14:17 squeezeplay: INFO net.comet - Comet.lua:625 Comet {mysqueezebox.com}: _handshake error: fab4.squeezenetwork.com Try again Jan 28 04:14:17 squeezeplay: INFO net.comet - Comet.lua:995 Comet {mysqueezebox.com}: handleAdvice state=CONNECTING Jan 28 04:14:17 squeezeplay: INFO squeezebox.server - SlimServer.lua:708 disconnected mysqueezebox.com idleTimeoutTriggered: nil Jan 28 04:14:17 squeezeplay: INFO net.comet - Comet.lua:1036 Comet {mysqueezebox.com}: advice is retry, connect in 4.789 seconds Jan 28 04:14:20 squeezeplay: INFO applet.SetupLanguage - SetupLanguageApplet.lua:195 Locale choice set to EN Jan 28 04:14:20 squeezeplay: INFO applet.SetupWelcome - SetupWelcomeApplet.lua:166 step2 Jan 28 04:14:20 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:704 store settings: SetupLanguage Jan 28 04:14:21 squeezeplay: INFO applet.SetupWelcome - SetupWelcomeApplet.lua:177 step3 Jan 28 04:14:22 squeezeplay: INFO net.comet - Comet.lua:625 Comet {mysqueezebox.com}: _handshake error: fab4.squeezenetwork.com Try again Jan 28 04:14:22 squeezeplay: INFO net.comet - Comet.lua:995 Comet {mysqueezebox.com}: handleAdvice state=CONNECTING Jan 28 04:14:22 squeezeplay: INFO squeezebox.server - SlimServer.lua:708 disconnected mysqueezebox.com idleTimeoutTriggered: nil Jan 28 04:14:22 squeezeplay: INFO net.comet - Comet.lua:1036 Comet {mysqueezebox.com}: advice is retry, connect in 3.498 seconds Jan 28 04:14:23 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:704 store settings: SetupNetworking Jan 28 04:14:25 squeezeplay: INFO net.comet - Comet.lua:625 Comet {mysqueezebox.com}: _handshake error: fab4.squeezenetwork.com Try again Jan 28 04:14:25 squeezeplay: INFO net.comet - Comet.lua:995 Comet {mysqueezebox.com}: handleAdvice state=CONNECTING Jan 28 04:14:25 squeezeplay: INFO squeezebox.server - SlimServer.lua:708 disconnected mysqueezebox.com idleTimeoutTriggered: nil Jan 28 04:14:25 squeezeplay: INFO net.comet - Comet.lua:1036 Comet {mysqueezebox.com}: advice is retry, connect in 2.907 seconds Jan 28 04:14:26 squeezeplay: INFO net.socket - Networking.lua:624 scan found 20 wireless networks Jan 28 04:14:28 squeezeplay: INFO net.comet - Comet.lua:625 Comet {mysqueezebox.com}: _handshake error: fab4.squeezenetwork.com Try again Jan 28 04:14:28 squeezeplay: INFO net.comet - Comet.lua:995 Comet {mysqueezebox.com}: handleAdvice state=CONNECTING Jan 28 04:14:28 squeezeplay: INFO squeezebox.server - SlimServer.lua:708 disconnected mysqueezebox.com idleTimeoutTriggered: nil Jan 28 04:14:28 squeezeplay: INFO net.comet - Comet.lua:1036 Comet {mysqueezebox.com}: advice is retry, connect in 2.035 seconds Jan 28 04:14:31 squeezeplay: INFO net.comet - Comet.lua:625 Comet {mysqueezebox.com}: _handshake error: fab4.squeezenetwork.com Try again Jan 28 04:14:31 squeezeplay: INFO net.comet - Comet.lua:995 Comet {mysqueezebox.com}: handleAdvice state=CONNECTING Jan 28 04:14:31 squeezeplay: INFO squeezebox.server - SlimServer.lua:708 disconnected mysqueezebox.com idleTimeoutTriggered: nil Jan 28 04:14:31 squeezeplay: INFO net.comet - Comet.lua:1036 Comet {mysqueezebox.com}: advice is retry, connect in 2.955 seconds Jan 28 04:14:33 udhcpc[1089]: Sending discover... Jan 28 04:14:34 squeezeplay: INFO net.comet - Comet.lua:625 Comet {mysqueezebox.com}: _handshake error: fab4.squeezenetwork.com Try again Jan 28 04:14:34 squeezeplay: INFO net.comet - Comet.lua:995 Comet {mysqueezebox.com}: handleAdvice state=CONNECTING Jan 28 04:14:34 squeezeplay: INFO squeezebox.server - SlimServer.lua:708 disconnected mysqueezebox.com idleTimeoutTriggered: nil Jan 28 04:14:34 squeezeplay: INFO net.comet - Comet.lua:1036 Comet {mysqueezebox.com}: advice is retry, connect in 2.737 seconds Jan 28 04:14:36 udhcpc[1089]: Sending discover... Jan 28 04:14:37 squeezeplay: INFO net.comet - Comet.lua:625 Comet {mysqueezebox.com}: _handshake error: fab4.squeezenetwork.com Try again Jan 28 04:14:37 squeezeplay: INFO net.comet - Comet.lua:995 Comet {mysqueezebox.com}: handleAdvice state=CONNECTING Jan 28 04:14:37 squeezeplay: INFO squeezebox.server - SlimServer.lua:708 disconnected mysqueezebox.com idleTimeoutTriggered: nil Jan 28 04:14:37 squeezeplay: INFO net.comet - Comet.lua:1036 Comet {mysqueezebox.com}: advice is retry, connect in 4.018 seconds Jan 28 04:14:37 squeezeplay: INFO net.socket - Networking.lua:915 remove network WNR3500 Jan 28 04:14:37 squeezeplay: INFO net.socket - Networking.lua:795 add network WNR3500 Jan 28 04:14:37 squeezeplay: INFO net.socket - Networking.lua:915 remove network WNR3500 Jan 28 04:14:37 squeezeplay: INFO net.socket - Networking.lua:808 Connect to WNR3500 Jan 28 04:14:37 squeezeplay: INFO net.socket - Networking.lua:845 encryption WPA2 Jan 28 04:14:39 wpa_supplicant[1038]: Trying to associate with 00:1e:2a:72:41:17 (SSID='WNR3500' freq=2412 MHz) Jan 28 04:14:39 udhcpc[1089]: Sending discover... Jan 28 04:14:40 wpa_supplicant[1038]: Associated with 00:1e:2a:72:41:17 Jan 28 04:14:41 squeezeplay: INFO net.comet - Comet.lua:625 Comet {mysqueezebox.com}: _handshake error: fab4.squeezenetwork.com Try again Jan 28 04:14:41 squeezeplay: INFO net.comet - Comet.lua:995 Comet {mysqueezebox.com}: handleAdvice state=CONNECTING Jan 28 04:14:41 squeezeplay: INFO squeezebox.server - SlimServer.lua:708 disconnected mysqueezebox.com idleTimeoutTriggered: nil Jan 28 04:14:41 squeezeplay: INFO net.comet - Comet.lua:1036 Comet {mysqueezebox.com}: advice is retry, connect in 2.259 seconds Jan 28 04:14:41 wpa_supplicant[1038]: Trying to associate with 00:1e:2a:72:41:17 (SSID='WNR3500' freq=2412 MHz) Jan 28 04:14:43 root: udhcpc_action eth0 leasefail ip= Jan 28 04:14:43 wpa_supplicant[1038]: Associated with 00:1e:2a:72:41:17 Jan 28 04:14:43 kernel: unionfs: new lower inode mtime (bindex=0, name=etc) Jan 28 04:14:43 squeezeplay: INFO net.socket - Networking.lua:976 select network WNR3500 Jan 28 04:14:43 squeezeplay: INFO net.socket - Networking.lua:1123 ifdown eth0 Jan 28 04:14:43 squeezeplay: INFO net.comet - Comet.lua:625 Comet {mysqueezebox.com}: _handshake error: fab4.squeezenetwork.com Try again Jan 28 04:14:43 squeezeplay: INFO net.comet - Comet.lua:995 Comet {mysqueezebox.com}: handleAdvice state=CONNECTING Jan 28 04:14:43 squeezeplay: INFO squeezebox.server - SlimServer.lua:708 disconnected mysqueezebox.com idleTimeoutTriggered: nil Jan 28 04:14:43 squeezeplay: INFO net.comet - Comet.lua:1036 Comet {mysqueezebox.com}: advice is retry, connect in 3.73 seconds Jan 28 04:14:43 udhcpc[1089]: Received SIGTERM Jan 28 04:14:43 udhcpc[1089]: Entering released state Jan 28 04:14:44 squeezeplay: INFO net.socket - Networking.lua:1726 restartWpaCli Jan 28 04:14:44 wpa_supplicant[1038]: CTRL-EVENT-DISCONNECTED - Disconnect event - remove keys Jan 28 04:14:44 squeezeplay: WARN net.socket - SocketUdp.lua:210 SocketUdp:writePump:Network is unreachable Jan 28 04:14:44 squeezeplay: WARN net.socket - SocketUdp.lua:210 SocketUdp:writePump:Network is unreachable Jan 28 04:14:45 wpa_supplicant[1038]: Trying to associate with 00:1e:2a:72:41:17 (SSID='WNR3500' freq=2412 MHz) Jan 28 04:14:46 wpa_supplicant[1038]: Associated with 00:1e:2a:72:41:17 Jan 28 04:14:46 wpa_supplicant[1038]: WPA: Key negotiation completed with 00:1e:2a:72:41:17 [PTK=CCMP GTK=CCMP] Jan 28 04:14:46 wpa_supplicant[1038]: CTRL-EVENT-CONNECTED - Connection to 00:1e:2a:72:41:17 completed (auth) [id=0 id_str=] Jan 28 04:14:46 root: wpa_action wlan0 CONNECTED Jan 28 04:14:46 squeezeplay: INFO net.socket - Networking.lua:1095 ifup "wlan0=WNR3500" Jan 28 04:14:47 udhcpc[1156]: udhcpc (v1.13.4) started Jan 28 04:14:47 root: udhcpc_action wlan0 deconfig ip= Jan 28 04:14:47 udhcpc[1156]: Sending discover... Jan 28 04:14:47 squeezeplay: INFO net.comet - Comet.lua:625 Comet {mysqueezebox.com}: _handshake error: fab4.squeezenetwork.com Try again Jan 28 04:14:47 squeezeplay: INFO net.comet - Comet.lua:995 Comet {mysqueezebox.com}: handleAdvice state=CONNECTING Jan 28 04:14:47 squeezeplay: INFO squeezebox.server - SlimServer.lua:708 disconnected mysqueezebox.com idleTimeoutTriggered: nil Jan 28 04:14:47 squeezeplay: INFO net.comet - Comet.lua:1036 Comet {mysqueezebox.com}: advice is retry, connect in 4.648 seconds Jan 28 04:14:47 udhcpc[1156]: Sending select for 192.168.1.4... Jan 28 04:14:48 udhcpc[1156]: Lease of 192.168.1.4 obtained, lease time 86400 Jan 28 04:14:48 root: udhcpc_action wlan0 bound ip=192.168.1.4 Jan 28 04:14:48 squeezeplay: INFO net.comet - Comet.lua:240 Comet {mysqueezebox.com}: Got networkConnected event, will try to reconnect Jan 28 04:14:48 squeezeplay: INFO squeezebox.server - SlimServer.lua:708 disconnected mysqueezebox.com idleTimeoutTriggered: nil Jan 28 04:14:48 squeezeplay: INFO squeezebox.server - SlimServer.lua:685 connected mysqueezebox.com Jan 28 04:14:48 squeezeplay: INFO squeezebox.server - SlimServer.lua:313 mysqueezebox.com firmware=http://update.squeezenetwork.com/update/firmware/.7.4/fab4_7.4.0_r7467.bin force=false Jan 28 04:14:49 squeezeplay: INFO applet.SlimMenus - SlimMenusApplet.lua:387 _menuSink() SlimServer {mysqueezebox.com} menuDirective: nil isCurrentServer:nil Jan 28 04:14:50 squeezeplay: INFO applet.SetupWelcome - SetupWelcomeApplet.lua:189 step6 Jan 28 04:14:50 squeezeplay: INFO applet.SlimDiscovery - SlimDiscoveryApplet.lua:583 selected Squeezebox Touch Jan 28 04:14:50 squeezeplay: INFO applet.SlimMenus - SlimMenusApplet.lua:974 SlimMenusApplet:notify_playerCurrent(LocalPlayer {Squeezebox Touch}) Jan 28 04:14:50 squeezeplay: INFO applet.SlimMenus - SlimMenusApplet.lua:1000 player changed from:false to LocalPlayer {Squeezebox Touch} but server not yet present Jan 28 04:14:50 squeezeplay: INFO applet.SetupWelcome - SetupWelcomeApplet.lua:227 step7 Jan 28 04:14:50 squeezeplay: INFO applet.SetupWelcome - SetupWelcomeApplet.lua:530 network setup complete Jan 28 04:14:50 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:704 store settings: SetupWelcome Jan 28 04:14:50 squeezeplay: INFO applet.SetupWelcome - SetupWelcomeApplet.lua:452 registration on SN Jan 28 04:14:51 squeezeplay: INFO applet.SlimMenus - SlimMenusApplet.lua:387 _menuSink() SlimServer {mysqueezebox.com} menuDirective: nil isCurrentServer:nil Jan 28 04:14:52 squeezeplay: INFO applet.AutoSkin - AutoSkinApplet.lua:167 timeout since last touch, shift to skin for current proximity Jan 28 04:14:54 squeezeplay: INFO applet.SlimBrowser - SlimBrowserApplet.lua:1808 serverlinked: pin: false Jan 28 04:14:54 squeezeplay: INFO applet.SetupWelcome - SetupWelcomeApplet.lua:498 notify_serverLinked: SlimServer {mysqueezebox.com} Jan 28 04:14:54 squeezeplay: INFO applet.SetupWelcome - SetupWelcomeApplet.lua:508 server linked: SlimServer {mysqueezebox.com} pin=false Jan 28 04:14:54 squeezeplay: INFO applet.SetupWelcome - SetupWelcomeApplet.lua:521 connecting LocalPlayer {Squeezebox Touch} to SlimServer {mysqueezebox.com} Jan 28 04:14:54 squeezeplay: INFO applet.SetupWelcome - SetupWelcomeApplet.lua:461 step9 Jan 28 04:14:54 squeezeplay: INFO applet.SetupWelcome - SetupWelcomeApplet.lua:140 _setupComplete gohome=false Jan 28 04:14:54 squeezeplay: INFO applet.SetupWelcome - SetupWelcomeApplet.lua:113 _enableNormalEscapeMechanisms Jan 28 04:14:54 squeezeplay: INFO applet.SetupWelcome - SetupWelcomeApplet.lua:530 network setup complete Jan 28 04:14:54 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:704 store settings: SetupWelcome Jan 28 04:14:54 squeezeplay: INFO net.slimproto - SlimProto.lua:549 connect to fab4.squeezenetwork.com (67.155.107.15) Jan 28 04:14:54 squeezeplay: INFO applet.SlimDiscovery - SlimDiscoveryApplet.lua:490 notify_playerPower: true Jan 28 04:14:54 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:704 store settings: Playback Jan 28 04:14:54 squeezeplay: INFO applet.SlimDiscovery - SlimDiscoveryApplet.lua:426 connected Squeezebox Touch Jan 28 04:14:54 squeezeplay: INFO squeezeplay.applets - AppletManager.lua:704 store settings: SlimDiscovery Jan 28 04:14:54 squeezeplay: INFO applet.SlimMenus - SlimMenusApplet.lua:974 SlimMenusApplet:notify_playerCurrent(LocalPlayer {Squeezebox Touch}) Jan 28 04:14:54 squeezeplay: INFO applet.SlimMenus - SlimMenusApplet.lua:1051 player changed from:false to LocalPlayer {Squeezebox Touch} for server: SlimServer {mysqueezebox.com} from server: nil Jan 28 04:14:54 squeezeplay: INFO applet.SlimMenus - SlimMenusApplet.lua:1059 Jan 28 04:14:54 squeezeplay: Subscribing to /slim/menustatus/ Jan 28 04:14:54 squeezeplay: 00:04:20:22:01:56 Sep 9 17:45:32 squeezeplay: INFO applet.SlimMenus - SlimMenusApplet.lua:387 _menuSink() SlimServer {mysqueezebox.com} menuDirective: nil isCurrentServer:nil Sep 9 17:45:32 squeezeplay: INFO applet.SlimMenus - SlimMenusApplet.lua:387 _menuSink() SlimServer {mysqueezebox.com} menuDirective: nil isCurrentServer:true Sep 9 17:45:32 squeezeplay: INFO applet.SlimMenus - SlimMenusApplet.lua:646 hiding any 'connecting to server' popup after menu response from current server, SlimServer {mysqueezebox.com} Sep 9 17:45:33 squeezeplay: INFO applet.ChooseMusicSource - ChooseMusicSourceApplet.lua:546 Hiding popup, exists?: nil Sep 9 17:45:33 squeezeplay: WARN jive.applets.SnoozeAlarm - AlarmSnoozeApplet.lua:40 alarmState: nil Sep 9 17:45:40 squeezeplay: INFO squeezeplay.applets - AppletMeta.lua:145 entering SetupFirmwareUpgrade Sep 9 17:45:40 squeezeplay: INFO applet.SetupFirmwareUpgrade - SetupFirmwareUpgradeApplet.lua:99 Adding to Upgrades: http://update.squeezenetwork.com/update/firmware/.7.4/fab4_7.4.0_r7467.bin version: 7.4.0 r7467 Sep 9 17:45:42 squeezeplay: INFO applet.SetupFirmware - UpgradeUBI.lua:524 /usr/sbin/ubirmvol /dev/ubi0 -N kernel_upg Sep 9 17:45:42 squeezeplay: INFO applet.SetupFirmware - UpgradeUBI.lua:266 /sbin/udevtrigger --subsystem-match=ubi Sep 9 17:45:42 squeezeplay: INFO squeezebox.server - SlimServer.lua:708 disconnected mysqueezebox.com idleTimeoutTriggered: nil Sep 9 17:45:42 root: udev/mount.sh [/dev/mtdblock:kernel_upg] is blacklisted, ignoring Sep 9 17:45:42 squeezeplay: INFO applet.SetupFirmware - UpgradeUBI.lua:277 /sbin/udevsettle Sep 9 17:45:43 squeezeplay: INFO applet.SetupFirmware - UpgradeUBI.lua:387 Firmware url=http://update.squeezenetwork.com/update/firmware/.7.4/fab4_7.4.0_r7467.bin Sep 9 17:45:43 squeezeplay: INFO applet.SetupFirmware - UpgradeUBI.lua:457 /usr/sbin/ubimkvol /dev/ubi0 -N kernel_upg -s 2060744 Sep 9 17:45:43 root: udev/mount.sh [/dev/mtdblock:kernel_upg] is blacklisted, ignoring Sep 9 17:45:43 squeezeplay: INFO applet.SetupFirmware - UpgradeUBI.lua:266 /sbin/udevtrigger --subsystem-match=ubi Sep 9 17:45:43 squeezeplay: INFO applet.SetupFirmware - UpgradeUBI.lua:277 /sbin/udevsettle Sep 9 17:45:44 squeezeplay: INFO applet.SetupFirmware - UpgradeUBI.lua:471 /usr/sbin/ubiupdatevol /dev/ubi/kernel_upg/vol -s 2060744 - Sep 9 17:46:08 squeezeplay: INFO applet.AutoSkin - AutoSkinApplet.lua:167 timeout since last touch, shift to skin for current proximity Sep 9 17:46:09 squeezeplay: ERROR applet.SetupFirmware - UpgradeUBI.lua:295 upgrade sink: closed Sep 9 17:46:09 squeezeplay: stack traceback: Sep 9 17:46:09 squeezeplay: ...are/jive/applets/SetupFirmwareUpgrade/UpgradeUBI.lua:295: in function 'snk' Sep 9 17:46:09 squeezeplay: /usr/share/lua/5.1/ltn12.lua:260: in function Sep 9 17:46:09 squeezeplay: ERROR applet.SetupFirmwareUpgrade - SetupFirmwareUpgradeApplet.lua:423 Upgrade failed: closed Sep 9 17:46:09 squeezeplay: stack traceback: Sep 9 17:46:09 squeezeplay: [C 0x5f818]: in function 'error' Sep 9 17:46:09 squeezeplay: ...are/jive/applets/SetupFirmwareUpgrade/UpgradeUBI.lua:444: in function 'download' Sep 9 17:46:09 squeezeplay: ...are/jive/applets/SetupFirmwareUpgrade/UpgradeUBI.lua:98: in function <...are/jive/applets/SetupFirmwareUpgrade/UpgradeUBI.lua:58>closed Sep 9 17:46:09 squeezeplay: stack traceback: Sep 9 17:46:09 squeezeplay: /usr/share/jive/jive/utils/coxpcall.lua:29: in function Sep 9 17:46:09 squeezeplay: (tail call): ? Sep 9 17:46:09 squeezeplay: (tail call): ? Sep 9 17:46:09 squeezeplay: (tail call): ? Sep 9 17:46:09 squeezeplay: (tail cal Sep 9 17:46:10 squeezeplay: INFO squeezebox.server - SlimServer.lua:685 connected mysqueezebox.com Sep 9 17:46:10 squeezeplay: INFO squeezebox.server - SlimServer.lua:313 mysqueezebox.com firmware=http://update.squeezenetwork.com/update/firmware/.7.4/fab4_7.4.0_r7467.bin force=false Sep 9 17:46:10 squeezeplay: INFO applet.SlimBrowser - SlimBrowserApplet.lua:3336 Player gone while browsing it ! -- packing home! Sep 9 17:46:46 kernel: UBI warning: vol_cdev_release: update of volume 3 not finished, volume is damaged Sep 9 17:46:50 squeezeplay: INFO applet.SetupFirmware - UpgradeUBI.lua:524 /usr/sbin/ubirmvol /dev/ubi0 -N kernel_upg Sep 9 17:46:50 squeezeplay: INFO applet.SetupFirmware - UpgradeUBI.lua:266 /sbin/udevtrigger --subsystem-match=ubi Sep 9 17:46:50 squeezeplay: INFO squeezebox.server - SlimServer.lua:708 disconnected mysqueezebox.com idleTimeoutTriggered: nil Sep 9 17:46:50 root: udev/mount.sh [/dev/mtdblock:kernel_upg] is blacklisted, ignoring Sep 9 17:46:50 squeezeplay: INFO applet.SetupFirmware - UpgradeUBI.lua:277 /sbin/udevsettle Sep 9 17:46:50 squeezeplay: INFO applet.SetupFirmware - UpgradeUBI.lua:387 Firmware url=http://update.squeezenetwork.com/update/firmware/.7.4/fab4_7.4.0_r7467.bin Sep 9 17:46:51 squeezeplay: INFO applet.SetupFirmware - UpgradeUBI.lua:457 /usr/sbin/ubimkvol /dev/ubi0 -N kernel_upg -s 2060744 Sep 9 17:46:51 root: udev/mount.sh [/dev/mtdblock:kernel_upg] is blacklisted, ignoring Sep 9 17:46:51 squeezeplay: INFO applet.SetupFirmware - UpgradeUBI.lua:266 /sbin/udevtrigger --subsystem-match=ubi Sep 9 17:46:51 squeezeplay: INFO applet.SetupFirmware - UpgradeUBI.lua:277 /sbin/udevsettle Sep 9 17:46:51 squeezeplay: INFO applet.SetupFirmware - UpgradeUBI.lua:471 /usr/sbin/ubiupdatevol /dev/ubi/kernel_upg/vol -s 2060744 - Sep 9 17:47:08 squeezeplay: ERROR applet.SetupFirmware - UpgradeUBI.lua:295 upgrade sink: closed Sep 9 17:47:08 squeezeplay: stack traceback: Sep 9 17:47:08 squeezeplay: ...are/jive/applets/SetupFirmwareUpgrade/UpgradeUBI.lua:295: in function 'snk' Sep 9 17:47:08 squeezeplay: /usr/share/lua/5.1/ltn12.lua:260: in function Sep 9 17:47:08 squeezeplay: ERROR applet.SetupFirmwareUpgrade - SetupFirmwareUpgradeApplet.lua:423 Upgrade failed: closed Sep 9 17:47:08 squeezeplay: stack traceback: Sep 9 17:47:08 squeezeplay: [C 0x5f818]: in function 'error' Sep 9 17:47:08 squeezeplay: ...are/jive/applets/SetupFirmwareUpgrade/UpgradeUBI.lua:444: in function 'download' Sep 9 17:47:08 squeezeplay: ...are/jive/applets/SetupFirmwareUpgrade/UpgradeUBI.lua:98: in function <...are/jive/applets/SetupFirmwareUpgrade/UpgradeUBI.lua:58>closed Sep 9 17:47:08 squeezeplay: stack traceback: Sep 9 17:47:08 squeezeplay: /usr/share/jive/jive/utils/coxpcall.lua:29: in function Sep 9 17:47:08 squeezeplay: (tail call): ? Sep 9 17:47:08 squeezeplay: (tail call): ? Sep 9 17:47:08 squeezeplay: (tail call): ? Sep 9 17:47:08 squeezeplay: (tail cal Sep 9 17:47:09 squeezeplay: INFO squeezebox.server - SlimServer.lua:685 connected mysqueezebox.com Sep 9 17:47:09 squeezeplay: INFO squeezebox.server - SlimServer.lua:313 mysqueezebox.com firmware=http://update.squeezenetwork.com/update/firmware/.7.4/fab4_7.4.0_r7467.bin force=false