Ticket #316: syslog_090115_1141.txt

Jan 15 11:38:32 debian-gta02 syslogd 1.5.0#5: restart.
Jan 15 11:38:33 debian-gta02 kernel: klogd 1.5.0#5, log source = /proc/kmsg started.
Jan 15 11:38:33 debian-gta02 kernel: Linux version 2.6.24-20081103.git7172ec57 (gismo@agricola) (gcc version 4.3.2 (Debian 4.3.2-1) ) #1 PREEMPT Wed Dec 17 18:06:43 UTC 2008
Jan 15 11:38:33 debian-gta02 kernel: CPU: ARM920T [41129200] revision 0 (ARMv4T), cr=c0007177
Jan 15 11:38:33 debian-gta02 kernel: Machine: GTA02
Jan 15 11:38:33 debian-gta02 kernel: Memory policy: ECC disabled, Data cache writeback
Jan 15 11:38:33 debian-gta02 kernel: On node 0 totalpages: 32768
Jan 15 11:38:33 debian-gta02 kernel:   DMA zone: 256 pages used for memmap
Jan 15 11:38:33 debian-gta02 kernel:   DMA zone: 0 pages reserved
Jan 15 11:38:33 debian-gta02 kernel:   DMA zone: 32512 pages, LIFO batch:7
Jan 15 11:38:33 debian-gta02 kernel:   Normal zone: 0 pages used for memmap
Jan 15 11:38:33 debian-gta02 kernel:   Movable zone: 0 pages used for memmap
Jan 15 11:38:33 debian-gta02 kernel: CPU S3C2442B (id 0x32440aab)
Jan 15 11:38:33 debian-gta02 kernel: S3C244X: core 400.000 MHz, memory 100.000 MHz, peripheral 50.000 MHz
Jan 15 11:38:33 debian-gta02 kernel: S3C24XX Clocks, (c) 2004 Simtec Electronics
Jan 15 11:38:33 debian-gta02 kernel: CLOCK: Slow mode (1.500 MHz), fast, MPLL on, UPLL on
Jan 15 11:38:33 debian-gta02 kernel: CPU0: D VIVT write-back cache
Jan 15 11:38:33 debian-gta02 kernel: CPU0: I cache: 16384 bytes, associativity 64, 32 byte lines, 8 sets
Jan 15 11:38:33 debian-gta02 kernel: CPU0: D cache: 16384 bytes, associativity 64, 32 byte lines, 8 sets
Jan 15 11:38:33 debian-gta02 kernel: Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 32512
Jan 15 11:38:33 debian-gta02 kernel: Kernel command line: rootfstype=jffs2 root=/dev/mtdblock6 console=ttySAC2,115200 console=tty0 loglevel=8 regular_boot mtdparts=physmap-flash:-(nor);neo1973-nand:0x00040000(u-boot),0x00040000(u-boot_env),0x00800000(kernel),0x000a0000(splash),0x00040000(factory),0x0f6a0000(rootfs) rootfstype=ext2 root=/dev/mmcblk0p2 rootdelay=5
Jan 15 11:38:33 debian-gta02 kernel: irq: clearing pending ext status 000f12f0
Jan 15 11:38:33 debian-gta02 kernel: irq: clearing pending ext status 000902d0
Jan 15 11:38:33 debian-gta02 kernel: irq: clearing subpending status 00000087
Jan 15 11:38:33 debian-gta02 kernel: irq: clearing subpending status 00000082
Jan 15 11:38:33 debian-gta02 kernel: PID hash table entries: 512 (order: 9, 2048 bytes)
Jan 15 11:38:33 debian-gta02 kernel: pclk = 50000000
Jan 15 11:38:33 debian-gta02 kernel: timer_usec_ticks = 7864
Jan 15 11:38:33 debian-gta02 kernel: timer tcon=00500000, tcnt a2c1, tcfg 00000200,00000000, usec 00001eb8
Jan 15 11:38:33 debian-gta02 kernel: Console: colour dummy device 80x30
Jan 15 11:38:33 debian-gta02 kernel: console [tty0] enabled
Jan 15 11:38:33 debian-gta02 kernel: console [ttySAC2] enabled
Jan 15 11:38:33 debian-gta02 kernel: Dentry cache hash table entries: 16384 (order: 4, 65536 bytes)
Jan 15 11:38:33 debian-gta02 kernel: Inode-cache hash table entries: 8192 (order: 3, 32768 bytes)
Jan 15 11:38:33 debian-gta02 kernel: Memory: 128MB = 128MB total
Jan 15 11:38:33 debian-gta02 kernel: Memory: 126080KB available (3268K code, 346K data, 128K init)
Jan 15 11:38:33 debian-gta02 kernel: Calibrating delay loop... 199.47 BogoMIPS (lpj=498688)
Jan 15 11:38:33 debian-gta02 kernel: Mount-cache hash table entries: 512
Jan 15 11:38:33 debian-gta02 kernel: CPU: Testing write buffer coherency: ok
Jan 15 11:38:33 debian-gta02 kernel: net_namespace: 64 bytes
Jan 15 11:38:33 debian-gta02 kernel: NET: Registered protocol family 16
Jan 15 11:38:33 debian-gta02 kernel: S3C2410 Power Management, (c) 2004 Simtec Electronics
Jan 15 11:38:33 debian-gta02 kernel: wake enabled for irq 17
Jan 15 11:38:33 debian-gta02 kernel: wake enabled for irq 49
Jan 15 11:38:33 debian-gta02 kernel: S3C2442: Initialising architecture
Jan 15 11:38:33 debian-gta02 kernel: S3C24XX DMA Driver, (c) 2003-2004,2006 Simtec Electronics
Jan 15 11:38:33 debian-gta02 kernel: DMA channel 0 at c8800000, irq 33
Jan 15 11:38:33 debian-gta02 kernel: DMA channel 1 at c8800040, irq 34
Jan 15 11:38:33 debian-gta02 kernel: DMA channel 2 at c8800080, irq 35
Jan 15 11:38:33 debian-gta02 kernel: DMA channel 3 at c88000c0, irq 36
Jan 15 11:38:33 debian-gta02 kernel: S3C2442: Clock Support, DVS off
Jan 15 11:38:33 debian-gta02 kernel: Linux Plug and Play Support v0.97 (c) Adam Belay
Jan 15 11:38:34 debian-gta02 kernel: usbcore: registered new interface driver usbfs
Jan 15 11:38:34 debian-gta02 kernel: usbcore: registered new interface driver hub
Jan 15 11:38:34 debian-gta02 kernel: usbcore: registered new device driver usb
Jan 15 11:38:34 debian-gta02 kernel: pnp: the driver 'system' has been registered
Jan 15 11:38:34 debian-gta02 kernel: NET: Registered protocol family 2
Jan 15 11:38:34 debian-gta02 kernel: IP route cache hash table entries: 1024 (order: 0, 4096 bytes)
Jan 15 11:38:34 debian-gta02 kernel: TCP established hash table entries: 4096 (order: 3, 32768 bytes)
Jan 15 11:38:34 debian-gta02 kernel: TCP bind hash table entries: 4096 (order: 2, 16384 bytes)
Jan 15 11:38:34 debian-gta02 kernel: TCP: Hash tables configured (established 4096 bind 4096)
Jan 15 11:38:34 debian-gta02 kernel: TCP reno registered
Jan 15 11:38:34 debian-gta02 kernel: s3c24xx_pwm s3c24xx_pwm.0: s3c24xx_pwm is registered 
Jan 15 11:38:34 debian-gta02 kernel: Enabling FIQ using irq 29
Jan 15 11:38:34 debian-gta02 kernel: not changing prescaler of PWM 3, since it's shared with timer4 (clock tick)
Jan 15 11:38:34 debian-gta02 kernel: NetWinder Floating Point Emulator V0.97 (double precision)
Jan 15 11:38:34 debian-gta02 kernel: neo1973-version neo1973-version.0: starting
Jan 15 11:38:34 debian-gta02 kernel: neo1973-pm-host neo1973-pm-host.0: starting
Jan 15 11:38:34 debian-gta02 kernel: neo1973-resume neo1973-resume.0: starting
Jan 15 11:38:34 debian-gta02 kernel: neo1973-memconfig neo1973-memconfig.0: starting
Jan 15 11:38:34 debian-gta02 kernel: JFFS2 version 2.2. (NAND) (SUMMARY)  © 2001-2006 Red Hat, Inc.
Jan 15 11:38:34 debian-gta02 kernel: io scheduler noop registered
Jan 15 11:38:34 debian-gta02 kernel: io scheduler deadline registered (default)
Jan 15 11:38:34 debian-gta02 kernel: s3c2440-uart.0: s3c2410_serial0 at MMIO 0x50000000 (irq = 70) is a S3C2440
Jan 15 11:38:34 debian-gta02 kernel: s3c2440-uart.1: s3c2410_serial1 at MMIO 0x50004000 (irq = 73) is a S3C2440
Jan 15 11:38:34 debian-gta02 kernel: s3c2440-uart.2: s3c2410_serial2 at MMIO 0x50008000 (irq = 76) is a S3C2440
Jan 15 11:38:34 debian-gta02 kernel: RAMDISK driver initialized: 16 RAM disks of 4096K size 1024 blocksize
Jan 15 11:38:34 debian-gta02 kernel: glamo3362 glamo3362.0: Detected Glamo core 3650 Revision 0002 (49119232Hz CPU / 81887232Hz Memory)
Jan 15 11:38:34 debian-gta02 kernel: glamo3362 glamo3362.0: Glamo core now 49119232Hz CPU / 89980928Hz Memory)
Jan 15 11:38:34 debian-gta02 kernel: Detected S-Media IRQ# pullup, enabling interrupt
Jan 15 11:38:34 debian-gta02 kernel: glamo-spi-gpio glamo-spi-gpio.0: registering c03618a8: jbt6k74
Jan 15 11:38:34 debian-gta02 kernel: SMEDIA Glamo frame buffer driver (C) 2007 Openmoko, Inc.
Jan 15 11:38:34 debian-gta02 kernel: Console: switching to colour frame buffer device 80x58
Jan 15 11:38:34 debian-gta02 kernel: fb0: SMedia Glamo frame buffer device
Jan 15 11:38:34 debian-gta02 kernel: glamo-mci glamo-mci.0: glamo_mci driver (C)2007 Openmoko, Inc
Jan 15 11:38:34 debian-gta02 kernel: glamo-mci glamo-mci.0: probe: mapped mci_base:c8864400 irq:0.
Jan 15 11:38:34 debian-gta02 kernel: glamo-mci glamo-mci.0: glamo_mci_set_ios: power down.
Jan 15 11:38:34 debian-gta02 kernel: glamo-mci glamo-mci.0: initialisation done.
Jan 15 11:38:34 debian-gta02 kernel: mmc_set_power(power_mode=1, vdd=20
Jan 15 11:38:34 debian-gta02 kernel: physmap platform flash device: 00200000 at 18000000
Jan 15 11:38:34 debian-gta02 kernel: physmap-flash.0: Found 1 x16 devices at 0x0 in 16-bit bank
Jan 15 11:38:34 debian-gta02 kernel:  Intel/Sharp Extended Query Table at 0x0039
Jan 15 11:38:34 debian-gta02 last message repeated 4 times
Jan 15 11:38:34 debian-gta02 kernel: cfi_cmdset_0001: Erase suspend on write enabled
Jan 15 11:38:34 debian-gta02 kernel: erase region 0: offset=0x0,size=0x2000,blocks=8
Jan 15 11:38:34 debian-gta02 kernel: erase region 1: offset=0x10000,size=0x10000,blocks=31
Jan 15 11:38:34 debian-gta02 kernel: physmap-flash.0: 1 set(s) of 1 interleaved chips --> 4 partitions of 512 KiB
Jan 15 11:38:34 debian-gta02 kernel: RedBoot partition parsing not available
Jan 15 11:38:34 debian-gta02 kernel: S3C24XX NAND Driver, (c) 2004 Simtec Electronics
Jan 15 11:38:34 debian-gta02 kernel: s3c2440-nand s3c2440-nand: Tacls=0, 0ns Twrph0=3 30ns, Twrph1=2 20ns
Jan 15 11:38:34 debian-gta02 kernel: NAND device: Manufacturer ID: 0xec, Chip ID: 0xaa (Samsung NAND 256MiB 1,8V 8-bit)
Jan 15 11:38:34 debian-gta02 kernel: s3c2410_nand_update_chip: chip c7d13eb8: 11
Jan 15 11:38:34 debian-gta02 kernel: Bad block table found at page 131008, version 0x01
Jan 15 11:38:34 debian-gta02 kernel: Bad block table found at page 130944, version 0x01
Jan 15 11:38:34 debian-gta02 kernel: 6 cmdlinepart partitions found on MTD device neo1973-nand
Jan 15 11:38:34 debian-gta02 kernel: Creating 6 MTD partitions on "neo1973-nand":
Jan 15 11:38:34 debian-gta02 kernel: 0x00000000-0x00040000 : "u-boot"
Jan 15 11:38:34 debian-gta02 kernel: 0x00040000-0x00080000 : "u-boot_env"
Jan 15 11:38:34 debian-gta02 kernel: 0x00080000-0x00880000 : "kernel"
Jan 15 11:38:34 debian-gta02 kernel: 0x00880000-0x00920000 : "splash"
Jan 15 11:38:34 debian-gta02 kernel: 0x00920000-0x00960000 : "factory"
Jan 15 11:38:34 debian-gta02 kernel: 0x00960000-0x10000000 : "rootfs"
Jan 15 11:38:34 debian-gta02 kernel: usbmon: debugfs is not available
Jan 15 11:38:34 debian-gta02 kernel: usbcore: registered new interface driver libusual
Jan 15 11:38:34 debian-gta02 kernel: s3c2410_udc: debugfs dir creation failed -19
Jan 15 11:38:34 debian-gta02 kernel: s3c2440-usbgadget s3c2440-usbgadget: S3C2440: increasing FIFO to 128 bytes
Jan 15 11:38:34 debian-gta02 kernel: gta02_udc_command(2)
Jan 15 11:38:34 debian-gta02 kernel: mice: PS/2 mouse device common for all mice
Jan 15 11:38:34 debian-gta02 kernel: input: Neo1973 Buttons as /devices/platform/neo1973-button.0/input/input0
Jan 15 11:38:34 debian-gta02 kernel: wake enabled for irq 48
Jan 15 11:38:34 debian-gta02 kernel: s3c2440-ts s3c2440-ts: successfully loaded
Jan 15 11:38:34 debian-gta02 kernel: input: s3c2410 TouchScreen as /devices/virtual/input/input1
Jan 15 11:38:34 debian-gta02 kernel: input: lis302-1 (top) as /devices/virtual/input/input2
Jan 15 11:38:34 debian-gta02 kernel: lis302dl lis302dl.1: Found lis302-1 (top)
Jan 15 11:38:34 debian-gta02 kernel: input: lis302-2 (bottom) as /devices/virtual/input/input3
Jan 15 11:38:34 debian-gta02 kernel: lis302dl lis302dl.2: Found lis302-2 (bottom)
Jan 15 11:38:34 debian-gta02 kernel: i2c /dev entries driver
Jan 15 11:38:34 debian-gta02 kernel: s3c2440-i2c s3c2440-i2c: slave address 0x10
Jan 15 11:38:34 debian-gta02 kernel: s3c2440-i2c s3c2440-i2c: bus frequency set to 390 KHz
Jan 15 11:38:34 debian-gta02 kernel: s3c2440-i2c s3c2440-i2c: i2c-0: S3C I2C adapter
Jan 15 11:38:34 debian-gta02 kernel: input: GTA02 PMU events as /devices/platform/s3c2440-i2c/i2c-adapter/i2c-0/0-0073/input/input4
Jan 15 11:38:34 debian-gta02 kernel: wake enabled for irq 53
Jan 15 11:38:34 debian-gta02 kernel: pcf50633: dev (254:0)
Jan 15 11:38:35 debian-gta02 kernel: pcf50633 0-0073: rtc core: registered pcf50633 as rtc0
Jan 15 11:38:35 debian-gta02 kernel: pcf50633 0-0073: probe completed
Jan 15 11:38:35 debian-gta02 kernel: neo1973-pm-bt neo1973-pm-bt.0: FIC Neo1973 Bluetooth Power Management: starting
Jan 15 11:38:35 debian-gta02 kernel: SD power -> 3200mV
Jan 15 11:38:35 debian-gta02 kernel: glamo-mci glamo-mci.0: powered (vdd = 20) clk: 0kHz div=255 (req: 0kHz). Bus width=0
Jan 15 11:38:35 debian-gta02 kernel: glamo-mci glamo-mci.0: powered (vdd = 20) clk: 195kHz div=255 (req: 195kHz). Bus width=0
Jan 15 11:38:35 debian-gta02 last message repeated 2 times
Jan 15 11:38:35 debian-gta02 kernel: glamo-mci glamo-mci.0: Error after cmd: 0x8120
Jan 15 11:38:35 debian-gta02 kernel: glamo-mci glamo-mci.0: Error after cmd: 0x120
Jan 15 11:38:35 debian-gta02 kernel: glamo-mci glamo-mci.0: Error after cmd: 0x8120
Jan 15 11:38:35 debian-gta02 kernel: glamo-mci glamo-mci.0: Error after cmd: 0x120
Jan 15 11:38:35 debian-gta02 kernel: mmc_set_power(power_mode=2, vdd=15
Jan 15 11:38:35 debian-gta02 kernel: SD power -> 2700mV
Jan 15 11:38:35 debian-gta02 kernel: neo1973-pm-gps neo1973-pm-gps.0: FIC Neo1973 GPS Power Managerment:starting
Jan 15 11:38:35 debian-gta02 kernel: APM Battery Driver
Jan 15 11:38:35 debian-gta02 kernel: bq27000-battery bq27000-battery.0: BQ27000 Battery Driver (C) 2008 Openmoko, Inc
Jan 15 11:38:35 debian-gta02 kernel: S3c2440 SDIO Host controller
Jan 15 11:38:35 debian-gta02 kernel: pnp: the driver 'sdio_s3c24xx_hcd' has been registered
Jan 15 11:38:35 debian-gta02 kernel: mapped channel 0 to 0
Jan 15 11:38:35 debian-gta02 kernel: S3c24xx SDIO: IRQ:37 Detect IRQ:0 DMA channel:0 base@0xc9a00000 PCLK@50000 kHz
Jan 15 11:38:35 debian-gta02 kernel: sdio_s3c24xx_hcd 00:00: driver attached
Jan 15 11:38:35 debian-gta02 kernel: sdio_s3c24xx_hcd 00:00: SDIO device, IDs SD_0008 (active)
Jan 15 11:38:35 debian-gta02 kernel: pnp: the driver 'sdio_wlan' has been registered
Jan 15 11:38:35 debian-gta02 kernel: glamo-mci glamo-mci.0: powered (vdd = 15) clk: 195kHz div=255 (req: 195kHz). Bus width=0
Jan 15 11:38:35 debian-gta02 last message repeated 2 times
Jan 15 11:38:35 debian-gta02 kernel: Registered led device: neo1973:vibrator
Jan 15 11:38:35 debian-gta02 kernel: Registered led device: gta02-power:orange
Jan 15 11:38:35 debian-gta02 kernel: Registered led device: gta02-power:blue
Jan 15 11:38:35 debian-gta02 kernel: Registered led device: gta02-aux:red
Jan 15 11:38:35 debian-gta02 kernel: glamo-mci glamo-mci.0: powered (vdd = 15) clk: 195kHz div=255 (req: 195kHz). Bus width=0
Jan 15 11:38:35 debian-gta02 kernel: usbcore: registered new interface driver hiddev
Jan 15 11:38:35 debian-gta02 kernel: usbcore: registered new interface driver usbhid
Jan 15 11:38:35 debian-gta02 kernel: drivers/hid/usbhid/hid-core.c: v2.6:USB HID core driver
Jan 15 11:38:35 debian-gta02 kernel: glamo-mci glamo-mci.0: powered (vdd = 15) clk: 195kHz div=255 (req: 195kHz). Bus width=0
Jan 15 11:38:35 debian-gta02 kernel: glamo-mci glamo-mci.0: powered (vdd = 15) clk: 16666kHz div=2 (req: 16666kHz). Bus width=0
Jan 15 11:38:35 debian-gta02 kernel: glamo-mci glamo-mci.0: powered (vdd = 15) clk: 16666kHz div=2 (req: 16666kHz). Bus width=2
Jan 15 11:38:35 debian-gta02 kernel: mmc0: new high speed SDHC card at address d65c
Jan 15 11:38:35 debian-gta02 kernel: mmcblk0: mmc0:d65c SU04G 3979776KiB 
Jan 15 11:38:35 debian-gta02 kernel:  mmcblk0:<6>TCP cubic registered
Jan 15 11:38:35 debian-gta02 kernel: NET: Registered protocol family 1
Jan 15 11:38:35 debian-gta02 kernel: NET: Registered protocol family 17
Jan 15 11:38:35 debian-gta02 kernel:  p1 p2
Jan 15 11:38:35 debian-gta02 kernel: RPC: Registered udp transport module.
Jan 15 11:38:35 debian-gta02 kernel: RPC: Registered tcp transport module.
Jan 15 11:38:35 debian-gta02 kernel: pcf50633 0-0073: setting system clock to 2009-01-15 10:37:52 UTC (1232015872)
Jan 15 11:38:35 debian-gta02 kernel: CRCFAIL 0x1a3f
Jan 15 11:38:35 debian-gta02 kernel: Waiting 5sec before mounting root device...
Jan 15 11:38:35 debian-gta02 kernel: CRCFAIL 0x1a3f
Jan 15 11:38:35 debian-gta02 kernel: CRCFAIL 0x1a3f
Jan 15 11:38:35 debian-gta02 kernel: sdio_wlan 00:01: driver attached
Jan 15 11:38:35 debian-gta02 kernel: sdio_wlan 00:01: SDIO device, IDs SD_0001 (active)
Jan 15 11:38:35 debian-gta02 kernel: BMI Get Target Info: Exit (ver: 0x20000059 type: 0x1)
Jan 15 11:38:35 debian-gta02 kernel: SDIO_INT_CTRL enable IRQ
Jan 15 11:38:35 debian-gta02 kernel: AR6000 Reg Code = 0x40000060
Jan 15 11:38:35 debian-gta02 kernel: VFS: Mounted root (ext2 filesystem).
Jan 15 11:38:35 debian-gta02 kernel: Freeing init memory: 128K
Jan 15 11:38:35 debian-gta02 kernel: ether gadget: using random self ethernet address
Jan 15 11:38:35 debian-gta02 kernel: ether gadget: using random host ethernet address
Jan 15 11:38:35 debian-gta02 kernel: usb0: Ethernet Gadget, version: May Day 2005
Jan 15 11:38:35 debian-gta02 kernel: usb0: using s3c2410_udc, OUT ep2-bulk IN ep1-bulk STATUS ep3-bulk
Jan 15 11:38:35 debian-gta02 kernel: usb0: MAC 72:c1:5f:36:57:00
Jan 15 11:38:35 debian-gta02 kernel: usb0: HOST MAC 5e:d1:03:16:0c:fe
Jan 15 11:38:35 debian-gta02 kernel: usb0: RNDIS ready
Jan 15 11:38:35 debian-gta02 kernel: gta02_udc_command(1)
Jan 15 11:38:35 debian-gta02 kernel: usb0: full speed config #1: 500 mA, Ethernet Gadget, using CDC Ethernet
Jan 15 11:38:35 debian-gta02 kernel: ASoC version 0.13.1
Jan 15 11:38:35 debian-gta02 kernel: wm8753: WM8753 Audio Codec 0.16
Jan 15 11:38:35 debian-gta02 kernel: asoc: WM8753 HiFi <-> s3c24xx-i2s mapping ok
Jan 15 11:38:35 debian-gta02 kernel: asoc: WM8753 Voice <-> Bluetooth mapping ok
Jan 15 11:38:35 debian-gta02 kernel: ohci_hcd: 2006 August 04 USB 1.1 'Open' Host Controller (OHCI) Driver
Jan 15 11:38:35 debian-gta02 kernel: s3c2410-ohci s3c2410-ohci: S3C24XX OHCI
Jan 15 11:38:35 debian-gta02 kernel: s3c2410-ohci s3c2410-ohci: new USB bus registered, assigned bus number 1
Jan 15 11:38:35 debian-gta02 kernel: s3c2410-ohci s3c2410-ohci: irq 42, io mem 0x49000000
Jan 15 11:38:35 debian-gta02 kernel: usb usb1: configuration #1 chosen from 1 choice
Jan 15 11:38:35 debian-gta02 kernel: hub 1-0:1.0: USB hub found
Jan 15 11:38:35 debian-gta02 kernel: hub 1-0:1.0: 2 ports detected
Jan 15 11:38:35 debian-gta02 kernel: Bluetooth: Core ver 2.11
Jan 15 11:38:35 debian-gta02 kernel: NET: Registered protocol family 31
Jan 15 11:38:35 debian-gta02 kernel: Bluetooth: HCI device and connection manager initialized
Jan 15 11:38:35 debian-gta02 kernel: Bluetooth: HCI socket layer initialized
Jan 15 11:38:36 debian-gta02 kernel: Bluetooth: HCI USB driver ver 2.9
Jan 15 11:38:36 debian-gta02 kernel: usbcore: registered new interface driver hci_usb
Jan 15 11:38:37 debian-gta02 apmd[1178]: apmd 3.2.1 interfacing with apm driver 1.13 and APM BIOS 1.2
Jan 15 11:38:38 debian-gta02 kernel: NET: Registered protocol family 10
Jan 15 11:38:38 debian-gta02 dropbear[1194]: Running in background
Jan 15 11:38:40 debian-gta02 fso-gpsd[1203]: WARNING: org.freedesktop.Gypsy.Server: Create: The name org.freedesktop.Gypsy was not provided by any .service files
Jan 15 11:38:43 debian-gta02 2009.01.15 11:38:43 root     INFO     Installprefix is /usr
Jan 15 11:38:43 debian-gta02 2009.01.15 11:38:43 root     INFO     Root dir is /etc/freesmartphone
Jan 15 11:38:43 debian-gta02 wwwoffled[1215]: WWWOFFLE Demon Version 2.9d (with ipv6,with zlib,with gnutls) started.
Jan 15 11:38:44 debian-gta02 2009.01.15 11:38:44 frameworkd.subsystem INFO     subsystem frameworkd took 0.10 seconds to startup
Jan 15 11:38:44 debian-gta02 2009.01.15 11:38:44 frameworkd.controller INFO     launching subsystem odeviced
Jan 15 11:38:44 debian-gta02 2009.01.15 11:38:44 odeviced.powercontrol INFO     NeoUsbHostPowerControl 0.0.0 initialized. Serving org.freesmartphone.Device.PowerControl at /org/freesmartphone/Device/PowerControl/UsbHost
Jan 15 11:38:44 debian-gta02 wwwoffled[1215]: Could not import private key [Base64 unexpected header error.].
Jan 15 11:38:45 debian-gta02 2009.01.15 11:38:45 odeviced.powercontrol INFO     NeoBluetoothPowerControl 0.0.0 initialized. Serving org.freesmartphone.Device.PowerControl at /org/freesmartphone/Device/PowerControl/Bluetooth
Jan 15 11:38:45 debian-gta02 wwwoffled[1215]: The WWWOFFLE root CA private key file 'certificates/root/root-key.pem' cannot be loaded; delete problem file and start WWWOFFLE again to recreate it.
Jan 15 11:38:45 debian-gta02 2009.01.15 11:38:45 odeviced.powercontrol INFO     NeoWifiPowerControl 0.0.0 initialized. Serving org.freesmartphone.Device.PowerControl at /org/freesmartphone/Device/PowerControl/WiFi
Jan 15 11:38:45 debian-gta02 2009.01.15 11:38:45 frameworkd.subsystem INFO     skipping module odeviced.accelerometer as requested via config file.
Jan 15 11:38:45 debian-gta02 2009.01.15 11:38:45 odeviced.idlenotifier INFO     IdleNotifier 0.9.9.3 initialized. Serving org.freesmartphone.Device.IdleNotifier at /org/freesmartphone/Device/IdleNotifier/0
Jan 15 11:38:45 debian-gta02 2009.01.15 11:38:45 odeviced.idlenotifier INFO     skipping input node 2 due to configuration
Jan 15 11:38:45 debian-gta02 2009.01.15 11:38:45 odeviced.idlenotifier INFO     skipping input node 3 due to configuration
Jan 15 11:38:45 debian-gta02 2009.01.15 11:38:45 odeviced.idlenotifier INFO     opened 3 input file descriptors
Jan 15 11:38:46 debian-gta02 2009.01.15 11:38:46 odeviced.input INFO     Input 0.9.9.4 initialized. Serving org.freesmartphone.Device.Input at /org/freesmartphone/Device/Input
Jan 15 11:38:46 debian-gta02 2009.01.15 11:38:46 odeviced.input INFO     skipping input node 1 due to it supporting EV_ABS
Jan 15 11:38:46 debian-gta02 2009.01.15 11:38:46 odeviced.input INFO     skipping input node 2 due to configuration
Jan 15 11:38:46 debian-gta02 2009.01.15 11:38:46 odeviced.input INFO     skipping input node 3 due to configuration
Jan 15 11:38:46 debian-gta02 2009.01.15 11:38:46 odeviced.input INFO     opened 2 input file descriptors
Jan 15 11:38:46 debian-gta02 2009.01.15 11:38:46 odeviced.info INFO     Info 0.1.3 initialized. Serving org.freesmartphone.Device.Info at /org/freesmartphone/Device/Info
Jan 15 11:38:47 debian-gta02 su[1221]: Successful su for root by root
Jan 15 11:38:47 debian-gta02 su[1221]: + ??? root:root
Jan 15 11:38:47 debian-gta02 su[1221]: pam_unix(su:session): session opened for user root by (uid=0)
Jan 15 11:38:48 debian-gta02 anacron[1232]: Anacron 2.3 started on 2009-01-15
Jan 15 11:38:48 debian-gta02 kernel: usb0: no IPv6 routers present
Jan 15 11:38:49 debian-gta02 anacron[1232]: Normal exit (0 jobs run)
Jan 15 11:38:49 debian-gta02 2009.01.15 11:38:49 odeviced.audio INFO     Audio 0.5.1.1 initialized. Serving org.freesmartphone.Device.Audio at /org/freesmartphone/Device/Audio
Jan 15 11:38:49 debian-gta02 2009.01.15 11:38:49 odeviced.audio WARNING  GST can't parse modplug; Not adding mod to decoderMap
Jan 15 11:38:50 debian-gta02 /usr/sbin/cron[1254]: (CRON) INFO (pidfile fd = 3)
Jan 15 11:38:51 debian-gta02 /usr/sbin/cron[1255]: (CRON) STARTUP (fork ok)
Jan 15 11:38:51 debian-gta02 /usr/sbin/cron[1255]: (CRON) INFO (Running @reboot jobs)
Jan 15 11:38:51 debian-gta02 2009.01.15 11:38:51 odeviced.audio WARNING  GST can't parse wavparse; Not adding wav to decoderMap
Jan 15 11:38:51 debian-gta02 2009.01.15 11:38:51 odeviced.audio WARNING  GST can't parse oggdemux ! ivorbisdec ! audioconvert; Not adding ogg to decoderMap
Jan 15 11:38:51 debian-gta02 2009.01.15 11:38:51 odeviced.audio WARNING  GST can't parse oggdemux ! vorbisdec ! audioconvert; Not adding ogg to decoderMap
Jan 15 11:38:51 debian-gta02 2009.01.15 11:38:51 odeviced.audio INFO      ::: using alsa scenarios in /usr/share/openmoko/scenarios, default = stereoout
Jan 15 11:38:51 debian-gta02 2009.01.15 11:38:51 odeviced.kernel26 INFO     Display 0.9.8 initialized. Serving org.freesmartphone.Device.Display at /org/freesmartphone/Device/Display/pcf50633_bl
Jan 15 11:38:51 debian-gta02 2009.01.15 11:38:51 odeviced.kernel26 INFO     framebuffer blanking enabled
Jan 15 11:38:51 debian-gta02 2009.01.15 11:38:51 odeviced.kernel26 INFO     LED 0.9.8 initialized. Serving org.freesmartphone.Device.LED at /org/freesmartphone/Device/LED/neo1973_vibrator
Jan 15 11:38:51 debian-gta02 2009.01.15 11:38:51 odeviced.kernel26 INFO     LED 0.9.8 initialized. Serving org.freesmartphone.Device.LED at /org/freesmartphone/Device/LED/gta02_power_orange
Jan 15 11:38:51 debian-gta02 2009.01.15 11:38:51 odeviced.kernel26 INFO     LED 0.9.8 initialized. Serving org.freesmartphone.Device.LED at /org/freesmartphone/Device/LED/gta02_power_blue
Jan 15 11:38:51 debian-gta02 2009.01.15 11:38:51 odeviced.kernel26 INFO     LED 0.9.8 initialized. Serving org.freesmartphone.Device.LED at /org/freesmartphone/Device/LED/gta02_aux_red
Jan 15 11:38:51 debian-gta02 2009.01.15 11:38:51 odeviced.kernel26 INFO     PowerSupplyApm 0.9.8 initialized. Serving org.freesmartphone.Device.PowerSupply at /org/freesmartphone/Device/PowerSupply/apm
Jan 15 11:38:52 debian-gta02 2009.01.15 11:38:52 odeviced.kernel26 INFO     PowerSupply 0.9.8 initialized. Serving org.freesmartphone.Device.PowerSupply at /org/freesmartphone/Device/PowerSupply/bat
Jan 15 11:38:52 debian-gta02 2009.01.15 11:38:52 odeviced.kernel26 INFO     RealTimeClock 0.9.8 initialized. Serving org.freesmartphone.Device.RealTimeClock at /org/freesmartphone/Device/RealTimeClock/rtc0
Jan 15 11:38:52 debian-gta02 2009.01.15 11:38:52 frameworkd.subsystem INFO     subsystem odeviced took 7.79 seconds to startup
Jan 15 11:38:52 debian-gta02 2009.01.15 11:38:52 frameworkd.controller INFO     launching subsystem oeventsd
Jan 15 11:38:54 debian-gta02 2009.01.15 11:38:54 oeventsd INFO     EventsManager 0.3.0 initialized. Serving org.freesmartphone.Events at /org/freesmartphone/Events
Jan 15 11:38:59 debian-gta02 dbus-daemon: Rejected send message, 1 matched rules; type="method_call", sender=":1.1" (uid=0 pid=1200 comm="/usr/bin/python /usr/bin/frameworkd ") interface="org.freedesktop.DBus.Introspectable" member="Introspect" error name="(unset)" requested_reply=0 destination="org.freesmartphone.odeviced" (uid=0 pid=1200 comm="/usr/bin/python /usr/bin/frameworkd "))
Jan 15 11:39:00 debian-gta02 dbus-daemon: Rejected send message, 1 matched rules; type="method_call", sender=":1.1" (uid=0 pid=1200 comm="/usr/bin/python /usr/bin/frameworkd ") interface="org.freedesktop.DBus.Introspectable" member="Introspect" error name="(unset)" requested_reply=0 destination="org.freesmartphone.odeviced" (uid=0 pid=1200 comm="/usr/bin/python /usr/bin/frameworkd "))
Jan 15 11:39:00 debian-gta02 dbus-daemon: Rejected send message, 1 matched rules; type="method_call", sender=":1.1" (uid=0 pid=1200 comm="/usr/bin/python /usr/bin/frameworkd ") interface="org.freedesktop.DBus.Introspectable" member="Introspect" error name="(unset)" requested_reply=0 destination="org.freesmartphone.odeviced" (uid=0 pid=1200 comm="/usr/bin/python /usr/bin/frameworkd "))
Jan 15 11:39:03 debian-gta02 2009.01.15 11:39:03 oeventsd INFO     Created new rule : on Time(12:29) if And() then Debug("A Test")
Jan 15 11:39:03 debian-gta02 2009.01.15 11:39:03 oeventsd INFO     Created new rule : on InputTrigger if And(switch == POWER,event == released,duration == 0) then Suspend(())
Jan 15 11:39:03 debian-gta02 2009.01.15 11:39:03 oeventsd INFO     Created new rule : on IncomingMessage if And() then MessageToneAction(play)
Jan 15 11:39:03 debian-gta02 2009.01.15 11:39:03 oeventsd INFO     Created new rule : While CallListContains(incoming) if ~(CallListContains(active)) then [RingToneAction(), SetBrightness((90,))]
Jan 15 11:39:03 debian-gta02 2009.01.15 11:39:03 oeventsd INFO     Created new rule : While CallStatus if Or(status == outgoing,status == active) then SetScenario(gsmhandset)
Jan 15 11:39:03 debian-gta02 2009.01.15 11:39:03 oeventsd INFO     Created new rule : While PowerStatus if status == charging then SetLed(gta02_power_orange, light)
Jan 15 11:39:03 debian-gta02 2009.01.15 11:39:03 oeventsd INFO     Created new rule : While PowerStatus if status == critical then SetLed(gta02_power_orange, blink)
Jan 15 11:39:03 debian-gta02 2009.01.15 11:39:03 oeventsd INFO     Created new rule : While PowerStatus if status == full then SetLed(gta02_power_blue, light)
Jan 15 11:39:03 debian-gta02 2009.01.15 11:39:03 oeventsd INFO     Created new rule : on PowerStatus if status == empty then CommandAction(poweroff)
Jan 15 11:39:03 debian-gta02 2009.01.15 11:39:03 oeventsd INFO     Created new rule : on InputTrigger if And(switch == HEADSET,event == pressed) then CommandAction(amixer -d sset "Amp Spk" mute)
Jan 15 11:39:03 debian-gta02 2009.01.15 11:39:03 oeventsd INFO     Created new rule : on InputTrigger if And(switch == HEADSET,event == released) then CommandAction(amixer -d sset "Amp Spk" unmute)
Jan 15 11:39:03 debian-gta02 2009.01.15 11:39:03 oeventsd INFO     Created new rule : 'test-rule'
Jan 15 11:39:03 debian-gta02 2009.01.15 11:39:03 oeventsd INFO     Created new rule : on Test(test) if And() then Debug("trigger test")
Jan 15 11:39:03 debian-gta02 2009.01.15 11:39:03 oeventsd INFO     Created new rule : While Test(test2) if And() then RingToneAction()
Jan 15 11:39:04 debian-gta02 2009.01.15 11:39:04 oeventsd INFO     Created new rule : on DBusTrigger(org.freesmartphone.odeviced None.State) if And() then Debug("dbus trigger test")
Jan 15 11:39:04 debian-gta02 2009.01.15 11:39:04 oeventsd INFO     Created new rule : on IdleState if status == busy then SetBrightness((90,))
Jan 15 11:39:04 debian-gta02 2009.01.15 11:39:04 oeventsd INFO     Created new rule : on IdleState if status == idle_dim then SetBrightness((20,))
Jan 15 11:39:04 debian-gta02 2009.01.15 11:39:04 oeventsd INFO     Created new rule : on IdleState if status == idle_prelock then SetBrightness((0,))
Jan 15 11:39:04 debian-gta02 2009.01.15 11:39:04 frameworkd.subsystem INFO     subsystem oeventsd took 12.06 seconds to startup
Jan 15 11:39:04 debian-gta02 2009.01.15 11:39:04 frameworkd.controller INFO     launching subsystem otimed
Jan 15 11:39:04 debian-gta02 2009.01.15 11:39:04 frameworkd.subsystem WARNING  Can't claim bus name 'org.freesmartphone.otimed', check configuration in /etc/dbus-1/system.d/frameworkd.conf
Jan 15 11:39:04 debian-gta02 2009.01.15 11:39:04 otimed.alarm INFO     AlarmController initialized. Serving org.freesmartphone.Time.Alarm at /org/freesmartphone/Time/Alarm
Jan 15 11:39:04 debian-gta02 2009.01.15 11:39:04 frameworkd.subsystem WARNING  service otimed doesn't have any busnames registered
Jan 15 11:39:04 debian-gta02 2009.01.15 11:39:04 frameworkd.subsystem INFO     subsystem otimed took 0.28 seconds to startup
Jan 15 11:39:04 debian-gta02 2009.01.15 11:39:04 frameworkd.controller INFO     launching subsystem ophoned
Jan 15 11:39:04 debian-gta02 2009.01.15 11:39:04 frameworkd.subsystem INFO     subsystem ophoned took 0.27 seconds to startup
Jan 15 11:39:04 debian-gta02 2009.01.15 11:39:04 frameworkd.controller INFO     launching subsystem ogsmd
Jan 15 11:39:06 debian-gta02 2009.01.15 11:39:06 ogsmd.objects INFO     Device initialized. Serving org.freesmartphone.GSM.Device at /org/freesmartphone/GSM/Device
Jan 15 11:39:06 debian-gta02 2009.01.15 11:39:06 ogsmd.objects INFO     Server initialized. Serving org.freesmartphone.GSM.Server at /org/freesmartphone/GSM/Server
Jan 15 11:39:06 debian-gta02 dbus-daemon: Rejected send message, 1 matched rules; type="method_call", sender=":1.1" (uid=0 pid=1200 comm="/usr/bin/python /usr/bin/frameworkd ") interface="org.freedesktop.DBus.Introspectable" member="Introspect" error name="(unset)" requested_reply=0 destination=":1.1" (uid=0 pid=1200 comm="/usr/bin/python /usr/bin/frameworkd "))
Jan 15 11:39:07 debian-gta02 2009.01.15 11:39:07 frameworkd.subsystem INFO     subsystem ogsmd took 2.14 seconds to startup
Jan 15 11:39:07 debian-gta02 2009.01.15 11:39:07 frameworkd.controller INFO     launching subsystem opreferencesd
Jan 15 11:39:07 debian-gta02 2009.01.15 11:39:07 opreferencesd INFO     PreferencesManager 0.2.0 initialized. Serving org.freesmartphone.Preferences at /org/freesmartphone/Preferences
Jan 15 11:39:07 debian-gta02 2009.01.15 11:39:07 opreferencesd INFO      ::: using schema path : /etc/freesmartphone/opreferences/schema
Jan 15 11:39:07 debian-gta02 2009.01.15 11:39:07 opreferencesd INFO      ::: using conf path : /etc/freesmartphone/opreferences/conf
Jan 15 11:39:07 debian-gta02 2009.01.15 11:39:07 opreferencesd INFO      ::: services : ['phone', 'rules', 'profiles']
Jan 15 11:39:07 debian-gta02 2009.01.15 11:39:07 frameworkd.subsystem INFO     subsystem opreferencesd took 0.41 seconds to startup
Jan 15 11:39:07 debian-gta02 2009.01.15 11:39:07 frameworkd.controller INFO     skipping subsystem opimd as requested via config file.
Jan 15 11:39:07 debian-gta02 2009.01.15 11:39:07 frameworkd.controller INFO     launching subsystem ousaged
Jan 15 11:39:07 debian-gta02 2009.01.15 11:39:07 ousaged  INFO     GenericUsageControl initialized. Serving org.freesmartphone.Usage at /org/freesmartphone/Usage
Jan 15 11:39:07 debian-gta02 2009.01.15 11:39:07 frameworkd.subsystem INFO     subsystem ousaged took 0.24 seconds to startup
Jan 15 11:39:07 debian-gta02 2009.01.15 11:39:07 frameworkd.controller INFO     launching subsystem ogpsd
Jan 15 11:39:08 debian-gta02 2009.01.15 11:39:08 ogpsd    INFO     GTA02Device initialized. Serving org.freedesktop.Gypsy at /org/freedesktop/Gypsy
Jan 15 11:39:08 debian-gta02 2009.01.15 11:39:08 frameworkd.subsystem INFO     subsystem ogpsd took 0.94 seconds to startup
Jan 15 11:39:08 debian-gta02 2009.01.15 11:39:08 frameworkd.controller INFO     ================== objects registered ===================
Jan 15 11:39:08 debian-gta02 2009.01.15 11:39:08 frameworkd.controller INFO     /org/freedesktop/Gypsy [org.freedesktop.Gypsy]
Jan 15 11:39:08 debian-gta02 2009.01.15 11:39:08 frameworkd.controller INFO     /org/freesmartphone/Device/Audio [org.freesmartphone.Device.Audio]
Jan 15 11:39:08 debian-gta02 2009.01.15 11:39:08 frameworkd.controller INFO     /org/freesmartphone/Device/Display/pcf50633_bl [org.freesmartphone.Device.Display]
Jan 15 11:39:08 debian-gta02 2009.01.15 11:39:08 frameworkd.controller INFO     /org/freesmartphone/Device/IdleNotifier/0 [org.freesmartphone.Device.IdleNotifier]
Jan 15 11:39:08 debian-gta02 2009.01.15 11:39:08 frameworkd.controller INFO     /org/freesmartphone/Device/Info [org.freesmartphone.Device.Info]
Jan 15 11:39:09 debian-gta02 2009.01.15 11:39:09 frameworkd.controller INFO     /org/freesmartphone/Device/Input [org.freesmartphone.Device.Input]
Jan 15 11:39:09 debian-gta02 2009.01.15 11:39:09 frameworkd.controller INFO     /org/freesmartphone/Device/LED/gta02_aux_red [org.freesmartphone.Device.LED]
Jan 15 11:39:09 debian-gta02 2009.01.15 11:39:09 frameworkd.controller INFO     /org/freesmartphone/Device/LED/gta02_power_blue [org.freesmartphone.Device.LED]
Jan 15 11:39:09 debian-gta02 2009.01.15 11:39:09 frameworkd.controller INFO     /org/freesmartphone/Device/LED/gta02_power_orange [org.freesmartphone.Device.LED]
Jan 15 11:39:09 debian-gta02 2009.01.15 11:39:09 frameworkd.controller INFO     /org/freesmartphone/Device/LED/neo1973_vibrator [org.freesmartphone.Device.LED]
Jan 15 11:39:09 debian-gta02 2009.01.15 11:39:09 frameworkd.controller INFO     /org/freesmartphone/Device/PowerControl/Bluetooth [org.freesmartphone.Device.PowerControl]
Jan 15 11:39:09 debian-gta02 2009.01.15 11:39:09 frameworkd.controller INFO     /org/freesmartphone/Device/PowerControl/UsbHost [org.freesmartphone.Device.PowerControl]
Jan 15 11:39:09 debian-gta02 2009.01.15 11:39:09 frameworkd.controller INFO     /org/freesmartphone/Device/PowerControl/WiFi [org.freesmartphone.Device.PowerControl]
Jan 15 11:39:09 debian-gta02 2009.01.15 11:39:09 frameworkd.controller INFO     /org/freesmartphone/Device/PowerSupply/apm [org.freesmartphone.Device.PowerSupply]
Jan 15 11:39:09 debian-gta02 2009.01.15 11:39:09 frameworkd.controller INFO     /org/freesmartphone/Device/PowerSupply/bat [org.freesmartphone.Device.PowerSupply]
Jan 15 11:39:09 debian-gta02 2009.01.15 11:39:09 frameworkd.controller INFO     /org/freesmartphone/Device/RealTimeClock/rtc0 [org.freesmartphone.Device.RealTimeClock]
Jan 15 11:39:09 debian-gta02 2009.01.15 11:39:09 frameworkd.controller INFO     /org/freesmartphone/Events [org.freesmartphone.Events]
Jan 15 11:39:09 debian-gta02 2009.01.15 11:39:09 frameworkd.controller INFO     /org/freesmartphone/Framework [org.freesmartphone.Framework]
Jan 15 11:39:09 debian-gta02 2009.01.15 11:39:09 frameworkd.controller INFO     /org/freesmartphone/GSM/Device [org.freesmartphone.GSM.Device]
Jan 15 11:39:09 debian-gta02 2009.01.15 11:39:09 frameworkd.controller INFO     /org/freesmartphone/GSM/Server [org.freesmartphone.GSM.Server]
Jan 15 11:39:09 debian-gta02 2009.01.15 11:39:09 frameworkd.controller INFO     /org/freesmartphone/Phone [org.freesmartphone.Phone]
Jan 15 11:39:09 debian-gta02 2009.01.15 11:39:09 frameworkd.controller INFO     /org/freesmartphone/Preferences [org.freesmartphone.Preferences]
Jan 15 11:39:09 debian-gta02 2009.01.15 11:39:09 frameworkd.controller INFO     /org/freesmartphone/Time [org.freesmartphone.Time]
Jan 15 11:39:09 debian-gta02 2009.01.15 11:39:09 frameworkd.controller INFO     /org/freesmartphone/Time/Alarm [org.freesmartphone.Time.Alarm]
Jan 15 11:39:09 debian-gta02 2009.01.15 11:39:09 frameworkd.controller INFO     /org/freesmartphone/Usage [org.freesmartphone.Usage]
Jan 15 11:39:09 debian-gta02 2009.01.15 11:39:09 odeviced.idlenotifier INFO     odeviced.idlenotifier state change to idle
Jan 15 11:39:09 debian-gta02 2009.01.15 11:39:09 dbus.proxies ERROR    Introspect error on org.freesmartphone.odeviced:/org/freesmartphone/Device/LED/gta02_power_orange: dbus.exceptions.DBusException: org.freedesktop.DBus.Error.AccessDenied: Rejected send message, 1 matched rules; type="method_call", sender=":1.1" (uid=0 pid=1200 comm="/usr/bin/python /usr/bin/frameworkd ") interface="org.freedesktop.DBus.Introspectable" member="Introspect" error name="(unset)" requested_reply=0 destination="org.freesmartphone.odeviced" (uid=0 pid=1200 comm="/usr/bin/python /usr/bin/frameworkd "))
Jan 15 11:39:09 debian-gta02 2009.01.15 11:39:09 dbus.proxies ERROR    Introspect error on org.freesmartphone.odeviced:/org/freesmartphone/Device/LED/gta02_power_orange: dbus.exceptions.DBusException: org.freedesktop.DBus.Error.AccessDenied: Rejected send message, 1 matched rules; type="method_call", sender=":1.1" (uid=0 pid=1200 comm="/usr/bin/python /usr/bin/frameworkd ") interface="org.freedesktop.DBus.Introspectable" member="Introspect" error name="(unset)" requested_reply=0 destination="org.freesmartphone.odeviced" (uid=0 pid=1200 comm="/usr/bin/python /usr/bin/frameworkd "))
Jan 15 11:39:09 debian-gta02 2009.01.15 11:39:09 dbus.proxies ERROR    Introspect error on org.freesmartphone.odeviced:/org/freesmartphone/Device/LED/gta02_power_blue: dbus.exceptions.DBusException: org.freedesktop.DBus.Error.AccessDenied: Rejected send message, 1 matched rules; type="method_call", sender=":1.1" (uid=0 pid=1200 comm="/usr/bin/python /usr/bin/frameworkd ") interface="org.freedesktop.DBus.Introspectable" member="Introspect" error name="(unset)" requested_reply=0 destination="org.freesmartphone.odeviced" (uid=0 pid=1200 comm="/usr/bin/python /usr/bin/frameworkd "))
Jan 15 11:39:09 debian-gta02 2009.01.15 11:39:09 dbus.proxies ERROR    Introspect error on :1.1:/org/freesmartphone/GSM/Device: dbus.exceptions.DBusException: org.freedesktop.DBus.Error.AccessDenied: Rejected send message, 1 matched rules; type="method_call", sender=":1.1" (uid=0 pid=1200 comm="/usr/bin/python /usr/bin/frameworkd ") interface="org.freedesktop.DBus.Introspectable" member="Introspect" error name="(unset)" requested_reply=0 destination=":1.1" (uid=0 pid=1200 comm="/usr/bin/python /usr/bin/frameworkd "))
Jan 15 11:39:09 debian-gta02 2009.01.15 11:39:09 frameworkd.controller INFO     ================== mainloop   entered ===================
Jan 15 11:39:09 debian-gta02 2009.01.15 11:39:09 frameworkd.controller INFO     startup time was 25.86 seconds
Jan 15 11:39:09 debian-gta02 2009.01.15 11:39:09 frameworkd.resource INFO     Trying to register resource Bluetooth
Jan 15 11:39:09 debian-gta02 2009.01.15 11:39:09 frameworkd.resource INFO     Trying to register resource WiFi
Jan 15 11:39:10 debian-gta02 2009.01.15 11:39:10 odeviced.audio INFO     sound scenario stereoout user
Jan 15 11:39:10 debian-gta02 2009.01.15 11:39:10 odeviced.audio INFO     default alsa scenario restored
Jan 15 11:39:10 debian-gta02 2009.01.15 11:39:10 oeventsd INFO     Updating the rules
Jan 15 11:39:10 debian-gta02 2009.01.15 11:39:10 opreferencesd INFO     GetService rules
Jan 15 11:39:10 debian-gta02 2009.01.15 11:39:10 opreferencesd INFO     no conf file : '/etc/freesmartphone/opreferences/conf/rules/default.yaml'
Jan 15 11:39:10 debian-gta02 2009.01.15 11:39:10 opreferencesd INFO     Service rules : can't find key enabled-rules, using default
Jan 15 11:39:11 debian-gta02 2009.01.15 11:39:11 oeventsd INFO     enable rule : on Time(12:29) if And() then Debug("A Test")
Jan 15 11:39:11 debian-gta02 2009.01.15 11:39:11 oeventsd INFO     enable rule : on InputTrigger if And(switch == POWER,event == released,duration == 0) then Suspend(())
Jan 15 11:39:11 debian-gta02 2009.01.15 11:39:11 oeventsd INFO     enable rule : on IncomingMessage if And() then MessageToneAction(play)
Jan 15 11:39:11 debian-gta02 2009.01.15 11:39:11 oeventsd INFO     enable rule : While CallListContains(incoming) if ~(CallListContains(active)) then [RingToneAction(), SetBrightness((90,))]
Jan 15 11:39:11 debian-gta02 2009.01.15 11:39:11 oeventsd INFO     enable rule : CallListContains(incoming)
Jan 15 11:39:11 debian-gta02 2009.01.15 11:39:11 oeventsd INFO     enable rule : While CallStatus if Or(status == outgoing,status == active) then SetScenario(gsmhandset)
Jan 15 11:39:11 debian-gta02 2009.01.15 11:39:11 oeventsd INFO     enable rule : While PowerStatus if status == charging then SetLed(gta02_power_orange, light)
Jan 15 11:39:11 debian-gta02 2009.01.15 11:39:11 oeventsd INFO     enable rule : While PowerStatus if status == critical then SetLed(gta02_power_orange, blink)
Jan 15 11:39:11 debian-gta02 2009.01.15 11:39:11 oeventsd INFO     enable rule : While PowerStatus if status == full then SetLed(gta02_power_blue, light)
Jan 15 11:39:11 debian-gta02 2009.01.15 11:39:11 oeventsd INFO     enable rule : on PowerStatus if status == empty then CommandAction(poweroff)
Jan 15 11:39:11 debian-gta02 2009.01.15 11:39:11 oeventsd INFO     enable rule : on InputTrigger if And(switch == HEADSET,event == pressed) then CommandAction(amixer -d sset "Amp Spk" mute)
Jan 15 11:39:11 debian-gta02 2009.01.15 11:39:11 oeventsd INFO     enable rule : on InputTrigger if And(switch == HEADSET,event == released) then CommandAction(amixer -d sset "Amp Spk" unmute)
Jan 15 11:39:11 debian-gta02 2009.01.15 11:39:11 oeventsd INFO     disable rule : 'test-rule'
Jan 15 11:39:11 debian-gta02 2009.01.15 11:39:11 oeventsd INFO     enable rule : on Test(test) if And() then Debug("trigger test")
Jan 15 11:39:11 debian-gta02 2009.01.15 11:39:11 oeventsd INFO     enable rule : While Test(test2) if And() then RingToneAction()
Jan 15 11:39:11 debian-gta02 2009.01.15 11:39:11 oeventsd INFO     enable rule : on DBusTrigger(org.freesmartphone.odeviced None.State) if And() then Debug("dbus trigger test")
Jan 15 11:39:11 debian-gta02 2009.01.15 11:39:11 oeventsd INFO     enable rule : on IdleState if status == busy then SetBrightness((90,))
Jan 15 11:39:11 debian-gta02 2009.01.15 11:39:11 oeventsd INFO     enable rule : on IdleState if status == idle_dim then SetBrightness((20,))
Jan 15 11:39:11 debian-gta02 2009.01.15 11:39:11 oeventsd INFO     enable rule : on IdleState if status == idle_prelock then SetBrightness((0,))
Jan 15 11:39:11 debian-gta02 2009.01.15 11:39:11 otimed.alarm INFO     getting rtc interface
Jan 15 11:39:11 debian-gta02 2009.01.15 11:39:11 frameworkd.resource INFO     Trying to register resource GSM
Jan 15 11:39:11 debian-gta02 2009.01.15 11:39:11 frameworkd.resource INFO     Trying to register resource GPS
Jan 15 11:39:12 debian-gta02 2009.01.15 11:39:12 ousaged  INFO     Register new resource Bluetooth
Jan 15 11:39:12 debian-gta02 2009.01.15 11:39:12 ousaged  INFO     Register new resource WiFi
Jan 15 11:39:12 debian-gta02 2009.01.15 11:39:12 ousaged  INFO     Register new resource GSM
Jan 15 11:39:12 debian-gta02 2009.01.15 11:39:12 ousaged  INFO     Register new resource GPS
Jan 15 11:39:20 debian-gta02 2009.01.15 11:39:20 odeviced.kernel26 INFO     power status now charging
Jan 15 11:39:21 debian-gta02 2009.01.15 11:39:21 oeventsd INFO     Receive PowerStatus, status = charging
Jan 15 11:39:21 debian-gta02 2009.01.15 11:39:21 oeventsd INFO     turn led gta02_power_orange on
Jan 15 11:39:21 debian-gta02 2009.01.15 11:39:21 oeventsd INFO     Receive PowerStatus, status = charging
Jan 15 11:39:21 debian-gta02 last message repeated 2 times
Jan 15 11:39:28 debian-gta02 2009.01.15 11:39:28 frameworkd.resource INFO     setting resource status for GSM from disabled to enabling
Jan 15 11:39:30 debian-gta02 2009.01.15 11:39:30 ogsmd    INFO     <CallChannel via unknown>: Creating channel with timeout = 3600 seconds
Jan 15 11:39:30 debian-gta02 2009.01.15 11:39:30 ogsmd    INFO     <UnsolicitedResponseChannel via unknown>: Creating channel with timeout = 10 seconds
Jan 15 11:39:30 debian-gta02 2009.01.15 11:39:30 ogsmd    INFO     <MiscChannel via unknown>: Creating channel with timeout = 10 seconds
Jan 15 11:39:30 debian-gta02 2009.01.15 11:39:30 odeviced.idlenotifier INFO     odeviced.idlenotifier state change to idle_dim
Jan 15 11:39:30 debian-gta02 2009.01.15 11:39:30 oeventsd INFO     DebugAction : dbus trigger test
Jan 15 11:39:30 debian-gta02 2009.01.15 11:39:30 oeventsd INFO     Receive IdleState, status = idle_dim
Jan 15 11:39:30 debian-gta02 2009.01.15 11:39:30 oeventsd INFO     Receive IdleState, status = idle_dim
Jan 15 11:39:30 debian-gta02 2009.01.15 11:39:30 oeventsd INFO     call dbus signal /org/freesmartphone/Device/Display/pcf50633_bl SetBrightness((20,))
Jan 15 11:39:31 debian-gta02 2009.01.15 11:39:31 oeventsd INFO     Receive IdleState, status = idle_dim
Jan 15 11:39:31 debian-gta02 2009.01.15 11:39:31 ogsmd.modem.abstract DEBUG    trying to open channel MISC
Jan 15 11:39:31 debian-gta02 gsm0710muxd: gsm0710muxd.c:1935:main(): using '/sys/bus/platform/devices/neo1973-pm-gsm.0' as basedir for pm 
Jan 15 11:39:38 debian-gta02 /usr/sbin/gsm0710muxd[1305]: gsm0710muxd.c:1617:open_serial_device(): Opened serial port 
Jan 15 11:39:38 debian-gta02 /usr/sbin/gsm0710muxd[1305]: gsm0710muxd.c:1641:open_serial_device(): Configured serial device 
Jan 15 11:39:38 debian-gta02 /usr/sbin/gsm0710muxd[1305]: gsm0710muxd.c:1763:watchdog(): Watchdog started 
Jan 15 11:39:38 debian-gta02 /usr/sbin/gsm0710muxd[1305]: gsm0710muxd.c:1652:start_muxer(): Configuring modem 
Jan 15 11:39:39 debian-gta02 /usr/sbin/gsm0710muxd[1305]: gsm0710muxd.c:1656:start_muxer(): Modem does not respond to AT commands, trying close mux mode 
Jan 15 11:39:39 debian-gta02 /usr/sbin/gsm0710muxd[1305]: gsm0710muxd.c:1696:start_muxer(): Starting mux mode 
Jan 15 11:39:39 debian-gta02 /usr/sbin/gsm0710muxd[1305]: gsm0710muxd.c:1699:start_muxer(): Waiting for mux-mode 
Jan 15 11:39:40 debian-gta02 /usr/sbin/gsm0710muxd[1305]: gsm0710muxd.c:1701:start_muxer(): Init control channel 
Jan 15 11:39:40 debian-gta02 /usr/sbin/gsm0710muxd[1305]: gsm0710muxd.c:698:c_alloc_channel(): Connecting /dev/pts/0 to virtual channel 1 for ogsmd.misc on /dev/ttySAC0 
Jan 15 11:39:40 debian-gta02 2009.01.15 11:39:40 ogsmd    INFO     <MiscChannel via unknown>: initializing
Jan 15 11:39:40 debian-gta02 2009.01.15 11:39:40 ogsmd    DEBUG    (modem init... try #1)
Jan 15 11:39:40 debian-gta02 /usr/sbin/gsm0710muxd[1305]: gsm0710muxd.c:1421:extract_frames(): Logical channel 1 opened 
Jan 15 11:39:40 debian-gta02 2009.01.15 11:39:40 ogsmd    DEBUG    read: AT-Command Interpreter ready
Jan 15 11:39:40 debian-gta02 2009.01.15 11:39:40 ogsmd    INFO     <MiscChannel via /dev/pts/0>: responding OK
Jan 15 11:39:40 debian-gta02 2009.01.15 11:39:40 ogsmd.modem.abstract DEBUG    trying to open channel UNSOL
Jan 15 11:39:40 debian-gta02 /usr/sbin/gsm0710muxd[1305]: gsm0710muxd.c:698:c_alloc_channel(): Connecting /dev/pts/1 to virtual channel 2 for ogsmd.unsolicited on /dev/ttySAC0 
Jan 15 11:39:40 debian-gta02 /usr/sbin/gsm0710muxd[1305]: gsm0710muxd.c:1421:extract_frames(): Logical channel 2 opened 
Jan 15 11:39:40 debian-gta02 2009.01.15 11:39:40 ogsmd    INFO     <UnsolicitedResponseChannel via unknown>: initializing
Jan 15 11:39:41 debian-gta02 2009.01.15 11:39:41 ogsmd    DEBUG    (modem init... try #1)
Jan 15 11:39:41 debian-gta02 2009.01.15 11:39:41 ogsmd    DEBUG    read: AT-Command Interpreter ready
Jan 15 11:39:41 debian-gta02 2009.01.15 11:39:41 ogsmd    INFO     <UnsolicitedResponseChannel via /dev/pts/1>: responding OK
Jan 15 11:39:41 debian-gta02 2009.01.15 11:39:41 ogsmd.modem.abstract DEBUG    trying to open channel CALL
Jan 15 11:39:41 debian-gta02 /usr/sbin/gsm0710muxd[1305]: gsm0710muxd.c:698:c_alloc_channel(): Connecting /dev/pts/2 to virtual channel 3 for ogsmd.call on /dev/ttySAC0 
Jan 15 11:39:41 debian-gta02 /usr/sbin/gsm0710muxd[1305]: gsm0710muxd.c:1421:extract_frames(): Logical channel 3 opened 
Jan 15 11:39:41 debian-gta02 2009.01.15 11:39:41 ogsmd    INFO     <CallChannel via unknown>: initializing
Jan 15 11:39:41 debian-gta02 2009.01.15 11:39:41 ogsmd    DEBUG    (modem init... try #1)
Jan 15 11:39:41 debian-gta02 2009.01.15 11:39:41 ogsmd    DEBUG    read: AT-Command Interpreter ready
Jan 15 11:39:41 debian-gta02 2009.01.15 11:39:41 ogsmd    INFO     <CallChannel via /dev/pts/2>: responding OK
Jan 15 11:39:41 debian-gta02 2009.01.15 11:39:41 frameworkd.resource INFO     setting resource status for GSM from enabling to enabled
Jan 15 11:39:41 debian-gta02 2009.01.15 11:39:41 oeventsd INFO     signal SetBrightness responded : ()
Jan 15 11:39:41 debian-gta02 2009.01.15 11:39:41 odeviced.idlenotifier INFO     odeviced.idlenotifier state change to busy
Jan 15 11:39:41 debian-gta02 2009.01.15 11:39:41 ogsmd    DEBUG    <MiscChannel via /dev/pts/0> queue is: deque([('ATZ\r\n', None, None, 10), ('ATE0V1\r\n', None, None, 10), ('AT+CMEE=1\r\n', None, None, 10), ('AT+CRC=1\r\n', None, None, 10), ('AT+CSCS="UCS2"\r\n', None, None, 10), ('AT+CSDH=1\r\n', None, None, 10), ('AT+CSNS=0\r\n', None, None, 10), ('AT+CMGF=0\r\n', None, None, 10), ('AT+CLIP=0\r\n', None, None, 10), ('AT+COLP=0\r\n', None, None, 10), ('AT+CCWA=0\r\n', None, None, 10)])
Jan 15 11:39:41 debian-gta02 2009.01.15 11:39:41 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: sending 5 bytes: 'ATZ\r\n'
Jan 15 11:39:41 debian-gta02 2009.01.15 11:39:41 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1> queue is: deque([('ATZ\r\n', None, None, 10), ('ATE0V1\r\n', None, None, 10), ('AT+CMEE=1\r\n', None, None, 10), ('AT+CRC=1\r\n', None, None, 10), ('AT+CSCS="UCS2"\r\n', None, None, 10), ('AT+CSDH=1\r\n', None, None, 10), ('AT+CSNS=0\r\n', None, None, 10), ('AT+CMGF=0\r\n', None, None, 10), ('AT+CLIP=0\r\n', None, None, 10), ('AT+COLP=0\r\n', None, None, 10), ('AT+CCWA=0\r\n', None, None, 10), ('AT+CLIP=1\r\n', None, None, 10), ('AT+COLP=0\r\n', None, None, 10), ('AT+CCWA=1\r\n', None, None, 10), ('AT+CSSN=1,1\r\n', None, None, 10), ('AT+CTZU=1\r\n', None, None, 10), ('AT+CTZR=1\r\n', None, None, 10), ('AT+CREG=2\r\n', None, None, 10), ('AT+CAOC=2\r\n', None, None, 10), ('AT+CGEREP=2,1\r\n', None, None, 10), ('AT+CGREG=2\r\n', None, None, 10), ('AT%CPI=3\r\n', None, None, 10), ('AT%CSCN=1,2,1,2\r\n', None, None, 10), ('AT%CSQ=1\r\n', None, None, 10), ('AT%CNIV=1\r\n', None, None, 10), ('AT%CGEREP=1\r\n', None, None, 10), (
Jan 15 11:39:41 debian-gta02 2009.01.15 11:39:41 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: sending 5 bytes: 'ATZ\r\n'
Jan 15 11:39:41 debian-gta02 2009.01.15 11:39:41 ogsmd    DEBUG    <CallChannel via /dev/pts/2> queue is: deque([('ATZ\r\n', None, None, 3600), ('ATE0V1\r\n', None, None, 3600), ('AT+CMEE=1\r\n', None, None, 3600), ('AT+CRC=1\r\n', None, None, 3600), ('AT+CSCS="UCS2"\r\n', None, None, 3600), ('AT+CSDH=1\r\n', None, None, 3600), ('AT+CSNS=0\r\n', None, None, 3600), ('AT+CMGF=0\r\n', None, None, 3600), ('AT+CLIP=0\r\n', None, None, 3600), ('AT+COLP=0\r\n', None, None, 3600), ('AT+CCWA=0\r\n', None, None, 3600)])
Jan 15 11:39:41 debian-gta02 2009.01.15 11:39:41 ogsmd    DEBUG    <CallChannel via /dev/pts/2>: sending 5 bytes: 'ATZ\r\n'
Jan 15 11:39:41 debian-gta02 2009.01.15 11:39:41 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:39:41 debian-gta02 2009.01.15 11:39:41 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: COMPLETED 'ATZ' => ['OK']
Jan 15 11:39:41 debian-gta02 2009.01.15 11:39:41 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:39:41 debian-gta02 2009.01.15 11:39:41 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: COMPLETED 'ATZ' => ['OK']
Jan 15 11:39:41 debian-gta02 2009.01.15 11:39:41 ogsmd    DEBUG    <CallChannel via /dev/pts/2>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:39:41 debian-gta02 2009.01.15 11:39:41 ogsmd    DEBUG    <CallChannel via /dev/pts/2>: COMPLETED 'ATZ' => ['OK']
Jan 15 11:39:41 debian-gta02 2009.01.15 11:39:41 ogsmd    DEBUG    <MiscChannel via /dev/pts/0> queue is: deque([('ATE0V1\r\n', None, None, 10), ('AT+CMEE=1\r\n', None, None, 10), ('AT+CRC=1\r\n', None, None, 10), ('AT+CSCS="UCS2"\r\n', None, None, 10), ('AT+CSDH=1\r\n', None, None, 10), ('AT+CSNS=0\r\n', None, None, 10), ('AT+CMGF=0\r\n', None, None, 10), ('AT+CLIP=0\r\n', None, None, 10), ('AT+COLP=0\r\n', None, None, 10), ('AT+CCWA=0\r\n', None, None, 10)])
Jan 15 11:39:41 debian-gta02 2009.01.15 11:39:41 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: sending 8 bytes: 'ATE0V1\r\n'
Jan 15 11:39:41 debian-gta02 2009.01.15 11:39:41 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1> queue is: deque([('ATE0V1\r\n', None, None, 10), ('AT+CMEE=1\r\n', None, None, 10), ('AT+CRC=1\r\n', None, None, 10), ('AT+CSCS="UCS2"\r\n', None, None, 10), ('AT+CSDH=1\r\n', None, None, 10), ('AT+CSNS=0\r\n', None, None, 10), ('AT+CMGF=0\r\n', None, None, 10), ('AT+CLIP=0\r\n', None, None, 10), ('AT+COLP=0\r\n', None, None, 10), ('AT+CCWA=0\r\n', None, None, 10), ('AT+CLIP=1\r\n', None, None, 10), ('AT+COLP=0\r\n', None, None, 10), ('AT+CCWA=1\r\n', None, None, 10), ('AT+CSSN=1,1\r\n', None, None, 10), ('AT+CTZU=1\r\n', None, None, 10), ('AT+CTZR=1\r\n', None, None, 10), ('AT+CREG=2\r\n', None, None, 10), ('AT+CAOC=2\r\n', None, None, 10), ('AT+CGEREP=2,1\r\n', None, None, 10), ('AT+CGREG=2\r\n', None, None, 10), ('AT%CPI=3\r\n', None, None, 10), ('AT%CSCN=1,2,1,2\r\n', None, None, 10), ('AT%CSQ=1\r\n', None, None, 10), ('AT%CNIV=1\r\n', None, None, 10), ('AT%CGEREP=1\r\n', None, None, 10), ('AT%CGREG=3\r\n', None, None,
Jan 15 11:39:41 debian-gta02 2009.01.15 11:39:41 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: sending 8 bytes: 'ATE0V1\r\n'
Jan 15 11:39:41 debian-gta02 2009.01.15 11:39:41 ousaged  INFO     Enabled GSM in 13.1 seconds
Jan 15 11:39:41 debian-gta02 2009.01.15 11:39:41 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:39:41 debian-gta02 2009.01.15 11:39:41 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: COMPLETED 'ATE0V1' => ['OK']
Jan 15 11:39:41 debian-gta02 2009.01.15 11:39:41 ogsmd    DEBUG    <CallChannel via /dev/pts/2> queue is: deque([('ATE0V1\r\n', None, None, 3600), ('AT+CMEE=1\r\n', None, None, 3600), ('AT+CRC=1\r\n', None, None, 3600), ('AT+CSCS="UCS2"\r\n', None, None, 3600), ('AT+CSDH=1\r\n', None, None, 3600), ('AT+CSNS=0\r\n', None, None, 3600), ('AT+CMGF=0\r\n', None, None, 3600), ('AT+CLIP=0\r\n', None, None, 3600), ('AT+COLP=0\r\n', None, None, 3600), ('AT+CCWA=0\r\n', None, None, 3600)])
Jan 15 11:39:41 debian-gta02 2009.01.15 11:39:41 ogsmd    DEBUG    <CallChannel via /dev/pts/2>: sending 8 bytes: 'ATE0V1\r\n'
Jan 15 11:39:41 debian-gta02 2009.01.15 11:39:41 oeventsd INFO     DebugAction : dbus trigger test
Jan 15 11:39:41 debian-gta02 2009.01.15 11:39:41 oeventsd INFO     Receive IdleState, status = busy
Jan 15 11:39:41 debian-gta02 2009.01.15 11:39:41 oeventsd INFO     call dbus signal /org/freesmartphone/Device/Display/pcf50633_bl SetBrightness((90,))
Jan 15 11:39:42 debian-gta02 2009.01.15 11:39:42 oeventsd INFO     Receive IdleState, status = busy
Jan 15 11:39:42 debian-gta02 2009.01.15 11:39:42 oeventsd INFO     Receive IdleState, status = busy
Jan 15 11:39:42 debian-gta02 2009.01.15 11:39:42 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:39:42 debian-gta02 2009.01.15 11:39:42 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: COMPLETED 'ATE0V1' => ['OK']
Jan 15 11:39:42 debian-gta02 2009.01.15 11:39:42 ogsmd    DEBUG    <MiscChannel via /dev/pts/0> queue is: deque([('AT+CMEE=1\r\n', None, None, 10), ('AT+CRC=1\r\n', None, None, 10), ('AT+CSCS="UCS2"\r\n', None, None, 10), ('AT+CSDH=1\r\n', None, None, 10), ('AT+CSNS=0\r\n', None, None, 10), ('AT+CMGF=0\r\n', None, None, 10), ('AT+CLIP=0\r\n', None, None, 10), ('AT+COLP=0\r\n', None, None, 10), ('AT+CCWA=0\r\n', None, None, 10)])
Jan 15 11:39:42 debian-gta02 2009.01.15 11:39:42 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: sending 11 bytes: 'AT+CMEE=1\r\n'
Jan 15 11:39:42 debian-gta02 2009.01.15 11:39:42 ogsmd    DEBUG    <CallChannel via /dev/pts/2>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:39:42 debian-gta02 2009.01.15 11:39:42 ogsmd    DEBUG    <CallChannel via /dev/pts/2>: COMPLETED 'ATE0V1' => ['OK']
Jan 15 11:39:42 debian-gta02 2009.01.15 11:39:42 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1> queue is: deque([('AT+CMEE=1\r\n', None, None, 10), ('AT+CRC=1\r\n', None, None, 10), ('AT+CSCS="UCS2"\r\n', None, None, 10), ('AT+CSDH=1\r\n', None, None, 10), ('AT+CSNS=0\r\n', None, None, 10), ('AT+CMGF=0\r\n', None, None, 10), ('AT+CLIP=0\r\n', None, None, 10), ('AT+COLP=0\r\n', None, None, 10), ('AT+CCWA=0\r\n', None, None, 10), ('AT+CLIP=1\r\n', None, None, 10), ('AT+COLP=0\r\n', None, None, 10), ('AT+CCWA=1\r\n', None, None, 10), ('AT+CSSN=1,1\r\n', None, None, 10), ('AT+CTZU=1\r\n', None, None, 10), ('AT+CTZR=1\r\n', None, None, 10), ('AT+CREG=2\r\n', None, None, 10), ('AT+CAOC=2\r\n', None, None, 10), ('AT+CGEREP=2,1\r\n', None, None, 10), ('AT+CGREG=2\r\n', None, None, 10), ('AT%CPI=3\r\n', None, None, 10), ('AT%CSCN=1,2,1,2\r\n', None, None, 10), ('AT%CSQ=1\r\n', None, None, 10), ('AT%CNIV=1\r\n', None, None, 10), ('AT%CGEREP=1\r\n', None, None, 10), ('AT%CGREG=3\r\n', None, None, 10), ('AT%CSTAT=1\r\n', None, N
Jan 15 11:39:42 debian-gta02 2009.01.15 11:39:42 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: sending 11 bytes: 'AT+CMEE=1\r\n'
Jan 15 11:39:42 debian-gta02 2009.01.15 11:39:42 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:39:42 debian-gta02 2009.01.15 11:39:42 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: COMPLETED 'AT+CMEE=1' => ['OK']
Jan 15 11:39:42 debian-gta02 2009.01.15 11:39:42 ogsmd    DEBUG    <CallChannel via /dev/pts/2> queue is: deque([('AT+CMEE=1\r\n', None, None, 3600), ('AT+CRC=1\r\n', None, None, 3600), ('AT+CSCS="UCS2"\r\n', None, None, 3600), ('AT+CSDH=1\r\n', None, None, 3600), ('AT+CSNS=0\r\n', None, None, 3600), ('AT+CMGF=0\r\n', None, None, 3600), ('AT+CLIP=0\r\n', None, None, 3600), ('AT+COLP=0\r\n', None, None, 3600), ('AT+CCWA=0\r\n', None, None, 3600)])
Jan 15 11:39:42 debian-gta02 2009.01.15 11:39:42 ogsmd    DEBUG    <CallChannel via /dev/pts/2>: sending 11 bytes: 'AT+CMEE=1\r\n'
Jan 15 11:39:42 debian-gta02 2009.01.15 11:39:42 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:39:42 debian-gta02 2009.01.15 11:39:42 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: COMPLETED 'AT+CMEE=1' => ['OK']
Jan 15 11:39:42 debian-gta02 2009.01.15 11:39:42 ogsmd    DEBUG    <MiscChannel via /dev/pts/0> queue is: deque([('AT+CRC=1\r\n', None, None, 10), ('AT+CSCS="UCS2"\r\n', None, None, 10), ('AT+CSDH=1\r\n', None, None, 10), ('AT+CSNS=0\r\n', None, None, 10), ('AT+CMGF=0\r\n', None, None, 10), ('AT+CLIP=0\r\n', None, None, 10), ('AT+COLP=0\r\n', None, None, 10), ('AT+CCWA=0\r\n', None, None, 10), ('AT+CPIN?\r\n', <bound method DeviceSetAntennaPower.intermediateResponse of <ogsmd.modems.abstract.mediator.DeviceSetAntennaPower object at 0x6c8310>>, <bound method DeviceSetAntennaPower.errorFromChannel of <ogsmd.modems.abstract.mediator.DeviceSetAntennaPower object at 0x6c8310>>, 15)])
Jan 15 11:39:42 debian-gta02 2009.01.15 11:39:42 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: sending 10 bytes: 'AT+CRC=1\r\n'
Jan 15 11:39:42 debian-gta02 2009.01.15 11:39:42 ogsmd    DEBUG    <CallChannel via /dev/pts/2>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:39:42 debian-gta02 2009.01.15 11:39:42 ogsmd    DEBUG    <CallChannel via /dev/pts/2>: COMPLETED 'AT+CMEE=1' => ['OK']
Jan 15 11:39:42 debian-gta02 2009.01.15 11:39:42 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1> queue is: deque([('AT+CRC=1\r\n', None, None, 10), ('AT+CSCS="UCS2"\r\n', None, None, 10), ('AT+CSDH=1\r\n', None, None, 10), ('AT+CSNS=0\r\n', None, None, 10), ('AT+CMGF=0\r\n', None, None, 10), ('AT+CLIP=0\r\n', None, None, 10), ('AT+COLP=0\r\n', None, None, 10), ('AT+CCWA=0\r\n', None, None, 10), ('AT+CLIP=1\r\n', None, None, 10), ('AT+COLP=0\r\n', None, None, 10), ('AT+CCWA=1\r\n', None, None, 10), ('AT+CSSN=1,1\r\n', None, None, 10), ('AT+CTZU=1\r\n', None, None, 10), ('AT+CTZR=1\r\n', None, None, 10), ('AT+CREG=2\r\n', None, None, 10), ('AT+CAOC=2\r\n', None, None, 10), ('AT+CGEREP=2,1\r\n', None, None, 10), ('AT+CGREG=2\r\n', None, None, 10), ('AT%CPI=3\r\n', None, None, 10), ('AT%CSCN=1,2,1,2\r\n', None, None, 10), ('AT%CSQ=1\r\n', None, None, 10), ('AT%CNIV=1\r\n', None, None, 10), ('AT%CGEREP=1\r\n', None, None, 10), ('AT%CGREG=3\r\n', None, None, 10), ('AT%CSTAT=1\r\n', None, None, 10), ('AT@ST="-26"\r\n', None,
Jan 15 11:39:42 debian-gta02 2009.01.15 11:39:42 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: sending 10 bytes: 'AT+CRC=1\r\n'
Jan 15 11:39:42 debian-gta02 2009.01.15 11:39:42 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:39:42 debian-gta02 2009.01.15 11:39:42 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: COMPLETED 'AT+CRC=1' => ['OK']
Jan 15 11:39:42 debian-gta02 2009.01.15 11:39:42 ogsmd    DEBUG    <CallChannel via /dev/pts/2> queue is: deque([('AT+CRC=1\r\n', None, None, 3600), ('AT+CSCS="UCS2"\r\n', None, None, 3600), ('AT+CSDH=1\r\n', None, None, 3600), ('AT+CSNS=0\r\n', None, None, 3600), ('AT+CMGF=0\r\n', None, None, 3600), ('AT+CLIP=0\r\n', None, None, 3600), ('AT+COLP=0\r\n', None, None, 3600), ('AT+CCWA=0\r\n', None, None, 3600)])
Jan 15 11:39:42 debian-gta02 2009.01.15 11:39:42 ogsmd    DEBUG    <CallChannel via /dev/pts/2>: sending 10 bytes: 'AT+CRC=1\r\n'
Jan 15 11:39:42 debian-gta02 2009.01.15 11:39:42 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:39:42 debian-gta02 2009.01.15 11:39:42 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: COMPLETED 'AT+CRC=1' => ['OK']
Jan 15 11:39:42 debian-gta02 2009.01.15 11:39:42 ogsmd    DEBUG    <MiscChannel via /dev/pts/0> queue is: deque([('AT+CSCS="UCS2"\r\n', None, None, 10), ('AT+CSDH=1\r\n', None, None, 10), ('AT+CSNS=0\r\n', None, None, 10), ('AT+CMGF=0\r\n', None, None, 10), ('AT+CLIP=0\r\n', None, None, 10), ('AT+COLP=0\r\n', None, None, 10), ('AT+CCWA=0\r\n', None, None, 10), ('AT+CPIN?\r\n', <bound method DeviceSetAntennaPower.intermediateResponse of <ogsmd.modems.abstract.mediator.DeviceSetAntennaPower object at 0x6c8310>>, <bound method DeviceSetAntennaPower.errorFromChannel of <ogsmd.modems.abstract.mediator.DeviceSetAntennaPower object at 0x6c8310>>, 15)])
Jan 15 11:39:42 debian-gta02 2009.01.15 11:39:42 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: sending 16 bytes: 'AT+CSCS="UCS2"\r\n'
Jan 15 11:39:42 debian-gta02 2009.01.15 11:39:42 ogsmd    DEBUG    <CallChannel via /dev/pts/2>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:39:42 debian-gta02 2009.01.15 11:39:42 ogsmd    DEBUG    <CallChannel via /dev/pts/2>: COMPLETED 'AT+CRC=1' => ['OK']
Jan 15 11:39:42 debian-gta02 2009.01.15 11:39:42 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1> queue is: deque([('AT+CSCS="UCS2"\r\n', None, None, 10), ('AT+CSDH=1\r\n', None, None, 10), ('AT+CSNS=0\r\n', None, None, 10), ('AT+CMGF=0\r\n', None, None, 10), ('AT+CLIP=0\r\n', None, None, 10), ('AT+COLP=0\r\n', None, None, 10), ('AT+CCWA=0\r\n', None, None, 10), ('AT+CLIP=1\r\n', None, None, 10), ('AT+COLP=0\r\n', None, None, 10), ('AT+CCWA=1\r\n', None, None, 10), ('AT+CSSN=1,1\r\n', None, None, 10), ('AT+CTZU=1\r\n', None, None, 10), ('AT+CTZR=1\r\n', None, None, 10), ('AT+CREG=2\r\n', None, None, 10), ('AT+CAOC=2\r\n', None, None, 10), ('AT+CGEREP=2,1\r\n', None, None, 10), ('AT+CGREG=2\r\n', None, None, 10), ('AT%CPI=3\r\n', None, None, 10), ('AT%CSCN=1,2,1,2\r\n', None, None, 10), ('AT%CSQ=1\r\n', None, None, 10), ('AT%CNIV=1\r\n', None, None, 10), ('AT%CGEREP=1\r\n', None, None, 10), ('AT%CGREG=3\r\n', None, None, 10), ('AT%CSTAT=1\r\n', None, None, 10), ('AT@ST="-26"\r\n', None, None, 10), ('AT%N028B\r\n', None,
Jan 15 11:39:42 debian-gta02 2009.01.15 11:39:42 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: sending 16 bytes: 'AT+CSCS="UCS2"\r\n'
Jan 15 11:39:42 debian-gta02 2009.01.15 11:39:42 oeventsd INFO     signal SetBrightness responded : ()
Jan 15 11:39:43 debian-gta02 2009.01.15 11:39:43 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:39:43 debian-gta02 2009.01.15 11:39:43 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: COMPLETED 'AT+CSCS="UCS2"' => ['OK']
Jan 15 11:39:43 debian-gta02 2009.01.15 11:39:43 ogsmd    DEBUG    <CallChannel via /dev/pts/2> queue is: deque([('AT+CSCS="UCS2"\r\n', None, None, 3600), ('AT+CSDH=1\r\n', None, None, 3600), ('AT+CSNS=0\r\n', None, None, 3600), ('AT+CMGF=0\r\n', None, None, 3600), ('AT+CLIP=0\r\n', None, None, 3600), ('AT+COLP=0\r\n', None, None, 3600), ('AT+CCWA=0\r\n', None, None, 3600)])
Jan 15 11:39:43 debian-gta02 2009.01.15 11:39:43 ogsmd    DEBUG    <CallChannel via /dev/pts/2>: sending 16 bytes: 'AT+CSCS="UCS2"\r\n'
Jan 15 11:39:43 debian-gta02 2009.01.15 11:39:43 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:39:43 debian-gta02 2009.01.15 11:39:43 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: COMPLETED 'AT+CSCS="UCS2"' => ['OK']
Jan 15 11:39:43 debian-gta02 2009.01.15 11:39:43 ogsmd    DEBUG    <MiscChannel via /dev/pts/0> queue is: deque([('AT+CSDH=1\r\n', None, None, 10), ('AT+CSNS=0\r\n', None, None, 10), ('AT+CMGF=0\r\n', None, None, 10), ('AT+CLIP=0\r\n', None, None, 10), ('AT+COLP=0\r\n', None, None, 10), ('AT+CCWA=0\r\n', None, None, 10), ('AT+CPIN?\r\n', <bound method DeviceSetAntennaPower.intermediateResponse of <ogsmd.modems.abstract.mediator.DeviceSetAntennaPower object at 0x6c8310>>, <bound method DeviceSetAntennaPower.errorFromChannel of <ogsmd.modems.abstract.mediator.DeviceSetAntennaPower object at 0x6c8310>>, 15)])
Jan 15 11:39:43 debian-gta02 2009.01.15 11:39:43 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: sending 11 bytes: 'AT+CSDH=1\r\n'
Jan 15 11:39:43 debian-gta02 2009.01.15 11:39:43 ogsmd    DEBUG    <CallChannel via /dev/pts/2>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:39:43 debian-gta02 2009.01.15 11:39:43 ogsmd    DEBUG    <CallChannel via /dev/pts/2>: COMPLETED 'AT+CSCS="UCS2"' => ['OK']
Jan 15 11:39:43 debian-gta02 2009.01.15 11:39:43 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1> queue is: deque([('AT+CSDH=1\r\n', None, None, 10), ('AT+CSNS=0\r\n', None, None, 10), ('AT+CMGF=0\r\n', None, None, 10), ('AT+CLIP=0\r\n', None, None, 10), ('AT+COLP=0\r\n', None, None, 10), ('AT+CCWA=0\r\n', None, None, 10), ('AT+CLIP=1\r\n', None, None, 10), ('AT+COLP=0\r\n', None, None, 10), ('AT+CCWA=1\r\n', None, None, 10), ('AT+CSSN=1,1\r\n', None, None, 10), ('AT+CTZU=1\r\n', None, None, 10), ('AT+CTZR=1\r\n', None, None, 10), ('AT+CREG=2\r\n', None, None, 10), ('AT+CAOC=2\r\n', None, None, 10), ('AT+CGEREP=2,1\r\n', None, None, 10), ('AT+CGREG=2\r\n', None, None, 10), ('AT%CPI=3\r\n', None, None, 10), ('AT%CSCN=1,2,1,2\r\n', None, None, 10), ('AT%CSQ=1\r\n', None, None, 10), ('AT%CNIV=1\r\n', None, None, 10), ('AT%CGEREP=1\r\n', None, None, 10), ('AT%CGREG=3\r\n', None, None, 10), ('AT%CSTAT=1\r\n', None, None, 10), ('AT@ST="-26"\r\n', None, None, 10), ('AT%N028B\r\n', None, None, 10), ('AT%N0125\r\n', None, None,
Jan 15 11:39:43 debian-gta02 2009.01.15 11:39:43 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: sending 11 bytes: 'AT+CSDH=1\r\n'
Jan 15 11:39:43 debian-gta02 2009.01.15 11:39:43 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:39:43 debian-gta02 2009.01.15 11:39:43 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: COMPLETED 'AT+CSDH=1' => ['OK']
Jan 15 11:39:43 debian-gta02 2009.01.15 11:39:43 ogsmd    DEBUG    <CallChannel via /dev/pts/2> queue is: deque([('AT+CSDH=1\r\n', None, None, 3600), ('AT+CSNS=0\r\n', None, None, 3600), ('AT+CMGF=0\r\n', None, None, 3600), ('AT+CLIP=0\r\n', None, None, 3600), ('AT+COLP=0\r\n', None, None, 3600), ('AT+CCWA=0\r\n', None, None, 3600)])
Jan 15 11:39:43 debian-gta02 2009.01.15 11:39:43 ogsmd    DEBUG    <CallChannel via /dev/pts/2>: sending 11 bytes: 'AT+CSDH=1\r\n'
Jan 15 11:39:43 debian-gta02 2009.01.15 11:39:43 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:39:43 debian-gta02 2009.01.15 11:39:43 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: COMPLETED 'AT+CSDH=1' => ['OK']
Jan 15 11:39:43 debian-gta02 2009.01.15 11:39:43 ogsmd    DEBUG    <MiscChannel via /dev/pts/0> queue is: deque([('AT+CSNS=0\r\n', None, None, 10), ('AT+CMGF=0\r\n', None, None, 10), ('AT+CLIP=0\r\n', None, None, 10), ('AT+COLP=0\r\n', None, None, 10), ('AT+CCWA=0\r\n', None, None, 10), ('AT+CPIN?\r\n', <bound method DeviceSetAntennaPower.intermediateResponse of <ogsmd.modems.abstract.mediator.DeviceSetAntennaPower object at 0x6c8310>>, <bound method DeviceSetAntennaPower.errorFromChannel of <ogsmd.modems.abstract.mediator.DeviceSetAntennaPower object at 0x6c8310>>, 15)])
Jan 15 11:39:43 debian-gta02 2009.01.15 11:39:43 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: sending 11 bytes: 'AT+CSNS=0\r\n'
Jan 15 11:39:43 debian-gta02 2009.01.15 11:39:43 ogsmd    DEBUG    <CallChannel via /dev/pts/2>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:39:43 debian-gta02 2009.01.15 11:39:43 ogsmd    DEBUG    <CallChannel via /dev/pts/2>: COMPLETED 'AT+CSDH=1' => ['OK']
Jan 15 11:39:43 debian-gta02 2009.01.15 11:39:43 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1> queue is: deque([('AT+CSNS=0\r\n', None, None, 10), ('AT+CMGF=0\r\n', None, None, 10), ('AT+CLIP=0\r\n', None, None, 10), ('AT+COLP=0\r\n', None, None, 10), ('AT+CCWA=0\r\n', None, None, 10), ('AT+CLIP=1\r\n', None, None, 10), ('AT+COLP=0\r\n', None, None, 10), ('AT+CCWA=1\r\n', None, None, 10), ('AT+CSSN=1,1\r\n', None, None, 10), ('AT+CTZU=1\r\n', None, None, 10), ('AT+CTZR=1\r\n', None, None, 10), ('AT+CREG=2\r\n', None, None, 10), ('AT+CAOC=2\r\n', None, None, 10), ('AT+CGEREP=2,1\r\n', None, None, 10), ('AT+CGREG=2\r\n', None, None, 10), ('AT%CPI=3\r\n', None, None, 10), ('AT%CSCN=1,2,1,2\r\n', None, None, 10), ('AT%CSQ=1\r\n', None, None, 10), ('AT%CNIV=1\r\n', None, None, 10), ('AT%CGEREP=1\r\n', None, None, 10), ('AT%CGREG=3\r\n', None, None, 10), ('AT%CSTAT=1\r\n', None, None, 10), ('AT@ST="-26"\r\n', None, None, 10), ('AT%N028B\r\n', None, None, 10), ('AT%N0125\r\n', None, None, 10), ('AT%SLEEP=2\r\n', None, None
Jan 15 11:39:43 debian-gta02 2009.01.15 11:39:43 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: sending 11 bytes: 'AT+CSNS=0\r\n'
Jan 15 11:39:43 debian-gta02 2009.01.15 11:39:43 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:39:43 debian-gta02 2009.01.15 11:39:43 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: COMPLETED 'AT+CSNS=0' => ['OK']
Jan 15 11:39:43 debian-gta02 2009.01.15 11:39:43 ogsmd    DEBUG    <CallChannel via /dev/pts/2> queue is: deque([('AT+CSNS=0\r\n', None, None, 3600), ('AT+CMGF=0\r\n', None, None, 3600), ('AT+CLIP=0\r\n', None, None, 3600), ('AT+COLP=0\r\n', None, None, 3600), ('AT+CCWA=0\r\n', None, None, 3600)])
Jan 15 11:39:43 debian-gta02 2009.01.15 11:39:43 ogsmd    DEBUG    <CallChannel via /dev/pts/2>: sending 11 bytes: 'AT+CSNS=0\r\n'
Jan 15 11:39:43 debian-gta02 2009.01.15 11:39:43 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:39:43 debian-gta02 2009.01.15 11:39:43 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: COMPLETED 'AT+CSNS=0' => ['OK']
Jan 15 11:39:43 debian-gta02 2009.01.15 11:39:43 ogsmd    DEBUG    <MiscChannel via /dev/pts/0> queue is: deque([('AT+CMGF=0\r\n', None, None, 10), ('AT+CLIP=0\r\n', None, None, 10), ('AT+COLP=0\r\n', None, None, 10), ('AT+CCWA=0\r\n', None, None, 10), ('AT+CPIN?\r\n', <bound method DeviceSetAntennaPower.intermediateResponse of <ogsmd.modems.abstract.mediator.DeviceSetAntennaPower object at 0x6c8310>>, <bound method DeviceSetAntennaPower.errorFromChannel of <ogsmd.modems.abstract.mediator.DeviceSetAntennaPower object at 0x6c8310>>, 15)])
Jan 15 11:39:43 debian-gta02 2009.01.15 11:39:43 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: sending 11 bytes: 'AT+CMGF=0\r\n'
Jan 15 11:39:43 debian-gta02 2009.01.15 11:39:43 ogsmd    DEBUG    <CallChannel via /dev/pts/2>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:39:43 debian-gta02 2009.01.15 11:39:43 ogsmd    DEBUG    <CallChannel via /dev/pts/2>: COMPLETED 'AT+CSNS=0' => ['OK']
Jan 15 11:39:43 debian-gta02 2009.01.15 11:39:43 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1> queue is: deque([('AT+CMGF=0\r\n', None, None, 10), ('AT+CLIP=0\r\n', None, None, 10), ('AT+COLP=0\r\n', None, None, 10), ('AT+CCWA=0\r\n', None, None, 10), ('AT+CLIP=1\r\n', None, None, 10), ('AT+COLP=0\r\n', None, None, 10), ('AT+CCWA=1\r\n', None, None, 10), ('AT+CSSN=1,1\r\n', None, None, 10), ('AT+CTZU=1\r\n', None, None, 10), ('AT+CTZR=1\r\n', None, None, 10), ('AT+CREG=2\r\n', None, None, 10), ('AT+CAOC=2\r\n', None, None, 10), ('AT+CGEREP=2,1\r\n', None, None, 10), ('AT+CGREG=2\r\n', None, None, 10), ('AT%CPI=3\r\n', None, None, 10), ('AT%CSCN=1,2,1,2\r\n', None, None, 10), ('AT%CSQ=1\r\n', None, None, 10), ('AT%CNIV=1\r\n', None, None, 10), ('AT%CGEREP=1\r\n', None, None, 10), ('AT%CGREG=3\r\n', None, None, 10), ('AT%CSTAT=1\r\n', None, None, 10), ('AT@ST="-26"\r\n', None, None, 10), ('AT%N028B\r\n', None, None, 10), ('AT%N0125\r\n', None, None, 10), ('AT%SLEEP=2\r\n', None, None, 10)])
Jan 15 11:39:43 debian-gta02 2009.01.15 11:39:43 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: sending 11 bytes: 'AT+CMGF=0\r\n'
Jan 15 11:39:43 debian-gta02 2009.01.15 11:39:43 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:39:43 debian-gta02 2009.01.15 11:39:43 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: COMPLETED 'AT+CMGF=0' => ['OK']
Jan 15 11:39:43 debian-gta02 2009.01.15 11:39:43 ogsmd    DEBUG    <CallChannel via /dev/pts/2> queue is: deque([('AT+CMGF=0\r\n', None, None, 3600), ('AT+CLIP=0\r\n', None, None, 3600), ('AT+COLP=0\r\n', None, None, 3600), ('AT+CCWA=0\r\n', None, None, 3600)])
Jan 15 11:39:43 debian-gta02 2009.01.15 11:39:43 ogsmd    DEBUG    <CallChannel via /dev/pts/2>: sending 11 bytes: 'AT+CMGF=0\r\n'
Jan 15 11:39:43 debian-gta02 2009.01.15 11:39:43 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:39:43 debian-gta02 2009.01.15 11:39:43 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: COMPLETED 'AT+CMGF=0' => ['OK']
Jan 15 11:39:43 debian-gta02 2009.01.15 11:39:43 ogsmd    DEBUG    <MiscChannel via /dev/pts/0> queue is: deque([('AT+CLIP=0\r\n', None, None, 10), ('AT+COLP=0\r\n', None, None, 10), ('AT+CCWA=0\r\n', None, None, 10), ('AT+CPIN?\r\n', <bound method DeviceSetAntennaPower.intermediateResponse of <ogsmd.modems.abstract.mediator.DeviceSetAntennaPower object at 0x6c8310>>, <bound method DeviceSetAntennaPower.errorFromChannel of <ogsmd.modems.abstract.mediator.DeviceSetAntennaPower object at 0x6c8310>>, 15)])
Jan 15 11:39:44 debian-gta02 2009.01.15 11:39:44 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: sending 11 bytes: 'AT+CLIP=0\r\n'
Jan 15 11:39:44 debian-gta02 2009.01.15 11:39:44 ogsmd    DEBUG    <CallChannel via /dev/pts/2>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:39:44 debian-gta02 2009.01.15 11:39:44 ogsmd    DEBUG    <CallChannel via /dev/pts/2>: COMPLETED 'AT+CMGF=0' => ['OK']
Jan 15 11:39:44 debian-gta02 2009.01.15 11:39:44 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1> queue is: deque([('AT+CLIP=0\r\n', None, None, 10), ('AT+COLP=0\r\n', None, None, 10), ('AT+CCWA=0\r\n', None, None, 10), ('AT+CLIP=1\r\n', None, None, 10), ('AT+COLP=0\r\n', None, None, 10), ('AT+CCWA=1\r\n', None, None, 10), ('AT+CSSN=1,1\r\n', None, None, 10), ('AT+CTZU=1\r\n', None, None, 10), ('AT+CTZR=1\r\n', None, None, 10), ('AT+CREG=2\r\n', None, None, 10), ('AT+CAOC=2\r\n', None, None, 10), ('AT+CGEREP=2,1\r\n', None, None, 10), ('AT+CGREG=2\r\n', None, None, 10), ('AT%CPI=3\r\n', None, None, 10), ('AT%CSCN=1,2,1,2\r\n', None, None, 10), ('AT%CSQ=1\r\n', None, None, 10), ('AT%CNIV=1\r\n', None, None, 10), ('AT%CGEREP=1\r\n', None, None, 10), ('AT%CGREG=3\r\n', None, None, 10), ('AT%CSTAT=1\r\n', None, None, 10), ('AT@ST="-26"\r\n', None, None, 10), ('AT%N028B\r\n', None, None, 10), ('AT%N0125\r\n', None, None, 10), ('AT%SLEEP=2\r\n', None, None, 10)])
Jan 15 11:39:44 debian-gta02 2009.01.15 11:39:44 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: sending 11 bytes: 'AT+CLIP=0\r\n'
Jan 15 11:39:44 debian-gta02 2009.01.15 11:39:44 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:39:44 debian-gta02 2009.01.15 11:39:44 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: COMPLETED 'AT+CLIP=0' => ['OK']
Jan 15 11:39:44 debian-gta02 2009.01.15 11:39:44 ogsmd    DEBUG    <CallChannel via /dev/pts/2> queue is: deque([('AT+CLIP=0\r\n', None, None, 3600), ('AT+COLP=0\r\n', None, None, 3600), ('AT+CCWA=0\r\n', None, None, 3600)])
Jan 15 11:39:44 debian-gta02 2009.01.15 11:39:44 ogsmd    DEBUG    <CallChannel via /dev/pts/2>: sending 11 bytes: 'AT+CLIP=0\r\n'
Jan 15 11:39:44 debian-gta02 2009.01.15 11:39:44 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:39:44 debian-gta02 2009.01.15 11:39:44 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: COMPLETED 'AT+CLIP=0' => ['OK']
Jan 15 11:39:44 debian-gta02 2009.01.15 11:39:44 ogsmd    DEBUG    <MiscChannel via /dev/pts/0> queue is: deque([('AT+COLP=0\r\n', None, None, 10), ('AT+CCWA=0\r\n', None, None, 10), ('AT+CPIN?\r\n', <bound method DeviceSetAntennaPower.intermediateResponse of <ogsmd.modems.abstract.mediator.DeviceSetAntennaPower object at 0x6c8310>>, <bound method DeviceSetAntennaPower.errorFromChannel of <ogsmd.modems.abstract.mediator.DeviceSetAntennaPower object at 0x6c8310>>, 15)])
Jan 15 11:39:44 debian-gta02 2009.01.15 11:39:44 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: sending 11 bytes: 'AT+COLP=0\r\n'
Jan 15 11:39:44 debian-gta02 2009.01.15 11:39:44 ogsmd    DEBUG    <CallChannel via /dev/pts/2>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:39:44 debian-gta02 2009.01.15 11:39:44 ogsmd    DEBUG    <CallChannel via /dev/pts/2>: COMPLETED 'AT+CLIP=0' => ['OK']
Jan 15 11:39:44 debian-gta02 2009.01.15 11:39:44 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1> queue is: deque([('AT+COLP=0\r\n', None, None, 10), ('AT+CCWA=0\r\n', None, None, 10), ('AT+CLIP=1\r\n', None, None, 10), ('AT+COLP=0\r\n', None, None, 10), ('AT+CCWA=1\r\n', None, None, 10), ('AT+CSSN=1,1\r\n', None, None, 10), ('AT+CTZU=1\r\n', None, None, 10), ('AT+CTZR=1\r\n', None, None, 10), ('AT+CREG=2\r\n', None, None, 10), ('AT+CAOC=2\r\n', None, None, 10), ('AT+CGEREP=2,1\r\n', None, None, 10), ('AT+CGREG=2\r\n', None, None, 10), ('AT%CPI=3\r\n', None, None, 10), ('AT%CSCN=1,2,1,2\r\n', None, None, 10), ('AT%CSQ=1\r\n', None, None, 10), ('AT%CNIV=1\r\n', None, None, 10), ('AT%CGEREP=1\r\n', None, None, 10), ('AT%CGREG=3\r\n', None, None, 10), ('AT%CSTAT=1\r\n', None, None, 10), ('AT@ST="-26"\r\n', None, None, 10), ('AT%N028B\r\n', None, None, 10), ('AT%N0125\r\n', None, None, 10), ('AT%SLEEP=2\r\n', None, None, 10)])
Jan 15 11:39:44 debian-gta02 2009.01.15 11:39:44 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: sending 11 bytes: 'AT+COLP=0\r\n'
Jan 15 11:39:44 debian-gta02 2009.01.15 11:39:44 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:39:44 debian-gta02 2009.01.15 11:39:44 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: COMPLETED 'AT+COLP=0' => ['OK']
Jan 15 11:39:44 debian-gta02 2009.01.15 11:39:44 ogsmd    DEBUG    <CallChannel via /dev/pts/2> queue is: deque([('AT+COLP=0\r\n', None, None, 3600), ('AT+CCWA=0\r\n', None, None, 3600)])
Jan 15 11:39:44 debian-gta02 2009.01.15 11:39:44 ogsmd    DEBUG    <CallChannel via /dev/pts/2>: sending 11 bytes: 'AT+COLP=0\r\n'
Jan 15 11:39:44 debian-gta02 2009.01.15 11:39:44 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:39:44 debian-gta02 2009.01.15 11:39:44 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: COMPLETED 'AT+COLP=0' => ['OK']
Jan 15 11:39:44 debian-gta02 2009.01.15 11:39:44 ogsmd    DEBUG    <MiscChannel via /dev/pts/0> queue is: deque([('AT+CCWA=0\r\n', None, None, 10), ('AT+CPIN?\r\n', <bound method DeviceSetAntennaPower.intermediateResponse of <ogsmd.modems.abstract.mediator.DeviceSetAntennaPower object at 0x6c8310>>, <bound method DeviceSetAntennaPower.errorFromChannel of <ogsmd.modems.abstract.mediator.DeviceSetAntennaPower object at 0x6c8310>>, 15)])
Jan 15 11:39:44 debian-gta02 2009.01.15 11:39:44 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: sending 11 bytes: 'AT+CCWA=0\r\n'
Jan 15 11:39:44 debian-gta02 2009.01.15 11:39:44 ogsmd    DEBUG    <CallChannel via /dev/pts/2>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:39:44 debian-gta02 2009.01.15 11:39:44 ogsmd    DEBUG    <CallChannel via /dev/pts/2>: COMPLETED 'AT+COLP=0' => ['OK']
Jan 15 11:39:44 debian-gta02 2009.01.15 11:39:44 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1> queue is: deque([('AT+CCWA=0\r\n', None, None, 10), ('AT+CLIP=1\r\n', None, None, 10), ('AT+COLP=0\r\n', None, None, 10), ('AT+CCWA=1\r\n', None, None, 10), ('AT+CSSN=1,1\r\n', None, None, 10), ('AT+CTZU=1\r\n', None, None, 10), ('AT+CTZR=1\r\n', None, None, 10), ('AT+CREG=2\r\n', None, None, 10), ('AT+CAOC=2\r\n', None, None, 10), ('AT+CGEREP=2,1\r\n', None, None, 10), ('AT+CGREG=2\r\n', None, None, 10), ('AT%CPI=3\r\n', None, None, 10), ('AT%CSCN=1,2,1,2\r\n', None, None, 10), ('AT%CSQ=1\r\n', None, None, 10), ('AT%CNIV=1\r\n', None, None, 10), ('AT%CGEREP=1\r\n', None, None, 10), ('AT%CGREG=3\r\n', None, None, 10), ('AT%CSTAT=1\r\n', None, None, 10), ('AT@ST="-26"\r\n', None, None, 10), ('AT%N028B\r\n', None, None, 10), ('AT%N0125\r\n', None, None, 10), ('AT%SLEEP=2\r\n', None, None, 10)])
Jan 15 11:39:44 debian-gta02 2009.01.15 11:39:44 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: sending 11 bytes: 'AT+CCWA=0\r\n'
Jan 15 11:39:44 debian-gta02 2009.01.15 11:39:44 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:39:44 debian-gta02 2009.01.15 11:39:44 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: COMPLETED 'AT+CCWA=0' => ['OK']
Jan 15 11:39:44 debian-gta02 2009.01.15 11:39:44 ogsmd    DEBUG    <CallChannel via /dev/pts/2> queue is: deque([('AT+CCWA=0\r\n', None, None, 3600)])
Jan 15 11:39:44 debian-gta02 2009.01.15 11:39:44 ogsmd    DEBUG    <CallChannel via /dev/pts/2>: sending 11 bytes: 'AT+CCWA=0\r\n'
Jan 15 11:39:44 debian-gta02 2009.01.15 11:39:44 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:39:44 debian-gta02 2009.01.15 11:39:44 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: COMPLETED 'AT+CCWA=0' => ['OK']
Jan 15 11:39:44 debian-gta02 2009.01.15 11:39:44 ogsmd    DEBUG    <MiscChannel via /dev/pts/0> queue is: deque([('AT+CPIN?\r\n', <bound method DeviceSetAntennaPower.intermediateResponse of <ogsmd.modems.abstract.mediator.DeviceSetAntennaPower object at 0x6c8310>>, <bound method DeviceSetAntennaPower.errorFromChannel of <ogsmd.modems.abstract.mediator.DeviceSetAntennaPower object at 0x6c8310>>, 15)])
Jan 15 11:39:44 debian-gta02 2009.01.15 11:39:44 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: sending 10 bytes: 'AT+CPIN?\r\n'
Jan 15 11:39:44 debian-gta02 2009.01.15 11:39:44 ogsmd    DEBUG    <CallChannel via /dev/pts/2>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:39:44 debian-gta02 2009.01.15 11:39:44 ogsmd    DEBUG    <CallChannel via /dev/pts/2>: COMPLETED 'AT+CCWA=0' => ['OK']
Jan 15 11:39:44 debian-gta02 2009.01.15 11:39:44 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1> queue is: deque([('AT+CLIP=1\r\n', None, None, 10), ('AT+COLP=0\r\n', None, None, 10), ('AT+CCWA=1\r\n', None, None, 10), ('AT+CSSN=1,1\r\n', None, None, 10), ('AT+CTZU=1\r\n', None, None, 10), ('AT+CTZR=1\r\n', None, None, 10), ('AT+CREG=2\r\n', None, None, 10), ('AT+CAOC=2\r\n', None, None, 10), ('AT+CGEREP=2,1\r\n', None, None, 10), ('AT+CGREG=2\r\n', None, None, 10), ('AT%CPI=3\r\n', None, None, 10), ('AT%CSCN=1,2,1,2\r\n', None, None, 10), ('AT%CSQ=1\r\n', None, None, 10), ('AT%CNIV=1\r\n', None, None, 10), ('AT%CGEREP=1\r\n', None, None, 10), ('AT%CGREG=3\r\n', None, None, 10), ('AT%CSTAT=1\r\n', None, None, 10), ('AT@ST="-26"\r\n', None, None, 10), ('AT%N028B\r\n', None, None, 10), ('AT%N0125\r\n', None, None, 10), ('AT%SLEEP=2\r\n', None, None, 10)])
Jan 15 11:39:44 debian-gta02 2009.01.15 11:39:44 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: sending 11 bytes: 'AT+CLIP=1\r\n'
Jan 15 11:39:44 debian-gta02 2009.01.15 11:39:44 ogsmd    DEBUG    <CallChannel via /dev/pts/2> queue is: deque([])
Jan 15 11:39:44 debian-gta02 2009.01.15 11:39:44 ogsmd    DEBUG    <CallChannel via /dev/pts/2>: nothing in request queue
Jan 15 11:39:44 debian-gta02 2009.01.15 11:39:44 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:39:44 debian-gta02 2009.01.15 11:39:44 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: COMPLETED 'AT+CLIP=1' => ['OK']
Jan 15 11:39:45 debian-gta02 2009.01.15 11:39:44 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1> queue is: deque([('AT+COLP=0\r\n', None, None, 10), ('AT+CCWA=1\r\n', None, None, 10), ('AT+CSSN=1,1\r\n', None, None, 10), ('AT+CTZU=1\r\n', None, None, 10), ('AT+CTZR=1\r\n', None, None, 10), ('AT+CREG=2\r\n', None, None, 10), ('AT+CAOC=2\r\n', None, None, 10), ('AT+CGEREP=2,1\r\n', None, None, 10), ('AT+CGREG=2\r\n', None, None, 10), ('AT%CPI=3\r\n', None, None, 10), ('AT%CSCN=1,2,1,2\r\n', None, None, 10), ('AT%CSQ=1\r\n', None, None, 10), ('AT%CNIV=1\r\n', None, None, 10), ('AT%CGEREP=1\r\n', None, None, 10), ('AT%CGREG=3\r\n', None, None, 10), ('AT%CSTAT=1\r\n', None, None, 10), ('AT@ST="-26"\r\n', None, None, 10), ('AT%N028B\r\n', None, None, 10), ('AT%N0125\r\n', None, None, 10), ('AT%SLEEP=2\r\n', None, None, 10)])
Jan 15 11:39:45 debian-gta02 2009.01.15 11:39:45 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: sending 11 bytes: 'AT+COLP=0\r\n'
Jan 15 11:39:45 debian-gta02 2009.01.15 11:39:45 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:39:45 debian-gta02 2009.01.15 11:39:45 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: COMPLETED 'AT+COLP=0' => ['OK']
Jan 15 11:39:45 debian-gta02 2009.01.15 11:39:45 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1> queue is: deque([('AT+CCWA=1\r\n', None, None, 10), ('AT+CSSN=1,1\r\n', None, None, 10), ('AT+CTZU=1\r\n', None, None, 10), ('AT+CTZR=1\r\n', None, None, 10), ('AT+CREG=2\r\n', None, None, 10), ('AT+CAOC=2\r\n', None, None, 10), ('AT+CGEREP=2,1\r\n', None, None, 10), ('AT+CGREG=2\r\n', None, None, 10), ('AT%CPI=3\r\n', None, None, 10), ('AT%CSCN=1,2,1,2\r\n', None, None, 10), ('AT%CSQ=1\r\n', None, None, 10), ('AT%CNIV=1\r\n', None, None, 10), ('AT%CGEREP=1\r\n', None, None, 10), ('AT%CGREG=3\r\n', None, None, 10), ('AT%CSTAT=1\r\n', None, None, 10), ('AT@ST="-26"\r\n', None, None, 10), ('AT%N028B\r\n', None, None, 10), ('AT%N0125\r\n', None, None, 10), ('AT%SLEEP=2\r\n', None, None, 10)])
Jan 15 11:39:45 debian-gta02 2009.01.15 11:39:45 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: sending 11 bytes: 'AT+CCWA=1\r\n'
Jan 15 11:39:45 debian-gta02 2009.01.15 11:39:45 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:39:45 debian-gta02 2009.01.15 11:39:45 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: COMPLETED 'AT+CCWA=1' => ['OK']
Jan 15 11:39:45 debian-gta02 2009.01.15 11:39:45 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1> queue is: deque([('AT+CSSN=1,1\r\n', None, None, 10), ('AT+CTZU=1\r\n', None, None, 10), ('AT+CTZR=1\r\n', None, None, 10), ('AT+CREG=2\r\n', None, None, 10), ('AT+CAOC=2\r\n', None, None, 10), ('AT+CGEREP=2,1\r\n', None, None, 10), ('AT+CGREG=2\r\n', None, None, 10), ('AT%CPI=3\r\n', None, None, 10), ('AT%CSCN=1,2,1,2\r\n', None, None, 10), ('AT%CSQ=1\r\n', None, None, 10), ('AT%CNIV=1\r\n', None, None, 10), ('AT%CGEREP=1\r\n', None, None, 10), ('AT%CGREG=3\r\n', None, None, 10), ('AT%CSTAT=1\r\n', None, None, 10), ('AT@ST="-26"\r\n', None, None, 10), ('AT%N028B\r\n', None, None, 10), ('AT%N0125\r\n', None, None, 10), ('AT%SLEEP=2\r\n', None, None, 10)])
Jan 15 11:39:45 debian-gta02 2009.01.15 11:39:45 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: sending 13 bytes: 'AT+CSSN=1,1\r\n'
Jan 15 11:39:45 debian-gta02 2009.01.15 11:39:45 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:39:45 debian-gta02 2009.01.15 11:39:45 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: COMPLETED 'AT+CSSN=1,1' => ['OK']
Jan 15 11:39:45 debian-gta02 2009.01.15 11:39:45 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1> queue is: deque([('AT+CTZU=1\r\n', None, None, 10), ('AT+CTZR=1\r\n', None, None, 10), ('AT+CREG=2\r\n', None, None, 10), ('AT+CAOC=2\r\n', None, None, 10), ('AT+CGEREP=2,1\r\n', None, None, 10), ('AT+CGREG=2\r\n', None, None, 10), ('AT%CPI=3\r\n', None, None, 10), ('AT%CSCN=1,2,1,2\r\n', None, None, 10), ('AT%CSQ=1\r\n', None, None, 10), ('AT%CNIV=1\r\n', None, None, 10), ('AT%CGEREP=1\r\n', None, None, 10), ('AT%CGREG=3\r\n', None, None, 10), ('AT%CSTAT=1\r\n', None, None, 10), ('AT@ST="-26"\r\n', None, None, 10), ('AT%N028B\r\n', None, None, 10), ('AT%N0125\r\n', None, None, 10), ('AT%SLEEP=2\r\n', None, None, 10)])
Jan 15 11:39:45 debian-gta02 2009.01.15 11:39:45 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: sending 11 bytes: 'AT+CTZU=1\r\n'
Jan 15 11:39:45 debian-gta02 2009.01.15 11:39:45 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:39:45 debian-gta02 2009.01.15 11:39:45 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: COMPLETED 'AT+CTZU=1' => ['OK']
Jan 15 11:39:45 debian-gta02 2009.01.15 11:39:45 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1> queue is: deque([('AT+CTZR=1\r\n', None, None, 10), ('AT+CREG=2\r\n', None, None, 10), ('AT+CAOC=2\r\n', None, None, 10), ('AT+CGEREP=2,1\r\n', None, None, 10), ('AT+CGREG=2\r\n', None, None, 10), ('AT%CPI=3\r\n', None, None, 10), ('AT%CSCN=1,2,1,2\r\n', None, None, 10), ('AT%CSQ=1\r\n', None, None, 10), ('AT%CNIV=1\r\n', None, None, 10), ('AT%CGEREP=1\r\n', None, None, 10), ('AT%CGREG=3\r\n', None, None, 10), ('AT%CSTAT=1\r\n', None, None, 10), ('AT@ST="-26"\r\n', None, None, 10), ('AT%N028B\r\n', None, None, 10), ('AT%N0125\r\n', None, None, 10), ('AT%SLEEP=2\r\n', None, None, 10)])
Jan 15 11:39:45 debian-gta02 2009.01.15 11:39:45 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: sending 11 bytes: 'AT+CTZR=1\r\n'
Jan 15 11:39:45 debian-gta02 2009.01.15 11:39:45 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:39:45 debian-gta02 2009.01.15 11:39:45 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: COMPLETED 'AT+CTZR=1' => ['OK']
Jan 15 11:39:45 debian-gta02 2009.01.15 11:39:45 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1> queue is: deque([('AT+CREG=2\r\n', None, None, 10), ('AT+CAOC=2\r\n', None, None, 10), ('AT+CGEREP=2,1\r\n', None, None, 10), ('AT+CGREG=2\r\n', None, None, 10), ('AT%CPI=3\r\n', None, None, 10), ('AT%CSCN=1,2,1,2\r\n', None, None, 10), ('AT%CSQ=1\r\n', None, None, 10), ('AT%CNIV=1\r\n', None, None, 10), ('AT%CGEREP=1\r\n', None, None, 10), ('AT%CGREG=3\r\n', None, None, 10), ('AT%CSTAT=1\r\n', None, None, 10), ('AT@ST="-26"\r\n', None, None, 10), ('AT%N028B\r\n', None, None, 10), ('AT%N0125\r\n', None, None, 10), ('AT%SLEEP=2\r\n', None, None, 10)])
Jan 15 11:39:45 debian-gta02 2009.01.15 11:39:45 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: sending 11 bytes: 'AT+CREG=2\r\n'
Jan 15 11:39:45 debian-gta02 2009.01.15 11:39:45 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:39:45 debian-gta02 2009.01.15 11:39:45 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: COMPLETED 'AT+CREG=2' => ['OK']
Jan 15 11:39:45 debian-gta02 2009.01.15 11:39:45 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1> queue is: deque([('AT+CAOC=2\r\n', None, None, 10), ('AT+CGEREP=2,1\r\n', None, None, 10), ('AT+CGREG=2\r\n', None, None, 10), ('AT%CPI=3\r\n', None, None, 10), ('AT%CSCN=1,2,1,2\r\n', None, None, 10), ('AT%CSQ=1\r\n', None, None, 10), ('AT%CNIV=1\r\n', None, None, 10), ('AT%CGEREP=1\r\n', None, None, 10), ('AT%CGREG=3\r\n', None, None, 10), ('AT%CSTAT=1\r\n', None, None, 10), ('AT@ST="-26"\r\n', None, None, 10), ('AT%N028B\r\n', None, None, 10), ('AT%N0125\r\n', None, None, 10), ('AT%SLEEP=2\r\n', None, None, 10)])
Jan 15 11:39:45 debian-gta02 2009.01.15 11:39:45 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: sending 11 bytes: 'AT+CAOC=2\r\n'
Jan 15 11:39:45 debian-gta02 2009.01.15 11:39:45 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:39:45 debian-gta02 2009.01.15 11:39:45 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: COMPLETED 'AT+CAOC=2' => ['OK']
Jan 15 11:39:45 debian-gta02 2009.01.15 11:39:45 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1> queue is: deque([('AT+CGEREP=2,1\r\n', None, None, 10), ('AT+CGREG=2\r\n', None, None, 10), ('AT%CPI=3\r\n', None, None, 10), ('AT%CSCN=1,2,1,2\r\n', None, None, 10), ('AT%CSQ=1\r\n', None, None, 10), ('AT%CNIV=1\r\n', None, None, 10), ('AT%CGEREP=1\r\n', None, None, 10), ('AT%CGREG=3\r\n', None, None, 10), ('AT%CSTAT=1\r\n', None, None, 10), ('AT@ST="-26"\r\n', None, None, 10), ('AT%N028B\r\n', None, None, 10), ('AT%N0125\r\n', None, None, 10), ('AT%SLEEP=2\r\n', None, None, 10)])
Jan 15 11:39:45 debian-gta02 2009.01.15 11:39:45 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: sending 15 bytes: 'AT+CGEREP=2,1\r\n'
Jan 15 11:39:45 debian-gta02 2009.01.15 11:39:45 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:39:45 debian-gta02 2009.01.15 11:39:45 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: COMPLETED 'AT+CGEREP=2,1' => ['OK']
Jan 15 11:39:45 debian-gta02 2009.01.15 11:39:45 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1> queue is: deque([('AT+CGREG=2\r\n', None, None, 10), ('AT%CPI=3\r\n', None, None, 10), ('AT%CSCN=1,2,1,2\r\n', None, None, 10), ('AT%CSQ=1\r\n', None, None, 10), ('AT%CNIV=1\r\n', None, None, 10), ('AT%CGEREP=1\r\n', None, None, 10), ('AT%CGREG=3\r\n', None, None, 10), ('AT%CSTAT=1\r\n', None, None, 10), ('AT@ST="-26"\r\n', None, None, 10), ('AT%N028B\r\n', None, None, 10), ('AT%N0125\r\n', None, None, 10), ('AT%SLEEP=2\r\n', None, None, 10)])
Jan 15 11:39:45 debian-gta02 2009.01.15 11:39:45 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: sending 12 bytes: 'AT+CGREG=2\r\n'
Jan 15 11:39:45 debian-gta02 2009.01.15 11:39:45 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:39:45 debian-gta02 2009.01.15 11:39:45 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: COMPLETED 'AT+CGREG=2' => ['OK']
Jan 15 11:39:45 debian-gta02 2009.01.15 11:39:45 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1> queue is: deque([('AT%CPI=3\r\n', None, None, 10), ('AT%CSCN=1,2,1,2\r\n', None, None, 10), ('AT%CSQ=1\r\n', None, None, 10), ('AT%CNIV=1\r\n', None, None, 10), ('AT%CGEREP=1\r\n', None, None, 10), ('AT%CGREG=3\r\n', None, None, 10), ('AT%CSTAT=1\r\n', None, None, 10), ('AT@ST="-26"\r\n', None, None, 10), ('AT%N028B\r\n', None, None, 10), ('AT%N0125\r\n', None, None, 10), ('AT%SLEEP=2\r\n', None, None, 10)])
Jan 15 11:39:45 debian-gta02 2009.01.15 11:39:45 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: sending 10 bytes: 'AT%CPI=3\r\n'
Jan 15 11:39:45 debian-gta02 2009.01.15 11:39:45 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:39:45 debian-gta02 2009.01.15 11:39:45 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: COMPLETED 'AT%CPI=3' => ['OK']
Jan 15 11:39:45 debian-gta02 2009.01.15 11:39:45 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1> queue is: deque([('AT%CSCN=1,2,1,2\r\n', None, None, 10), ('AT%CSQ=1\r\n', None, None, 10), ('AT%CNIV=1\r\n', None, None, 10), ('AT%CGEREP=1\r\n', None, None, 10), ('AT%CGREG=3\r\n', None, None, 10), ('AT%CSTAT=1\r\n', None, None, 10), ('AT@ST="-26"\r\n', None, None, 10), ('AT%N028B\r\n', None, None, 10), ('AT%N0125\r\n', None, None, 10), ('AT%SLEEP=2\r\n', None, None, 10)])
Jan 15 11:39:46 debian-gta02 2009.01.15 11:39:46 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: sending 17 bytes: 'AT%CSCN=1,2,1,2\r\n'
Jan 15 11:39:46 debian-gta02 2009.01.15 11:39:46 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:39:46 debian-gta02 2009.01.15 11:39:46 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: COMPLETED 'AT%CSCN=1,2,1,2' => ['OK']
Jan 15 11:39:46 debian-gta02 2009.01.15 11:39:46 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1> queue is: deque([('AT%CSQ=1\r\n', None, None, 10), ('AT%CNIV=1\r\n', None, None, 10), ('AT%CGEREP=1\r\n', None, None, 10), ('AT%CGREG=3\r\n', None, None, 10), ('AT%CSTAT=1\r\n', None, None, 10), ('AT@ST="-26"\r\n', None, None, 10), ('AT%N028B\r\n', None, None, 10), ('AT%N0125\r\n', None, None, 10), ('AT%SLEEP=2\r\n', None, None, 10)])
Jan 15 11:39:46 debian-gta02 2009.01.15 11:39:46 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: sending 10 bytes: 'AT%CSQ=1\r\n'
Jan 15 11:39:46 debian-gta02 2009.01.15 11:39:46 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: got 12 bytes from: '\r\n%CSQ: 1 \r\n'
Jan 15 11:39:46 debian-gta02 2009.01.15 11:39:46 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:39:46 debian-gta02 2009.01.15 11:39:46 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: COMPLETED 'AT%CSQ=1' => ['%CSQ: 1 ', 'OK']
Jan 15 11:39:46 debian-gta02 2009.01.15 11:39:46 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1> queue is: deque([('AT%CNIV=1\r\n', None, None, 10), ('AT%CGEREP=1\r\n', None, None, 10), ('AT%CGREG=3\r\n', None, None, 10), ('AT%CSTAT=1\r\n', None, None, 10), ('AT@ST="-26"\r\n', None, None, 10), ('AT%N028B\r\n', None, None, 10), ('AT%N0125\r\n', None, None, 10), ('AT%SLEEP=2\r\n', None, None, 10)])
Jan 15 11:39:46 debian-gta02 2009.01.15 11:39:46 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: sending 11 bytes: 'AT%CNIV=1\r\n'
Jan 15 11:39:46 debian-gta02 2009.01.15 11:39:46 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:39:46 debian-gta02 2009.01.15 11:39:46 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: COMPLETED 'AT%CNIV=1' => ['OK']
Jan 15 11:39:46 debian-gta02 2009.01.15 11:39:46 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1> queue is: deque([('AT%CGEREP=1\r\n', None, None, 10), ('AT%CGREG=3\r\n', None, None, 10), ('AT%CSTAT=1\r\n', None, None, 10), ('AT@ST="-26"\r\n', None, None, 10), ('AT%N028B\r\n', None, None, 10), ('AT%N0125\r\n', None, None, 10), ('AT%SLEEP=2\r\n', None, None, 10)])
Jan 15 11:39:46 debian-gta02 2009.01.15 11:39:46 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: sending 13 bytes: 'AT%CGEREP=1\r\n'
Jan 15 11:39:46 debian-gta02 2009.01.15 11:39:46 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:39:46 debian-gta02 2009.01.15 11:39:46 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: COMPLETED 'AT%CGEREP=1' => ['OK']
Jan 15 11:39:46 debian-gta02 2009.01.15 11:39:46 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1> queue is: deque([('AT%CGREG=3\r\n', None, None, 10), ('AT%CSTAT=1\r\n', None, None, 10), ('AT@ST="-26"\r\n', None, None, 10), ('AT%N028B\r\n', None, None, 10), ('AT%N0125\r\n', None, None, 10), ('AT%SLEEP=2\r\n', None, None, 10)])
Jan 15 11:39:46 debian-gta02 2009.01.15 11:39:46 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: sending 12 bytes: 'AT%CGREG=3\r\n'
Jan 15 11:39:46 debian-gta02 2009.01.15 11:39:46 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:39:46 debian-gta02 2009.01.15 11:39:46 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: COMPLETED 'AT%CGREG=3' => ['OK']
Jan 15 11:39:46 debian-gta02 2009.01.15 11:39:46 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1> queue is: deque([('AT%CSTAT=1\r\n', None, None, 10), ('AT@ST="-26"\r\n', None, None, 10), ('AT%N028B\r\n', None, None, 10), ('AT%N0125\r\n', None, None, 10), ('AT%SLEEP=2\r\n', None, None, 10)])
Jan 15 11:39:46 debian-gta02 2009.01.15 11:39:46 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: sending 12 bytes: 'AT%CSTAT=1\r\n'
Jan 15 11:39:46 debian-gta02 2009.01.15 11:39:46 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:39:46 debian-gta02 2009.01.15 11:39:46 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: COMPLETED 'AT%CSTAT=1' => ['OK']
Jan 15 11:39:46 debian-gta02 2009.01.15 11:39:46 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1> queue is: deque([('AT@ST="-26"\r\n', None, None, 10), ('AT%N028B\r\n', None, None, 10), ('AT%N0125\r\n', None, None, 10), ('AT%SLEEP=2\r\n', None, None, 10)])
Jan 15 11:39:46 debian-gta02 2009.01.15 11:39:46 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: sending 13 bytes: 'AT@ST="-26"\r\n'
Jan 15 11:39:46 debian-gta02 2009.01.15 11:39:46 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:39:46 debian-gta02 2009.01.15 11:39:46 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: COMPLETED 'AT@ST="-26"' => ['OK']
Jan 15 11:39:46 debian-gta02 2009.01.15 11:39:46 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1> queue is: deque([('AT%N028B\r\n', None, None, 10), ('AT%N0125\r\n', None, None, 10), ('AT%SLEEP=2\r\n', None, None, 10)])
Jan 15 11:39:46 debian-gta02 2009.01.15 11:39:46 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: sending 10 bytes: 'AT%N028B\r\n'
Jan 15 11:39:46 debian-gta02 2009.01.15 11:39:46 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 24 bytes from: '\r\n+CPIN: SIM PIN\r\n\r\nOK\r\n'
Jan 15 11:39:46 debian-gta02 2009.01.15 11:39:46 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: COMPLETED 'AT+CPIN?' => ['+CPIN: SIM PIN', 'OK']
Jan 15 11:39:46 debian-gta02 2009.01.15 11:39:46 ogsmd.objects INFO     auth status changed to SIM PIN
Jan 15 11:39:46 debian-gta02 2009.01.15 11:39:46 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: got 58 bytes from: '%CSTAT: PHB, 0\r\n\r\nEXT: I\r\nLong AEC -6 dB is active\r\n\r\nOK\r\n'
Jan 15 11:39:46 debian-gta02 2009.01.15 11:39:46 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: COMPLETED 'AT%N028B' => ['%CSTAT: PHB, 0', 'EXT: I', 'Long AEC -6 dB is active', 'OK']
Jan 15 11:39:46 debian-gta02 2009.01.15 11:39:46 ogsmd    DEBUG    <MiscChannel via /dev/pts/0> queue is: deque([('AT+CFUN=1\r\n', <bound method DeviceSetAntennaPower.responseFromChannel of <ogsmd.modems.abstract.mediator.DeviceSetAntennaPower object at 0x6c8310>>, <bound method DeviceSetAntennaPower.errorFromChannel of <ogsmd.modems.abstract.mediator.DeviceSetAntennaPower object at 0x6c8310>>, 10)])
Jan 15 11:39:46 debian-gta02 2009.01.15 11:39:46 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: sending 11 bytes: 'AT+CFUN=1\r\n'
Jan 15 11:39:46 debian-gta02 2009.01.15 11:39:46 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1> queue is: deque([('AT%N0125\r\n', None, None, 10), ('AT%SLEEP=2\r\n', None, None, 10)])
Jan 15 11:39:46 debian-gta02 2009.01.15 11:39:46 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: sending 10 bytes: 'AT%N0125\r\n'
Jan 15 11:39:46 debian-gta02 2009.01.15 11:39:46 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: got 10 bytes from: '\r\nEXT: I\r\n'
Jan 15 11:39:47 debian-gta02 2009.01.15 11:39:47 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: got 39 bytes from: 'Noise reduction -6 dB is active\r\n\r\nOK\r\n'
Jan 15 11:39:47 debian-gta02 2009.01.15 11:39:47 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: COMPLETED 'AT%N0125' => ['EXT: I', 'Noise reduction -6 dB is active', 'OK']
Jan 15 11:39:47 debian-gta02 2009.01.15 11:39:47 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1> queue is: deque([('AT%SLEEP=2\r\n', None, None, 10)])
Jan 15 11:39:47 debian-gta02 2009.01.15 11:39:47 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: sending 12 bytes: 'AT%SLEEP=2\r\n'
Jan 15 11:39:47 debian-gta02 2009.01.15 11:39:47 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: got 10 bytes from: '\r\nEXT: I\r\n'
Jan 15 11:39:47 debian-gta02 2009.01.15 11:39:47 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:39:47 debian-gta02 2009.01.15 11:39:47 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: COMPLETED 'AT%SLEEP=2' => ['EXT: I', 'OK']
Jan 15 11:39:47 debian-gta02 2009.01.15 11:39:47 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1> queue is: deque([])
Jan 15 11:39:47 debian-gta02 2009.01.15 11:39:47 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: nothing in request queue
Jan 15 11:39:48 debian-gta02 2009.01.15 11:39:48 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: got 16 bytes from: '%CSTAT: PHB, 0\r\n'
Jan 15 11:39:48 debian-gta02 2009.01.15 11:39:48 ogsmd    INFO     subsystem PHB readyness now False
Jan 15 11:39:48 debian-gta02 2009.01.15 11:39:48 ogsmd.objects INFO     sim ready status False
Jan 15 11:39:48 debian-gta02 2009.01.15 11:39:48 ogsmd    INFO     full readyness now False
Jan 15 11:39:48 debian-gta02 2009.01.15 11:39:48 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 18 bytes from: '\r\n+CME ERROR: 11\r\n'
Jan 15 11:39:48 debian-gta02 2009.01.15 11:39:48 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: COMPLETED 'AT+CFUN=1' => ['+CME ERROR: 11']
Jan 15 11:39:49 debian-gta02 2009.01.15 11:39:49 ogsmd    DEBUG    <MiscChannel via /dev/pts/0> queue is: deque([('AT+CPIN?\r\n', <bound method DeviceSetAntennaPower.intermediateResponse2 of <ogsmd.modems.abstract.mediator.DeviceSetAntennaPower object at 0x6c8310>>, <bound method DeviceSetAntennaPower.errorFromChannel of <ogsmd.modems.abstract.mediator.DeviceSetAntennaPower object at 0x6c8310>>, 15)])
Jan 15 11:39:49 debian-gta02 2009.01.15 11:39:49 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: sending 10 bytes: 'AT+CPIN?\r\n'
Jan 15 11:39:49 debian-gta02 2009.01.15 11:39:49 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 24 bytes from: '\r\n+CPIN: SIM PIN\r\n\r\nOK\r\n'
Jan 15 11:39:49 debian-gta02 2009.01.15 11:39:49 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: COMPLETED 'AT+CPIN?' => ['+CPIN: SIM PIN', 'OK']
Jan 15 11:39:49 debian-gta02 2009.01.15 11:39:49 ogsmd    DEBUG    <MiscChannel via /dev/pts/0> queue is: deque([('AT+CPIN?\r\n', <bound method SimGetAuthStatus.responseFromChannel of <ogsmd.modems.abstract.mediator.SimGetAuthStatus object at 0x810cf0>>, <bound method SimGetAuthStatus.errorFromChannel of <ogsmd.modems.abstract.mediator.SimGetAuthStatus object at 0x810cf0>>, 15)])
Jan 15 11:39:49 debian-gta02 2009.01.15 11:39:49 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: sending 10 bytes: 'AT+CPIN?\r\n'
Jan 15 11:39:49 debian-gta02 2009.01.15 11:39:49 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 24 bytes from: '\r\n+CPIN: SIM PIN\r\n\r\nOK\r\n'
Jan 15 11:39:49 debian-gta02 2009.01.15 11:39:49 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: COMPLETED 'AT+CPIN?' => ['+CPIN: SIM PIN', 'OK']
Jan 15 11:39:49 debian-gta02 2009.01.15 11:39:49 ogsmd    DEBUG    <MiscChannel via /dev/pts/0> queue is: deque([])
Jan 15 11:39:49 debian-gta02 2009.01.15 11:39:49 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: nothing in request queue
Jan 15 11:39:52 debian-gta02 2009.01.15 11:39:52 odeviced.idlenotifier INFO     odeviced.idlenotifier state change to idle
Jan 15 11:39:52 debian-gta02 2009.01.15 11:39:52 oeventsd INFO     DebugAction : dbus trigger test
Jan 15 11:39:52 debian-gta02 2009.01.15 11:39:52 oeventsd INFO     Receive IdleState, status = idle
Jan 15 11:39:52 debian-gta02 last message repeated 2 times
Jan 15 11:39:52 debian-gta02 2009.01.15 11:39:52 odeviced.idlenotifier INFO     odeviced.idlenotifier state change to busy
Jan 15 11:39:52 debian-gta02 2009.01.15 11:39:52 oeventsd INFO     DebugAction : dbus trigger test
Jan 15 11:39:52 debian-gta02 2009.01.15 11:39:52 oeventsd INFO     Receive IdleState, status = busy
Jan 15 11:39:52 debian-gta02 2009.01.15 11:39:52 oeventsd INFO     call dbus signal /org/freesmartphone/Device/Display/pcf50633_bl SetBrightness((90,))
Jan 15 11:39:52 debian-gta02 2009.01.15 11:39:52 oeventsd INFO     Receive IdleState, status = busy
Jan 15 11:39:52 debian-gta02 2009.01.15 11:39:52 oeventsd INFO     Receive IdleState, status = busy
Jan 15 11:39:53 debian-gta02 2009.01.15 11:39:52 oeventsd INFO     signal SetBrightness responded : ()
Jan 15 11:39:55 debian-gta02 2009.01.15 11:39:55 ogsmd    WARNING  <MiscChannel via /dev/pts/0>: Got unicode input. Trying to convert to plain string...
Jan 15 11:39:55 debian-gta02 2009.01.15 11:39:55 ogsmd    DEBUG    <MiscChannel via /dev/pts/0> queue is: deque([('AT+CPIN="2513"\r\n', <bound method SimSendAuthCode.responseFromChannel of <ogsmd.modems.abstract.mediator.SimSendAuthCode object at 0x7962d0>>, <bound method SimSendAuthCode.errorFromChannel of <ogsmd.modems.abstract.mediator.SimSendAuthCode object at 0x7962d0>>, 15)])
Jan 15 11:39:55 debian-gta02 2009.01.15 11:39:55 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: sending 16 bytes: 'AT+CPIN="2513"\r\n'
Jan 15 11:39:57 debian-gta02 2009.01.15 11:39:57 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: got 16 bytes from: '%CSTAT: PHB, 0\r\n'
Jan 15 11:39:57 debian-gta02 2009.01.15 11:39:57 ogsmd    INFO     subsystem PHB readyness now False
Jan 15 11:39:57 debian-gta02 2009.01.15 11:39:57 ogsmd    INFO     full readyness now False
Jan 15 11:39:57 debian-gta02 2009.01.15 11:39:57 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: got 16 bytes from: '%CSTAT: PHB, 0\r\n'
Jan 15 11:39:57 debian-gta02 2009.01.15 11:39:57 ogsmd    INFO     subsystem PHB readyness now False
Jan 15 11:39:57 debian-gta02 2009.01.15 11:39:57 ogsmd    INFO     full readyness now False
Jan 15 11:39:57 debian-gta02 2009.01.15 11:39:57 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: got 16 bytes from: '%CSTAT: PHB, 0\r\n'
Jan 15 11:39:57 debian-gta02 2009.01.15 11:39:57 ogsmd    INFO     subsystem PHB readyness now False
Jan 15 11:39:57 debian-gta02 2009.01.15 11:39:57 ogsmd    INFO     full readyness now False
Jan 15 11:39:57 debian-gta02 2009.01.15 11:39:57 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 22 bytes from: '\r\n+CPIN: READY\r\n\r\nOK\r\n'
Jan 15 11:39:57 debian-gta02 2009.01.15 11:39:57 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: COMPLETED 'AT+CPIN="2513"' => ['+CPIN: READY', 'OK']
Jan 15 11:39:57 debian-gta02 2009.01.15 11:39:57 ogsmd.objects INFO     auth status changed to READY
Jan 15 11:39:57 debian-gta02 2009.01.15 11:39:57 ogsmd    DEBUG    <MiscChannel via /dev/pts/0> queue is: deque([])
Jan 15 11:39:57 debian-gta02 2009.01.15 11:39:57 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: nothing in request queue
Jan 15 11:39:57 debian-gta02 2009.01.15 11:39:57 ogsmd    DEBUG    <MiscChannel via /dev/pts/0> queue is: deque([('AT+CPIN?\r\n', <bound method DeviceSetAntennaPower.intermediateResponse of <ogsmd.modems.abstract.mediator.DeviceSetAntennaPower object at 0x7962d0>>, <bound method DeviceSetAntennaPower.errorFromChannel of <ogsmd.modems.abstract.mediator.DeviceSetAntennaPower object at 0x7962d0>>, 15)])
Jan 15 11:39:57 debian-gta02 2009.01.15 11:39:57 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: sending 10 bytes: 'AT+CPIN?\r\n'
Jan 15 11:39:57 debian-gta02 2009.01.15 11:39:57 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 22 bytes from: '\r\n+CPIN: READY\r\n\r\nOK\r\n'
Jan 15 11:39:57 debian-gta02 2009.01.15 11:39:57 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: COMPLETED 'AT+CPIN?' => ['+CPIN: READY', 'OK']
Jan 15 11:39:57 debian-gta02 2009.01.15 11:39:57 ogsmd    DEBUG    <MiscChannel via /dev/pts/0> queue is: deque([('AT+CFUN=1\r\n', <bound method DeviceSetAntennaPower.responseFromChannel of <ogsmd.modems.abstract.mediator.DeviceSetAntennaPower object at 0x7962d0>>, <bound method DeviceSetAntennaPower.errorFromChannel of <ogsmd.modems.abstract.mediator.DeviceSetAntennaPower object at 0x7962d0>>, 10)])
Jan 15 11:39:57 debian-gta02 2009.01.15 11:39:57 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: sending 11 bytes: 'AT+CFUN=1\r\n'
Jan 15 11:39:57 debian-gta02 2009.01.15 11:39:57 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:39:57 debian-gta02 2009.01.15 11:39:57 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: COMPLETED 'AT+CFUN=1' => ['OK']
Jan 15 11:39:57 debian-gta02 2009.01.15 11:39:57 ogsmd    DEBUG    <MiscChannel via /dev/pts/0> queue is: deque([('AT+CPIN?\r\n', <bound method DeviceSetAntennaPower.intermediateResponse2 of <ogsmd.modems.abstract.mediator.DeviceSetAntennaPower object at 0x7962d0>>, <bound method DeviceSetAntennaPower.errorFromChannel of <ogsmd.modems.abstract.mediator.DeviceSetAntennaPower object at 0x7962d0>>, 15)])
Jan 15 11:39:57 debian-gta02 2009.01.15 11:39:57 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: sending 10 bytes: 'AT+CPIN?\r\n'
Jan 15 11:39:57 debian-gta02 2009.01.15 11:39:57 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 16 bytes from: '\r\n+CPIN: READY\r\n'
Jan 15 11:39:57 debian-gta02 2009.01.15 11:39:57 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:39:58 debian-gta02 2009.01.15 11:39:58 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: COMPLETED 'AT+CPIN?' => ['+CPIN: READY', 'OK']
Jan 15 11:39:58 debian-gta02 2009.01.15 11:39:58 ogsmd    DEBUG    <MiscChannel via /dev/pts/0> queue is: deque([])
Jan 15 11:39:58 debian-gta02 2009.01.15 11:39:58 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: nothing in request queue
Jan 15 11:39:58 debian-gta02 2009.01.15 11:39:58 ogsmd    DEBUG    <MiscChannel via /dev/pts/0> queue is: deque([('AT+COPS=0,0\r\n', <bound method NetworkRegister.responseFromChannel of <ogsmd.modems.abstract.mediator.NetworkRegister object at 0x6c8310>>, <bound method NetworkRegister.errorFromChannel of <ogsmd.modems.abstract.mediator.NetworkRegister object at 0x6c8310>>, 30)])
Jan 15 11:39:58 debian-gta02 2009.01.15 11:39:58 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: sending 13 bytes: 'AT+COPS=0,0\r\n'
Jan 15 11:39:58 debian-gta02 2009.01.15 11:39:58 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: got 12 bytes from: '\r\n+CREG: 2\r\n'
Jan 15 11:39:59 debian-gta02 2009.01.15 11:39:59 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: got 17 bytes from: '%CSTAT: EONS, 0\r\n'
Jan 15 11:39:59 debian-gta02 2009.01.15 11:39:59 ogsmd    INFO     full readyness now False
Jan 15 11:39:59 debian-gta02 2009.01.15 11:39:59 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: got 32 bytes from: '%CSTAT: PHB, 0\r\n%CSTAT: SMS, 1\r\n'
Jan 15 11:39:59 debian-gta02 2009.01.15 11:39:59 ogsmd    INFO     subsystem PHB readyness now False
Jan 15 11:39:59 debian-gta02 2009.01.15 11:39:59 ogsmd    INFO     full readyness now False
Jan 15 11:39:59 debian-gta02 2009.01.15 11:39:59 ogsmd    INFO     subsystem SMS readyness now True
Jan 15 11:39:59 debian-gta02 2009.01.15 11:39:59 ogsmd    INFO     full readyness now False
Jan 15 11:40:03 debian-gta02 2009.01.15 11:40:03 odeviced.idlenotifier INFO     odeviced.idlenotifier state change to idle
Jan 15 11:40:03 debian-gta02 2009.01.15 11:40:03 oeventsd INFO     DebugAction : dbus trigger test
Jan 15 11:40:03 debian-gta02 2009.01.15 11:40:03 oeventsd INFO     Receive IdleState, status = idle
Jan 15 11:40:03 debian-gta02 last message repeated 2 times
Jan 15 11:40:03 debian-gta02 2009.01.15 11:40:03 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: got 20 bytes from: '\r\n%CSQ:  23, 99, 2\r\n'
Jan 15 11:40:03 debian-gta02 2009.01.15 11:40:03 ogsmd.objects INFO     org.freesmartphone.GSM.Network.SignalStrength: 91
Jan 15 11:40:03 debian-gta02 2009.01.15 11:40:03 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:40:03 debian-gta02 2009.01.15 11:40:03 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: COMPLETED 'AT+COPS=0,0' => ['OK']
Jan 15 11:40:03 debian-gta02 2009.01.15 11:40:03 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: got 26 bytes from: '\r\n+CREG: 1,"02C3","5CF5"\r\n'
Jan 15 11:40:03 debian-gta02 2009.01.15 11:40:03 ogsmd    DEBUG    <MiscChannel via /dev/pts/0> queue is: deque([('AT+CSQ\r\n', <bound method NetworkGetStatus.genResponseFromChannel of <ogsmd.modems.abstract.mediator.NetworkGetStatus object at 0x810dd0>>, <bound method NetworkGetStatus.genErrorFromChannel of <ogsmd.modems.abstract.mediator.NetworkGetStatus object at 0x810dd0>>, 10), ('AT+CSQ\r\n', <bound method NetworkGetStatus.genResponseFromChannel of <ogsmd.modems.abstract.mediator.NetworkGetStatus object at 0x7962b0>>, <bound method NetworkGetStatus.genErrorFromChannel of <ogsmd.modems.abstract.mediator.NetworkGetStatus object at 0x7962b0>>, 10)])
Jan 15 11:40:03 debian-gta02 2009.01.15 11:40:03 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: sending 8 bytes: 'AT+CSQ\r\n'
Jan 15 11:40:03 debian-gta02 2009.01.15 11:40:03 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 16 bytes from: '\r\n+CSQ: 23,99 \r\n'
Jan 15 11:40:03 debian-gta02 2009.01.15 11:40:03 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:40:03 debian-gta02 2009.01.15 11:40:03 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: COMPLETED 'AT+CSQ' => ['+CSQ: 23,99 ', 'OK']
Jan 15 11:40:03 debian-gta02 2009.01.15 11:40:03 ogsmd    DEBUG    <MiscChannel via /dev/pts/0> queue is: deque([('AT+CSQ\r\n', <bound method NetworkGetStatus.genResponseFromChannel of <ogsmd.modems.abstract.mediator.NetworkGetStatus object at 0x7962b0>>, <bound method NetworkGetStatus.genErrorFromChannel of <ogsmd.modems.abstract.mediator.NetworkGetStatus object at 0x7962b0>>, 10), ('AT+CREG?;+COPS=3,0;+COPS?;+COPS=3,2;+COPS?\r\n', <bound method NetworkGetStatus.genResponseFromChannel of <ogsmd.modems.abstract.mediator.NetworkGetStatus object at 0x810dd0>>, <bound method NetworkGetStatus.genErrorFromChannel of <ogsmd.modems.abstract.mediator.NetworkGetStatus object at 0x810dd0>>, 10)])
Jan 15 11:40:03 debian-gta02 2009.01.15 11:40:03 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: sending 8 bytes: 'AT+CSQ\r\n'
Jan 15 11:40:03 debian-gta02 2009.01.15 11:40:03 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 16 bytes from: '\r\n+CSQ: 23,99 \r\n'
Jan 15 11:40:03 debian-gta02 2009.01.15 11:40:03 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:40:03 debian-gta02 2009.01.15 11:40:03 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: COMPLETED 'AT+CSQ' => ['+CSQ: 23,99 ', 'OK']
Jan 15 11:40:03 debian-gta02 2009.01.15 11:40:03 ogsmd    DEBUG    <MiscChannel via /dev/pts/0> queue is: deque([('AT+CREG?;+COPS=3,0;+COPS?;+COPS=3,2;+COPS?\r\n', <bound method NetworkGetStatus.genResponseFromChannel of <ogsmd.modems.abstract.mediator.NetworkGetStatus object at 0x810dd0>>, <bound method NetworkGetStatus.genErrorFromChannel of <ogsmd.modems.abstract.mediator.NetworkGetStatus object at 0x810dd0>>, 10), ('AT+CREG?;+COPS=3,0;+COPS?;+COPS=3,2;+COPS?\r\n', <bound method NetworkGetStatus.genResponseFromChannel of <ogsmd.modems.abstract.mediator.NetworkGetStatus object at 0x7962b0>>, <bound method NetworkGetStatus.genErrorFromChannel of <ogsmd.modems.abstract.mediator.NetworkGetStatus object at 0x7962b0>>, 10)])
Jan 15 11:40:03 debian-gta02 2009.01.15 11:40:03 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: sending 44 bytes: 'AT+CREG?;+COPS=3,0;+COPS?;+COPS=3,2;+COPS?\r\n'
Jan 15 11:40:03 debian-gta02 2009.01.15 11:40:03 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 14 bytes from: '\r\n+CREG: 0,1\r\n'
Jan 15 11:40:03 debian-gta02 2009.01.15 11:40:03 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 25 bytes from: '\r\n+COPS: 0,0,"Swisscom"\r\n'
Jan 15 11:40:04 debian-gta02 2009.01.15 11:40:03 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 28 bytes from: '\r\n+COPS: 0,2,"22801"\r\n\r\nOK\r\n'
Jan 15 11:40:04 debian-gta02 2009.01.15 11:40:04 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: COMPLETED 'AT+CREG?;+COPS=3,0;+COPS?;+COPS=3,2;+COPS?' => ['+CREG: 0,1', '+COPS: 0,0,"Swisscom"', '+COPS: 0,2,"22801"', 'OK']
Jan 15 11:40:04 debian-gta02 2009.01.15 11:40:04 ogsmd.objects INFO     org.freesmartphone.GSM.Network.Status: {'code': 22801, 'cid': '5CF5', 'registration': 'home', 'lac': '02C3', 'strength': 91, 'mode': 'automatic', 'provider': 'Swisscom'}
Jan 15 11:40:04 debian-gta02 2009.01.15 11:40:04 ogsmd    DEBUG    <MiscChannel via /dev/pts/0> queue is: deque([('AT+CREG?;+COPS=3,0;+COPS?;+COPS=3,2;+COPS?\r\n', <bound method NetworkGetStatus.genResponseFromChannel of <ogsmd.modems.abstract.mediator.NetworkGetStatus object at 0x7962b0>>, <bound method NetworkGetStatus.genErrorFromChannel of <ogsmd.modems.abstract.mediator.NetworkGetStatus object at 0x7962b0>>, 10)])
Jan 15 11:40:04 debian-gta02 2009.01.15 11:40:04 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: sending 44 bytes: 'AT+CREG?;+COPS=3,0;+COPS?;+COPS=3,2;+COPS?\r\n'
Jan 15 11:40:04 debian-gta02 2009.01.15 11:40:04 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 67 bytes from: '\r\n+CREG: 0,1\r\n\r\n+COPS: 0,0,"Swisscom"\r\n\r\n+COPS: 0,2,"22801"\r\n\r\nOK\r\n'
Jan 15 11:40:04 debian-gta02 2009.01.15 11:40:04 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: COMPLETED 'AT+CREG?;+COPS=3,0;+COPS?;+COPS=3,2;+COPS?' => ['+CREG: 0,1', '+COPS: 0,0,"Swisscom"', '+COPS: 0,2,"22801"', 'OK']
Jan 15 11:40:04 debian-gta02 2009.01.15 11:40:04 ogsmd.objects INFO     org.freesmartphone.GSM.Network.Status: {'code': 22801, 'cid': '5CF5', 'registration': 'home', 'lac': '02C3', 'strength': 91, 'mode': 'automatic', 'provider': 'Swisscom'}
Jan 15 11:40:04 debian-gta02 2009.01.15 11:40:04 ogsmd    DEBUG    <MiscChannel via /dev/pts/0> queue is: deque([])
Jan 15 11:40:04 debian-gta02 2009.01.15 11:40:04 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: nothing in request queue
Jan 15 11:40:08 debian-gta02 2009.01.15 11:40:08 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1> queue is: deque([('AT+CSMS=1\r\n', None, None, 10), ('AT+CNMI=2,1,2,1,1\r\n', None, None, 10)])
Jan 15 11:40:08 debian-gta02 2009.01.15 11:40:08 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: sending 11 bytes: 'AT+CSMS=1\r\n'
Jan 15 11:40:08 debian-gta02 2009.01.15 11:40:08 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: got 16 bytes from: '\r\n+CSMS: 1,1,1\r\n'
Jan 15 11:40:08 debian-gta02 2009.01.15 11:40:08 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:40:08 debian-gta02 2009.01.15 11:40:08 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: COMPLETED 'AT+CSMS=1' => ['+CSMS: 1,1,1', 'OK']
Jan 15 11:40:08 debian-gta02 2009.01.15 11:40:08 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1> queue is: deque([('AT+CNMI=2,1,2,1,1\r\n', None, None, 10)])
Jan 15 11:40:08 debian-gta02 2009.01.15 11:40:08 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: sending 19 bytes: 'AT+CNMI=2,1,2,1,1\r\n'
Jan 15 11:40:08 debian-gta02 2009.01.15 11:40:08 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:40:08 debian-gta02 2009.01.15 11:40:08 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: COMPLETED 'AT+CNMI=2,1,2,1,1' => ['OK']
Jan 15 11:40:08 debian-gta02 2009.01.15 11:40:08 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1> queue is: deque([])
Jan 15 11:40:08 debian-gta02 2009.01.15 11:40:08 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: nothing in request queue
Jan 15 11:40:12 debian-gta02 2009.01.15 11:40:12 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: got 16 bytes from: '%CSTAT: PHB, 1\r\n'
Jan 15 11:40:12 debian-gta02 2009.01.15 11:40:12 ogsmd    INFO     subsystem PHB readyness now True
Jan 15 11:40:12 debian-gta02 2009.01.15 11:40:12 ogsmd.objects INFO     sim ready status True
Jan 15 11:40:12 debian-gta02 2009.01.15 11:40:12 ogsmd    INFO     full readyness now True
Jan 15 11:40:13 debian-gta02 2009.01.15 11:40:13 ogsmd    DEBUG    <MiscChannel via /dev/pts/0> queue is: deque([('AT+CPBS="SM";+CPBR=?\r\n', <bound method SimGetPhonebookInfo.responseFromChannel of <ogsmd.modems.abstract.mediator.SimGetPhonebookInfo object at 0x810f90>>, <bound method SimGetPhonebookInfo.errorFromChannel of <ogsmd.modems.abstract.mediator.SimGetPhonebookInfo object at 0x810f90>>, 10), ('AT+CMGL=4\r\n', <bound method SimRetrieveMessagebook.responseFromChannel of <ogsmd.modems.abstract.mediator.SimRetrieveMessagebook object at 0x810f30>>, <bound method SimRetrieveMessagebook.errorFromChannel of <ogsmd.modems.abstract.mediator.SimRetrieveMessagebook object at 0x810f30>>, 10)])
Jan 15 11:40:13 debian-gta02 2009.01.15 11:40:13 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: sending 22 bytes: 'AT+CPBS="SM";+CPBR=?\r\n'
Jan 15 11:40:13 debian-gta02 2009.01.15 11:40:13 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 24 bytes from: '\r\n+CPBR: (1-250),44,20\r\n'
Jan 15 11:40:13 debian-gta02 2009.01.15 11:40:13 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:40:13 debian-gta02 2009.01.15 11:40:13 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: COMPLETED 'AT+CPBS="SM";+CPBR=?' => ['+CPBR: (1-250),44,20', 'OK']
Jan 15 11:40:13 debian-gta02 2009.01.15 11:40:13 ogsmd    DEBUG    <MiscChannel via /dev/pts/0> queue is: deque([('AT+CMGL=4\r\n', <bound method SimRetrieveMessagebook.responseFromChannel of <ogsmd.modems.abstract.mediator.SimRetrieveMessagebook object at 0x810f30>>, <bound method SimRetrieveMessagebook.errorFromChannel of <ogsmd.modems.abstract.mediator.SimRetrieveMessagebook object at 0x810f30>>, 10), ('AT+CPBS="SM";+CPBR=1,250\r\n', <bound method SimRetrievePhonebook.responseFromChannel of <ogsmd.modems.abstract.mediator.SimRetrievePhonebook object at 0x810db0>>, <bound method SimRetrievePhonebook.errorFromChannel of <ogsmd.modems.abstract.mediator.SimRetrievePhonebook object at 0x810db0>>, 10)])
Jan 15 11:40:13 debian-gta02 2009.01.15 11:40:13 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: sending 11 bytes: 'AT+CMGL=4\r\n'
Jan 15 11:40:13 debian-gta02 2009.01.15 11:40:13 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 18 bytes from: '\r\n+CMGL: 1,1,,84\r\n'
Jan 15 11:40:13 debian-gta02 2009.01.15 11:40:13 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 64 bytes from: '07911497949900F004038108F10000801171317291404ED77419742EDFFDEEF9'
Jan 15 11:40:13 debian-gta02 2009.01.15 11:40:13 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 122 bytes from: '184D07DDEB727219442ECB41D3B2DC9E1E97EFE5317A5E6683C2F5F9B96CF6A3E57417C8282FD7DD64767A8C2E838E72FF7C5E064DEFE9F97CFC6E03\r\n'
Jan 15 11:40:13 debian-gta02 2009.01.15 11:40:13 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 16 bytes from: '+CMGL: 5,1,,84\r\n'
Jan 15 11:40:13 debian-gta02 2009.01.15 11:40:13 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 128 bytes from: '07911497949900F04405810350F039F580216181248040440C05040B8423F008042168020256594730330086818805810301517F890A805377697373636F6D00'
Jan 15 11:40:13 debian-gta02 2009.01.15 11:40:13 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 58 bytes from: '8A828E026C009657656C636F6D65204D4D53205377697373636F6D00\r\n'
Jan 15 11:40:13 debian-gta02 2009.01.15 11:40:13 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 59 bytes from: '+CMGL: 13,1,"0041006E0064007200650061002C002000500072",78\r\n'
Jan 15 11:40:13 debian-gta02 2009.01.15 11:40:13 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 180 bytes from: '07911497949900F0040B911497567613F700008021621160704043CA325D4F079DCB68FA1CC47ECF41ED341D442EBB4165F99C5E7683D66937B92C07B1C37573D9ED0259CBF2737A3E07ADC3EDB23C0C72A7C768BA0B\r\n\r\nOK\r\n'
Jan 15 11:40:13 debian-gta02 2009.01.15 11:40:13 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: COMPLETED 'AT+CMGL=4' => ['+CMGL: 1,1,,84', '07911497949900F004038108F10000801171317291404ED77419742EDFFDEEF9184D07DDEB727219442ECB41D3B2DC9E1E97EFE5317A5E6683C2F5F9B96CF6A3E57417C8282FD7DD64767A8C2E838E72FF7C5E064DEFE9F97CFC6E03', '+CMGL: 5,1,,84', '07911497949900F04405810350F039F580216181248040440C05040B8423F008042168020256594730330086818805810301517F890A805377697373636F6D008A828E026C009657656C636F6D65204D4D53205377697373636F6D00', '+CMGL: 13,1,"0041006E0064007200650061002C002000500072",78', '07911497949900F0040B911497567613F700008021621160704043CA325D4F079DCB68FA1CC47ECF41ED341D442EBB4165F99C5E7683D66937B92C07B1C37573D9ED0259CBF2737A3E07ADC3EDB23C0C72A7C768BA0B', 'OK']
Jan 15 11:40:13 debian-gta02 2009.01.15 11:40:13 ogsmd    DEBUG    <MiscChannel via /dev/pts/0> queue is: deque([('AT+CPBS="SM";+CPBR=1,250\r\n', <bound method SimRetrievePhonebook.responseFromChannel of <ogsmd.modems.abstract.mediator.SimRetrievePhonebook object at 0x810db0>>, <bound method SimRetrievePhonebook.errorFromChannel of <ogsmd.modems.abstract.mediator.SimRetrievePhonebook object at 0x810db0>>, 10)])
Jan 15 11:40:13 debian-gta02 2009.01.15 11:40:13 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: sending 26 bytes: 'AT+CPBS="SM";+CPBR=1,250\r\n'
Jan 15 11:40:13 debian-gta02 2009.01.15 11:40:13 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 64 bytes from: '\r\n+CPBR: 1,"144",129,"004100410020004E006F0074007200750066002000'
Jan 15 11:40:13 debian-gta02 2009.01.15 11:40:13 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 41 bytes from: '480069006C00660065002000530063002F0057"\r\n'
Jan 15 11:40:14 debian-gta02 2009.01.15 11:40:14 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1> queue is: deque([('AT+CSMS=1\r\n', None, None, 10), ('AT+CNMI=2,1,2,1,1\r\n', None, None, 10)])
Jan 15 11:40:14 debian-gta02 2009.01.15 11:40:14 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: sending 11 bytes: 'AT+CSMS=1\r\n'
Jan 15 11:40:14 debian-gta02 2009.01.15 11:40:14 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 64 bytes from: '+CPBR: 2,"41622861212",145,"0048006F0074006C0069006E006500200049'
Jan 15 11:40:14 debian-gta02 2009.01.15 11:40:14 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 211 bytes from: '004E0054002F0048"\r\n+CPBR: 3,"1144",129,"0043006F006E006E006500630074002F0048"\r\n+CPBR: 4,"*139#",129,"0053004D005300200043006F006E006E006500630074002F0048"\r\n+CPBR: 5,"41813026607",145,"0041006E0064007200650061002'
Jan 15 11:40:14 debian-gta02 2009.01.15 11:40:14 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: got 16 bytes from: '\r\n+CSMS: 1,1,1\r\n'
Jan 15 11:40:14 debian-gta02 2009.01.15 11:40:14 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 512 bytes from: 'C0020005000720069007600E9002F0048"\r\n+CPBR: 6,"41813031891",145,"0041006E0064007200650061002C0020004100720062006500690074002D00480061006E002F0057"\r\n+CPBR: 7,"41813004270",145,"00460065007500650072006400650070006F00740020005600E40074007400690073002F0057"\r\n+CPBR: 8,"41800117117",145,"004200610068006E0070006F006C0069007A00650069002F0057"\r\n+CPBR: 9,"31118650333",145,"004C007500640069002000760064002000560065006E002F0048"\r\n+CPBR: 10,"49803171077",145,"00570065006900730065002C002000520065006E006100740065002F0048"\r\n'
Jan 15 11:40:14 debian-gta02 2009.01.15 11:40:14 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 384 bytes from: '+CPBR: 11,"41813031499",145,"0041006E0064007200650061002C0020004200750072006F002F0057"\r\n+CPBR: 12,"117",129,"0050006F006C0069007A006500690020004E006F0074002F0048"\r\n+CPBR: 13,"41800556464",145,"0048006F0074006C0069006E0065002000430048002F0048"\r\n+CPBR: 14,"144",129,"00530061006E0069007400E400740020004E006F0074002F0048"\r\n+CPBR: 15,"1414",129,"00480065006C0069002F0048"\r\n+CPBR: 16,"0813'
Jan 15 11:40:14 debian-gta02 2009.01.15 11:40:14 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 384 bytes from: '004222",129,"0050006F006C0069007A00650069002000420061006400200052006100670061007A002F0048"\r\n+CPBR: 17,"491733562556",145,"004A0075006E0067002C00200045006C006B0065002F004D"\r\n+CPBR: 18,"491733562552",145,"005200650068006D002C0020004C006100720073002F004D"\r\n+CPBR: 19,"41813061286",145,"0045007200770069006E0020002600200052006500670075006C006100200047006F0072002F0048"\r\n+CPBR: 20,"0031118'
Jan 15 11:40:14 debian-gta02 2009.01.15 11:40:14 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 384 bytes from: '617104",129,"004D00610063006800740065006C0064002F0048"\r\n+CPBR: 21,"31642825253",145,"004A0065006C006700650072002F004D"\r\n+CPBR: 23,"0041792453484",160,"005700650069007300650020005400750069006E00730074007200610061002F004D"\r\n+CPBR: 24,"41813302037",145,"00530074006500660061006E002000260020004D0061007200740069006E00610020004E002F0048"\r\n+CPBR: 25,"41813302035",145,"005300740065006600610'
Jan 15 11:40:14 debian-gta02 2009.01.15 11:40:14 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 256 bytes from: '06E002000260020004D0061007200740069006E00610020004E002F0057"\r\n+CPBR: 26,"31629005902",145,"004D0061007200740069006E0061002F004D"\r\n+CPBR: 27,"393482846012",145,"0041006E006900620061006C002F0048"\r\n+CPBR: 28,"31651619038",145,"00420061007200620065007200200026'
Jan 15 11:40:14 debian-gta02 2009.01.15 11:40:14 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 384 bytes from: '00200045007200690063002F004D"\r\n+CPBR: 29,"118",129,"004600650075007200770065006800720020004E006F0074002F0048"\r\n+CPBR: 30,"31640467082",145,"004C007500640069002000760064002000560065006E002F004D"\r\n+CPBR: 31,"41719888614",145,"0047006F00720074002000530074006500660061006E000A002F0048"\r\n+CPBR: 32,"41813061215",145,"0047006F007200740020004A006F00730065007000200028005600610074006500720029'
Jan 15 11:40:14 debian-gta02 2009.01.15 11:40:14 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 448 bytes from: '002F0048"\r\n+CPBR: 33,"41813061373",145,"004A00FC00720067002000530070007200650063006800650072002F0048"\r\n+CPBR: 34,"41792704315",145,"0053006F006E006A0061002000530070007200650063006800650072002F004D"\r\n+CPBR: 35,"31614839349",145,"00520069006E0073006B006500200053002E002F004D"\r\n+CPBR: 36,"31622068628",145,"0054006A0065006500720064002F004D"\r\n+CPBR: 37,"4151228644444",145,"005300620062002000420061006400200052006100670061007A002F0057"\r\n+CPBR: 38,"3170'
Jan 15 11:40:14 debian-gta02 2009.01.15 11:40:14 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 384 bytes from: '3569333",145,"00410073006E0020004B0061006C006B006D0061006E00200041006E006E0065006D0061002F0057"\r\n+CPBR: 39,"31112",145,"004100410020004E006F00740072007500660020004E00690065006400650072006C0061002F0057"\r\n+CPBR: 40,"31206627416",145,"004D0061007200740069006E0061002F0048"\r\n+CPBR: 41,"31703652754",145,"004A0069006D006D0079002F0048"\r\n+CPBR: 42,"31618880451",145,"004A0069006D006D0079002F'
Jan 15 11:40:14 debian-gta02 2009.01.15 11:40:14 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 512 bytes from: '004D"\r\n+CPBR: 43,"31703634515",145,"004B00690072007300740065006E002F0048"\r\n+CPBR: 44,"31648272196",145,"004B00690072007300740065006E002F004D"\r\n+CPBR: 45,"31648462047",145,"004A006F0061006E0069006B0065002F004D"\r\n+CPBR: 46,"4114556002",145,"0043006F006E00730075006C0061006100740020005A00FC0072006900630068002F0057"\r\n+CPBR: 47,"41796567317",145,"0041006E0064007200650061002C0020005000720069007600E9002F004D"\r\n+CPBR: 48,"31620247245",145,"004E0061007400610073006800610020002800560061006E0020004A0069006D006D0079002F0'
Jan 15 11:40:14 debian-gta02 2009.01.15 11:40:14 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 256 bytes from: '04D"\r\n+CPBR: 49,"41813026237",145,"005400720069006A006E006A006500200026002000460065006C00690078002F0048"\r\n+CPBR: 50,"41813026666",145,"005400610078006900200045007800700072006500730073002F0057"\r\n+CPBR: 51,"41795339211",145,"004400F6007200740065002F004D"\r\n+C'
Jan 15 11:40:14 debian-gta02 2009.01.15 11:40:14 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 384 bytes from: 'PBR: 52,"31650954904",145,"004D00610063006800740065006C0064002F004D"\r\n+CPBR: 53,"31629082653",145,"004D00610063006800740065006C0064002F0057"\r\n+CPBR: 54,"41813061418",145,"00440061006E00690065006C0061002000260020005200610069006E0065007200200047002F0048"\r\n+CPBR: 55,"41813061368",145,"004800650069006E007A00200026002000530079006C0076006900610020004B006E0069002F0048"\r\n+CPBR: 56,"3162077'
Jan 15 11:40:14 debian-gta02 2009.01.15 11:40:14 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 256 bytes from: '0563",145,"004A0061006E00200054002F004D"\r\n+CPBR: 57,"491784165677",145,"00570065006900730065002C002000520065006E006100740065002F0057"\r\n+CPBR: 58,"41813022317",145,"004C0061006500740073006300680020004800610075007300610072007A0074002F0057"\r\n+CPBR: 59,"417978'
Jan 15 11:40:14 debian-gta02 2009.01.15 11:40:14 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 448 bytes from: '43441",145,"005400720069006A006E006A006500200026002000460065006C00690078002F004D"\r\n+CPBR: 60,"41793771159",145,"004200650061007400650020004B007200690065006700650072002F004D"\r\n+CPBR: 61,"491776043457",145,"00570065006900730065002C002000520065006E006100740065002F004D"\r\n+CPBR: 62,"0813061323",129,"005600E400740074006E006500720062006500720067006200610068006E002F0057"\r\n+CPBR: 63,"41812523207",145,"004400F6007200740065002F0048"\r\n+CPBR: 64,"4181306112'
Jan 15 11:40:14 debian-gta02 2009.01.15 11:40:14 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 256 bytes from: '0",145,"00430068007200690073002000260020004500640069007400680020004D006100650073002F0048"\r\n+CPBR: 65,"41444221803",145,"004200650061007400650020004B007200690065006700650072002F0048"\r\n+CPBR: 66,"31334619332",145,"004B00610074006A006100200042006F006E00670065'
Jan 15 11:40:14 debian-gta02 2009.01.15 11:40:14 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 448 bytes from: '00720073002F0048"\r\n+CPBR: 67,"31636174875",145,"004B00610074006A006100200042006F006E0067006500720073002F004D"\r\n+CPBR: 68,"0794483815",129,"005600E400740074006E006500720062006500720067006200610068006E002F004D"\r\n+CPBR: 70,"31622396966",145,"00480065006E00730069006E00670020004A0061006E002F004D"\r\n+CPBR: 71,"0813061246",129,"004A00F60072006700200026002000450064006900740068002000420061006D00650072002F0048"\r\n+CPBR: 72,"41765879946",145,"00420065007400'
Jan 15 11:40:14 debian-gta02 2009.01.15 11:40:14 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 192 bytes from: '740069006E006100200057006500620065007200200026002000480065002F004D"\r\n+CPBR: 73,"41813061356",145,"005000610072006E00610073007300690061002F0048"\r\n+CPBR: 74,"0813004242",129,"0050006600E40066006'
Jan 15 11:40:15 debian-gta02 2009.01.15 11:40:15 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 192 bytes from: '500720073002000470065006D00650069006E00640065002F0057"\r\n+CPBR: 75,"31703563563",145,"004B00610074006A006100200042006F006E0067006500720073002F0057"\r\n+CPBR: 76,"41813061372",145,"005000610074007'
Jan 15 11:40:15 debian-gta02 2009.01.15 11:40:15 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 448 bytes from: '200690063006B0020002600200052006500670075006C006100200053002F0048"\r\n+CPBR: 77,"41817200970",145,"004600650075006500720061006C00610072006D002F0057"\r\n+CPBR: 78,"41813061470",145,"005000610072006E00610073007300690061002F0057"\r\n+CPBR: 79,"41813061404",145,"004B006F00720069006E006E006100200046007200F60068006C006900630068002F0048"\r\n+CPBR: 80,"41449404646",145,"004B006F00720069006E006E006100200046007200F60068006C006900630068002F0057"\r\n+CPBR: 81,"41813'
Jan 15 11:40:15 debian-gta02 kernel: rxerr: port ch=0x2c, rxs=0x00000001
Jan 15 11:40:15 debian-gta02 2009.01.15 11:40:15 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 552 bytes from: '061412",145,"00570061006C007400650072002000260020004D00610072006700720069007400680020002F0048"\r\n+CPBR: 82,\x00"41794336783",145,"00570061006C00740065007200200026002000400680020002F004D"\r\n+CPBR: 83,"41813061222",145,"004D00610072006700720069007400200026002000500065007400650072002000470065002F0048"\r\n+CPBR: 84,"41763681418",145,"00440061006E00690065006C0061002000260020005200610069006E0065007200200047002F004D"\r\n+CPBR: 85,"41813226645",145,"00420065007400740069006E006100200057006500620065007200200026002000480065002F0048"\r\n+CPBR: 86,"41774479228",145,"005'
Jan 15 11:40:15 debian-gta02 2009.01.15 11:40:15 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 240 bytes from: '300740065006600660069002F0048"\r\n+CPBR: 87,"4916095135836",145,"004D00610074007400680065007500730020002600200053007500730061006E006E0065002F004D"\r\n+CPBR: 88,"31610469342",145,"004D006100720063002000440065002000430072006F006D002F004D"\r\n\r\nOK\r\n'
Jan 15 11:40:15 debian-gta02 2009.01.15 11:40:15 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: COMPLETED 'AT+CPBS="SM";+CPBR=1,250' => ['+CPBR: 1,"144",129,"004100410020004E006F0074007200750066002000480069006C00660065002000530063002F0057"', '+CPBR: 2,"41622861212",145,"0048006F0074006C0069006E006500200049004E0054002F0048"', '+CPBR: 3,"1144",129,"0043006F006E006E006500630074002F0048"', '+CPBR: 4,"*139#",129,"0053004D005300200043006F006E006E006500630074002F0048"', '+CPBR: 5,"41813026607",145,"0041006E0064007200650061002C0020005000720069007600E9002F0048"', '+CPBR: 6,"41813031891",145,"0041006E0064007200650061002C0020004100720062006500690074002D00480061006E002F0057"', '+CPBR: 7,"41813004270",145,"00460065007500650072006400650070006F00740020005600E40074007400690073002F0057"', '+CPBR: 8,"41800117117",145,"004200610068006E0070006F006C0069007A00650069002F0057"', '+CPBR: 9,"31118650333",145,"004C007500640069002000760064002000560065006E002F0048"', '+CPBR: 10,"49803171077",145,"00570065006900730065002C002000520065006E006100740065002F0048"',
Jan 15 11:40:15 debian-gta02 2009.01.15 11:40:15 ogsmd    WARNING  Out-of-spec GSM number type seen: 160. Please report.
Jan 15 11:40:15 debian-gta02 2009.01.15 11:40:15 dbus.service ERROR    Unable to append ([(1, u'AA Notruf Hilfe Sc/W', '144')<<private info deleted>>2035'), (26, u'Mart
Jan 15 11:40:15 debian-gta02 2009.01.15 11:40:15 ogsmd    ERROR    unhandled exception in response callback: expected string without null bytes Traceback (most recent call last):   File "/var/lib/python-support/python2.5/framework/subsystems/ogsmd/gsm/channel.py", line 383, in handleResponseToRequest     ok_cb( reqstring.strip(), response )   File "/var/lib/python-support/python2.5/framework/subsystems/ogsmd/modems/abstract/mediator.py", line 650, in responseFromChannel     self._ok( result )   File "/var/lib/python-support/python2.5/dbus/service.py", line 663, in <lambda>     keywords[return_callback] = lambda *retval: _method_reply_return(connection, message, method_name, signature, *retval)   File "/var/lib/python-support/python2.5/dbus/service.py", line 252, in _method_reply_return     reply.append(signature=signature, *retval) TypeError: expected string without null bytes
Jan 15 11:40:15 debian-gta02 2009.01.15 11:40:15 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:40:15 debian-gta02 2009.01.15 11:40:15 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: COMPLETED 'AT+CSMS=1' => ['+CSMS: 1,1,1', 'OK']
Jan 15 11:40:15 debian-gta02 2009.01.15 11:40:15 ogsmd    DEBUG    <MiscChannel via /dev/pts/0> queue is: deque([])
Jan 15 11:40:15 debian-gta02 2009.01.15 11:40:15 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: nothing in request queue
Jan 15 11:40:15 debian-gta02 2009.01.15 11:40:15 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1> queue is: deque([('AT+CNMI=2,1,2,1,1\r\n', None, None, 10)])
Jan 15 11:40:15 debian-gta02 2009.01.15 11:40:15 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: sending 19 bytes: 'AT+CNMI=2,1,2,1,1\r\n'
Jan 15 11:40:15 debian-gta02 2009.01.15 11:40:15 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: got 6 bytes from: '\r\nOK\r\n'
Jan 15 11:40:15 debian-gta02 2009.01.15 11:40:15 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: COMPLETED 'AT+CNMI=2,1,2,1,1' => ['OK']
Jan 15 11:40:15 debian-gta02 2009.01.15 11:40:15 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1> queue is: deque([])
Jan 15 11:40:15 debian-gta02 2009.01.15 11:40:15 ogsmd    DEBUG    <UnsolicitedResponseChannel via /dev/pts/1>: nothing in request queue
Jan 15 11:40:21 debian-gta02 2009.01.15 11:40:21 ogsmd    DEBUG    <MiscChannel via /dev/pts/0> queue is: deque([('AT+CMGL=2\r\n', <bound method SimRetrieveMessagebook.responseFromChannel of <ogsmd.modems.abstract.mediator.SimRetrieveMessagebook object at 0x810f70>>, <bound method SimRetrieveMessagebook.errorFromChannel of <ogsmd.modems.abstract.mediator.SimRetrieveMessagebook object at 0x810f70>>, 10)])
Jan 15 11:40:21 debian-gta02 2009.01.15 11:40:21 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: sending 11 bytes: 'AT+CMGL=2\r\n'
Jan 15 11:40:21 debian-gta02 2009.01.15 11:40:21 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 19 bytes from: '\r\n+CMS ERROR: 321\r\n'
Jan 15 11:40:21 debian-gta02 2009.01.15 11:40:21 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: COMPLETED 'AT+CMGL=2' => ['+CMS ERROR: 321']
Jan 15 11:40:21 debian-gta02 2009.01.15 11:40:21 ogsmd    DEBUG    <MiscChannel via /dev/pts/0> queue is: deque([])
Jan 15 11:40:21 debian-gta02 2009.01.15 11:40:21 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: nothing in request queue
Jan 15 11:40:23 debian-gta02 2009.01.15 11:40:23 odeviced.idlenotifier INFO     odeviced.idlenotifier state change to idle_dim
Jan 15 11:40:23 debian-gta02 2009.01.15 11:40:23 oeventsd INFO     DebugAction : dbus trigger test
Jan 15 11:40:23 debian-gta02 2009.01.15 11:40:23 oeventsd INFO     Receive IdleState, status = idle_dim
Jan 15 11:40:23 debian-gta02 2009.01.15 11:40:23 oeventsd INFO     Receive IdleState, status = idle_dim
Jan 15 11:40:23 debian-gta02 2009.01.15 11:40:23 oeventsd INFO     call dbus signal /org/freesmartphone/Device/Display/pcf50633_bl SetBrightness((20,))
Jan 15 11:40:23 debian-gta02 2009.01.15 11:40:23 oeventsd INFO     Receive IdleState, status = idle_dim
Jan 15 11:40:23 debian-gta02 2009.01.15 11:40:23 oeventsd INFO     signal SetBrightness responded : ()
Jan 15 11:40:23 debian-gta02 2009.01.15 11:40:23 odeviced.idlenotifier INFO     odeviced.idlenotifier state change to busy
Jan 15 11:40:23 debian-gta02 2009.01.15 11:40:23 oeventsd INFO     DebugAction : dbus trigger test
Jan 15 11:40:23 debian-gta02 2009.01.15 11:40:23 oeventsd INFO     Receive IdleState, status = busy
Jan 15 11:40:23 debian-gta02 2009.01.15 11:40:23 oeventsd INFO     call dbus signal /org/freesmartphone/Device/Display/pcf50633_bl SetBrightness((90,))
Jan 15 11:40:23 debian-gta02 2009.01.15 11:40:23 oeventsd INFO     Receive IdleState, status = busy
Jan 15 11:40:23 debian-gta02 2009.01.15 11:40:23 oeventsd INFO     Receive IdleState, status = busy
Jan 15 11:40:23 debian-gta02 2009.01.15 11:40:23 oeventsd INFO     signal SetBrightness responded : ()
Jan 15 11:40:34 debian-gta02 2009.01.15 11:40:34 odeviced.idlenotifier INFO     odeviced.idlenotifier state change to idle
Jan 15 11:40:34 debian-gta02 2009.01.15 11:40:34 oeventsd INFO     DebugAction : dbus trigger test
Jan 15 11:40:34 debian-gta02 2009.01.15 11:40:34 oeventsd INFO     Receive IdleState, status = idle
Jan 15 11:40:34 debian-gta02 last message repeated 2 times
Jan 15 11:40:34 debian-gta02 2009.01.15 11:40:34 odeviced.idlenotifier INFO     odeviced.idlenotifier state change to busy
Jan 15 11:40:34 debian-gta02 2009.01.15 11:40:34 oeventsd INFO     DebugAction : dbus trigger test
Jan 15 11:40:34 debian-gta02 2009.01.15 11:40:34 oeventsd INFO     Receive IdleState, status = busy
Jan 15 11:40:34 debian-gta02 2009.01.15 11:40:34 oeventsd INFO     call dbus signal /org/freesmartphone/Device/Display/pcf50633_bl SetBrightness((90,))
Jan 15 11:40:34 debian-gta02 2009.01.15 11:40:34 oeventsd INFO     Receive IdleState, status = busy
Jan 15 11:40:34 debian-gta02 2009.01.15 11:40:34 oeventsd INFO     Receive IdleState, status = busy
Jan 15 11:40:34 debian-gta02 2009.01.15 11:40:34 oeventsd INFO     signal SetBrightness responded : ()
Jan 15 11:40:44 debian-gta02 2009.01.15 11:40:44 odeviced.idlenotifier INFO     odeviced.idlenotifier state change to idle
Jan 15 11:40:44 debian-gta02 2009.01.15 11:40:44 oeventsd INFO     DebugAction : dbus trigger test
Jan 15 11:40:44 debian-gta02 2009.01.15 11:40:44 oeventsd INFO     Receive IdleState, status = idle
Jan 15 11:40:44 debian-gta02 last message repeated 2 times
Jan 15 11:40:50 debian-gta02 dropbear[1306]: Child connection from ::ffff:192.168.0.200:57919
Jan 15 11:40:55 debian-gta02 dropbear[1306]: password auth succeeded for 'root' from ::ffff:192.168.0.200:57919
Jan 15 11:41:04 debian-gta02 2009.01.15 11:41:04 odeviced.idlenotifier INFO     odeviced.idlenotifier state change to idle_dim
Jan 15 11:41:04 debian-gta02 2009.01.15 11:41:04 oeventsd INFO     DebugAction : dbus trigger test
Jan 15 11:41:04 debian-gta02 2009.01.15 11:41:04 oeventsd INFO     Receive IdleState, status = idle_dim
Jan 15 11:41:04 debian-gta02 2009.01.15 11:41:04 oeventsd INFO     Receive IdleState, status = idle_dim
Jan 15 11:41:04 debian-gta02 2009.01.15 11:41:04 oeventsd INFO     call dbus signal /org/freesmartphone/Device/Display/pcf50633_bl SetBrightness((20,))
Jan 15 11:41:04 debian-gta02 2009.01.15 11:41:04 oeventsd INFO     Receive IdleState, status = idle_dim
Jan 15 11:41:04 debian-gta02 2009.01.15 11:41:04 oeventsd INFO     signal SetBrightness responded : ()
Jan 15 11:41:16 debian-gta02 2009.01.15 11:41:16 odeviced.idlenotifier INFO     odeviced.idlenotifier state change to idle_prelock
Jan 15 11:41:16 debian-gta02 2009.01.15 11:41:16 oeventsd INFO     DebugAction : dbus trigger test
Jan 15 11:41:16 debian-gta02 2009.01.15 11:41:16 oeventsd INFO     Receive IdleState, status = idle_prelock
Jan 15 11:41:16 debian-gta02 last message repeated 2 times
Jan 15 11:41:16 debian-gta02 2009.01.15 11:41:16 oeventsd INFO     call dbus signal /org/freesmartphone/Device/Display/pcf50633_bl SetBrightness((0,))
Jan 15 11:41:16 debian-gta02 2009.01.15 11:41:16 oeventsd INFO     signal SetBrightness responded : ()
Jan 15 11:41:18 debian-gta02 2009.01.15 11:41:18 odeviced.idlenotifier INFO     odeviced.idlenotifier state change to lock
Jan 15 11:41:18 debian-gta02 2009.01.15 11:41:18 oeventsd INFO     DebugAction : dbus trigger test
Jan 15 11:41:18 debian-gta02 2009.01.15 11:41:18 oeventsd INFO     Receive IdleState, status = lock
Jan 15 11:41:18 debian-gta02 last message repeated 2 times
Jan 15 11:41:21 debian-gta02 2009.01.15 11:41:21 ogsmd    DEBUG    (<MiscChannel via /dev/pts/0>: last communication with modem was 59 seconds ago. Sending EOF to wakeup)
Jan 15 11:41:21 debian-gta02 2009.01.15 11:41:21 ogsmd    DEBUG    <MiscChannel via /dev/pts/0> queue is: deque([('AT+CMGL=2\r\n', <bound method SimRetrieveMessagebook.responseFromChannel of <ogsmd.modems.abstract.mediator.SimRetrieveMessagebook object at 0x81e9d0>>, <bound method SimRetrieveMessagebook.errorFromChannel of <ogsmd.modems.abstract.mediator.SimRetrieveMessagebook object at 0x81e9d0>>, 10)])
Jan 15 11:41:21 debian-gta02 2009.01.15 11:41:21 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: sending 11 bytes: 'AT+CMGL=2\r\n'
Jan 15 11:41:21 debian-gta02 2009.01.15 11:41:21 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: got 19 bytes from: '\r\n+CMS ERROR: 321\r\n'
Jan 15 11:41:21 debian-gta02 2009.01.15 11:41:21 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: COMPLETED 'AT+CMGL=2' => ['+CMS ERROR: 321']
Jan 15 11:41:21 debian-gta02 2009.01.15 11:41:21 ogsmd    DEBUG    <MiscChannel via /dev/pts/0> queue is: deque([])
Jan 15 11:41:21 debian-gta02 2009.01.15 11:41:21 ogsmd    DEBUG    <MiscChannel via /dev/pts/0>: nothing in request queue
Jan 15 11:41:38 debian-gta02 2009.01.15 11:41:38 odeviced.idlenotifier INFO     odeviced.idlenotifier state change to suspend
Jan 15 11:41:38 debian-gta02 2009.01.15 11:41:38 oeventsd INFO     DebugAction : dbus trigger test
Jan 15 11:41:38 debian-gta02 2009.01.15 11:41:38 oeventsd INFO     Receive IdleState, status = suspend