[packagekit] Bug in get updates

Tue Nov 27 15:45:10 PST 2007

On Tue, 2007-11-27 at 23:09 +0000, Richard Hughes wrote:
> On Tue, 2007-11-27 at 17:30 -0500, Elliot Peele wrote:
> > I was trying to figure out why I haven't seen an update from PackageKit
> > in awhile, it looks like packagekitd isn't returning the correct thing.
> > 
> > https://bugs.freedesktop.org/show_bug.cgi?id=13400
> 
> Could you please grab me the output of
> 
> pkcon --verbose get updates
> 
> and
> 
> packagekitd --verbose --backend=conary
> 
> (while you do a get-updates call)

Attached.

Elliot
-------------- next part --------------
[root at localhost ~]# kill -9 2691
[root at localhost ~]# packagekitd --verbose --backend=conary
[pk_debug_init] pk-debug.c:136 (18:41:58):       Verbose debugging enabled
[pk_conf_init] pk-conf.c:129 (18:41:58):         config file not found '../etc/PackageKit.conf'
[pk_conf_init] pk-conf.c:136 (18:41:58):         using config file '/etc/PackageKit/PackageKit.conf'
[main] pk-main.c:214 (18:41:58):         daemon shutdown set to 0 seconds
[pk_security_init] pk-security-polkit.c:204 (18:41:58):  Using PolicyKit security framework
[pk_transaction_db_init] pk-transaction-db.c:383 (18:41:58):     trying to open database '/var/lib/PackageKit/transactions.db'
[pk_engine_use_backend] pk-engine.c:162 (18:41:58):      trying backend conary
[pk_backend_load] pk-backend.c:164 (18:41:58):   Trying to load : conary
[pk_backend_build_library_path] pk-backend.c:140 (18:41:58):     dlopening '/usr/lib/packagekit-backend/libpk_backend_conary.so'
[pk_engine_reset_timer] pk-engine.c:184 (18:41:58):      reset timer
[pk_backend_finalize] pk-backend.c:1893 (18:41:58):      freeing conary (0x8094800)
[pk_inhibit_remove] pk-inhibit.c:201 (18:41:58):         cannot find item 0x8094800
[pk_engine_get_transaction_list] pk-engine.c:2339 (18:41:58):    GetTransactionList method called
[pk_transaction_list_get_array] pk-transaction-list.c:320 (18:41:58):    0 active transactions
[pk_engine_get_transaction_list] pk-engine.c:2339 (18:41:58):    GetTransactionList method called
[pk_transaction_list_get_array] pk-transaction-list.c:320 (18:41:58):    0 active transactions
[pk_engine_get_tid] pk-engine.c:824 (18:42:09):  GetTid method called
[pk_transaction_id_load_job_count] pk-transaction-id.c:86 (18:42:09):    job=4
[pk_transaction_id_save_job_count] pk-transaction-id.c:100 (18:42:09):   saving 5
[pk_engine_get_tid] pk-engine.c:827 (18:42:09):  sending tid: '4;eedddebb;data'
[pk_engine_get_updates] pk-engine.c:940 (18:42:09):      GetUpdates method called: 4;eedddebb;data
[pk_backend_load] pk-backend.c:164 (18:42:09):   Trying to load : conary
[pk_backend_build_library_path] pk-backend.c:140 (18:42:09):     dlopening '/usr/lib/packagekit-backend/libpk_backend_conary.so'
[pk_engine_reset_timer] pk-engine.c:184 (18:42:09):      reset timer
[pk_backend_set_dbus_name] pk-backend.c:1849 (18:42:09):         assiging :1.5406 to 0x80948a8
*** WARNING ***
[pk_backend_connection_changed_cb] pk-backend.c:1871 (18:42:09):         client disconnected.... 1
[pk_engine_caller_active_changed_cb] pk-engine.c:651 (18:42:09):         emitting caller-active-changed tid:4;eedddebb;data, 0
[pk_backend_set_role] pk-backend.c:748 (18:42:09):       setting role to get-updates
[pk_transaction_list_commit] pk-transaction-list.c:258 (18:42:09):       marking transaction 4;eedddebb;data as committed
[pk_transaction_list_commit] pk-transaction-list.c:262 (18:42:09):       emmitting ::changed
[pk_transaction_list_get_array] pk-transaction-list.c:320 (18:42:09):    1 active transactions
[pk_engine_transaction_list_changed_cb] pk-engine.c:201 (18:42:09):      emitting transaction-list-changed
[pk_engine_reset_timer] pk-engine.c:184 (18:42:09):      reset timer
[pk_transaction_list_commit] pk-transaction-list.c:291 (18:42:09):       running 4;eedddebb;data
[pk_backend_spawn_helper_internal] pk-backend.c:596 (18:42:09):  using spawn filename /usr/share/PackageKit/helpers/conary/get-updates.py
[pk_backend_spawn_helper_new] pk-backend.c:567 (18:42:09):       allocating spawn 0x8090588
[pk_spawn_command] pk-spawn.c:291 (18:42:09):    command '/usr/share/PackageKit/helpers/conary/get-updates.py'
[pk_inhibit_lock] pk-inhibit.c:86 (18:42:09):    Trying to AcquireInterfaceLock
[pk_inhibit_lock] pk-inhibit.c:108 (18:42:09):   emit lock 1
[pk_engine_inhibit_locked_cb] pk-engine.c:214 (18:42:09):        emitting locked 1
[pk_engine_get_role] pk-engine.c:2386 (18:42:09):        GetRole method called: 4;eedddebb;data
[pk_engine_get_status] pk-engine.c:2358 (18:42:09):      GetStatus method called: 4;eedddebb;data
[pk_engine_get_role] pk-engine.c:2386 (18:42:09):        GetRole method called: 4;eedddebb;data
[pk_engine_get_status] pk-engine.c:2358 (18:42:09):      GetStatus method called: 4;eedddebb;data
[pk_spawn_emit_whole_lines] pk-spawn.c:135 (18:42:10):   emitting stderr allow-interrupt        true
[pk_backend_spawn_stderr_cb] pk-backend.c:552 (18:42:10):        stderr from 0x8090588 = 'allow-interrupt       true'
[pk_backend_allow_interrupt] pk-backend.c:1182 (18:42:10):       emit allow-interrupt 1
[pk_inhibit_unlock] pk-inhibit.c:127 (18:42:10):         Trying to ReleaseInterfaceLock
[pk_inhibit_unlock] pk-inhibit.c:149 (18:42:10):         emit lock 0
[pk_engine_inhibit_locked_cb] pk-engine.c:214 (18:42:10):        emitting locked 0
[pk_engine_allow_interrupt_cb] pk-engine.c:630 (18:42:10):       emitting allow-interrpt tid:4;eedddebb;data, 1
[pk_spawn_emit_whole_lines] pk-spawn.c:135 (18:42:10):   emitting stderr no-percentage-updates
[pk_backend_spawn_stderr_cb] pk-backend.c:552 (18:42:10):        stderr from 0x8090588 = 'no-percentage-updates'
[pk_backend_emit_progress_changed] pk-backend.c:686 (18:42:10):  emit progress 101, 101, 718, 0
[pk_engine_progress_changed_cb] pk-engine.c:260 (18:42:10):      emitting percentage-changed tid:4;eedddebb;data 101, 101, 718, 0
[pk_engine_reset_timer] pk-engine.c:184 (18:42:10):      reset timer
[pk_spawn_check_child] pk-spawn.c:185 (18:42:14):        Running fork successful
[pk_spawn_check_child] pk-spawn.c:200 (18:42:14):        emitting finished 0
[pk_backend_spawn_finished_cb] pk-backend.c:508 (18:42:14):      deleting spawn 0x8090588, exit success
[pk_backend_spawn_helper_delete] pk-backend.c:493 (18:42:14):    deleting spawn 0x8090588
[pk_backend_finished] pk-backend.c:1083 (18:42:14):      finished role get-updates
Backends should send status <value> signals to update the UI!
If you are:
* Calling out to external tools, the compiled backend should call pk_backend_change_status() manually.
* Using a scripted backend with dumb commands then this should be set at the start of the runtime call
   - see helpers/yumBackend.py:self.status()
* Using a scripted backend with clever commands then a   callback should use map values into status enums
   - see helpers/yumBackend.py:self.state_actions
*** WARNING ***
[pk_backend_finished] pk-backend.c:1125 (18:42:14):      GUI will remain unchanged!
[pk_inhibit_remove] pk-inhibit.c:201 (18:42:14):         cannot find item 0x80948a8
[pk_backend_finished] pk-backend.c:1136 (18:42:14):      adding finished 0x80948a8 to timeout loop
[pk_backend_finished_delay] pk-backend.c:1065 (18:42:14):        emit finished 0
[pk_engine_finish_invalidate_caches] pk-engine.c:511 (18:42:14):         invalidating caches
[pk_engine_finish_invalidate_caches] pk-engine.c:520 (18:42:14):         reffing updates cache
[pk_engine_finished_cb] pk-engine.c:602 (18:42:14):      backend was running for 4367 ms
[pk_transaction_db_sql_statement] pk-transaction-db.c:195 (18:42:14):    statement=UPDATE transactions SET succeeded = 1, duration = 4367 WHERE transaction_id = '4;eedddebb;data'
[pk_engine_finished_cb] pk-engine.c:606 (18:42:14):      emitting finished transaction:4;eedddebb;data, 'success', 4367
[pk_engine_reset_timer] pk-engine.c:184 (18:42:14):      reset timer
[pk_transaction_list_backend_finished_cb] pk-transaction-list.c:191 (18:42:14):  transaction 4;eedddebb;data completed, marking finished
[pk_transaction_list_backend_finished_cb] pk-transaction-list.c:195 (18:42:14):  emmitting ::changed
[pk_transaction_list_get_array] pk-transaction-list.c:320 (18:42:14):    1 active transactions
[pk_engine_transaction_list_changed_cb] pk-engine.c:201 (18:42:14):      emitting transaction-list-changed
[pk_engine_reset_timer] pk-engine.c:184 (18:42:14):      reset timer
[pk_engine_get_role] pk-engine.c:2386 (18:42:14):        GetRole method called: 4;eedddebb;data
*** WARNING ***
[pk_backend_connection_changed_cb] pk-backend.c:1871 (18:42:14):         client disconnected.... 0
[pk_engine_caller_active_changed_cb] pk-engine.c:651 (18:42:14):         emitting caller-active-changed tid:4;eedddebb;data, 0
[pk_transaction_list_remove_item_timeout] pk-transaction-list.c:159 (18:42:19):  transaction 4;eedddebb;data completed, removing
[pk_backend_finalize] pk-backend.c:1893 (18:42:19):      freeing conary (0x80948a8)
[pk_inhibit_remove] pk-inhibit.c:201 (18:42:19):         cannot find item 0x80948a8

-------------- next part --------------
[elliot at localhost ~]$ pkcon --verbose get updates
[pk_debug_init] pk-debug.c:136 (18:42:09):       Verbose debugging enabled
[pk_connection_connection_changed_cb] pk-connection.c:116 (18:42:09):    emit connection-changed: 1
[pk_client_connect] pk-client.c:2522 (18:42:09):         connect
[main] pk-console.c:1055 (18:42:09):     actions=cancel;get-description;get-files;get-updates;install-package;refresh-cache;remove-package;resolve;search-name;update-package;update-system
[pk_client_allocate_transaction_id] pk-client.c:957 (18:42:09):  Got tid: '4;eedddebb;data'
[pk_client_wait] pk-console.c:352 (18:42:09):    starting loop
[pk_client_caller_active_changed_cb] pk-client.c:681 (18:42:09):         emit caller-active-changed 0
[pk_client_locked_cb] pk-client.c:660 (18:42:09):        emit locked 1
[pk_client_locked_cb] pk-client.c:660 (18:42:10):        emit locked 0
[pk_client_progress_changed_cb] pk-client.c:404 (18:42:10):      emit progress-changed 101, 101, 718, 0
    [.........................................................................................===.....................]                [pk_client_finished_cb] pk-client.c:380 (18:42:14):      emit finished success, 4367
get-updates runtime was 4.4 seconds             