Re: [Thinstation-general] Thinstation don't look for config at usb stick
Brought to you by:
doncuppjr
From: Steven R. <s.r...@ms...> - 2009-05-30 11:51:50
|
Trevor Batley schrieb: > On Fri, 2009-05-29 at 15:55 +0200, Steven Roscher wrote: >> Hi there! >> >> >> Trevor Batley schrieb: >> >>> This sounds the most logical (and I seem o remember a conversation along >>> these lines a while ago). >>> >>> The only thing I can think of is that we put a wait in after the initial >>> udev stuff to give it some time to load. >>> >>> You can see what happened with udev by look in in /var/log/messages >>> (verbose, but it has all udev messages). >> Here is the message-log which is created on bootup - hope you can find >> the failure. >> >> greets >> plain text document attachment (messages) >> May 30 01:44:10 ts_0015F25B8586 user.info kernel: usb 1-7: configuration #1 chosen from 1 choice >> May 30 01:44:10 ts_0015F25B8586 user.info kernel: scsi4 : SCSI emulation for USB Mass Storage devices >> May 30 01:44:10 ts_0015F25B8586 user.debug kernel: usb-storage: device found at 3 >> May 30 01:44:10 ts_0015F25B8586 user.debug kernel: usb-storage: waiting for device to settle before scanning >> May 30 01:44:10 ts_0015F25B8586 user.info kernel: usb 2-2: new low speed USB device using ohci_hcd and address 2 >> May 30 01:44:10 ts_0015F25B8586 user.info kernel: usb 2-2: configuration #1 chosen from 1 choice >> May 30 01:44:10 ts_0015F25B8586 user.info kernel: input: Logitech USB-PS/2 Optical Mouse as /class/input/input3 >> May 30 01:44:10 ts_0015F25B8586 user.info kernel: input: USB HID v1.10 Mouse [Logitech USB-PS/2 Optical Mouse] on usb-0000:00:0b.0-2 >> May 30 01:44:12 ts_0015F25B8586 user.notice kernel: scsi 4:0:0:0: Direct-Access Kingston DataTraveler 2.0 PMAP PQ: 0 ANSI: 0 CCS >> May 29 15:44:12 ts_0015F25B8586 daemon.info udevd-event[4333]: udev_event_run: seq 1258 finished >> May 29 15:44:12 ts_0015F25B8586 daemon.info udevd[864]: udev_event_run: seq 1258 forked, pid [4333], 'add' 'scsi', 0 seconds old >> May 29 15:44:12 ts_0015F25B8586 daemon.info udevd[864]: udev_done: seq 1258, pid [4333] exit with 0, 0 seconds old >> May 29 15:44:12 ts_0015F25B8586 daemon.info udevd-event[4336]: udev_event_run: seq 1259 finished >> May 29 15:44:12 ts_0015F25B8586 daemon.info udevd[864]: udev_event_run: seq 1259 forked, pid [4336], 'add' 'scsi_disk', 0 seconds old >> May 29 15:44:12 ts_0015F25B8586 daemon.info udevd[864]: udev_done: seq 1259, pid [4336] exit with 0, 0 seconds old >> May 30 01:44:12 ts_0015F25B8586 user.notice kernel: sd 4:0:0:0: [sda] 3966976 512-byte hardware sectors (2031 MB) >> May 30 01:44:12 ts_0015F25B8586 user.notice kernel: sd 4:0:0:0: [sda] Write Protect is off >> May 30 01:44:12 ts_0015F25B8586 user.debug kernel: sd 4:0:0:0: [sda] Mode Sense: 23 00 00 00 >> May 30 01:44:12 ts_0015F25B8586 user.err kernel: sd 4:0:0:0: [sda] Assuming drive cache: write through >> May 30 01:44:12 ts_0015F25B8586 user.notice kernel: sd 4:0:0:0: [sda] 3966976 512-byte hardware sectors (2031 MB) >> May 30 01:44:12 ts_0015F25B8586 user.notice kernel: sd 4:0:0:0: [sda] Write Protect is off >> May 30 01:44:12 ts_0015F25B8586 user.debug kernel: sd 4:0:0:0: [sda] Mode Sense: 23 00 00 00 >> May 30 01:44:12 ts_0015F25B8586 user.err kernel: sd 4:0:0:0: [sda] Assuming drive cache: write through >> May 30 01:44:12 ts_0015F25B8586 user.info kernel: sda1 >> May 29 15:44:12 ts_0015F25B8586 daemon.info udevd-event[4338]: udev_rules_get_name: no node name set, will use kernel name 'sda' >> May 29 15:44:12 ts_0015F25B8586 daemon.info udevd-event[4338]: udev_db_get_device: no db file to read /dev/.udev/db/block@sda: No such file or directory >> May 29 15:44:12 ts_0015F25B8586 daemon.info udevd-event[4338]: udev_node_add: creating device node '/dev/sda', major = '8', minor = '0', mode = '0660', uid = '0', gid = '0' >> May 29 15:44:12 ts_0015F25B8586 daemon.info udevd-event[4338]: run_program: '/etc/udev/scripts/usb.sh' >> May 29 15:44:12 ts_0015F25B8586 daemon.info udevd[864]: udev_event_run: seq 1260 forked, pid [4338], 'add' 'block', 0 seconds old >> May 30 01:44:12 ts_0015F25B8586 user.notice kernel: sd 4:0:0:0: [sda] Attached SCSI removable disk >> May 29 15:44:12 ts_0015F25B8586 daemon.info udevd-event[4342]: udev_event_run: seq 1262 finished >> May 30 01:44:12 ts_0015F25B8586 user.debug kernel: usb-storage: device scan complete >> May 29 15:44:12 ts_0015F25B8586 daemon.info udevd[864]: udev_event_run: seq 1262 forked, pid [4342], 'add' 'scsi_device', 0 seconds old >> May 29 15:44:12 ts_0015F25B8586 daemon.info udevd[864]: udev_done: seq 1262, pid [4342] exit with 0, 0 seconds old >> May 29 15:44:12 ts_0015F25B8586 daemon.info udevd-event[4338]: run_program: '/etc/udev/scripts/usb.sh' returned with status 0 >> May 29 15:44:12 ts_0015F25B8586 daemon.info udevd-event[4338]: udev_event_run: seq 1260 finished >> May 29 15:44:12 ts_0015F25B8586 daemon.info udevd[864]: udev_done: seq 1260, pid [4338] exit with 0, 0 seconds old >> May 29 15:44:12 ts_0015F25B8586 daemon.info udevd-event[4364]: udev_rules_get_name: no node name set, will use kernel name 'sda1' >> May 29 15:44:12 ts_0015F25B8586 daemon.info udevd-event[4364]: udev_db_get_device: no db file to read /dev/.udev/db/block@sda@sda1: No such file or directory >> May 29 15:44:12 ts_0015F25B8586 daemon.info udevd-event[4364]: udev_node_add: creating device node '/dev/sda1', major = '8', minor = '1', mode = '0660', uid = '0', gid = '0' >> May 29 15:44:12 ts_0015F25B8586 daemon.info udevd-event[4364]: run_program: '/etc/udev/scripts/usb.sh' >> May 29 15:44:12 ts_0015F25B8586 daemon.info udevd[864]: udev_event_run: seq 1261 forked, pid [4364], 'add' 'block', 0 seconds old >> May 29 15:44:12 ts_0015F25B8586 daemon.info udevd-event[4364]: run_program: '/etc/udev/scripts/usb.sh' (stdout) 'drwxr-xr-x 4 root root 16384 Jan 1 1970 .' >> May 29 15:44:12 ts_0015F25B8586 daemon.info udevd-event[4364]: run_program: '/etc/udev/scripts/usb.sh' (stdout) 'drwxr-xr-x 1 root root 80 May 30 01:44 ..' >> May 29 15:44:12 ts_0015F25B8586 daemon.info udevd-event[4364]: run_program: '/etc/udev/scripts/usb.sh' (stdout) 'drwxr-xr-x 4 root root 32768 May 28 03:50 .Trash-1000' >> May 29 15:44:12 ts_0015F25B8586 daemon.info udevd-event[4364]: run_program: '/etc/udev/scripts/usb.sh' (stdout) '-rwxr-xr-x 1 root root 15568175 May 29 03:09 initrd' >> May 29 15:44:12 ts_0015F25B8586 daemon.info udevd-event[4364]: run_program: '/etc/udev/scripts/usb.sh' (stdout) '-r-xr-xr-x 1 root root 9660 May 29 02:12 ldlinux.sys' >> May 29 15:44:12 ts_0015F25B8586 daemon.info udevd-event[4364]: run_program: '/etc/udev/scripts/usb.sh' (stdout) '-rwxr-xr-x 1 root root 74101 May 29 15:37 messages' >> May 29 15:44:12 ts_0015F25B8586 daemon.info udevd-event[4364]: run_program: '/etc/udev/scripts/usb.sh' (stdout) '-rwxr-xr-x 1 root root 22188 Sep 9 2007 syslinux' >> May 29 15:44:12 ts_0015F25B8586 daemon.info udevd-event[4364]: run_program: '/etc/udev/scripts/usb.sh' (stdout) '-rwxr-xr-x 1 root root 179 May 29 03:09 syslinux.cfg' >> May 29 15:44:12 ts_0015F25B8586 daemon.info udevd-event[4364]: run_program: '/etc/udev/scripts/usb.sh' (stdout) '-rwxr-xr-x 1 root root 142 Sep 9 2007 syslinux.cfg.tpl' >> May 29 15:44:12 ts_0015F25B8586 daemon.info udevd-event[4364]: run_program: '/etc/udev/scripts/usb.sh' (stdout) '-rwxr-xr-x 1 root root 16844 Sep 9 2007 syslinux.com' >> May 29 15:44:12 ts_0015F25B8586 daemon.info udevd-event[4364]: run_program: '/etc/udev/scripts/usb.sh' (stdout) '-rwxr-xr-x 1 root root 30208 Sep 9 2007 syslinux.exe' >> May 29 15:44:12 ts_0015F25B8586 daemon.info udevd-event[4364]: run_program: '/etc/udev/scripts/usb.sh' (stdout) 'drwxr-xr-x 2 root root 32768 May 27 21:05 thinstation.profile' >> May 29 15:44:12 ts_0015F25B8586 daemon.info udevd-event[4364]: run_program: '/etc/udev/scripts/usb.sh' (stdout) '-rwxr-xr-x 1 root root 21 May 28 05:21 thinstation.txt' >> May 29 15:44:12 ts_0015F25B8586 daemon.info udevd-event[4364]: run_program: '/etc/udev/scripts/usb.sh' (stdout) '-rwxr-xr-x 1 root root 1334296 May 29 03:09 vmlinuz' >> May 29 15:44:12 ts_0015F25B8586 daemon.info udevd-event[4364]: run_program: '/etc/udev/scripts/usb.sh' returned with status 0 >> May 29 15:44:12 ts_0015F25B8586 daemon.info udevd-event[4364]: udev_event_run: seq 1261 finished >> May 29 15:44:12 ts_0015F25B8586 daemon.info udevd[864]: udev_done: seq 1261, pid [4364] exit with 0, 0 seconds old >> May 30 01:44:12 ts_0015F25B8586 daemon.debug automount[4742]: >> umount: Couldn't umount unionfs: No such file or directory >> May 30 01:44:12 ts_0015F25B8586 daemon.debug automount[4742]: >> umount: Couldn't umount /: No such file or directory >> May 30 01:44:12 ts_0015F25B8586 daemon.err automount[4742]: find_mntent: setmntent: No such file or directory >> May 29 15:44:12 ts_0015F25B8586 daemon.info udevd-event[4822]: udev_event_run: seq 1263 finished >> May 29 15:44:12 ts_0015F25B8586 daemon.info udevd[864]: udev_event_run: seq 1263 forked, pid [4822], 'add' 'module', 0 seconds old >> May 29 15:44:12 ts_0015F25B8586 daemon.info udevd[864]: udev_done: seq 1263, pid [4822] exit with 0, 0 seconds old >> May 29 15:44:12 ts_0015F25B8586 daemon.info udevd-event[4825]: udev_rules_get_name: no node name set, will use kernel name 'rtc' >> May 29 15:44:12 ts_0015F25B8586 daemon.info udevd-event[4825]: udev_db_get_device: no db file to read /dev/.udev/db/class@misc@rtc: No such file or directory >> May 29 15:44:12 ts_0015F25B8586 daemon.info udevd-event[4825]: udev_node_add: creating device node '/dev/rtc', major = '10', minor = '135', mode = '0660', uid = '0', gid = '0' >> May 29 15:44:12 ts_0015F25B8586 daemon.info udevd-event[4825]: udev_event_run: seq 1264 finished >> May 30 01:44:12 ts_0015F25B8586 user.info kernel: Real Time Clock Driver v1.12ac >> May 29 15:44:12 ts_0015F25B8586 daemon.info udevd[864]: udev_event_run: seq 1264 forked, pid [4825], 'add' 'misc', 0 seconds old >> May 29 15:44:12 ts_0015F25B8586 daemon.info udevd[864]: udev_done: seq 1264, pid [4825] exit with 0, 0 seconds old >> May 29 15:44:13 ts_0015F25B8586 user.info pcscd: pcscdaemon.c:464:main() pcsc-lite 1.3.1 daemon ready. >> May 29 15:44:13 ts_0015F25B8586 user.info pcscd: hotplug_libusb.c:105:HPReadBundleValues() Cannot open PC/SC drivers directory: /usr/lib/pcsc/drivers >> May 29 15:44:13 ts_0015F25B8586 user.info pcscd: hotplug_libusb.c:106:HPReadBundleValues() Disabling USB support for pcscd. >> May 29 15:44:13 ts_0015F25B8586 user.info kernel: unionfs: new lower inode mtime (bindex=0, name=tty1) >> May 29 05:44:13 ts_0015F25B8586 daemon.info init: Starting pid 5164, console /dev/tty1: '/bin/start-session' > > Well it found your usb stick on /dev/sda1 (and you do have a > thinstation.profile directory on it... :-) and I assume it's a Kingston > DataTraveler 2.0. OK, that was easy ;) > > The session doesn't appear to be starting until after this > (/bin/start-session line), but I'm pretty sure it would have run the > profile-setup earlier. > > Mostly because this exposes a bug that was introduced with 2.2...... > > profile-setup is run twice (as of 2.2), very early and then later (to > try and pick up local configs that may affect networking, and then after > networking has started. But unfortunately the 2nd run doesn't do > anything due to a small bug (and this where it would most likely pick up > a usb stick). Ah, that's could be a problem. I try to set a static net config in the conf.user file, because there is no DHCP Server on my net. Because of not finding this script at boottime, thinstation try to boot with dhcp lookup but doesn't get an answer and stop booting. So I think because of not setting the right network setting I don't reach the second profile-setup anymore. > > You were using the LiveCD, so I can't send you an updated script to > check if that fixes the problem.... Yes, because of easy starting the machine and copying the message log. But I've also a linux PC with Thinstation build enviroment running. I have build some images but the failure is also there - of course, you wrote this. So please go on and give this patch a try. This most essential thing for me is to get the network setting from the stick. .Steven |