[Pkg-utopia-maintainers] Bug#539977: network-manager: fails to connect using hsdpa modem Novatel Merlin X950D

[Stone]

Package: network-manager
Version: 0.7.1-1
Severity: normal
Tags: l10n

Hello, 

First of all I have noticed strange behaviour using modem with module usbserial:
I put in file /etc/modprobe.d/usbserial.conf line:
options usbserial vendor=0x1410 product=0x1450 but it doesn't work all the time.
Sometime, after start of the system I have to comment out this line or put different value for example 0x4400 in field product to make udev recognize modem as 0x1450.
This is very odd. But If it recignize as 0x1450 I cannot connect using network-manager with my local provider, all I get from udev is:

/sbin/udevd --debug
[6136] parse_file: reading '/etc/udev/rules.d/025_libgphoto2.rules' as rules file
[6136] parse_file: reading '/etc/udev/rules.d/11-hplj10xx.rules' as rules file
[6136] parse_file: reading '/lib/udev/rules.d/45-libmtp8.rules' as rules file
[6136] parse_file: reading '/lib/udev/rules.d/50-udev.rules' as rules file
[6136] parse_file: reading '/etc/udev/rules.d/56-hpmud_support.rules' as rules file
[6136] parse_file: reading '/lib/udev/rules.d/60-persistent-input.rules' as rules file
[6136] parse_file: reading '/lib/udev/rules.d/60-persistent-serial.rules' as rules file
[6136] parse_file: reading '/lib/udev/rules.d/60-persistent-storage-tape.rules' as rules file
[6136] parse_file: reading '/lib/udev/rules.d/60-persistent-storage.rules' as rules file
[6136] parse_file: reading '/lib/udev/rules.d/60-persistent-v4l.rules' as rules file
[6136] parse_file: reading '/dev/.udev/rules.d/61-dev-root-link.rules' as rules file
[6136] parse_file: reading '/lib/udev/rules.d/62-bluez-hid2hci.rules' as rules file
[6136] parse_file: reading '/etc/udev/rules.d/70-persistent-cd.rules' as rules file
[6136] parse_file: reading '/etc/udev/rules.d/70-persistent-net.rules' as rules file
[6136] parse_file: reading '/lib/udev/rules.d/75-cd-aliases-generator.rules' as rules file
[6136] parse_file: reading '/lib/udev/rules.d/75-persistent-net-generator.rules' as rules file
[6136] parse_file: reading '/lib/udev/rules.d/77-nm-probe-modem-capabilities.rules' as rules file
[6136] parse_file: reading '/lib/udev/rules.d/80-alsa.rules' as rules file
[6136] parse_file: reading '/lib/udev/rules.d/80-drivers.rules' as rules file
[6136] parse_file: reading '/etc/udev/rules.d/85-pcmcia.rules' as rules file
[6136] parse_file: reading '/lib/udev/rules.d/90-hal.rules' as rules file
[6136] parse_file: reading '/lib/udev/rules.d/91-permissions.rules' as rules file
[6136] util_lookup_group: specified group 'nvram' unknown
[6136] util_lookup_user: specified user 'tss' unknown
[6136] util_lookup_group: specified group 'tss' unknown
[6136] util_lookup_group: specified group 'fuse' unknown
[6136] util_lookup_group: specified group 'kvm' unknown
[6136] util_lookup_group: specified group 'rdma' unknown
[6136] parse_file: reading '/lib/udev/rules.d/95-late.rules' as rules file
[6136] parse_file: reading '/etc/udev/rules.d/libmtp7.rules' as rules file
[6136] parse_file: reading '/etc/udev/rules.d/z60_hdparm.rules' as rules file
[6136] parse_file: reading '/etc/udev/rules.d/z60_hplip.rules' as rules file
[6136] parse_file: reading '/etc/udev/rules.d/z60_libpisock9.rules' as rules file
[6136] parse_file: reading '/etc/udev/rules.d/z60_libsane-extras.rules' as rules file
[6136] parse_file: reading '/etc/udev/rules.d/z60_libsane.rules' as rules file
[6136] parse_file: reading '/etc/udev/rules.d/z60_xserver-xorg-input-wacom.rules' as rules file
[6136] udev_rules_new: rules use 162036 bytes tokens (13503 * 12 bytes), 17253 bytes buffer
[6136] udev_rules_new: temporary index used 35740 bytes (1787 * 20 bytes)
[6136] udev_device_new_from_syspath: device 0x8ff4bc0 has devpath '/devices/virtual/block/loop1'
[6136] udev_device_read_db: device 0x8ff4bc0 filled with db file data
[6136] udev_watch_restore: restoring old watch on '/dev/loop1'
[6136] udev_watch_begin: adding watch on '/dev/loop1'
[6136] udev_device_new_from_syspath: device 0x8ff4bc0 has devpath '/devices/virtual/block/loop3'
[6136] udev_device_read_db: device 0x8ff4bc0 filled with db file data
[6136] udev_watch_restore: restoring old watch on '/dev/loop3'
[6136] udev_watch_begin: adding watch on '/dev/loop3'
[6136] udev_device_new_from_syspath: device 0x8ff4bc0 has devpath '/devices/virtual/block/loop4'
[6136] udev_device_read_db: device 0x8ff4bc0 filled with db file data
[6136] udev_watch_restore: restoring old watch on '/dev/loop4'
[6136] udev_watch_begin: adding watch on '/dev/loop4'
[6136] udev_device_new_from_syspath: device 0x8ff4bc0 has devpath '/devices/virtual/block/loop0'
[6136] udev_device_read_db: device 0x8ff4bc0 filled with db file data
[6136] udev_watch_restore: restoring old watch on '/dev/loop0'
[6136] udev_watch_begin: adding watch on '/dev/loop0'
[6136] udev_device_new_from_syspath: device 0x8ff4bc0 has devpath '/devices/virtual/block/loop6'
[6136] udev_device_read_db: device 0x8ff4bc0 filled with db file data
[6136] udev_watch_restore: restoring old watch on '/dev/loop6'
[6136] udev_watch_begin: adding watch on '/dev/loop6'
[6136] udev_device_new_from_syspath: device 0x8ff4bc0 has devpath '/devices/virtual/block/loop2'
[6136] udev_device_read_db: device 0x8ff4bc0 filled with db file data
[6136] udev_watch_restore: restoring old watch on '/dev/loop2'
[6136] udev_watch_begin: adding watch on '/dev/loop2'
[6136] udev_device_new_from_syspath: device 0x8ff4bc0 has devpath '/devices/virtual/block/loop7'
[6136] udev_device_read_db: device 0x8ff4bc0 filled with db file data
[6136] udev_watch_restore: restoring old watch on '/dev/loop7'
[6136] udev_watch_begin: adding watch on '/dev/loop7'
[6136] udev_device_new_from_syspath: device 0x8ff4bc0 has devpath '/devices/virtual/block/loop5'
[6136] udev_device_read_db: device 0x8ff4bc0 filled with db file data
[6136] udev_watch_restore: restoring old watch on '/dev/loop5'
[6136] udev_watch_begin: adding watch on '/dev/loop5'
[6136] udev_device_new_from_syspath: device 0x8ff4bc0 has devpath '/devices/virtual/block/ram0'
[6136] udev_device_read_db: device 0x8ff4bc0 filled with db file data
[6136] udev_watch_restore: restoring old watch on '/dev/ram0'
[6136] udev_watch_begin: adding watch on '/dev/ram0'
[6136] udev_device_new_from_syspath: device 0x8ff4bc0 has devpath '/devices/pci0000:00/0000:00:1f.2/host0/target0:0:0/0:0:0:0/block/sda'
[6136] udev_device_read_db: device 0x8ff4bc0 filled with db file data
[6136] udev_watch_restore: restoring old watch on '/dev/sda'
[6136] udev_watch_begin: adding watch on '/dev/sda'
[6136] udev_device_new_from_syspath: device 0x8ff4bc0 has devpath '/devices/virtual/block/ram1'
[6136] udev_device_read_db: device 0x8ff4bc0 filled with db file data
[6136] udev_watch_restore: restoring old watch on '/dev/ram1'
[6136] udev_watch_begin: adding watch on '/dev/ram1'
[6136] udev_device_new_from_syspath: device 0x8ff4bc0 has devpath '/devices/virtual/block/ram2'
[6136] udev_device_read_db: device 0x8ff4bc0 filled with db file data
[6136] udev_watch_restore: restoring old watch on '/dev/ram2'
[6136] udev_watch_begin: adding watch on '/dev/ram2'
[6136] udev_device_new_from_syspath: device 0x8ff4bc0 has devpath '/devices/virtual/block/ram3'
[6136] udev_device_read_db: device 0x8ff4bc0 filled with db file data
[6136] udev_watch_restore: restoring old watch on '/dev/ram3'
[6136] udev_watch_begin: adding watch on '/dev/ram3'
[6136] udev_device_new_from_syspath: device 0x8ff4bc0 has devpath '/devices/virtual/block/ram4'
[6136] udev_device_read_db: device 0x8ff4bc0 filled with db file data
[6136] udev_watch_restore: restoring old watch on '/dev/ram4'
[6136] udev_watch_begin: adding watch on '/dev/ram4'
[6136] udev_device_new_from_syspath: device 0x8ff4bc0 has devpath '/devices/virtual/block/ram5'
[6136] udev_device_read_db: device 0x8ff4bc0 filled with db file data
[6136] udev_watch_restore: restoring old watch on '/dev/ram5'
[6136] udev_watch_begin: adding watch on '/dev/ram5'
[6136] udev_device_new_from_syspath: device 0x8ff4bc0 has devpath '/devices/virtual/block/ram6'
[6136] udev_device_read_db: device 0x8ff4bc0 filled with db file data
[6136] udev_watch_restore: restoring old watch on '/dev/ram6'
[6136] udev_watch_begin: adding watch on '/dev/ram6'
[6136] udev_device_new_from_syspath: device 0x8ff4bc0 has devpath '/devices/virtual/block/ram7'
[6136] udev_device_read_db: device 0x8ff4bc0 filled with db file data
[6136] udev_watch_restore: restoring old watch on '/dev/ram7'
[6136] udev_watch_begin: adding watch on '/dev/ram7'
[6136] udev_device_new_from_syspath: device 0x8ff4bc0 has devpath '/devices/pci0000:00/0000:00:1f.2/host0/target0:0:0/0:0:0:0/block/sda/sda2'
[6136] udev_device_read_db: device 0x8ff4bc0 filled with db file data
[6136] udev_watch_restore: restoring old watch on '/dev/sda2'
[6136] udev_watch_begin: adding watch on '/dev/sda2'
[6136] udev_device_new_from_syspath: device 0x8ff4bc0 has devpath '/devices/virtual/block/ram14'
[6136] udev_device_read_db: device 0x8ff4bc0 filled with db file data
[6136] udev_watch_restore: restoring old watch on '/dev/ram14'
[6136] udev_watch_begin: adding watch on '/dev/ram14'
[6136] udev_device_new_from_syspath: device 0x8ff4bc0 has devpath '/devices/virtual/block/ram13'
[6136] udev_device_read_db: device 0x8ff4bc0 filled with db file data
[6136] udev_watch_restore: restoring old watch on '/dev/ram13'
[6136] udev_watch_begin: adding watch on '/dev/ram13'
[6136] udev_device_new_from_syspath: device 0x8ff4bc0 has devpath '/devices/virtual/block/ram10'
[6136] udev_device_read_db: device 0x8ff4bc0 filled with db file data
[6136] udev_watch_restore: restoring old watch on '/dev/ram10'
[6136] udev_watch_begin: adding watch on '/dev/ram10'
[6136] udev_device_new_from_syspath: device 0x8ff4bc0 has devpath '/devices/virtual/block/ram11'
[6136] udev_device_read_db: device 0x8ff4bc0 filled with db file data
[6136] udev_watch_restore: restoring old watch on '/dev/ram11'
[6136] udev_watch_begin: adding watch on '/dev/ram11'
[6136] udev_device_new_from_syspath: device 0x8ff4bc0 has devpath '/devices/virtual/block/ram12'
[6136] udev_device_read_db: device 0x8ff4bc0 filled with db file data
[6136] udev_watch_restore: restoring old watch on '/dev/ram12'
[6136] udev_watch_begin: adding watch on '/dev/ram12'
[6136] udev_device_new_from_syspath: device 0x8ff4bc0 has devpath '/devices/virtual/block/ram8'
[6136] udev_device_read_db: device 0x8ff4bc0 filled with db file data
[6136] udev_watch_restore: restoring old watch on '/dev/ram8'
[6136] udev_watch_begin: adding watch on '/dev/ram8'
[6136] udev_device_new_from_syspath: device 0x8ff4bc0 has devpath '/devices/virtual/block/ram15'
[6136] udev_device_read_db: device 0x8ff4bc0 filled with db file data
[6136] udev_watch_restore: restoring old watch on '/dev/ram15'
[6136] udev_watch_begin: adding watch on '/dev/ram15'
[6136] udev_device_new_from_syspath: device 0x8ff4bc0 has devpath '/devices/virtual/block/ram9'
[6136] udev_device_read_db: device 0x8ff4bc0 filled with db file data
[6136] udev_watch_restore: restoring old watch on '/dev/ram9'
[6136] udev_watch_begin: adding watch on '/dev/ram9'
[6136] udev_device_new_from_syspath: device 0x8ff4bc0 has devpath '/devices/pci0000:00/0000:00:1f.2/host0/target0:0:0/0:0:0:0/block/sda/sda1'
[6136] udev_device_read_db: device 0x8ff4bc0 filled with db file data
[6136] udev_watch_restore: restoring old watch on '/dev/sda1'
[6136] udev_watch_begin: adding watch on '/dev/sda1'
[6136] main: initialize max_childs to 1122
[6136] event_queue_insert: seq 1800 queued, 'add' 'usb'
[6136] event_fork: seq 1800 forked, pid [6140], 'add' 'usb', 0 seconds old
[6140] udev_device_new_from_syspath: device 0x8fe45c0 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2'
[6140] udev_device_new_from_syspath: device 0x8ff4e60 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2'
[6140] udev_device_new_from_syspath: device 0x8feeb00 has devpath '/devices/pci0000:00/0000:00:1a.0'
[6140] udev_device_new_from_syspath: device 0x8feebc8 has devpath '/devices/pci0000:00'
[6140] udev_rules_apply_to_event: PROGRAM 'check-ptp-camera 06/01/01' /etc/udev/rules.d/025_libgphoto2.rules:967
[6140] util_run_program: 'check-ptp-camera 06/01/01'
[6140] util_run_program: '/lib/udev/check-ptp-camera' returned with status 1
[6140] udev_rules_apply_to_event: LINK 'char/189:143' /lib/udev/rules.d/50-udev.rules:2
[6140] udev_rules_apply_to_event: NAME 'bus/usb/002/016' /lib/udev/rules.d/50-udev.rules:35
[6140] udev_rules_apply_to_event: RUN 'socket:@/org/freedesktop/hal/udev_event' /lib/udev/rules.d/90-hal.rules:2
[6140] udev_rules_apply_to_event: MODE 0664 /lib/udev/rules.d/91-permissions.rules:40
[6136] event_queue_insert: seq 1801 queued, 'add' 'usb'
[6140] udev_device_update_db: create db link (bus/usb/002/016 char/189:143)
[6140] udev_node_add: creating device node '/dev/bus/usb/002/016', devnum=189:143, mode=0664, uid=0, gid=0
[6140] udev_node_mknod: mknod(/dev/bus/usb/002/016, 020664, (189,143))
[6140] udev_node_mknod: chmod(/dev/bus/usb/002/016, 020664)
[6140] udev_node_mknod: chown(/dev/bus/usb/002/016, 0, 0)
[6140] update_link: '/dev/char/189:143' with target '/dev/bus/usb/002/016' has the highest priority 0, create it
[6140] node_symlink: creating symlink '/dev/char/189:143' to '../bus/usb/002/016'
[6140] udev_monitor_send_device: passed 321 bytes to monitor 0x8feeea8
[6140] udev_monitor_send_device: passed -1 bytes to monitor 0x8fe4648
[6140] event_fork: seq 1800 exit with 0
[6136] udev_done: seq 1800 cleanup, pid [6140], status 0, 0 seconds old
[6143] udev_rules_apply_to_event: RUN '/sbin/modprobe -b $env{MODALIAS}' /lib/udev/rules.d/80-drivers.rules:10
[6143] udev_rules_apply_to_event: RUN 'socket:@/org/freedesktop/hal/udev_event' /lib/udev/rules.d/90-hal.rules:2
[6143] udev_device_new_from_syspath: device 0x8ff4bc0 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2'
[6143] udev_device_new_from_syspath: device 0x8fe45c0 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2'
[6143] udev_device_new_from_syspath: device 0x8feeb50 has devpath '/devices/pci0000:00/0000:00:1a.0'
[6143] udev_device_new_from_syspath: device 0x8feec08 has devpath '/devices/pci0000:00'
[6143] util_run_program: '/sbin/modprobe -b usb:v1410p5010d0000dc00dsc00dp00ic08isc06ip50'
[6143] util_run_program: '/sbin/modprobe' returned with status 0
[6143] udev_monitor_send_device: passed 314 bytes to monitor 0x8feee98
[6143] udev_monitor_send_device: passed -1 bytes to monitor 0x8fe4648
[6143] event_fork: seq 1801 exit with 0
[6136] event_fork: seq 1801 forked, pid [6143], 'add' 'usb', 0 seconds old
[6136] udev_done: seq 1801 cleanup, pid [6143], status 0, 0 seconds old
[6136] event_queue_insert: seq 1802 queued, 'add' 'scsi'
[6136] event_fork: seq 1802 forked, pid [6146], 'add' 'scsi', 0 seconds old
[6136] event_queue_insert: seq 1803 queued, 'add' 'scsi_host'
[6136] event_queue_insert: seq 1804 queued, 'add' 'usb_endpoint'
[6136] event_fork: seq 1804 forked, pid [6148], 'add' 'usb_endpoint', 0 seconds old
[6136] event_queue_insert: seq 1805 queued, 'add' 'usb_endpoint'
[6149] udev_device_new_from_syspath: device 0x8fe45c0 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2/2-2:1.0/usb_endpoint/usbdev2.16_ep09'
[6149] udev_rules_apply_to_event: LINK 'char/251:21' /lib/udev/rules.d/50-udev.rules:2
[6149] udev_rules_apply_to_event: RUN 'socket:@/org/freedesktop/hal/udev_event' /lib/udev/rules.d/90-hal.rules:2
[6149] udev_device_new_from_syspath: device 0x8fef060 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2/2-2:1.0'
[6149] udev_device_new_from_syspath: device 0x8fef1d8 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2'
[6149] udev_device_new_from_syspath: device 0x8fef330 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2'
[6149] udev_device_new_from_syspath: device 0x8fef488 has devpath '/devices/pci0000:00/0000:00:1a.0'
[6149] udev_device_new_from_syspath: device 0x8fef600 has devpath '/devices/pci0000:00'
[6149] udev_event_execute_rules: no node name set, will use kernel name 'usbdev2.16_ep09'
[6149] udev_device_update_db: create db link (usbdev2.16_ep09 char/251:21)
[6149] udev_node_add: creating device node '/dev/usbdev2.16_ep09', devnum=251:21, mode=0660, uid=0, gid=0
[6149] udev_node_mknod: mknod(/dev/usbdev2.16_ep09, 020660, (251,21))
[6149] udev_node_mknod: chmod(/dev/usbdev2.16_ep09, 020660)
[6149] udev_node_mknod: chown(/dev/usbdev2.16_ep09, 0, 0)
[6149] update_link: '/dev/char/251:21' with target '/dev/usbdev2.16_ep09' has the highest priority 0, create it
[6149] node_symlink: creating symlink '/dev/char/251:21' to '../usbdev2.16_ep09'
[6149] udev_monitor_send_device: passed 301 bytes to monitor 0x8fef8d8
[6149] udev_monitor_send_device: passed -1 bytes to monitor 0x8fe4648
[6149] event_fork: seq 1805 exit with 0
[6136] event_fork: seq 1805 forked, pid [6149], 'add' 'usb_endpoint', 0 seconds old
[6136] udev_done: seq 1805 cleanup, pid [6149], status 0, 0 seconds old
[6136] event_queue_insert: seq 1806 queued, 'add' 'usb_device'
[6150] udev_device_new_from_syspath: device 0x8fe45c0 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2/usb_device/usbdev2.16'
[6150] udev_device_new_from_syspath: device 0x8feefb0 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2'
[6150] udev_device_new_from_syspath: device 0x8fef098 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2'
[6150] udev_device_new_from_syspath: device 0x8fef1c0 has devpath '/devices/pci0000:00/0000:00:1a.0'
[6150] udev_device_new_from_syspath: device 0x8fef2e8 has devpath '/devices/pci0000:00'
[6150] udev_rules_apply_to_event: PROGRAM 'check-ptp-camera 06/01/01' /etc/udev/rules.d/025_libgphoto2.rules:967
[6150] util_run_program: 'check-ptp-camera 06/01/01'
[6150] util_run_program: '/lib/udev/check-ptp-camera' returned with status 1
[6150] udev_rules_apply_to_event: LINK 'char/189:143' /lib/udev/rules.d/50-udev.rules:2
[6150] udev_rules_apply_to_event: PROGRAM '/bin/sh -c 'K=usbdev2.16; K=${K#usbdev}; printf bus/usb/%03i/%03i ${K%%.*} ${K#*.}'' /lib/udev/rules.d/50-udev.rules:39
[6150] util_run_program: '/bin/sh -c 'K=usbdev2.16; K=${K#usbdev}; printf bus/usb/%03i/%03i ${K%%.*} ${K#*.}''
[6136] event_fork: seq 1806 forked, pid [6150], 'add' 'usb_device', 0 seconds old
[6136] event_queue_insert: seq 1807 queued, 'add' 'usb_endpoint'
[6136] event_fork: seq 1807 forked, pid [6153], 'add' 'usb_endpoint', 0 seconds old
[6153] udev_device_new_from_syspath: device 0x8fe45c0 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2/usb_endpoint/usbdev2.16_ep00'
[6153] udev_rules_apply_to_event: LINK 'char/251:22' /lib/udev/rules.d/50-udev.rules:2
[6153] udev_rules_apply_to_event: RUN 'socket:@/org/freedesktop/hal/udev_event' /lib/udev/rules.d/90-hal.rules:2
[6153] udev_device_new_from_syspath: device 0x8fef2d8 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2'
[6153] udev_device_new_from_syspath: device 0x8fef430 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2'
[6153] udev_device_new_from_syspath: device 0x8fef588 has devpath '/devices/pci0000:00/0000:00:1a.0'
[6153] udev_device_new_from_syspath: device 0x8fef700 has devpath '/devices/pci0000:00'
[6153] udev_event_execute_rules: no node name set, will use kernel name 'usbdev2.16_ep00'
[6153] udev_device_update_db: create db link (usbdev2.16_ep00 char/251:22)
[6153] udev_node_add: creating device node '/dev/usbdev2.16_ep00', devnum=251:22, mode=0660, uid=0, gid=0
[6153] udev_node_mknod: mknod(/dev/usbdev2.16_ep00, 020660, (251,22))
[6153] udev_node_mknod: chmod(/dev/usbdev2.16_ep00, 020660)
[6153] udev_node_mknod: chown(/dev/usbdev2.16_ep00, 0, 0)
[6153] update_link: '/dev/char/251:22' with target '/dev/usbdev2.16_ep00' has the highest priority 0, create it
[6153] node_symlink: creating symlink '/dev/char/251:22' to '../usbdev2.16_ep00'
[6153] udev_monitor_send_device: passed 285 bytes to monitor 0x8fef9a8
[6153] udev_monitor_send_device: passed -1 bytes to monitor 0x8fe4648
[6153] event_fork: seq 1807 exit with 0
[6136] udev_done: seq 1807 cleanup, pid [6153], status 0, 0 seconds old
[6150] util_run_program: '/bin/sh' (stdout) 'bus/usb/002/016'
[6150] util_run_program: '/bin/sh' returned with status 0
[6150] udev_rules_apply_to_event: NAME 'bus/usb/002/016' /lib/udev/rules.d/50-udev.rules:39
[6150] udev_rules_apply_to_event: RUN 'socket:@/org/freedesktop/hal/udev_event' /lib/udev/rules.d/90-hal.rules:2
[6150] udev_rules_apply_to_event: MODE 0664 /lib/udev/rules.d/91-permissions.rules:38
[6150] udev_device_update_db: create db link (bus/usb/002/016 char/189:143)
[6150] udev_node_add: creating device node '/dev/bus/usb/002/016', devnum=189:143, mode=0664, uid=0, gid=0
[6150] udev_node_mknod: preserve file '/dev/bus/usb/002/016', because it has correct dev_t
[6150] update_link: found 2 devices with name '/dev/char/189:143'
[6150] udev_device_new_from_syspath: device 0x8fef8b8 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2'
[6150] udev_device_read_db: device 0x8fef8b8 filled with db symlink data '/dev/bus/usb/002/016'
[6150] update_link: '/dev/char/189:143' with target '/dev/bus/usb/002/016' has the highest priority 0, create it
[6150] node_symlink: preserve already existing symlink '/dev/char/189:143' to '../bus/usb/002/016'
[6150] udev_monitor_send_device: passed 271 bytes to monitor 0x8ff0f48
[6150] udev_monitor_send_device: passed -1 bytes to monitor 0x8fe4648
[6150] event_fork: seq 1806 exit with 0
[6136] udev_done: seq 1806 cleanup, pid [6150], status 0, 0 seconds old
[6148] udev_device_new_from_syspath: device 0x8fe45c0 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2/2-2:1.0/usb_endpoint/usbdev2.16_ep88'
[6148] udev_rules_apply_to_event: LINK 'char/251:20' /lib/udev/rules.d/50-udev.rules:2
[6148] udev_rules_apply_to_event: RUN 'socket:@/org/freedesktop/hal/udev_event' /lib/udev/rules.d/90-hal.rules:2
[6148] udev_device_new_from_syspath: device 0x8feee28 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2/2-2:1.0'
[6148] udev_device_new_from_syspath: device 0x8feef40 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2'
[6148] udev_device_new_from_syspath: device 0x8fef038 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2'
[6148] udev_device_new_from_syspath: device 0x8fef150 has devpath '/devices/pci0000:00/0000:00:1a.0'
[6148] udev_device_new_from_syspath: device 0x8fef2c8 has devpath '/devices/pci0000:00'
[6148] udev_event_execute_rules: no node name set, will use kernel name 'usbdev2.16_ep88'
[6148] udev_device_update_db: create db link (usbdev2.16_ep88 char/251:20)
[6148] udev_node_add: creating device node '/dev/usbdev2.16_ep88', devnum=251:20, mode=0660, uid=0, gid=0
[6148] udev_node_mknod: mknod(/dev/usbdev2.16_ep88, 020660, (251,20))
[6148] udev_node_mknod: chmod(/dev/usbdev2.16_ep88, 020660)
[6148] udev_node_mknod: chown(/dev/usbdev2.16_ep88, 0, 0)
[6148] update_link: '/dev/char/251:20' with target '/dev/usbdev2.16_ep88' has the highest priority 0, create it
[6148] node_symlink: creating symlink '/dev/char/251:20' to '../usbdev2.16_ep88'
[6148] udev_monitor_send_device: passed 301 bytes to monitor 0x8feed78
[6148] udev_monitor_send_device: passed -1 bytes to monitor 0x8fe4648
[6148] event_fork: seq 1804 exit with 0
[6136] udev_done: seq 1804 cleanup, pid [6148], status 0, 0 seconds old
[6146] udev_rules_apply_to_event: RUN 'socket:@/org/freedesktop/hal/udev_event' /lib/udev/rules.d/90-hal.rules:2
[6146] udev_monitor_send_device: passed 191 bytes to monitor 0x8feeb00
[6146] udev_monitor_send_device: passed -1 bytes to monitor 0x8fe4648
[6146] event_fork: seq 1802 exit with 0
[6136] udev_done: seq 1802 cleanup, pid [6146], status 0, 0 seconds old
[6154] udev_rules_apply_to_event: RUN 'socket:@/org/freedesktop/hal/udev_event' /lib/udev/rules.d/90-hal.rules:2
[6154] udev_device_new_from_syspath: device 0x8feef00 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2/2-2:1.0/host8'
[6154] udev_monitor_send_device: passed 210 bytes to monitor 0x8feebf0
[6154] udev_monitor_send_device: passed -1 bytes to monitor 0x8fe4648
[6154] event_fork: seq 1803 exit with 0
[6136] event_fork: seq 1803 forked, pid [6154], 'add' 'scsi_host', 0 seconds old
[6136] udev_done: seq 1803 cleanup, pid [6154], status 0, 0 seconds old
[6136] event_queue_insert: seq 1808 queued, 'add' 'scsi'
[6159] udev_rules_apply_to_event: RUN 'socket:@/org/freedesktop/hal/udev_event' /lib/udev/rules.d/90-hal.rules:2
[6159] udev_monitor_send_device: passed 217 bytes to monitor 0x8feeb00
[6159] udev_monitor_send_device: passed -1 bytes to monitor 0x8fe4648
[6159] event_fork: seq 1808 exit with 0
[6136] event_fork: seq 1808 forked, pid [6159], 'add' 'scsi', 0 seconds old
[6136] udev_done: seq 1808 cleanup, pid [6159], status 0, 0 seconds old
[6136] event_queue_insert: seq 1809 queued, 'add' 'scsi'
[6136] event_fork: seq 1809 forked, pid [6160], 'add' 'scsi', 0 seconds old
[6160] udev_rules_apply_to_event: RUN '/sbin/modprobe -b $env{MODALIAS}' /lib/udev/rules.d/80-drivers.rules:10
[6160] udev_rules_apply_to_event: RUN 'socket:@/org/freedesktop/hal/udev_event' /lib/udev/rules.d/90-hal.rules:2
[6160] util_run_program: '/sbin/modprobe -b scsi:t-0x05'
[6160] util_run_program: '/sbin/modprobe' returned with status 0
[6160] udev_monitor_send_device: passed 254 bytes to monitor 0x8ff4f80
[6160] udev_monitor_send_device: passed -1 bytes to monitor 0x8fe4648
[6160] event_fork: seq 1809 exit with 0
[6136] udev_done: seq 1809 cleanup, pid [6160], status 0, 0 seconds old
[6136] event_queue_insert: seq 1810 queued, 'add' 'block'
[6136] event_fork: seq 1810 forked, pid [6162], 'add' 'block', 0 seconds old
[6136] event_queue_insert: seq 1811 queued, 'add' 'bdi'
[6162] udev_device_new_from_syspath: device 0x8ff4bc0 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2/2-2:1.0/host8/target8:0:0/8:0:0:0/block/sr1'
[6162] udev_rules_apply_to_event: LINK 'block/11:1' /lib/udev/rules.d/50-udev.rules:1
[6162] udev_node_mknod: mknod(/dev/.tmp-block-11:1, 060600, (11,1))
[6162] udev_node_mknod: chmod(/dev/.tmp-block-11:1, 060600)
[6162] udev_node_mknod: chown(/dev/.tmp-block-11:1, 0, 0)
[6162] udev_rules_apply_to_event: IMPORT 'cdrom_id --export /dev/.tmp-block-11:1' /lib/udev/rules.d/50-udev.rules:8
[6162] util_run_program: 'cdrom_id --export /dev/.tmp-block-11:1'
[6136] event_fork: seq 1811 forked, pid [6164], 'add' 'bdi', 0 seconds old
[6136] event_queue_insert: seq 1812 queued, 'add' 'scsi_device'
[6165] udev_device_new_from_syspath: device 0x8fe45c0 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2/2-2:1.0/host8/target8:0:0/8:0:0:0'
[6165] udev_device_new_from_syspath: device 0x8feed00 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2/2-2:1.0/host8/target8:0:0'
[6165] udev_device_new_from_syspath: device 0x8fef0f8 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2/2-2:1.0/host8'
[6164] udev_rules_apply_to_event: RUN 'socket:@/org/freedesktop/hal/udev_event' /lib/udev/rules.d/90-hal.rules:2
[6165] udev_device_new_from_syspath: device 0x8fef180 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2/2-2:1.0'
[6165] udev_device_new_from_syspath: device 0x8fef248 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2'
[6164] udev_monitor_send_device: [6165] udev_device_new_from_syspath: device 0x8fef350 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2'
[6165] udev_device_new_from_syspath: device 0x8fef488 has devpath '/devices/pci0000:00/0000:00:1a.0'
[6165] udev_device_new_from_syspath: device 0x8fef5b0 has devpath '/devices/pci0000:00'
[6165] udev_rules_apply_to_event: RUN '/sbin/modprobe -b sr_mod' /lib/udev/rules.d/80-drivers.rules:39
[6165] udev_device_new_from_syspath: device 0x8fef748 has devpath '/module/sg'
[6165] util_resolve_subsys_kernel: path '[module/sg](null)' is '/sys/module/sg'
[6165] udev_rules_apply_to_event: RUN 'socket:@/org/freedesktop/hal/udev_event' /lib/udev/rules.d/90-hal.rules:2
passed 112 bytes to monitor 0x8feeb00
[6165] util_run_program: '/sbin/modprobe -b sr_mod'
[6164] udev_monitor_send_device: passed -1 bytes to monitor 0x8fe4648
[6164] event_fork: seq 1811 exit with 0
[6136] event_fork: seq 1812 forked, pid [6165], 'add' 'scsi_device', 0 seconds old
[6136] event_queue_insert: seq 1813 queued, 'add' 'scsi_generic'
[6167] udev_device_new_from_syspath: device 0x8fe45c0 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2/2-2:1.0/host8/target8:0:0/8:0:0:0/scsi_generic/sg2'
[6167] udev_rules_apply_to_event: LINK 'char/21:2' /lib/udev/rules.d/50-udev.rules:2
[6167] udev_device_new_from_syspath: device 0x8fef1c8 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2/2-2:1.0/host8/target8:0:0/8:0:0:0'
[6167] udev_device_new_from_syspath: device 0x8fef378 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2/2-2:1.0/host8/target8:0:0'
[6167] udev_device_new_from_syspath: device 0x8fef550 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2/2-2:1.0/host8'
[6167] udev_device_new_from_syspath: device 0x8fef6d8 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2/2-2:1.0'
[6167] udev_device_new_from_syspath: device 0x8fef870 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2'
[6167] udev_device_new_from_syspath: device 0x8fef9c8 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2'
[6167] udev_device_new_from_syspath: device 0x8feff40 has devpath '/devices/pci0000:00/0000:00:1a.0'
[6167] udev_device_new_from_syspath: device 0x8ff0088 has devpath '/devices/pci0000:00'
[6167] udev_rules_apply_to_event: RUN 'socket:@/org/freedesktop/hal/udev_event' /lib/udev/rules.d/90-hal.rules:2
[6167] udev_rules_apply_to_event: GROUP 24 /lib/udev/rules.d/91-permissions.rules:29
[6167] udev_event_execute_rules: no node name set, will use kernel name 'sg2'
[6167] udev_device_update_db: create db link (sg2 char/21:2)
[6167] udev_node_add: creating device node '/dev/sg2', devnum=21:2, mode=0660, uid=0, gid=24
[6167] udev_node_mknod: mknod(/dev/sg2, 020660, (21,2))
[6167] udev_node_mknod: chmod(/dev/sg2, 020660)
[6167] udev_node_mknod: chown(/dev/sg2, 0, 24)
[6167] update_link: '/dev/char/21:2' with target '/dev/sg2' has the highest priority 0, create it
[6167] node_symlink: creating symlink '/dev/char/21:2' to '../sg2'
[6167] udev_monitor_send_device: passed 313 bytes to monitor 0x8fef0f8
[6167] udev_monitor_send_device: passed -1 bytes to monitor 0x8fe4648
[6167] event_fork: seq 1813 exit with 0
[6136] event_fork: seq 1813 forked, pid [6167], 'add' 'scsi_generic', 0 seconds old
[6136] udev_done: seq 1811 cleanup, pid [6164], status 0, 0 seconds old
[6136] udev_done: seq 1813 cleanup, pid [6167], status 0, 0 seconds old
[6136] event_queue_insert: seq 1814 queued, 'add' 'bsg'
[6168] udev_device_new_from_syspath: device 0x8fe45c0 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2/2-2:1.0/host8/target8:0:0/8:0:0:0/bsg/8:0:0:0'
[6168] udev_rules_apply_to_event: LINK 'char/253:2' /lib/udev/rules.d/50-udev.rules:2
[6168] udev_rules_apply_to_event: NAME 'bsg/8:0:0:0' /lib/udev/rules.d/50-udev.rules:20
[6168] udev_rules_apply_to_event: RUN 'socket:@/org/freedesktop/hal/udev_event' /lib/udev/rules.d/90-hal.rules:2
[6168] udev_device_new_from_syspath: device 0x8fef0f8 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2/2-2:1.0/host8/target8:0:0/8:0:0:0'
[6168] udev_device_new_from_syspath: device 0x8fef228 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2/2-2:1.0/host8/target8:0:0'
[6168] udev_device_new_from_syspath: device 0x8fef350 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2/2-2:1.0/host8'
[6168] udev_device_new_from_syspath: device 0x8fef4d8 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2/2-2:1.0'
[6168] udev_device_new_from_syspath: device 0x8fef670 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2'
[6168] udev_device_new_from_syspath: device 0x8fef7c8 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2'
[6168] udev_device_new_from_syspath: device 0x8fef920 has devpath '/devices/pci0000:00/0000:00:1a.0'
[6168] udev_device_new_from_syspath: device 0x8fefa98 has devpath '/devices/pci0000:00'
[6168] udev_rules_apply_to_event: GROUP 24 /lib/udev/rules.d/91-permissions.rules:29
[6168] udev_device_update_db: create db link (bsg/8:0:0:0 char/253:2)
[6168] udev_node_add: creating device node '/dev/bsg/8:0:0:0', devnum=253:2, mode=0660, uid=0, gid=24
[6168] udev_node_mknod: mknod(/dev/bsg/8:0:0:0, 020660, (253,2))
[6168] udev_node_mknod: chmod(/dev/bsg/8:0:0:0, 020660)
[6168] udev_node_mknod: chown(/dev/bsg/8:0:0:0, 0, 24)
[6168] update_link: '/dev/char/253:2' with target '/dev/bsg/8:0:0:0' has the highest priority 0, create it
[6168] node_symlink: creating symlink '/dev/char/253:2' to '../bsg/8:0:0:0'
[6168] udev_monitor_send_device: passed 304 bytes to monitor 0x8feed18
[6168] udev_monitor_send_device: passed -1 bytes to monitor 0x8fe4648
[6168] event_fork: seq 1814 exit with 0
[6136] event_fork: seq 1814 forked, pid [6168], 'add' 'bsg', 0 seconds old
[6136] udev_done: seq 1814 cleanup, pid [6168], status 0, 0 seconds old
[6165] util_run_program: '/sbin/modprobe' returned with status 0
[6165] udev_monitor_send_device: passed 260 bytes to monitor 0x8fefa58
[6165] udev_monitor_send_device: passed -1 bytes to monitor 0x8fe4648
[6165] event_fork: seq 1812 exit with 0
[6136] udev_done: seq 1812 cleanup, pid [6165], status 0, 0 seconds old
[6162] util_run_program: '/lib/udev/cdrom_id' (stdout) 'ID_CDROM=1'
[6162] util_run_program: '/lib/udev/cdrom_id' returned with status 0
[6162] udev_device_new_from_syspath: device 0x8ff50d0 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2/2-2:1.0/host8/target8:0:0/8:0:0:0'
[6162] udev_rules_apply_to_event: LINK 'scd1' /lib/udev/rules.d/50-udev.rules:19
[6162] udev_device_new_from_syspath: device 0x8feed68 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2/2-2:1.0/host8/target8:0:0'
[6162] udev_device_new_from_syspath: device 0x8ff5188 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2/2-2:1.0/host8'
[6162] udev_device_new_from_syspath: device 0x8ff5378 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2/2-2:1.0'
[6162] udev_device_new_from_syspath: device 0x8fef088 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2'
[6162] udev_device_new_from_syspath: device 0x8fef208 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2'
[6162] udev_device_new_from_syspath: device 0x8fef380 has devpath '/devices/pci0000:00/0000:00:1a.0'
[6162] udev_device_new_from_syspath: device 0x8fef4e0 has devpath '/devices/pci0000:00'
[6162] udev_rules_apply_to_event: IMPORT 'usb_id --export /devices/pci0000:00/0000:00:1a.0/usb2/2-2/2-2:1.0/host8/target8:0:0/8:0:0:0/block/sr1' /lib/udev/rules.d/60-persistent-storage.rules:40
[6162] util_run_program: 'usb_id --export /devices/pci0000:00/0000:00:1a.0/usb2/2-2/2-2:1.0/host8/target8:0:0/8:0:0:0/block/sr1'
[6162] util_run_program: '/lib/udev/usb_id' (stdout) 'ID_VENDOR=Novatel'
[6162] util_run_program: '/lib/udev/usb_id' (stdout) 'ID_VENDOR_ENC=Novatel\x20'
[6162] util_run_program: '/lib/udev/usb_id' (stdout) 'ID_VENDOR_ID=1410'
[6162] util_run_program: '/lib/udev/usb_id' (stdout) 'ID_MODEL=Mass_Storage'
[6162] util_run_program: '/lib/udev/usb_id' (stdout) 'ID_MODEL_ENC=Mass\x20Storage\x20\x20\x20\x20'
[6162] util_run_program: '/lib/udev/usb_id' (stdout) 'ID_MODEL_ID=5010'
[6162] util_run_program: '/lib/udev/usb_id' (stdout) 'ID_REVISION=1.00'
[6162] util_run_program: '/lib/udev/usb_id' (stdout) 'ID_SERIAL=Novatel_Mass_Storage_356922010299400-0:0'
[6162] util_run_program: '/lib/udev/usb_id' (stdout) 'ID_SERIAL_SHORT=356922010299400'
[6162] util_run_program: '/lib/udev/usb_id' (stdout) 'ID_TYPE=cd'
[6162] util_run_program: '/lib/udev/usb_id' (stdout) 'ID_INSTANCE=0:0'
[6162] util_run_program: '/lib/udev/usb_id' (stdout) 'ID_BUS=usb'
[6162] util_run_program: '/lib/udev/usb_id' (stdout) 'ID_USB_INTERFACES=:080650:'
[6162] util_run_program: '/lib/udev/usb_id' (stdout) 'ID_USB_INTERFACE_NUM=00'
[6162] util_run_program: '/lib/udev/usb_id' (stdout) 'ID_USB_DRIVER=usb-storage'
[6162] util_run_program: '/lib/udev/usb_id' returned with status 0
[6162] udev_rules_apply_to_event: IMPORT 'path_id /devices/pci0000:00/0000:00:1a.0/usb2/2-2/2-2:1.0/host8/target8:0:0/8:0:0:0/block/sr1' /lib/udev/rules.d/60-persistent-storage.rules:58
[6162] util_run_program: 'path_id /devices/pci0000:00/0000:00:1a.0/usb2/2-2/2-2:1.0/host8/target8:0:0/8:0:0:0/block/sr1'
[6162] util_run_program: '/lib/udev/path_id' (stdout) 'ID_PATH=pci-0000:00:1a.0-usb-0:2:1.0-scsi-0:0:0:0'
[6162] util_run_program: '/lib/udev/path_id' returned with status 0
[6162] udev_rules_apply_to_event: LINK 'disk/by-id/usb-Novatel_Mass_Storage_356922010299400-0:0' /lib/udev/rules.d/60-persistent-storage.rules:89
[6162] udev_rules_apply_to_event: LINK 'disk/by-path/pci-0000:00:1a.0-usb-0:2:1.0-scsi-0:0:0:0' /lib/udev/rules.d/60-persistent-storage.rules:94
[6162] udev_rules_apply_to_event: LINK 'cdrom' /etc/udev/rules.d/70-persistent-cd.rules:13
[6162] udev_rules_apply_to_event: RUN '/usr/bin/eject %k' /etc/udev/rules.d/70-persistent-cd.rules:13
[6162] udev_rules_apply_to_event: RUN 'socket:@/org/freedesktop/hal/udev_event' /lib/udev/rules.d/90-hal.rules:2
[6162] udev_rules_apply_to_event: GROUP 6 /lib/udev/rules.d/91-permissions.rules:9
[6162] udev_rules_apply_to_event: GROUP 25 /lib/udev/rules.d/91-permissions.rules:10
[6162] udev_rules_apply_to_event: GROUP 25 /lib/udev/rules.d/91-permissions.rules:14
[6162] udev_rules_apply_to_event: GROUP 24 /lib/udev/rules.d/91-permissions.rules:19
[6162] udev_rules_apply_to_event: GROUP 24 /lib/udev/rules.d/91-permissions.rules:29
[6162] udev_event_execute_rules: no node name set, will use kernel name 'sr1'
[6162] udev_device_update_db: created db file for '/devices/pci0000:00/0000:00:1a.0/usb2/2-2/2-2:1.0/host8/target8:0:0/8:0:0:0/block/sr1' in '/dev/.udev/db/\x2fdevices\x2fpci0000:00\x2f0000:00:1a.0\x2fusb2\x2f2-2\x2f2-2:1.0\x2fhost8\x2ftarget8:0:0\x2f8:0:0:0\x2fblock\x2fsr1'
[6162] udev_node_add: creating device node '/dev/sr1', devnum=11:1, mode=0660, uid=0, gid=24
[6162] udev_node_mknod: mknod(/dev/sr1, 060660, (11,1))
[6162] udev_node_mknod: chmod(/dev/sr1, 060660)
[6162] udev_node_mknod: chown(/dev/sr1, 0, 24)
[6162] update_link: '/dev/block/11:1' with target '/dev/sr1' has the highest priority 0, create it
[6162] node_symlink: creating symlink '/dev/block/11:1' to '../sr1'
[6162] update_link: '/dev/scd1' with target '/dev/sr1' has the highest priority 0, create it
[6162] node_symlink: creating symlink '/dev/scd1' to 'sr1'
[6162] update_link: '/dev/disk/by-id/usb-Novatel_Mass_Storage_356922010299400-0:0' with target '/dev/sr1' has the highest priority 0, create it
[6162] node_symlink: creating symlink '/dev/disk/by-id/usb-Novatel_Mass_Storage_356922010299400-0:0' to '../../sr1'
[6162] update_link: '/dev/disk/by-path/pci-0000:00:1a.0-usb-0:2:1.0-scsi-0:0:0:0' with target '/dev/sr1' has the highest priority 0, create it
[6162] node_symlink: creating symlink '/dev/disk/by-path/pci-0000:00:1a.0-usb-0:2:1.0-scsi-0:0:0:0' to '../../sr1'
[6162] update_link: found 2 devices with name '/dev/cdrom'
[6162] udev_device_new_from_syspath: device 0x8ff1660 has devpath '/devices/pci0000:00/0000:00:1f.2/host1/target1:0:0/1:0:0:0/block/sr0'
[6162] udev_device_read_db: device 0x8ff1660 filled with db file data
[6162] update_link: '/dev/cdrom' with target '/dev/sr1' has the highest priority 0, create it
[6162] node_symlink: atomically replace '/dev/cdrom'
[6162] util_run_program: '/usr/bin/eject sr1'
[6162] util_run_program: '/usr/bin/eject' returned with status 0
[6162] udev_monitor_send_device: passed 882 bytes to monitor 0x8ff4bc0
[6162] udev_monitor_send_device: passed -1 bytes to monitor 0x8fe4648
[6162] event_fork: seq 1810 exit with 0
[6136] udev_done: seq 1810 cleanup, pid [6162], status 0, 0 seconds old
[6136] event_queue_insert: seq 1815 queued, 'remove' 'usb_endpoint'
[6136] event_fork: seq 1815 forked, pid [6186], 'remove' 'usb_endpoint', 0 seconds old
[6136] event_queue_insert: seq 1816 queued, 'remove' 'usb_endpoint'
[6186] udev_device_read_db: device 0x8ff4bc0 filled with db symlink data '/dev/usbdev2.16_ep88'
[6186] udev_rules_apply_to_event: RUN 'socket:@/org/freedesktop/hal/udev_event' /lib/udev/rules.d/90-hal.rules:2
[6186] update_link: no reference left, remove '/dev/char/251:20'
[6186] udev_node_remove: removing device node '/dev/usbdev2.16_ep88'
[6187] udev_device_read_db: device 0x8ff4e60 filled with db symlink data '/dev/usbdev2.16_ep09'
[6186] udev_monitor_send_device: passed 307 bytes to monitor 0x8feeb00
[6186] udev_monitor_send_device: passed -1 bytes to monitor 0x8fe4648
[6186] event_fork: [6187] udev_rules_apply_to_event: seq 1815 exit with 0
RUN 'socket:@/org/freedesktop/hal/udev_event' /lib/udev/rules.d/90-hal.rules:2
[6187] update_link: no reference left, remove '/dev/char/251:21'
[6187] udev_node_remove: removing device node '/dev/usbdev2.16_ep09'
[6187] udev_monitor_send_device: passed 307 bytes to monitor 0x8feebb0
[6187] udev_monitor_send_device: passed -1 bytes to monitor 0x8fe4648
[6187] event_fork: seq 1816 exit with 0
[6136] event_fork: seq 1816 forked, pid [6187], 'remove' 'usb_endpoint', 0 seconds old
[6136] udev_done: seq 1815 cleanup, pid [6186], status 0, 0 seconds old
[6136] udev_done: seq 1816 cleanup, pid [6187], status 0, 0 seconds old
[6136] event_queue_insert: seq 1817 queued, 'remove' 'bsg'
[6190] udev_device_read_db: device 0x8feef00 filled with db symlink data '/dev/bsg/8:0:0:0'
[6190] udev_rules_apply_to_event: RUN 'socket:@/org/freedesktop/hal/udev_event' /lib/udev/rules.d/90-hal.rules:2
[6190] update_link: no reference left, remove '/dev/char/253:2'
[6190] udev_node_remove: removing device node '/dev/bsg/8:0:0:0'
[6190] udev_monitor_send_device: passed 310 bytes to monitor 0x8feeb00
[6190] udev_monitor_send_device: passed -1 bytes to monitor 0x8fe4648
[6190] event_fork: seq 1817 exit with 0
[6136] event_fork: seq 1817 forked, pid [6190], 'remove' 'bsg', 0 seconds old
[6136] udev_done: seq 1817 cleanup, pid [6190], status 0, 0 seconds old
[6136] event_queue_insert: seq 1818 queued, 'remove' 'scsi_generic'
[6191] udev_device_read_db: device 0x8ff4e60 filled with db symlink data '/dev/sg2'
[6191] udev_rules_apply_to_event: RUN 'socket:@/org/freedesktop/hal/udev_event' /lib/udev/rules.d/90-hal.rules:2
[6191] update_link: no reference left, remove '/dev/char/21:2'
[6191] udev_node_remove: removing device node '/dev/sg2'
[6191] udev_monitor_send_device: passed 319 bytes to monitor 0x8feeb00
[6191] udev_monitor_send_device: passed -1 bytes to monitor 0x8fe4648
[6191] event_fork: seq 1818 exit with 0
[6136] event_fork: seq 1818 forked, pid [6191], 'remove' 'scsi_generic', 0 seconds old
[6136] udev_done: seq 1818 cleanup, pid [6191], status 0, 0 seconds old
[6136] event_queue_insert: seq 1819 queued, 'remove' 'scsi_device'
[6193] udev_rules_apply_to_event: RUN 'socket:@/org/freedesktop/hal/udev_event' /lib/udev/rules.d/90-hal.rules:2
[6193] udev_monitor_send_device: passed 266 bytes to monitor 0x8feeb00
[6193] udev_monitor_send_device: passed -1 bytes to monitor 0x8fe4648
[6193] event_fork: seq 1819 exit with 0
[6136] event_fork: seq 1819 forked, pid [6193], 'remove' 'scsi_device', 0 seconds old
[6136] udev_done: seq 1819 cleanup, pid [6193], status 0, 0 seconds old
[6136] event_queue_insert: seq 1820 queued, 'remove' 'bdi'
[6194] udev_rules_apply_to_event: RUN 'socket:@/org/freedesktop/hal/udev_event' /lib/udev/rules.d/90-hal.rules:2
[6194] udev_monitor_send_device: passed 118 bytes to monitor 0x8feeb00
[6194] udev_monitor_send_device: passed -1 bytes to monitor 0x8fe4648
[6194] event_fork: seq 1820 exit with 0
[6136] event_fork: seq 1820 forked, pid [6194], 'remove' 'bdi', 0 seconds old
[6136] udev_done: seq 1820 cleanup, pid [6194], status 0, 0 seconds old
[6136] event_queue_insert: seq 1821 queued, 'remove' 'block'
[6195] udev_device_read_db: device 0x8ff4bc0 filled with db file data
[6195] udev_rules_apply_to_event: RUN 'socket:@/org/freedesktop/hal/udev_event' /lib/udev/rules.d/90-hal.rules:2
[6195] update_link: no reference left, remove '/dev/block/11:1'
[6195] update_link: no reference left, remove '/dev/scd1'
[6195] update_link: no reference left, remove '/dev/disk/by-id/usb-Novatel_Mass_Storage_356922010299400-0:0'
[6195] update_link: no reference left, remove '/dev/disk/by-path/pci-0000:00:1a.0-usb-0:2:1.0-scsi-0:0:0:0'
[6195] udev_device_new_from_syspath: device 0x8ff5368 has devpath '/devices/pci0000:00/0000:00:1f.2/host1/target1:0:0/1:0:0:0/block/sr0'
[6195] udev_device_read_db: device 0x8ff5368 filled with db file data
[6195] update_link: '/dev/cdrom' with target '/dev/sr0' has the highest priority 0, create it
[6195] node_symlink: atomically replace '/dev/cdrom'
[6195] udev_node_remove: removing device node '/dev/sr1'
[6195] udev_monitor_send_device: passed 888 bytes to monitor 0x8ff4e48
[6195] udev_monitor_send_device: passed -1 bytes to monitor 0x8fe4648
[6195] event_fork: seq 1821 exit with 0
[6136] event_fork: seq 1821 forked, pid [6195], 'remove' 'block', 0 seconds old
[6136] udev_done: seq 1821 cleanup, pid [6195], status 0, 0 seconds old
[6136] event_queue_insert: seq 1822 queued, 'remove' 'scsi'
[6196] udev_rules_apply_to_event: RUN 'socket:@/org/freedesktop/hal/udev_event' /lib/udev/rules.d/90-hal.rules:2
[6196] udev_monitor_send_device: passed 260 bytes to monitor 0x8feeb00
[6196] udev_monitor_send_device: passed -1 bytes to monitor 0x8fe4648
[6196] event_fork: seq 1822 exit with 0
[6136] event_fork: seq 1822 forked, pid [6196], 'remove' 'scsi', 0 seconds old
[6136] udev_done: seq 1822 cleanup, pid [6196], status 0, 0 seconds old
[6136] event_queue_insert: seq 1823 queued, 'remove' 'scsi_host'
[6197] udev_rules_apply_to_event: RUN 'socket:@/org/freedesktop/hal/udev_event' /lib/udev/rules.d/90-hal.rules:2
[6197] udev_monitor_send_device: passed 216 bytes to monitor 0x8feeb00
[6197] udev_monitor_send_device: passed -1 bytes to monitor 0x8fe4648
[6197] event_fork: seq 1823 exit with 0
[6136] event_fork: seq 1823 forked, pid [6197], 'remove' 'scsi_host', 0 seconds old
[6136] udev_done: seq 1823 cleanup, pid [6197], status 0, 0 seconds old
[6136] event_queue_insert: seq 1824 queued, 'remove' 'scsi'
[6198] udev_rules_apply_to_event: RUN 'socket:@/org/freedesktop/hal/udev_event' /lib/udev/rules.d/90-hal.rules:2
[6198] udev_monitor_send_device: passed 197 bytes to monitor 0x8feeb00
[6198] udev_monitor_send_device: passed -1 bytes to monitor 0x8fe4648
[6198] event_fork: seq 1824 exit with 0
[6136] event_fork: seq 1824 forked, pid [6198], 'remove' 'scsi', 0 seconds old
[6136] udev_done: seq 1824 cleanup, pid [6198], status 0, 0 seconds old
[6136] event_queue_insert: seq 1825 queued, 'remove' 'usb'
[6199] udev_rules_apply_to_event: RUN 'socket:@/org/freedesktop/hal/udev_event' /lib/udev/rules.d/90-hal.rules:2
[6199] udev_device_new_from_syspath: device 0x8feef00 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2'
[6199] udev_device_new_from_syspath: device 0x8ff4bc0 has devpath '/devices/pci0000:00/0000:00:1a.0'
[6199] udev_monitor_send_device: passed 320 bytes to monitor 0x8feeb00
[6199] udev_monitor_send_device: passed -1 bytes to monitor 0x8fe4648
[6199] event_fork: seq 1825 exit with 0
[6136] event_fork: seq 1825 forked, pid [6199], 'remove' 'usb', 0 seconds old
[6136] udev_done: seq 1825 cleanup, pid [6199], status 0, 0 seconds old
[6136] event_queue_insert: seq 1826 queued, 'remove' 'scsi'
[6200] udev_rules_apply_to_event: RUN 'socket:@/org/freedesktop/hal/udev_event' /lib/udev/rules.d/90-hal.rules:2
[6200] udev_monitor_send_device: passed 125 bytes to monitor 0x8feeb00
[6200] udev_monitor_send_device: passed -1 bytes to monitor 0x8fe4648
[6200] event_fork: seq 1826 exit with 0
[6136] event_fork: seq 1826 forked, pid [6200], 'remove' 'scsi', 0 seconds old
[6136] udev_done: seq 1826 cleanup, pid [6200], status 0, 0 seconds old
[6136] event_queue_insert: seq 1827 queued, 'remove' 'usb_endpoint'
[6203] udev_device_read_db: device 0x8ff4bc0 filled with db symlink data '/dev/usbdev2.16_ep00'
[6203] udev_rules_apply_to_event: RUN 'socket:@/org/freedesktop/hal/udev_event' /lib/udev/rules.d/90-hal.rules:2
[6203] update_link: no reference left, remove '/dev/char/251:22'
[6203] udev_node_remove: removing device node '/dev/usbdev2.16_ep00'
[6203] udev_monitor_send_device: passed 291 bytes to monitor 0x8feeb00
[6203] udev_monitor_send_device: passed -1 bytes to monitor 0x8fe4648
[6203] event_fork: seq 1827 exit with 0
[6136] event_fork: seq 1827 forked, pid [6203], 'remove' 'usb_endpoint', 0 seconds old
[6136] udev_done: seq 1827 cleanup, pid [6203], status 0, 0 seconds old
[6136] event_queue_insert: seq 1828 queued, 'remove' 'usb_device'
[6205] udev_device_read_db: device 0x8ff4e60 filled with db symlink data '/dev/bus/usb/002/016'
[6205] udev_rules_apply_to_event: RUN 'socket:@/org/freedesktop/hal/udev_event' /lib/udev/rules.d/90-hal.rules:2
[6205] update_link: no current target for '/dev/char/189:143' found
[6205] udev_node_remove: removing device node '/dev/bus/usb/002/016'
[6205] udev_monitor_send_device: passed 277 bytes to monitor 0x8ff4bc0
[6205] udev_monitor_send_device: passed -1 bytes to monitor 0x8fe4648
[6205] event_fork: seq 1828 exit with 0
[6136] event_fork: seq 1828 forked, pid [6205], 'remove' 'usb_device', 0 seconds old
[6136] udev_done: seq 1828 cleanup, pid [6205], status 0, 0 seconds old
[6136] event_queue_insert: seq 1829 queued, 'remove' 'usb'
[6206] udev_device_read_db: device 0x8feef00 filled with db symlink data '/dev/bus/usb/002/016'
[6206] udev_rules_apply_to_event: RUN 'socket:@/org/freedesktop/hal/udev_event' /lib/udev/rules.d/90-hal.rules:2
[6206] udev_device_new_from_syspath: device 0x8ff4bc0 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2'
[6206] udev_device_new_from_syspath: device 0x8ff4e60 has devpath '/devices/pci0000:00/0000:00:1a.0'
[6206] update_link: no reference left, remove '/dev/char/189:143'
[6206] udev_node_remove: device node '/dev/bus/usb/002/016' not found
[6206] udev_monitor_send_device: passed 327 bytes to monitor 0x8feeb00
[6206] udev_monitor_send_device: passed -1 bytes to monitor 0x8fe4648
[6206] event_fork: seq 1829 exit with 0
[6136] event_fork: seq 1829 forked, pid [6206], 'remove' 'usb', 0 seconds old
[6136] udev_done: seq 1829 cleanup, pid [6206], status 0, 0 seconds old
[6136] event_queue_insert: seq 1830 queued, 'add' 'usb'
[6136] event_fork: seq 1830 forked, pid [6218], 'add' 'usb', 0 seconds old
[6218] udev_device_new_from_syspath: device 0x8ff4e60 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2'
[6218] udev_device_new_from_syspath: device 0x8feef00 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2'
[6218] udev_device_new_from_syspath: device 0x8fe45c0 has devpath '/devices/pci0000:00/0000:00:1a.0'
[6218] udev_device_new_from_syspath: device 0x8feeb00 has devpath '/devices/pci0000:00'
[6218] udev_rules_apply_to_event: PROGRAM 'check-ptp-camera 06/01/01' /etc/udev/rules.d/025_libgphoto2.rules:967
[6218] util_run_program: 'check-ptp-camera 06/01/01'
[6218] util_run_program: '/lib/udev/check-ptp-camera' returned with status 1
[6218] udev_rules_apply_to_event: LINK 'char/189:144' /lib/udev/rules.d/50-udev.rules:2
[6218] udev_rules_apply_to_event: NAME 'bus/usb/002/017' /lib/udev/rules.d/50-udev.rules:35
[6218] udev_rules_apply_to_event: RUN 'socket:@/org/freedesktop/hal/udev_event' /lib/udev/rules.d/90-hal.rules:2
[6218] udev_rules_apply_to_event: MODE 0664 /lib/udev/rules.d/91-permissions.rules:40
[6218] udev_device_update_db: create db link (bus/usb/002/017 char/189:144)
[6218] udev_node_add: creating device node '/dev/bus/usb/002/017', devnum=189:144, mode=0664, uid=0, gid=0
[6218] udev_node_mknod: mknod(/dev/bus/usb/002/017, 020664, (189,144))
[6218] udev_node_mknod: chmod(/dev/bus/usb/002/017, 020664)
[6218] udev_node_mknod: chown(/dev/bus/usb/002/017, 0, 0)
[6218] update_link: '/dev/char/189:144' with target '/dev/bus/usb/002/017' has the highest priority 0, create it
[6218] node_symlink: creating symlink '/dev/char/189:144' to '../bus/usb/002/017'
[6136] event_queue_insert: seq 1831 queued, 'add' 'usb'
[6136] event_queue_insert: seq 1832 queued, 'add' 'usb-serial'
[6136] event_queue_insert: seq 1833 queued, 'add' 'tty'
[6136] event_queue_insert: seq 1834 queued, 'add' 'usb_endpoint'
[6136] event_queue_insert: seq 1835 queued, 'add' 'usb_endpoint'
[6136] event_queue_insert: seq 1836 queued, 'add' 'usb_endpoint'
[6218] udev_monitor_send_device: passed 321 bytes to monitor 0x8ff4e48
[6218] udev_monitor_send_device: passed -1 bytes to monitor 0x8fe4648
[6136] event_queue_insert: seq 1837 queued, 'add' 'usb'
[6136] event_queue_insert: seq 1838 queued, 'add' 'usb-serial'
[6136] event_queue_insert: seq 1839 queued, 'add' 'tty'
[6136] event_queue_insert: seq 1840 queued, 'add' 'usb_endpoint'
[6136] event_queue_insert: seq 1841 queued, 'add' 'usb_endpoint'
[6136] event_queue_insert: seq 1842 queued, 'add' 'usb_device'
[6136] event_queue_insert: seq 1843 queued, 'add' 'usb_endpoint'
[6218] event_fork: seq 1830 exit with 0
[6136] udev_done: seq 1830 cleanup, pid [6218], status 0, 0 seconds old
[6220] udev_rules_apply_to_event: RUN '/sbin/modprobe -b $env{MODALIAS}' /lib/udev/rules.d/80-drivers.rules:10
[6220] udev_rules_apply_to_event: RUN 'socket:@/org/freedesktop/hal/udev_event' /lib/udev/rules.d/90-hal.rules:2
[6220] udev_device_new_from_syspath: device 0x8ff4bc0 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2'
[6220] udev_device_new_from_syspath: device 0x8fe45c0 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2'
[6220] udev_device_new_from_syspath: device 0x8ff16f0 has devpath '/devices/pci0000:00/0000:00:1a.0'
[6220] udev_device_new_from_syspath: device 0x8ff17c0 has devpath '/devices/pci0000:00'
[6220] util_run_program: '/sbin/modprobe -b usb:v1410p1450d0000dc00dsc00dp00icFFiscFFipFF'
[6136] event_fork: seq 1831 forked, pid [6220], 'add' 'usb', 0 seconds old
[6222] udev_rules_apply_to_event: RUN '/sbin/modprobe -b $env{MODALIAS}' /lib/udev/rules.d/80-drivers.rules:10
[6222] udev_rules_apply_to_event: RUN 'socket:@/org/freedesktop/hal/udev_event' /lib/udev/rules.d/90-hal.rules:2
[6222] udev_device_new_from_syspath: device 0x8ff4bc0 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2'
[6222] udev_device_new_from_syspath: device 0x8fe45c0 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2'
[6222] udev_device_new_from_syspath: device 0x8ff16f0 has devpath '/devices/pci0000:00/0000:00:1a.0'
[6222] udev_device_new_from_syspath: device 0x8ff17c0 has devpath '/devices/pci0000:00'
[6222] util_run_program: '/sbin/modprobe -b usb:v1410p1450d0000dc00dsc00dp00icFFiscFFipFF'
[6136] event_fork: seq 1837 forked, pid [6222], 'add' 'usb', 0 seconds old
[6224] udev_device_new_from_syspath: device 0x8ff4bc0 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2/usb_device/usbdev2.17'
[6224] udev_device_new_from_syspath: device 0x8fe45c0 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2'
[6224] udev_device_new_from_syspath: device 0x8ff16f0 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2'
[6224] udev_device_new_from_syspath: device 0x8ff1778 has devpath '/devices/pci0000:00/0000:00:1a.0'
[6224] udev_device_new_from_syspath: device 0x8ff1818 has devpath '/devices/pci0000:00'
[6224] udev_rules_apply_to_event: PROGRAM 'check-ptp-camera 06/01/01' /etc/udev/rules.d/025_libgphoto2.rules:967
[6224] util_run_program: 'check-ptp-camera 06/01/01'
[6224] util_run_program: '/lib/udev/check-ptp-camera' returned with status 1
[6224] udev_rules_apply_to_event: LINK 'char/189:144' /lib/udev/rules.d/50-udev.rules:2
[6224] udev_rules_apply_to_event: PROGRAM '/bin/sh -c 'K=usbdev2.17; K=${K#usbdev}; printf bus/usb/%03i/%03i ${K%%.*} ${K#*.}'' /lib/udev/rules.d/50-udev.rules:39
[6224] util_run_program: '/bin/sh -c 'K=usbdev2.17; K=${K#usbdev}; printf bus/usb/%03i/%03i ${K%%.*} ${K#*.}''
[6136] event_fork: seq 1842 forked, pid [6224], 'add' 'usb_device', 0 seconds old
[6136] event_fork: seq 1843 forked, pid [6227], 'add' 'usb_endpoint', 0 seconds old
[6227] udev_device_new_from_syspath: device 0x8ff4bc0 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2/usb_endpoint/usbdev2.17_ep00'
[6227] udev_rules_apply_to_event: LINK 'char/251:32' /lib/udev/rules.d/50-udev.rules:2
[6227] udev_rules_apply_to_event: RUN 'socket:@/org/freedesktop/hal/udev_event' /lib/udev/rules.d/90-hal.rules:2
[6222] util_run_program: '/sbin/modprobe' returned with status 0
[6222] udev_monitor_send_device: passed 319 bytes to monitor 0x8ff2a90
[6222] udev_monitor_send_device: passed -1 bytes to monitor 0x8fe4648
[6222] event_fork: seq 1837 exit with 0
[6136] udev_done: seq 1837 cleanup, pid [6222], status 0, 0 seconds old
[6136] event_fork: seq 1838 forked, pid [6228], 'add' 'usb-serial', 0 seconds old
[6136] event_fork: seq 1840 forked, pid [6229], 'add' 'usb_endpoint', 0 seconds old
[6136] event_fork: seq 1841 forked, pid [6230], 'add' 'usb_endpoint', 0 seconds old
[6227] udev_device_new_from_syspath: device 0x8fe45c0 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2'
[6227] udev_device_new_from_syspath: device 0x8ff16f0 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2'
[6227] udev_device_new_from_syspath: device 0x8ff1778 has devpath '/devices/pci0000:00/0000:00:1a.0'
[6227] udev_device_new_from_syspath: device 0x8ff1868 has devpath '/devices/pci0000:00'
[6227] udev_event_execute_rules: no node name set, will use kernel name 'usbdev2.17_ep00'
[6227] udev_device_update_db: create db link (usbdev2.17_ep00 char/251:32)
[6227] udev_node_add: creating device node '/dev/usbdev2.17_ep00', devnum=251:32, mode=0660, uid=0, gid=0
[6227] udev_node_mknod: mknod(/dev/usbdev2.17_ep00, 020660, (251,32))
[6227] udev_node_mknod: chmod(/dev/usbdev2.17_ep00, 020660)
[6227] udev_node_mknod: chown(/dev/usbdev2.17_ep00, 0, 0)
[6227] update_link: '/dev/char/251:32' with target '/dev/usbdev2.17_ep00' has the highest priority 0, create it
[6227] node_symlink: creating symlink '/dev/char/251:32' to '../usbdev2.17_ep00'
[6227] udev_monitor_send_device: passed 285 bytes to monitor 0x8ff1b20
[6227] udev_monitor_send_device: passed -1 bytes to monitor 0x8fe4648
[6227] event_fork: seq 1843 exit with 0
[6136] udev_done: seq 1843 cleanup, pid [6227], status 0, 0 seconds old
[6228] udev_rules_apply_to_event: RUN 'socket:@/org/freedesktop/hal/udev_event' /lib/udev/rules.d/90-hal.rules:2
[6228] udev_device_new_from_syspath: device 0x8ff4bc0 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2/2-2:1.1'
[6228] udev_device_new_from_syspath: device 0x8feff40 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2'
[6228] udev_device_new_from_syspath: device 0x8fe45c0 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2'
[6228] udev_device_new_from_syspath: device 0x8ff16f0 has devpath '/devices/pci0000:00/0000:00:1a.0'
[6228] udev_device_new_from_syspath: device 0x8ff17a8 has devpath '/devices/pci0000:00'
[6228] udev_monitor_send_device: passed 183 bytes to monitor 0x8ff1830
[6228] udev_monitor_send_device: passed -1 bytes to monitor 0x8fe4648
[6228] event_fork: seq 1838 exit with 0
[6136] udev_done: seq 1838 cleanup, pid [6228], status 0, 0 seconds old
[6231] udev_device_new_from_syspath: device 0x8ff4bc0 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2/2-2:1.1/ttyUSB1/tty/ttyUSB1'
[6231] udev_rules_apply_to_event: LINK 'char/188:1' /lib/udev/rules.d/50-udev.rules:2
[6231] udev_device_new_from_syspath: device 0x8feff40 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2/2-2:1.1/ttyUSB1'
[6231] udev_device_new_from_syspath: device 0x8ff1380 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2/2-2:1.1'
[6231] udev_device_new_from_syspath: device 0x8fe45c0 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2'
[6231] udev_device_new_from_syspath: device 0x8ff0360 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2'
[6231] udev_device_new_from_syspath: device 0x8ff16a0 has devpath '/devices/pci0000:00/0000:00:1a.0'
[6231] udev_device_new_from_syspath: device 0x8ff1728 has devpath '/devices/pci0000:00'
[6231] udev_rules_apply_to_event: IMPORT 'path_id' /lib/udev/rules.d/60-persistent-serial.rules:9
[6231] util_run_program: 'path_id'
[6220] util_run_program: '/sbin/modprobe' returned with status 0
[6220] udev_monitor_send_device: passed 319 bytes to monitor 0x8ff2a90
[6220] udev_monitor_send_device: passed -1 bytes to monitor 0x8fe4648
[6220] event_fork: seq 1831 exit with 0
[6224] util_run_program: '/bin/sh' (stdout) 'bus/usb/002/017'
[6224] util_run_program: '/bin/sh' returned with status 0
[6224] udev_rules_apply_to_event: NAME 'bus/usb/002/017' /lib/udev/rules.d/50-udev.rules:39
[6224] udev_rules_apply_to_event: RUN 'socket:@/org/freedesktop/hal/udev_event' /lib/udev/rules.d/90-hal.rules:2
[6224] udev_rules_apply_to_event: MODE 0664 /lib/udev/rules.d/91-permissions.rules:38
[6224] udev_device_update_db: create db link (bus/usb/002/017 char/189:144)
[6224] udev_node_add: creating device node '/dev/bus/usb/002/017', devnum=189:144, mode=0664, uid=0, gid=0
[6224] udev_node_mknod: preserve file '/dev/bus/usb/002/017', because it has correct dev_t
[6224] update_link: found 2 devices with name '/dev/char/189:144'
[6224] udev_device_new_from_syspath: device 0x8ff2cb8 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2'
[6224] udev_device_read_db: device 0x8ff2cb8 filled with db symlink data '/dev/bus/usb/002/017'
[6224] update_link: '/dev/char/189:144' with target '/dev/bus/usb/002/017' has the highest priority 0, create it
[6224] node_symlink: preserve already existing symlink '/dev/char/189:144' to '../bus/usb/002/017'
[6224] udev_monitor_send_device: passed 271 bytes to monitor 0x8ff2ec0
[6224] udev_monitor_send_device: passed -1 bytes to monitor 0x8fe4648
[6224] event_fork: seq 1842 exit with 0
[6136] event_fork: seq 1839 forked, pid [6231], 'add' 'tty', 0 seconds old
[6136] udev_done: seq 1831 cleanup, pid [6220], status 0, 0 seconds old
[6136] udev_done: seq 1842 cleanup, pid [6224], status 0, 0 seconds old
[6136] event_fork: seq 1832 forked, pid [6235], 'add' 'usb-serial', 0 seconds old
[6136] event_fork: seq 1834 forked, pid [6236], 'add' 'usb_endpoint', 0 seconds old
[6136] event_fork: seq 1835 forked, pid [6237], 'add' 'usb_endpoint', 0 seconds old
[6136] event_fork: seq 1836 forked, pid [6238], 'add' 'usb_endpoint', 0 seconds old
[6235] udev_rules_apply_to_event: RUN 'socket:@/org/freedesktop/hal/udev_event' /lib/udev/rules.d/90-hal.rules:2
[6235] udev_device_new_from_syspath: device 0x8ff4bc0 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2/2-2:1.0'
[6235] udev_device_new_from_syspath: device 0x8feff40 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2'
[6235] udev_device_new_from_syspath: device 0x8ff4e60 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2'
[6235] udev_device_new_from_syspath: device 0x8ff1080 has devpath '/devices/pci0000:00/0000:00:1a.0'
[6235] udev_device_new_from_syspath: device 0x8fe45c0 has devpath '/devices/pci0000:00'
[6235] udev_monitor_send_device: passed 183 bytes to monitor 0x8ff0360
[6235] udev_monitor_send_device: passed -1 bytes to monitor 0x8fe4648
[6235] event_fork: seq 1832 exit with 0
[6136] udev_done: seq 1832 cleanup, pid [6235], status 0, 0 seconds old
[6136] event_fork: seq 1833 forked, pid [6239], 'add' 'tty', 0 seconds old
[6238] udev_device_new_from_syspath: device 0x8ff4bc0 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2/2-2:1.0/usb_endpoint/usbdev2.17_ep02'
[6238] udev_rules_apply_to_event: LINK 'char/251:22' /lib/udev/rules.d/50-udev.rules:2
[6238] udev_rules_apply_to_event: RUN 'socket:@/org/freedesktop/hal/udev_event' /lib/udev/rules.d/90-hal.rules:2
[6238] udev_device_new_from_syspath: device 0x8feff40 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2/2-2:1.0'
[6238] udev_device_new_from_syspath: device 0x8ff4e60 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2'
[6238] udev_device_new_from_syspath: device 0x8ff1080 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2'
[6238] udev_device_new_from_syspath: device 0x8fe45c0 has devpath '/devices/pci0000:00/0000:00:1a.0'
[6238] udev_device_new_from_syspath: device 0x8ff0360 has devpath '/devices/pci0000:00'
[6238] udev_event_execute_rules: no node name set, will use kernel name 'usbdev2.17_ep02'
[6238] udev_device_update_db: create db link (usbdev2.17_ep02 char/251:22)
[6238] udev_node_add: creating device node '/dev/usbdev2.17_ep02', devnum=251:22, mode=0660, uid=0, gid=0
[6238] udev_node_mknod: mknod(/dev/usbdev2.17_ep02, 020660, (251,22))
[6238] udev_node_mknod: chmod(/dev/usbdev2.17_ep02, 020660)
[6238] udev_node_mknod: chown(/dev/usbdev2.17_ep02, 0, 0)
[6238] update_link: '/dev/char/251:22' with target '/dev/usbdev2.17_ep02' has the highest priority 0, create it
[6238] node_symlink: creating symlink '/dev/char/251:22' to '../usbdev2.17_ep02'
[6238] udev_monitor_send_device: passed 301 bytes to monitor 0x8feee10
[6238] udev_monitor_send_device: passed -1 bytes to monitor 0x8fe4648
[6238] event_fork: seq 1836 exit with 0
[6136] udev_done: seq 1836 cleanup, pid [6238], status 0, 0 seconds old
[6237] udev_device_new_from_syspath: device 0x8ff4bc0 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2/2-2:1.0/usb_endpoint/usbdev2.17_ep82'
[6237] udev_rules_apply_to_event: LINK 'char/251:21' /lib/udev/rules.d/50-udev.rules:2
[6237] udev_rules_apply_to_event: RUN 'socket:@/org/freedesktop/hal/udev_event' /lib/udev/rules.d/90-hal.rules:2
[6237] udev_device_new_from_syspath: device 0x8feff40 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2/2-2:1.0'
[6237] udev_device_new_from_syspath: device 0x8ff4e60 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2'
[6237] udev_device_new_from_syspath: device 0x8ff1080 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2'
[6237] udev_device_new_from_syspath: device 0x8fe45c0 has devpath '/devices/pci0000:00/0000:00:1a.0'
[6237] udev_device_new_from_syspath: device 0x8ff0360 has devpath '/devices/pci0000:00'
[6237] udev_event_execute_rules: no node name set, will use kernel name 'usbdev2.17_ep82'
[6237] udev_device_update_db: create db link (usbdev2.17_ep82 char/251:21)
[6237] udev_node_add: creating device node '/dev/usbdev2.17_ep82', devnum=251:21, mode=0660, uid=0, gid=0
[6237] udev_node_mknod: mknod(/dev/usbdev2.17_ep82, 020660, (251,21))
[6237] udev_node_mknod: chmod(/dev/usbdev2.17_ep82, 020660)
[6237] udev_node_mknod: chown(/dev/usbdev2.17_ep82, 0, 0)
[6237] update_link: '/dev/char/251:21' with target '/dev/usbdev2.17_ep82' has the highest priority 0, create it
[6237] node_symlink: creating symlink '/dev/char/251:21' to '../usbdev2.17_ep82'
[6237] udev_monitor_send_device: passed 301 bytes to monitor 0x8feee10
[6237] udev_monitor_send_device: passed -1 bytes to monitor 0x8fe4648
[6237] event_fork: seq 1835 exit with 0
[6136] udev_done: seq 1835 cleanup, pid [6237], status 0, 0 seconds old
[6239] udev_device_new_from_syspath: device 0x8ff4bc0 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2/2-2:1.0/ttyUSB0/tty/ttyUSB0'
[6239] udev_rules_apply_to_event: LINK 'char/188:0' /lib/udev/rules.d/50-udev.rules:2
[6239] udev_device_new_from_syspath: device 0x8feff40 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2/2-2:1.0/ttyUSB0'
[6239] udev_device_new_from_syspath: device 0x8ff4e60 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2/2-2:1.0'
[6239] udev_device_new_from_syspath: device 0x8ff1080 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2'
[6239] udev_device_new_from_syspath: device 0x8feef00 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2'
[6239] udev_device_new_from_syspath: device 0x8fe45c0 has devpath '/devices/pci0000:00/0000:00:1a.0'
[6239] udev_device_new_from_syspath: device 0x8ff0360 has devpath '/devices/pci0000:00'
[6239] udev_rules_apply_to_event: IMPORT 'path_id' /lib/udev/rules.d/60-persistent-serial.rules:9
[6239] util_run_program: 'path_id'
[6231] util_run_program: '/lib/udev/path_id' (stdout) 'ID_PATH=pci-0000:00:1a.0-usb-0:2:1.1'
[6231] util_run_program: '/lib/udev/path_id' returned with status 0
[6231] udev_rules_apply_to_event: LINK 'serial/by-path/pci-0000:00:1a.0-usb-0:2:1.1-port0' /lib/udev/rules.d/60-persistent-serial.rules:11
[6231] udev_rules_apply_to_event: IMPORT 'usb_id --export /devices/pci0000:00/0000:00:1a.0/usb2/2-2/2-2:1.1/ttyUSB1/tty/ttyUSB1' /lib/udev/rules.d/60-persistent-serial.rules:13
[6231] util_run_program: 'usb_id --export /devices/pci0000:00/0000:00:1a.0/usb2/2-2/2-2:1.1/ttyUSB1/tty/ttyUSB1'
[6236] udev_device_new_from_syspath: [6239] util_run_program: '/lib/udev/path_id' (stdout) 'ID_PATH=pci-0000:00:1a.0-usb-0:2:1.0'
[6239] util_run_program: '/lib/udev/path_id' returned with status 0
[6239] udev_rules_apply_to_event: LINK 'serial/by-path/pci-0000:00:1a.0-usb-0:2:1.0-port0' /lib/udev/rules.d/60-persistent-serial.rules:11
[6239] udev_rules_apply_to_event: IMPORT 'usb_id --export /devices/pci0000:00/0000:00:1a.0/usb2/2-2/2-2:1.0/ttyUSB0/tty/ttyUSB0' /lib/udev/rules.d/60-persistent-serial.rules:13
[6239] util_run_program: 'usb_id --export /devices/pci0000:00/0000:00:1a.0/usb2/2-2/2-2:1.0/ttyUSB0/tty/ttyUSB0'
device 0x8ff4bc0 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2/2-2:1.0/usb_endpoint/usbdev2.17_ep81'
[6236] udev_rules_apply_to_event: LINK 'char/251:20' /lib/udev/rules.d/50-udev.rules:2
[6236] udev_rules_apply_to_event: RUN 'socket:@/org/freedesktop/hal/udev_event' /lib/udev/rules.d/90-hal.rules:2
[6236] udev_device_new_from_syspath: device 0x8feff40 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2/2-2:1.0'
[6236] udev_device_new_from_syspath: device 0x8ff4e60 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2'
[6236] udev_device_new_from_syspath: device 0x8ff1080 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2'
[6236] udev_device_new_from_syspath: device 0x8fe45c0 has devpath '/devices/pci0000:00/0000:00:1a.0'
[6236] udev_device_new_from_syspath: device 0x8ff0360 has devpath '/devices/pci0000:00'
[6236] udev_event_execute_rules: no node name set, will use kernel name 'usbdev2.17_ep81'
[6236] udev_device_update_db: create db link (usbdev2.17_ep81 char/251:20)
[6236] udev_node_add: creating device node '/dev/usbdev2.17_ep81', devnum=251:20, mode=0660, uid=0, gid=0
[6236] udev_node_mknod: mknod(/dev/usbdev2.17_ep81, 020660, (251,20))
[6236] udev_node_mknod: chmod(/dev/usbdev2.17_ep81, 020660)
[6236] udev_node_mknod: chown(/dev/usbdev2.17_ep81, 0, 0)
[6236] update_link: '/dev/char/251:20' with target '/dev/usbdev2.17_ep81' has the highest priority 0, create it
[6236] node_symlink: creating symlink '/dev/char/251:20' to '../usbdev2.17_ep81'
[6236] udev_monitor_send_device: passed 301 bytes to monitor 0x8feee10
[6236] udev_monitor_send_device: passed -1 bytes to monitor 0x8fe4648
[6236] event_fork: seq 1834 exit with 0
[6136] udev_done: seq 1834 cleanup, pid [6236], status 0, 0 seconds old
[6239] util_run_program: '/lib/udev/usb_id' (stdout) 'ID_VENDOR=Novatel_Wireless'
[6239] util_run_program: '/lib/udev/usb_id' (stdout) 'ID_VENDOR_ENC=Novatel\x20Wireless'
[6239] util_run_program: '/lib/udev/usb_id' (stdout) 'ID_VENDOR_ID=1410'
[6239] util_run_program: '/lib/udev/usb_id' (stdout) 'ID_MODEL=Novatel_Wireless_HSUPA_Modem'
[6239] util_run_program: '/lib/udev/usb_id' (stdout) 'ID_MODEL_ENC=Novatel\x20Wireless\x20HSUPA\x20Modem'
[6239] util_run_program: '/lib/udev/usb_id' (stdout) 'ID_MODEL_ID=1450'
[6239] util_run_program: '/lib/udev/usb_id' (stdout) 'ID_REVISION=0000'
[6239] util_run_program: '/lib/udev/usb_id' (stdout) 'ID_SERIAL=Novatel_Wireless_Novatel_Wireless_HSUPA_Modem_356922010299401'
[6239] util_run_program: '/lib/udev/usb_id' (stdout) 'ID_SERIAL_SHORT=356922010299401'
[6239] util_run_program: '/lib/udev/usb_id' (stdout) 'ID_TYPE=generic'
[6239] util_run_program: '/lib/udev/usb_id' (stdout) 'ID_BUS=usb'
[6239] util_run_program: '/lib/udev/usb_id' (stdout) 'ID_USB_INTERFACES=:ffffff:'
[6239] util_run_program: '/lib/udev/usb_id' (stdout) 'ID_USB_INTERFACE_NUM=00'
[6239] util_run_program: '/lib/udev/usb_id' (stdout) 'ID_USB_DRIVER=option'
[6239] util_run_program: '/lib/udev/usb_id' returned with status 0
[6239] udev_rules_apply_to_event: LINK 'serial/by-id/usb-Novatel_Wireless_Novatel_Wireless_HSUPA_Modem_356922010299401-if00-port0' /lib/udev/rules.d/60-persistent-serial.rules:18
[6239] udev_node_mknod: mknod(/dev/.tmp-char-188:0, 020600, (188,0))
[6239] udev_node_mknod: chmod(/dev/.tmp-char-188:0, 020600)
[6239] udev_node_mknod: chown(/dev/.tmp-char-188:0, 0, 0)
[6239] udev_rules_apply_to_event: IMPORT 'nm-modem-probe --vid 0x1410 --pid 0x1450 --usb-interface 00 --driver option --delay 3000 --export /dev/.tmp-char-188:0' /lib/udev/rules.d/77-nm-probe-modem-capabilities.rules:20
[6239] util_run_program: 'nm-modem-probe --vid 0x1410 --pid 0x1450 --usb-interface 00 --driver option --delay 3000 --export /dev/.tmp-char-188:0'
[6230] udev_device_new_from_syspath: device 0x8ff4bc0 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2/2-2:1.1/usb_endpoint/usbdev2.17_ep04'
[6230] udev_rules_apply_to_event: LINK 'char/251:31' /lib/udev/rules.d/50-udev.rules:2
[6230] udev_rules_apply_to_event: RUN 'socket:@/org/freedesktop/hal/udev_event' /lib/udev/rules.d/90-hal.rules:2
[6230] udev_device_new_from_syspath: device 0x8feff40 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2/2-2:1.1'
[6230] udev_device_new_from_syspath: device 0x8fe45c0 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2'
[6230] udev_device_new_from_syspath: device 0x8ff16f0 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2'
[6230] udev_device_new_from_syspath: device 0x8ff1778 has devpath '/devices/pci0000:00/0000:00:1a.0'
[6230] udev_device_new_from_syspath: device 0x8ff1848 has devpath '/devices/pci0000:00'
[6230] udev_event_execute_rules: no node name set, will use kernel name 'usbdev2.17_ep04'
[6230] udev_device_update_db: create db link (usbdev2.17_ep04 char/251:31)
[6230] udev_node_add: creating device node '/dev/usbdev2.17_ep04', devnum=251:31, mode=0660, uid=0, gid=0
[6230] udev_node_mknod: mknod(/dev/usbdev2.17_ep04, 020660, (251,31))
[6230] udev_node_mknod: chmod(/dev/usbdev2.17_ep04, 020660)
[6230] udev_node_mknod: chown(/dev/usbdev2.17_ep04, 0, 0)
[6230] update_link: '/dev/char/251:31' with target '/dev/usbdev2.17_ep04' has the highest priority 0, create it
[6230] node_symlink: creating symlink '/dev/char/251:31' to '../usbdev2.17_ep04'
[6230] udev_monitor_send_device: passed 301 bytes to monitor 0x8ff1980
[6230] udev_monitor_send_device: passed -1 bytes to monitor 0x8fe4648
[6230] event_fork: seq 1841 exit with 0
[6229] udev_device_new_from_syspath: device 0x8ff4bc0 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2/2-2:1.1/usb_endpoint/usbdev2.17_ep84'
[6136] udev_done: seq 1841 cleanup, pid [6230], status 0, 0 seconds old
[6229] udev_rules_apply_to_event: LINK 'char/251:30' /lib/udev/rules.d/50-udev.rules:2
[6229] udev_rules_apply_to_event: RUN 'socket:@/org/freedesktop/hal/udev_event' /lib/udev/rules.d/90-hal.rules:2
[6229] udev_device_new_from_syspath: device 0x8feff40 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2/2-2:1.1'
[6229] udev_device_new_from_syspath: device 0x8fe45c0 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2/2-2'
[6229] udev_device_new_from_syspath: device 0x8ff16f0 has devpath '/devices/pci0000:00/0000:00:1a.0/usb2'
[6229] udev_device_new_from_syspath: device 0x8ff1778 has devpath '/devices/pci0000:00/0000:00:1a.0'
[6229] udev_device_new_from_syspath: device 0x8ff1848 has devpath '/devices/pci0000:00'
[6229] udev_event_execute_rules: no node name set, will use kernel name 'usbdev2.17_ep84'
[6229] udev_device_update_db: create db link (usbdev2.17_ep84 char/251:30)
[6229] udev_node_add: creating device node '/dev/usbdev2.17_ep84', devnum=251:30, mode=0660, uid=0, gid=0
[6229] udev_node_mknod: mknod(/dev/usbdev2.17_ep84, 020660, (251,30))
[6229] udev_node_mknod: chmod(/dev/usbdev2.17_ep84, 020660)
[6229] udev_node_mknod: chown(/dev/usbdev2.17_ep84, 0, 0)
[6229] update_link: '/dev/char/251:30' with target '/dev/usbdev2.17_ep84' has the highest priority 0, create it
[6229] node_symlink: creating symlink '/dev/char/251:30' to '../usbdev2.17_ep84'
[6229] udev_monitor_send_device: passed 301 bytes to monitor 0x8ff1980
[6229] udev_monitor_send_device: passed -1 bytes to monitor 0x8fe4648
[6229] event_fork: seq 1840 exit with 0
[6136] udev_done: seq 1840 cleanup, pid [6229], status 0, 0 seconds old
[6231] util_run_program: '/lib/udev/usb_id' (stdout) 'ID_VENDOR=Novatel_Wireless'
[6231] util_run_program: '/lib/udev/usb_id' (stdout) 'ID_VENDOR_ENC=Novatel\x20Wireless'
[6231] util_run_program: '/lib/udev/usb_id' (stdout) 'ID_VENDOR_ID=1410'
[6231] util_run_program: '/lib/udev/usb_id' (stdout) 'ID_MODEL=Novatel_Wireless_HSUPA_Modem'
[6231] util_run_program: '/lib/udev/usb_id' (stdout) 'ID_MODEL_ENC=Novatel\x20Wireless\x20HSUPA\x20Modem'
[6231] util_run_program: '/lib/udev/usb_id' (stdout) 'ID_MODEL_ID=1450'
[6231] util_run_program: '/lib/udev/usb_id' (stdout) 'ID_REVISION=0000'
[6231] util_run_program: '/lib/udev/usb_id' (stdout) 'ID_SERIAL=Novatel_Wireless_Novatel_Wireless_HSUPA_Modem_356922010299401'
[6231] util_run_program: '/lib/udev/usb_id' (stdout) 'ID_SERIAL_SHORT=356922010299401'
[6231] util_run_program: '/lib/udev/usb_id' (stdout) 'ID_TYPE=generic'
[6231] util_run_program: '/lib/udev/usb_id' (stdout) 'ID_BUS=usb'
[6231] util_run_program: '/lib/udev/usb_id' (stdout) 'ID_USB_INTERFACES=:ffffff:'
[6231] util_run_program: '/lib/udev/usb_id' (stdout) 'ID_USB_INTERFACE_NUM=01'
[6231] util_run_program: '/lib/udev/usb_id' (stdout) 'ID_USB_DRIVER=option'
[6231] util_run_program: '/lib/udev/usb_id' returned with status 0
[6231] udev_rules_apply_to_event: LINK 'serial/by-id/usb-Novatel_Wireless_Novatel_Wireless_HSUPA_Modem_356922010299401-if01-port0' /lib/udev/rules.d/60-persistent-serial.rules:18
[6231] udev_node_mknod: mknod(/dev/.tmp-char-188:1, 020600, (188,1))
[6231] udev_node_mknod: chmod(/dev/.tmp-char-188:1, 020600)
[6231] udev_node_mknod: chown(/dev/.tmp-char-188:1, 0, 0)
[6231] udev_rules_apply_to_event: IMPORT 'nm-modem-probe --vid 0x1410 --pid 0x1450 --usb-interface 01 --driver option --delay 3000 --export /dev/.tmp-char-188:1' /lib/udev/rules.d/77-nm-probe-modem-capabilities.rules:20
[6231] util_run_program: 'nm-modem-probe --vid 0x1410 --pid 0x1450 --usb-interface 01 --driver option --delay 3000 --export /dev/.tmp-char-188:1'
[6239] util_run_program: '/lib/udev/nm-modem-probe' (stdout) 'ID_NM_MODEM_GSM=1'
[6239] util_run_program: '/lib/udev/nm-modem-probe' (stdout) 'ID_NM_MODEM_PROBED=1'
[6239] util_run_program: '/lib/udev/nm-modem-probe' returned with status 0
[6239] udev_rules_apply_to_event: RUN 'socket:@/org/freedesktop/hal/udev_event' /lib/udev/rules.d/90-hal.rules:2
[6239] udev_rules_apply_to_event: GROUP 20 /lib/udev/rules.d/91-permissions.rules:47
[6239] udev_event_execute_rules: no node name set, will use kernel name 'ttyUSB0'
[6239] udev_device_update_db: created db file for '/devices/pci0000:00/0000:00:1a.0/usb2/2-2/2-2:1.0/ttyUSB0/tty/ttyUSB0' in '/dev/.udev/db/\x2fdevices\x2fpci0000:00\x2f0000:00:1a.0\x2fusb2\x2f2-2\x2f2-2:1.0\x2fttyUSB0\x2ftty\x2fttyUSB0'
[6239] udev_node_add: creating device node '/dev/ttyUSB0', devnum=188:0, mode=0660, uid=0, gid=20
[6239] udev_node_mknod: mknod(/dev/ttyUSB0, 020660, (188,0))
[6239] udev_node_mknod: chmod(/dev/ttyUSB0, 020660)
[6239] udev_node_mknod: chown(/dev/ttyUSB0, 0, 20)
[6239] update_link: '/dev/char/188:0' with target '/dev/ttyUSB0' has the highest priority 0, create it
[6239] node_symlink: creating symlink '/dev/char/188:0' to '../ttyUSB0'
[6239] update_link: '/dev/serial/by-path/pci-0000:00:1a.0-usb-0:2:1.0-port0' with target '/dev/ttyUSB0' has the highest priority 0, create it
[6239] node_symlink: creating symlink '/dev/serial/by-path/pci-0000:00:1a.0-usb-0:2:1.0-port0' to '../../ttyUSB0'
[6239] update_link: '/dev/serial/by-id/usb-Novatel_Wireless_Novatel_Wireless_HSUPA_Modem_356922010299401-if00-port0' with target '/dev/ttyUSB0' has the highest priority 0, create it
[6239] node_symlink: creating symlink '/dev/serial/by-id/usb-Novatel_Wireless_Novatel_Wireless_HSUPA_Modem_356922010299401-if00-port0' to '../../ttyUSB0'
[6239] udev_monitor_send_device: passed 973 bytes to monitor 0x8ff36c8
[6239] udev_monitor_send_device: passed -1 bytes to monitor 0x8fe4648
[6239] event_fork: seq 1833 exit with 0
[6136] udev_done: seq 1833 cleanup, pid [6239], status 0, 6 seconds old
[6231] util_run_program: '/lib/udev/nm-modem-probe' (stdout) 'ID_NM_MODEM_PROBED=1'
[6231] util_run_program: '/lib/udev/nm-modem-probe' returned with status 0
[6231] udev_rules_apply_to_event: RUN 'socket:@/org/freedesktop/hal/udev_event' /lib/udev/rules.d/90-hal.rules:2
[6231] udev_rules_apply_to_event: GROUP 20 /lib/udev/rules.d/91-permissions.rules:47
[6231] udev_event_execute_rules: no node name set, will use kernel name 'ttyUSB1'
[6231] udev_device_update_db: created db file for '/devices/pci0000:00/0000:00:1a.0/usb2/2-2/2-2:1.1/ttyUSB1/tty/ttyUSB1' in '/dev/.udev/db/\x2fdevices\x2fpci0000:00\x2f0000:00:1a.0\x2fusb2\x2f2-2\x2f2-2:1.1\x2fttyUSB1\x2ftty\x2fttyUSB1'
[6231] udev_node_add: creating device node '/dev/ttyUSB1', devnum=188:1, mode=0660, uid=0, gid=20
[6231] udev_node_mknod: mknod(/dev/ttyUSB1, 020660, (188,1))
[6231] udev_node_mknod: chmod(/dev/ttyUSB1, 020660)
[6231] udev_node_mknod: chown(/dev/ttyUSB1, 0, 20)
[6231] update_link: '/dev/char/188:1' with target '/dev/ttyUSB1' has the highest priority 0, create it
[6231] node_symlink: creating symlink '/dev/char/188:1' to '../ttyUSB1'
[6231] update_link: '/dev/serial/by-path/pci-0000:00:1a.0-usb-0:2:1.1-port0' with target '/dev/ttyUSB1' has the highest priority 0, create it
[6231] node_symlink: creating symlink '/dev/serial/by-path/pci-0000:00:1a.0-usb-0:2:1.1-port0' to '../../ttyUSB1'
[6231] update_link: '/dev/serial/by-id/usb-Novatel_Wireless_Novatel_Wireless_HSUPA_Modem_356922010299401-if01-port0' with target '/dev/ttyUSB1' has the highest priority 0, create it
[6231] node_symlink: creating symlink '/dev/serial/by-id/usb-Novatel_Wireless_Novatel_Wireless_HSUPA_Modem_356922010299401-if01-port0' to '../../ttyUSB1'
[6231] udev_monitor_send_device: passed 955 bytes to monitor 0x8ff3098
[6231] udev_monitor_send_device: passed -1 bytes to monitor 0x8fe4648
[6231] event_fork: seq 1839 exit with 0
[6136] udev_done: seq 1839 cleanup, pid [6231], status 0, 7 seconds old


and from network-manager:
NM_SERIAL_DEBUG=1 NM_PPP_DEBUG=1 /usr/sbin/NetworkManager --no-daemon
NetworkManager: <info>  starting...
/sbin/ifup: interface lo already configured
NetworkManager: <info>  Found radio killswitch /org/freedesktop/Hal/devices/computer_rfkill_dell_wifi_wlan
NetworkManager: <info>  Found radio killswitch /org/freedesktop/Hal/devices/pci_8086_4235_rfkill_5300AGN_wlan
NetworkManager: <info>  Found radio killswitch /org/freedesktop/Hal/devices/dell_wlan_switch
NetworkManager: <info>  (eth0): new Ethernet device (driver: 'e1000e')
NetworkManager: <info>  (eth0): exported as /org/freedesktop/Hal/devices/net_00_24_e8_a6_51_3a
NetworkManager: <info>  (wlan0): driver supports SSID scans (scan_capa 0x01).
NetworkManager: <info>  (wlan0): new 802.11 WiFi device (driver: 'iwlagn')
NetworkManager: <info>  (wlan0): exported as /org/freedesktop/Hal/devices/net_00_21_6a_4b_47_2e
NetworkManager: <info>  (ttyS0): ignoring due to lack of mobile broadband capabilties
NetworkManager: <WARN>  killswitch_getpower_reply(): Error getting killswitch power: dellWirelessCtl (/usr/bin/dellWirelessCtl) not available or executable.
NetworkManager: <info>  (eth0): device state change: 1 -> 2
NetworkManager: <info>  (eth0): bringing up device.
NetworkManager: <info>  (eth0): preparing device.
NetworkManager: <info>  (eth0): deactivating device (reason: 2).
NetworkManager: <info>  (wlan0): device state change: 1 -> 2
NetworkManager: <info>  (wlan0): bringing up device.
NetworkManager: <info>  (wlan0): preparing device.
NetworkManager: <info>  (wlan0): deactivating device (reason: 2).
NetworkManager: <info>  (ttyUSB0): found serial port (udev:GSM  hal:)
NetworkManager: <info>  (ttyUSB0): deferring until all ports found
NetworkManager: <info>  (ttyUSB1): ignoring due to lack of mobile broadband capabilties
NetworkManager: <info>  Re-checking deferred serial ports
NetworkManager: <info>  (ttyUSB0): new Modem device (driver: 'option')
NetworkManager: <info>  (ttyUSB0): exported as /org/freedesktop/Hal/devices/usb_device_1410_1450_356922010299401_if0_serial_usb_0
NetworkManager: <info>  (ttyUSB0): device state change: 1 -> 2
NetworkManager: <info>  (ttyUSB0): deactivating device (reason: 2).
NetworkManager: nm_system_device_flush_ip4_routes_with_iface: assertion `iface_idx >= 0' failed
NetworkManager: nm_system_device_flush_ip4_addresses_with_iface: assertion `iface_idx >= 0' failed
NetworkManager: <info>  (ttyUSB0): device state change: 2 -> 3
NetworkManager: <info>  Activation (ttyUSB0) starting connection 'iPlus'
NetworkManager: <info>  (ttyUSB0): device state change: 3 -> 4
NetworkManager: <info>  Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) scheduled...
NetworkManager: <info>  Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) started...
NetworkManager: <debug> [1249415979.018092] nm_serial_device_open(): (ttyUSB0) opening device...
NetworkManager: <info>  Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) complete.
NetworkManager: <debug> [1249415979.125758] nm_serial_debug(): Sending: 'ATZ E0 V1 X4 &C1 +FCLASS=0
'
NetworkManager: <debug> [1249415979.257347] nm_serial_debug(): Got: 'ATAZ TAZE0 TAZ VE0 1AZ V E0XAZ 4 E0XAZ 4 E0XAZ 4 E0 AZ & E0CAZ 1 E0 AZ 1+ E0FAZ C+ EFAZ C+ EFAZ C+ LFAZAC+ SFAZSC+ =FAZ0C+ =


ERROR

'
NetworkManager: <WARN>  init_done(): Trying alternate modem initialization (1)
NetworkManager: <debug> [1249415981.000348] nm_serial_debug(): Sending: 'ATZ E0 V1 &C1
'
NetworkManager: <debug> [1249415981.057299] nm_serial_debug(): Got: 'AATZ ATZE0 AT VZE0 1AT ZE0 1AT ZE0 1AT&ZE0C1AT1ZE0
'
NetworkManager: <debug> [1249415981.085246] nm_serial_debug(): Got: 'AATZ ATZE0 AT VZE0 1AT ZE0 1AT ZE0 1AT&ZE0C1AT1ZE0


ERROR

'
NetworkManager: <WARN>  init_done(): Trying alternate modem initialization (2)
NetworkManager: <debug> [1249415982.001724] nm_serial_debug(): Sending: 'AT&F E0 V1 X4 &C1 +FCLASS=0
'
NetworkManager: <debug> [1249415982.137308] nm_serial_debug(): Got: 'AT1ZEAT&FAT& E0FAT& E 0FAV& E 0FAV&1  E 0XV&14 E XV&14 E XV&  E &XV&C E 1XV&  E +XV&F E +CXV&L E CXV&L E CXV&AL SCXVSAL =CXV0AL 
'
NetworkManager: <debug> [1249415982.141330] nm_serial_debug(): Got: 'AT1ZEAT&FAT& E0FAT& E 0FAV& E 0FAV&1  E 0XV&14 E XV&14 E XV&  E &XV&C E 1XV&  E +XV&F E +CXV&L E CXV&L E CXV&AL SCXVSAL =CXV0AL 


ERROR

'
NetworkManager: <WARN>  init_done(): Trying alternate modem initialization (3)
NetworkManager: <debug> [1249415983.001356] nm_serial_debug(): Sending: 'AT&F E0 V1 &C1
'
NetworkManager: <debug> [1249415983.053304] nm_serial_debug(): Got: 'AATA&FTA& EFTA&0  EFVA&01 EF A&0& EF CA&01 EF


ERROR

'
NetworkManager: <WARN>  init_done(): Trying alternate modem initialization (4)
NetworkManager: <debug> [1249415984.002006] nm_serial_debug(): Sending: 'AT&F E0 V1
'
NetworkManager: <debug> [1249415984.037301] nm_serial_debug(): Got: 'AAT&AF T&AFE0 T& VAFE1 T&


ERROR

'
NetworkManager: <WARN>  init_done(): Trying alternate modem initialization (5)
NetworkManager: <debug> [1249415985.001447] nm_serial_debug(): Sending: '
AT&F E0 V1 X4 &C1 +CREG=0 +FCLASS=0
'
NetworkManager: <debug> [1249415985.173313] nm_serial_debug(): Got: 'AATA&FTA&F ETA&0 F EVA&01F E A&01XF E4A&0 XF 4A&0 XF 4A&& XFC4A&1 XF 4A&+ XFC4A&+R XFE4A&GR XE4A&GR XE4A&=GR0E4A =GR+E4AF=GRCE4AL=GRCAE4AS=GRSAE4S=GRSAE4S=G=SAE0S=G
'
NetworkManager: <debug> [1249415985.177258] nm_serial_debug(): Got: 'AATA&FTA&F ETA&0 F EVA&01F E A&01XF E4A&0 XF 4A&0 XF 4A&& XFC4A&1 XF 4A&+ XFC4A&+R XFE4A&GR XE4A&GR XE4A&=GR0E4A =GR+E4AF=GRCE4AL=GRCAE4AS=GRSAE4S=GRSAE4S=G=SAE0S=G


ERROR

'
NetworkManager: <WARN>  init_done(): Modem initialization failed
NetworkManager: <info>  (ttyUSB0): device state change: 4 -> 9
NetworkManager: <debug> [1249415985.177385] nm_serial_device_close(): Closing device 'ttyUSB0'
NetworkManager: <info>  Marking connection 'iPlus' invalid.
NetworkManager: <info>  Activation (ttyUSB0) failed.
NetworkManager: <info>  (ttyUSB0): device state change: 9 -> 3
NetworkManager: <info>  (ttyUSB0): deactivating device (reason: 0).
NetworkManager: nm_system_device_flush_ip4_routes_with_iface: assertion `iface_idx >= 0' failed
NetworkManager: nm_system_device_flush_ip4_addresses_with_iface: assertion `iface_idx >= 0' failed

I have configured PIN, and APN in configuration but its not coonnecting at all.




-- System Information:
Debian Release: squeeze/sid
  APT prefers unstable
  APT policy: (500, 'unstable'), (500, 'stable')
Architecture: i386 (i686)

Kernel: Linux 2.6.30-1-686-bigmem (SMP w/2 CPU cores)
Locale: LANG=pl_PL.UTF-8, LC_CTYPE=pl_PL.UTF-8 (charmap=UTF-8)
Shell: /bin/sh linked to /bin/bash

Versions of packages network-manager depends on:
ii  adduser      3.110                       add and remove users and groups
ii  dbus         1.2.16-2                    simple interprocess messaging syst
ii  dhcp3-client 3.1.2p1-1                   DHCP client
ii  hal          0.5.12~git20090406.46dc48-2 Hardware Abstraction Layer
ii  ifupdown     0.6.8+nmu1                  high level tools to configure netw
ii  libc6        2.9-23                      GNU C Library: Shared libraries
ii  libdbus-1-3  1.2.16-2                    simple interprocess messaging syst
ii  libdbus-glib 0.82-1                      simple interprocess messaging syst
ii  libgcrypt11  1.4.4-3                     LGPL Crypto library - runtime libr
ii  libglib2.0-0 2.20.4-1                    The GLib library of C routines
ii  libgnutls26  2.6.6-1                     the GNU TLS library - runtime libr
ii  libgpg-error 1.6-1                       library for common error values an
ii  libhal1      0.5.12~git20090406.46dc48-2 Hardware Abstraction Layer - share
ii  libnl1       1.1-5                       library for dealing with netlink s
ii  libnm-glib0  0.7.1-1                     network management framework (GLib
ii  libnm-util1  0.7.1-1                     network management framework (shar
ii  libpolkit-db 0.9-4                       library for accessing PolicyKit vi
ii  libpolkit2   0.9-4                       library for accessing PolicyKit
ii  libtasn1-3   2.3-1                       Manage ASN.1 structures (runtime)
ii  libuuid1     1.41.8-2                    Universally Unique ID library
ii  lsb-base     3.2-23                      Linux Standard Base 3.2 init scrip
ii  wpasupplican 0.6.9-3                     client support for WPA and WPA2 (I
ii  zlib1g       1:1.2.3.3.dfsg-14           compression library - runtime

Versions of packages network-manager recommends:
ii  dnsmasq-base               2.49-1        A small caching DNS proxy and DHCP
ii  iptables                   1.4.4-2       administration tools for packet fi
ii  network-manager-gnome      0.7.1-1       network management framework (GNOM
ii  policykit                  0.9-4         framework for managing administrat
ii  ppp                        2.4.4rel-10.1 Point-to-Point Protocol (PPP) - da

Versions of packages network-manager suggests:
ii  avahi-autoipd                 0.6.25-1   Avahi IPv4LL network address confi

-- no debconf information