Il Friday 11 April 2008 17:06:52 Daniel Drake ha scritto: > Odd, it is not handling the timeout but I can't see why that would be > the case. I guess those messages repeat rather quickly?
right, very quickly > > Which glib version is this? > glib 1.2.10-r5 and 2.14.6 > Please apply the attached patch and provide new logs. > Here is my new log sovereign flocchini # fprint_demo fp:debug [fp_init] fp:debug [register_driver] registered driver upekts fp:debug [register_driver] registered driver aes4000 fp:debug [register_driver] registered driver aes2501 fp:debug [register_driver] registered driver uru4000 fp:debug [register_driver] registered driver vcom5s fp:debug [find_supporting_driver] driver uru4000 supports USB device 045e:00ca async:debug [fp_async_dev_open] ** Message: now monitoring fd 4 async:debug [fpi_drvcb_open_complete] status 0 fp:debug [load_from_file] from /root/.fprint/prints/0002/00000000/2 fp:debug [fp_print_data_from_data] buffer size 2414 fp:debug [print_data_new] length=2404 driver=02 devtype=0000 fp:debug [load_from_file] from /root/.fprint/prints/0002/00000000/2 fp:debug [fp_print_data_from_data] buffer size 2414 fp:debug [print_data_new] length=2404 driver=02 devtype=0000 async:debug [fp_async_identify_start] fp:debug [generic_acquire_start] action 3 drv:debug [__ssm_call_handler] 0x8150480 entering state 0 uru4000:debug [sm_read_reg] read reg 7 poll:debug [fp_handle_events_timeout] g_n_t_e result 0 val 134697408.000000 uru4000:debug [sm_read_reg_cb] reg value 83 drv:debug [__ssm_call_handler] 0x8150480 entering state 1 drv:debug [__ssm_call_handler] 0x8150480 entering state 3 drv:debug [__ssm_call_handler] 0x8150480 entering state 4 drv:debug [__ssm_call_handler] 0x8145bd0 entering state 0 drv:debug [__ssm_call_handler] 0x8145bd0 entering state 1 uru4000:debug [fwfixer_run_state] looking for encryption byte at 510 poll:debug [handle_timeouts] g_n_t_e result 0 val 134549624.-1258115372 poll:debug [fp_handle_events_timeout] g_n_t_e result 0 val -1263349772.000016 uru4000:debug [fwfixer_read_cb] data: 1a 95 3c drv:debug [__ssm_call_handler] 0x8145bd0 entering state 1 uru4000:debug [fwfixer_run_state] looking for encryption byte at 62d poll:debug [handle_timeouts] g_n_t_e result 0 val -1263820208.-1074367348 poll:debug [fp_handle_events_timeout] g_n_t_e result 0 val -1263349772.000016 uru4000:debug [fwfixer_read_cb] data: ff 07 41 uru4000:debug [fwfixer_read_cb] using offset 62d drv:debug [__ssm_call_handler] 0x8145bd0 entering state 2 uru4000:debug [fwfixer_run_state] encryption is already disabled drv:debug [fpi_ssm_mark_completed] 0x8145bd0 completed with status 0 drv:debug [__ssm_call_handler] 0x8150480 entering state 5 drv:debug [__ssm_call_handler] 0x8151db0 entering state 0 drv:debug [__ssm_call_handler] 0x8151db0 entering state 1 uru4000:debug [sm_set_hwstat] set 03 poll:debug [handle_timeouts] g_n_t_e result 0 val -1263820208.-1074367348 poll:debug [fp_handle_events_timeout] g_n_t_e result 0 val -1263349772.000016 drv:debug [__ssm_call_handler] 0x8151db0 entering state 2 uru4000:debug [sm_read_reg] read reg 7 poll:debug [handle_timeouts] g_n_t_e result 0 val -1263820208.-1074367348 poll:debug [fp_handle_events_timeout] g_n_t_e result 0 val -1263349772.000016 uru4000:debug [sm_read_reg_cb] reg value 83 drv:debug [__ssm_call_handler] 0x8151db0 entering state 3 drv:debug [__ssm_call_handler] 0x8151db0 entering state 4 poll:debug [fpi_timeout_add] in 10ms poll:debug [get_next_timeout_expiry] next timeout in 0.009995s poll:debug [handle_timeouts] g_n_t_e result 1 val 0.009995 poll:debug [get_next_timeout_expiry] next timeout in 0.009979s poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired async:debug [fp_async_identify_stop] async:error [fp_async_identify_stop] BUG at async.c:381 drv:debug [__ssm_call_handler] 0x8145c70 entering state 0 uru4000:debug [sm_set_mode] mode 00 poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [fp_handle_events_timeout] g_n_t_e result 1 val 0.000000 poll:debug [handle_timeout] drv:debug [__ssm_call_handler] 0x8151db0 entering state 5 uru4000:debug [sm_do_challenge_response] poll:debug [fp_handle_events_timeout] g_n_t_e result 0 val -1263349772.000016 drv:debug [__ssm_call_handler] 0x8145c70 entering state 1 uru4000:debug [sm_set_hwstat] set 80 poll:debug [handle_timeouts] g_n_t_e result 0 val -1263820208.-1074367348 poll:debug [fp_handle_events_timeout] g_n_t_e result 0 val -1263349772.000016 poll:debug [handle_timeouts] g_n_t_e result 0 val -1263820208.-1074367348 poll:debug [fp_handle_events_timeout] g_n_t_e result 0 val -1263349772.000016 drv:debug [fpi_ssm_mark_completed] 0x8145c70 completed with status 0 poll:debug [handle_timeouts] g_n_t_e result 0 val -1263820208.-1074367348 poll:debug [fp_handle_events_timeout] g_n_t_e result 0 val -1261996576.000016 uru4000:debug [irq_handler] cancelled fp:debug [fpi_imgdev_deactivate_complete] async:debug [fpi_drvcb_identify_stopped] poll:debug [handle_timeouts] g_n_t_e result 0 val -1263820208.134982544 poll:debug [fp_handle_events_timeout] g_n_t_e result 0 val -1261996576.000016 drv:debug [__ssm_call_handler] 0x8151db0 entering state 6 drv:debug [__ssm_call_handler] 0x8151db0 entering state 1 uru4000:debug [sm_set_hwstat] set 03 poll:debug [handle_timeouts] g_n_t_e result 0 val -1263820208.134982464 poll:debug [fp_handle_events_timeout] g_n_t_e result 0 val -1263349772.000016 drv:debug [__ssm_call_handler] 0x8151db0 entering state 2 uru4000:debug [sm_read_reg] read reg 7 poll:debug [handle_timeouts] g_n_t_e result 0 val -1263820208.-1074367348 poll:debug [fp_handle_events_timeout] g_n_t_e result 0 val -1263349772.000016 uru4000:debug [sm_read_reg_cb] reg value 3 drv:debug [__ssm_call_handler] 0x8151db0 entering state 3 drv:debug [fpi_ssm_mark_completed] 0x8151db0 completed with status 0 drv:debug [__ssm_call_handler] 0x8150480 entering state 6 drv:debug [fpi_ssm_mark_aborted] error -5 from state 6 drv:debug [fpi_ssm_mark_completed] 0x8150480 completed with status -5 fp:debug [fpi_imgdev_activate_complete] status -5 fp:error [fpi_imgdev_activate_complete] unhandled action 0 poll:debug [handle_timeouts] g_n_t_e result 0 val -1263820208.-1074367348 sync:debug [fp_dev_close] async:debug [fpi_drvcb_close_complete] ** Message: no longer monitoring fd 4 sync:debug [sync_close_cb] fp:debug [fp_exit] sovereign flocchini # In the meantime, I discovered that with debug option activated, strange things happen even in auth process (but it still works) [EMAIL PROTECTED] ~ $ su fp:debug [fp_init] fp:debug [register_driver] registered driver upekts fp:debug [register_driver] registered driver aes4000 fp:debug [register_driver] registered driver aes2501 fp:debug [register_driver] registered driver uru4000 fp:debug [register_driver] registered driver vcom5s fp:debug [find_supporting_driver] driver uru4000 supports USB device 045e:00ca fp:debug [load_from_file] from /root/.fprint/prints/0002/00000000/2 fp:debug [fp_print_data_from_data] buffer size 2414 fp:debug [print_data_new] length=2404 driver=02 devtype=0000 sync:debug [fp_dev_open] async:debug [fp_async_dev_open] async:debug [fpi_drvcb_open_complete] status 0 sync:debug [sync_open_cb] status 0 Scan finger on Digital Persona U.are.U 4000/4000B sync:debug [fp_identify_finger_img] to be handled by uru4000 async:debug [fp_async_identify_start] fp:debug [generic_acquire_start] action 3 drv:debug [__ssm_call_handler] 0x8064840 entering state 0 uru4000:debug [sm_read_reg] read reg 7 poll:debug [fp_handle_events_timeout] g_n_t_e result 0 val -1259720716.134628864 uru4000:debug [sm_read_reg_cb] reg value 1 drv:debug [__ssm_call_handler] 0x8064840 entering state 1 drv:debug [__ssm_call_handler] 0x8064840 entering state 3 uru4000:debug [sm_set_hwstat] set 81 poll:debug [handle_timeouts] g_n_t_e result 0 val -1259742624.000003 poll:debug [fp_handle_events_timeout] g_n_t_e result 0 val -1259720716.134628864 uru4000:debug [irq_handler] recv irq type 56aa uru4000:debug [irq_handler] ignoring interrupt poll:debug [handle_timeouts] g_n_t_e result 0 val -1259742624.000003 poll:debug [fp_handle_events_timeout] g_n_t_e result 0 val -1259720716.134628864 drv:debug [__ssm_call_handler] 0x8064840 entering state 4 drv:debug [__ssm_call_handler] 0x80647c8 entering state 0 drv:debug [__ssm_call_handler] 0x80647c8 entering state 1 uru4000:debug [fwfixer_run_state] looking for encryption byte at 510 poll:debug [handle_timeouts] g_n_t_e result 0 val -1259742624.000003 poll:debug [fp_handle_events_timeout] g_n_t_e result 0 val -1259720716.134628864 uru4000:debug [fwfixer_read_cb] data: 1a 95 3c drv:debug [__ssm_call_handler] 0x80647c8 entering state 1 uru4000:debug [fwfixer_run_state] looking for encryption byte at 62d poll:debug [handle_timeouts] g_n_t_e result 0 val -1259742624.000003 poll:debug [fp_handle_events_timeout] g_n_t_e result 0 val -1259720716.134628864 uru4000:debug [fwfixer_read_cb] data: ff 07 41 uru4000:debug [fwfixer_read_cb] using offset 62d drv:debug [__ssm_call_handler] 0x80647c8 entering state 2 uru4000:debug [fwfixer_run_state] encryption is already disabled drv:debug [fpi_ssm_mark_completed] 0x80647c8 completed with status 0 drv:debug [__ssm_call_handler] 0x8064840 entering state 5 drv:debug [__ssm_call_handler] 0x80647f0 entering state 0 drv:debug [__ssm_call_handler] 0x80647f0 entering state 1 uru4000:debug [sm_set_hwstat] set 01 poll:debug [handle_timeouts] g_n_t_e result 0 val -1259742624.000003 poll:debug [fp_handle_events_timeout] g_n_t_e result 0 val -1259720716.134628864 drv:debug [__ssm_call_handler] 0x80647f0 entering state 2 uru4000:debug [sm_read_reg] read reg 7 poll:debug [handle_timeouts] g_n_t_e result 0 val -1259742624.000003 poll:debug [fp_handle_events_timeout] g_n_t_e result 0 val -1259720716.134628864 uru4000:debug [sm_read_reg_cb] reg value 81 drv:debug [__ssm_call_handler] 0x80647f0 entering state 3 drv:debug [__ssm_call_handler] 0x80647f0 entering state 4 poll:debug [fpi_timeout_add] in 10ms poll:debug [get_next_timeout_expiry] next timeout in 0.009997s poll:debug [handle_timeouts] g_n_t_e result 1 val 0.009997 poll:debug [get_next_timeout_expiry] next timeout in 0.009984s poll:debug [fp_handle_events_timeout] g_n_t_e result 1 val 0.009984 poll:debug [get_next_timeout_expiry] first timeout already expired poll:debug [handle_timeouts] g_n_t_e result 1 val 0.000000 poll:debug [handle_timeout] drv:debug [__ssm_call_handler] 0x80647f0 entering state 5 uru4000:debug [sm_do_challenge_response] poll:debug [fp_handle_events_timeout] g_n_t_e result 0 val 0.009984 poll:debug [handle_timeouts] g_n_t_e result 0 val 0.000000 poll:debug [fp_handle_events_timeout] g_n_t_e result 0 val 0.009984 drv:debug [__ssm_call_handler] 0x80647f0 entering state 6 drv:debug [__ssm_call_handler] 0x80647f0 entering state 1 uru4000:debug [sm_set_hwstat] set 01 poll:debug [handle_timeouts] g_n_t_e result 0 val 0.000000 poll:debug [fp_handle_events_timeout] g_n_t_e result 0 val 0.009984 drv:debug [__ssm_call_handler] 0x80647f0 entering state 2 uru4000:debug [sm_read_reg] read reg 7 poll:debug [handle_timeouts] g_n_t_e result 0 val 0.000000 poll:debug [fp_handle_events_timeout] g_n_t_e result 0 val 0.009984 uru4000:debug [sm_read_reg_cb] reg value 1 drv:debug [__ssm_call_handler] 0x80647f0 entering state 3 drv:debug [fpi_ssm_mark_completed] 0x80647f0 completed with status 0 drv:debug [__ssm_call_handler] 0x8064840 entering state 6 poll:debug [fpi_timeout_add] in 300ms poll:debug [get_next_timeout_expiry] next timeout in 0.299997s poll:debug [handle_timeouts] g_n_t_e result 1 val 0.299997 poll:debug [get_next_timeout_expiry] next timeout in 0.299974s poll:debug [fp_handle_events_timeout] g_n_t_e result 1 val 0.299974 uru4000:debug [irq_handler] recv irq type 56aa drv:debug [__ssm_call_handler] 0x8064840 entering state 7 poll:debug [fpi_timeout_cancel] drv:debug [fpi_ssm_mark_completed] 0x8064840 completed with status 0 fp:debug [fpi_imgdev_activate_complete] status 0 async:debug [fpi_drvcb_identify_started] status 0 poll:debug [handle_timeouts] g_n_t_e result 0 val 0.299997 poll:debug [fp_handle_events_timeout] g_n_t_e result 0 val 0.299974 poll:debug [handle_timeouts] g_n_t_e result 0 val 0.299997 poll:debug [fp_handle_events_timeout] g_n_t_e result 0 val 0.299974 poll:debug [handle_timeouts] g_n_t_e result 0 val 0.299997 poll:debug [fp_handle_events_timeout] g_n_t_e result 0 val 0.299974 poll:debug [handle_timeouts] g_n_t_e result 0 val 0.299997 poll:debug [fp_handle_events_timeout] g_n_t_e result 0 val 0.299974 poll:debug [handle_timeouts] g_n_t_e result 0 val 0.299997 poll:debug [fp_handle_events_timeout] g_n_t_e result 0 val 0.299974 uru4000:debug [irq_handler] recv irq type 0101 fp:debug [fpi_imgdev_report_finger_status] finger on sensor poll:debug [handle_timeouts] g_n_t_e result 0 val 0.299997 poll:debug [fp_handle_events_timeout] g_n_t_e result 0 val 0.299974 poll:debug [handle_timeouts] g_n_t_e result 0 val 0.299997 poll:debug [fp_handle_events_timeout] g_n_t_e result 0 val 0.299974 poll:debug [handle_timeouts] g_n_t_e result 0 val 0.299997 poll:debug [fp_handle_events_timeout] g_n_t_e result 0 val 0.299974 poll:debug [handle_timeouts] g_n_t_e result 0 val 0.299997 poll:debug [fp_handle_events_timeout] g_n_t_e result 0 val 0.299974 poll:debug [handle_timeouts] g_n_t_e result 0 val 0.299997 poll:debug [fp_handle_events_timeout] g_n_t_e result 0 val 0.299974 poll:debug [handle_timeouts] g_n_t_e result 0 val 0.299997 poll:debug [fp_handle_events_timeout] g_n_t_e result 0 val 0.299974 poll:debug [handle_timeouts] g_n_t_e result 0 val 0.299997 poll:debug [fp_handle_events_timeout] g_n_t_e result 0 val 0.299974 poll:debug [handle_timeouts] g_n_t_e result 0 val 0.299997 poll:debug [fp_handle_events_timeout] g_n_t_e result 0 val 0.299974 fp:debug [fpi_img_new] length=110976 fp:debug [fpi_imgdev_image_captured] fp:debug [fpi_img_detect_minutiae] minutiae scan completed in 0,095184 secs fp:debug [fpi_img_detect_minutiae] detected 91 minutiae fp:debug [print_data_new] length=2404 driver=02 devtype=0000 libusb:error [libusb_cancel_transfer] cancel transfer failed error -1 poll:debug [handle_timeouts] g_n_t_e result 0 val 0.299997 poll:debug [fp_handle_events_timeout] g_n_t_e result 0 val 0.299974 poll:debug [handle_timeouts] g_n_t_e result 0 val 0.299997 poll:debug [fp_handle_events_timeout] g_n_t_e result 0 val 0.299974 poll:debug [handle_timeouts] g_n_t_e result 0 val 0.299997 poll:debug [fp_handle_events_timeout] g_n_t_e result 0 val 0.299974 uru4000:debug [irq_handler] recv irq type 0200 fp:debug [fpi_imgdev_report_finger_status] finger removed async:debug [fpi_drvcb_report_identify_result] result 1 poll:debug [handle_timeouts] g_n_t_e result 0 val 0.299997 sync:debug [fp_identify_finger_img] result: match at offset 0 async:debug [fp_async_identify_stop] drv:debug [__ssm_call_handler] 0x8062a00 entering state 0 uru4000:debug [sm_set_mode] mode 00 poll:debug [fp_handle_events_timeout] g_n_t_e result 0 val -1259884815.134628864 uru4000:debug [image_cb] cancelled poll:debug [handle_timeouts] g_n_t_e result 0 val -1209125536.-1079468400 poll:debug [fp_handle_events_timeout] g_n_t_e result 0 val -1259884815.134628864 drv:debug [__ssm_call_handler] 0x8062a00 entering state 1 uru4000:debug [sm_set_hwstat] set 80 poll:debug [handle_timeouts] g_n_t_e result 0 val -1209125536.-1079468400 poll:debug [fp_handle_events_timeout] g_n_t_e result 0 val -1259884815.134628864 drv:debug [fpi_ssm_mark_completed] 0x8062a00 completed with status 0 poll:debug [handle_timeouts] g_n_t_e result 0 val -1209125536.-1079468400 poll:debug [fp_handle_events_timeout] g_n_t_e result 0 val -1259884815.134628864 uru4000:debug [irq_handler] cancelled fp:debug [fpi_imgdev_deactivate_complete] async:debug [fpi_drvcb_identify_stopped] sync:debug [identify_stop_cb] poll:debug [handle_timeouts] g_n_t_e result 0 val -1209125536.-1079468400 sync:debug [fp_dev_close] async:debug [fpi_drvcb_close_complete] sync:debug [sync_close_cb] sovereign flocchini # thank you :) _______________________________________________ fprint mailing list [email protected] http://lists.reactivated.net/mailman/listinfo/fprint
