See http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/138/changes Changes: [mls] [backend] implement 60 second timeout to receive the request ------------------------------------------ [...truncated 276778 lines...] [[0;37mDEBUG[0m|#30079] Cache delete: Directory_9eb4dcbc52b560fa70838c976db96afd [[0;37mDEBUG[0m|#30079] method_missing -to_str- nil [[0;37mDEBUG[0m|#30079] method_missing -to_str- nil [[0;37mDEBUG[0m|#30079] method_missing -to_ary- nil [[0;37mDEBUG[0m|#30079] Directory.find( 1800 seconds, {:project=>"NotExisiting", :package=>"notthere"}) [[0;37mDEBUG[0m|#30079] Cache miss: Directory_9eb4dcbc52b560fa70838c976db96afd ({:expires_in=>1800 seconds}) [[0;37mDEBUG[0m|#30079] [REST] find( Directory, [{:project=>"NotExisiting", :package=>"notthere"}] ) [[0;37mDEBUG[0m|#30079] http_do #1: method: get url: http://localhost:3203/source/NotExisiting/notthere? [[0;37mDEBUG[0m|#30079] Request took 0.047088073 seconds [[0;37mDEBUG[0m|#30079] Directory.find( {:project=>"NotExisiting", :package=>"notthere"} ) did not find anything, return nil [[0;37mDEBUG[0m|#30079] Rendered shared/_project_or_package_link (144.4ms) [[0;37mDEBUG[0m|#30079] Rendered shared/_project_or_package_link (0.6ms) [[0;37mDEBUG[0m|#30079] Rendered shared/_user_with_realname_and_icon (0.4ms) [[0;37mDEBUG[0m|#30079] Rendered request/_recent_events_table (0.9ms) [[0;37mDEBUG[0m|#30079] Rendered layouts/_watch_and_search (0.3ms) [[0;37mDEBUG[0m|#30079] Rendered layouts/_header (0.7ms) [[0;37mDEBUG[0m|#30079] Rendered layouts/_personal_navigation (0.7ms) [[0;37mDEBUG[0m|#30079] Rendered layouts/_flash (0.0ms) [[0;37mDEBUG[0m|#30079] Rendered layouts/_sponsor (0.1ms) [[32mINFO [0m|#30079] Completed in 353ms (View: 152, DB: 1, API: 330, XML: 2) | 200 OK [http://localhost/request/show/997] [[32mINFO [0m|#30079] Processing HomeController#icon (for 127.0.0.1 at 2012-05-02 19:00:54) [GET] [[32mINFO [0m|#30079] Parameters: {"size"=>"20", "id"=>"tom"} [[0;37mDEBUG[0m|#30079] Setting return_to: "/home/icon/tom?size=20" [[0;37mDEBUG[0m|#30079] Authenticating with iChain mode: off [[32mINFO [0m|#30079] Anonymous request to /home/icon/tom?size=20 [[0;37mDEBUG[0m|#30079] Cache hit: configuration ({:expires_in=>1800 seconds}) [[0;37mDEBUG[0m|#30079] Cache hit: home_face_tom_20 ({:expires_in=>18000 seconds}) [[32mINFO [0m|#30079] Completed in 4ms (View: 0, DB: 1, API: 0, XML: 0) | 200 OK [http://localhost/home/icon/tom?size=20] [[32mINFO [0m|#30079] Processing RequestController#show (for 127.0.0.1 at 2012-05-02 19:00:54) [GET] [[32mINFO [0m|#30079] Parameters: {"id"=>"998"} [[0;37mDEBUG[0m|#30079] Setting return_to: "/request/show/998" [[0;37mDEBUG[0m|#30079] Authenticating with iChain mode: off [[32mINFO [0m|#30079] Anonymous request to /request/show/998 [[0;37mDEBUG[0m|#30079] Cache hit: configuration ({:expires_in=>1800 seconds}) [[0;37mDEBUG[0m|#30079] BsRequest.find( 1800 seconds, 998) [[0;37mDEBUG[0m|#30079] Cache miss: BsRequest_b0f90678572a44675b741cd88ee01fd0 ({:expires_in=>1800 seconds}) [[0;37mDEBUG[0m|#30079] [REST] find( BsRequest, [{:id=>"998"}] ) [[0;37mDEBUG[0m|#30079] http_do #1: method: get url: http://localhost:3203/request/998 [[0;37mDEBUG[0m|#30079] Request took 0.014025768 seconds [[0;37mDEBUG[0m|#30079] Cache write (will save 14.57ms): BsRequest_b0f90678572a44675b741cd88ee01fd0 [[0;37mDEBUG[0m|#30079] method_missing -to_str- nil [[0;37mDEBUG[0m|#30079] method_missing -to_str- nil [[0;37mDEBUG[0m|#30079] method_missing -to_ary- nil [[0;37mDEBUG[0m|#30079] Person.find( 1800 seconds, tom, {}) [[0;37mDEBUG[0m|#30079] Cache hit: Person_834a8521d00df1c128b043ffe80b79f1 ({:expires_in=>1800 seconds}) [[0;37mDEBUG[0m|#30079] Cache miss: request_998_is_target_maintainer_ ({:expires_in=>7 days}) [[0;37mDEBUG[0m|#30079] method_missing -to_str- nil [[0;37mDEBUG[0m|#30079] method_missing -to_str- nil [[0;37mDEBUG[0m|#30079] method_missing -to_ary- nil [[0;37mDEBUG[0m|#30079] Package.find( 1800 seconds, unknown, {:project=>"NeitherExisting"}) [[0;37mDEBUG[0m|#30079] Cache miss: Package_042fe7e6e44aeb086beaed0429ae15ff ({:expires_in=>1800 seconds}) [[0;37mDEBUG[0m|#30079] [REST] find( Package, [{:project=>"NeitherExisting", :name=>"unknown"}] ) [[0;37mDEBUG[0m|#30079] http_do #1: method: get url: http://localhost:3203/source/NeitherExisting/unknown/_meta? [[0;37mDEBUG[0m|#30079] Request took 0.048301785 seconds [[0;37mDEBUG[0m|#30079] Package.find( {:project=>"NeitherExisting", :name=>"unknown"} ) did not find anything, return nil [[0;37mDEBUG[0m|#30079] method_missing -to_str- nil [[0;37mDEBUG[0m|#30079] method_missing -to_str- nil [[0;37mDEBUG[0m|#30079] method_missing -to_ary- nil [[0;37mDEBUG[0m|#30079] Package.find( 1800 seconds, unknown2, {:project=>"NeitherExisting"}) [[0;37mDEBUG[0m|#30079] Cache miss: Package_c887fa9370799c09a0d030703346ac72 ({:expires_in=>1800 seconds}) [[0;37mDEBUG[0m|#30079] [REST] find( Package, [{:project=>"NeitherExisting", :name=>"unknown2"}] ) [[0;37mDEBUG[0m|#30079] http_do #1: method: get url: http://localhost:3203/source/NeitherExisting/unknown2/_meta? [[0;37mDEBUG[0m|#30079] Request took 0.050667499 seconds [[0;37mDEBUG[0m|#30079] Package.find( {:project=>"NeitherExisting", :name=>"unknown2"} ) did not find anything, return nil [[0;37mDEBUG[0m|#30079] Cache write (will save 101.46ms): request_998_is_target_maintainer_ [[0;37mDEBUG[0m|#30079] Cache miss: request_998_actions ({:expires_in=>7 days}) [[0;37mDEBUG[0m|#30079] Cache miss: request_998_actiondiffs ({:expires_in=>7 days}) [[0;37mDEBUG[0m|#30079] --> direct_http url: #<URI::Generic:0x00000002019b98 URL:http://localhost:3203/request/998?cmd=diff&view=xml&withissues=1> [[0;37mDEBUG[0m|#30079] http_do #1: method: POST url: http://localhost:3203/request/998?cmd=diff&view=xml&withissues=1 [[0;37mDEBUG[0m|#30079] Request took 0.023789291 seconds [[0;37mDEBUG[0m|#30079] Cache write (will save 24.44ms): request_998_actiondiffs [[0;37mDEBUG[0m|#30079] method_missing -to_str- nil [[0;37mDEBUG[0m|#30079] method_missing -to_str- nil [[0;37mDEBUG[0m|#30079] method_missing -to_ary- nil [[0;37mDEBUG[0m|#30079] Package.find( 1800 seconds, {:project=>"NeitherExisting", :package=>"unknown"}) [[0;37mDEBUG[0m|#30079] Cache miss: Package_13486eb490e7f9af7937baadb5e1da65 ({:expires_in=>1800 seconds}) [[0;37mDEBUG[0m|#30079] [REST] find( Package, [{:project=>"NeitherExisting", :package=>"unknown"}] ) [[0;37mDEBUG[0m|#30079] http_do #1: method: get url: http://localhost:3203/source/NeitherExisting/_meta? [[0;37mDEBUG[0m|#30079] Request took 0.048826038 seconds [[0;37mDEBUG[0m|#30079] Package.find( {:project=>"NeitherExisting", :package=>"unknown"} ) did not find anything, return nil [[0;37mDEBUG[0m|#30079] Project.find( 1800 seconds, NeitherExisting) [[0;37mDEBUG[0m|#30079] Cache miss: Project_03273ba61a4ed6b136aaf48b19721f1c ({:expires_in=>1800 seconds}) [[0;37mDEBUG[0m|#30079] [REST] find( Project, [{:name=>"NeitherExisting"}] ) [[0;37mDEBUG[0m|#30079] http_do #1: method: get url: http://localhost:3203/source/NeitherExisting/_meta? [[0;37mDEBUG[0m|#30079] Request took 0.055063687 seconds [[0;37mDEBUG[0m|#30079] Project.find( {:name=>"NeitherExisting"} ) did not find anything, return nil [[0;37mDEBUG[0m|#30079] method_missing -to_str- nil [[0;37mDEBUG[0m|#30079] method_missing -to_str- nil [[0;37mDEBUG[0m|#30079] method_missing -to_ary- nil [[0;37mDEBUG[0m|#30079] Package.find( 1800 seconds, unknown, {:project=>"NeitherExisting"}) [[0;37mDEBUG[0m|#30079] Cache miss: Package_042fe7e6e44aeb086beaed0429ae15ff ({:expires_in=>1800 seconds}) [[0;37mDEBUG[0m|#30079] [REST] find( Package, [{:project=>"NeitherExisting", :name=>"unknown"}] ) [[0;37mDEBUG[0m|#30079] http_do #1: method: get url: http://localhost:3203/source/NeitherExisting/unknown/_meta? [[0;37mDEBUG[0m|#30079] Request took 0.050886663 seconds [[0;37mDEBUG[0m|#30079] Package.find( {:project=>"NeitherExisting", :name=>"unknown"} ) did not find anything, return nil [[0;37mDEBUG[0m|#30079] Cache hit: request_998_actiondiffs ({:expires_in=>7 days}) [[0;37mDEBUG[0m|#30079] Cache write (will save 183.52ms): request_998_actions [[32mINFO [0m|#30079] Rendering template within layouts/application [[32mINFO [0m|#30079] Rendering request/show [[0;37mDEBUG[0m|#30079] Rendered shared/_user_with_realname_and_icon (0.1ms) [[0;37mDEBUG[0m|#30079] Project.find( 1800 seconds, kde4) [[0;37mDEBUG[0m|#30079] Cache hit: Project_02bfffa9cd88e71a22c74df6329d4e44 ({:expires_in=>1800 seconds}) [[0;37mDEBUG[0m|#30079] method_missing -to_str- nil [[0;37mDEBUG[0m|#30079] method_missing -to_str- nil [[0;37mDEBUG[0m|#30079] method_missing -to_ary- nil [[0;37mDEBUG[0m|#30079] Package.find( 1800 seconds, kdelibs, {:project=>"kde4"}) [[0;37mDEBUG[0m|#30079] Cache hit: Package_faac300e6e34200cefc42ad1b3919c87 ({:expires_in=>1800 seconds}) [[0;37mDEBUG[0m|#30079] Cache delete: Directory_eb5f0a40cd5ae1fdfd240b427e4d4c2a [[0;37mDEBUG[0m|#30079] method_missing -to_str- nil [[0;37mDEBUG[0m|#30079] method_missing -to_str- nil [[0;37mDEBUG[0m|#30079] method_missing -to_ary- nil [[0;37mDEBUG[0m|#30079] Directory.find( 1800 seconds, {:project=>"kde4", :package=>"kdelibs"}) [[0;37mDEBUG[0m|#30079] Cache miss: Directory_eb5f0a40cd5ae1fdfd240b427e4d4c2a ({:expires_in=>1800 seconds}) [[0;37mDEBUG[0m|#30079] [REST] find( Directory, [{:project=>"kde4", :package=>"kdelibs"}] ) [[0;37mDEBUG[0m|#30079] http_do #1: method: get url: http://localhost:3203/source/kde4/kdelibs? [[0;37mDEBUG[0m|#30079] Request took 0.062941193 seconds [[0;37mDEBUG[0m|#30079] Cache write (will save 63.42ms): Directory_eb5f0a40cd5ae1fdfd240b427e4d4c2a [[0;37mDEBUG[0m|#30079] Rendered shared/_project_or_package_link (65.1ms) [[0;37mDEBUG[0m|#30079] Project.find( 1800 seconds, NeitherExisting) [[0;37mDEBUG[0m|#30079] Cache miss: Project_03273ba61a4ed6b136aaf48b19721f1c ({:expires_in=>1800 seconds}) [[0;37mDEBUG[0m|#30079] [REST] find( Project, [{:name=>"NeitherExisting"}] ) [[0;37mDEBUG[0m|#30079] http_do #1: method: get url: http://localhost:3203/source/NeitherExisting/_meta? [[0;37mDEBUG[0m|#30079] Request took 0.05478064 seconds [[0;37mDEBUG[0m|#30079] Project.find( {:name=>"NeitherExisting"} ) did not find anything, return nil [[0;37mDEBUG[0m|#30079] method_missing -to_str- nil [[0;37mDEBUG[0m|#30079] method_missing -to_str- nil [[0;37mDEBUG[0m|#30079] method_missing -to_ary- nil [[0;37mDEBUG[0m|#30079] Package.find( 1800 seconds, unknown, {:project=>"NeitherExisting"}) [[0;37mDEBUG[0m|#30079] Cache miss: Package_042fe7e6e44aeb086beaed0429ae15ff ({:expires_in=>1800 seconds}) [[0;37mDEBUG[0m|#30079] [REST] find( Package, [{:project=>"NeitherExisting", :name=>"unknown"}] ) [[0;37mDEBUG[0m|#30079] http_do #1: method: get url: http://localhost:3203/source/NeitherExisting/unknown/_meta? [[0;37mDEBUG[0m|#30079] Request took 0.050870761 seconds [[0;37mDEBUG[0m|#30079] Package.find( {:project=>"NeitherExisting", :name=>"unknown"} ) did not find anything, return nil [[0;37mDEBUG[0m|#30079] Rendered shared/_project_or_package_link (107.6ms) [[0;37mDEBUG[0m|#30079] Project.find( 1800 seconds, NeitherExisting) [[0;37mDEBUG[0m|#30079] Cache miss: Project_03273ba61a4ed6b136aaf48b19721f1c ({:expires_in=>1800 seconds}) [[0;37mDEBUG[0m|#30079] [REST] find( Project, [{:name=>"NeitherExisting"}] ) [[0;37mDEBUG[0m|#30079] http_do #1: method: get url: http://localhost:3203/source/NeitherExisting/_meta? [[0;37mDEBUG[0m|#30079] Request took 0.046989069 seconds [[0;37mDEBUG[0m|#30079] Project.find( {:name=>"NeitherExisting"} ) did not find anything, return nil [[0;37mDEBUG[0m|#30079] method_missing -to_str- nil [[0;37mDEBUG[0m|#30079] method_missing -to_str- nil [[0;37mDEBUG[0m|#30079] method_missing -to_ary- nil [[0;37mDEBUG[0m|#30079] Package.find( 1800 seconds, unknown2, {:project=>"NeitherExisting"}) [[0;37mDEBUG[0m|#30079] Cache miss: Package_c887fa9370799c09a0d030703346ac72 ({:expires_in=>1800 seconds}) [[0;37mDEBUG[0m|#30079] [REST] find( Package, [{:project=>"NeitherExisting", :name=>"unknown2"}] ) [[0;37mDEBUG[0m|#30079] http_do #1: method: get url: http://localhost:3203/source/NeitherExisting/unknown2/_meta? [[0;37mDEBUG[0m|#30079] Request took 0.047940173 seconds [[0;37mDEBUG[0m|#30079] Package.find( {:project=>"NeitherExisting", :name=>"unknown2"} ) did not find anything, return nil [[0;37mDEBUG[0m|#30079] Rendered shared/_project_or_package_link (96.7ms) [[0;37mDEBUG[0m|#30079] Rendered shared/_user_with_realname_and_icon (0.5ms) [[0;37mDEBUG[0m|#30079] Rendered request/_recent_events_table (1.0ms) [[0;37mDEBUG[0m|#30079] Rendered layouts/_watch_and_search (0.3ms) [[0;37mDEBUG[0m|#30079] Rendered layouts/_header (0.7ms) [[0;37mDEBUG[0m|#30079] Rendered layouts/_personal_navigation (0.6ms) [[0;37mDEBUG[0m|#30079] Rendered layouts/_flash (0.1ms) [[0;37mDEBUG[0m|#30079] Rendered layouts/_sponsor (0.0ms) [[32mINFO [0m|#30079] Completed in 584ms (View: 277, DB: 0, API: 556, XML: 1) | 200 OK [http://localhost/request/show/998] [[32mINFO [0m|#30079] Processing HomeController#icon (for 127.0.0.1 at 2012-05-02 19:00:55) [GET] [[32mINFO [0m|#30079] Parameters: {"size"=>"20", "id"=>"tom"} [[0;37mDEBUG[0m|#30079] Setting return_to: "/home/icon/tom?size=20" [[0;37mDEBUG[0m|#30079] Authenticating with iChain mode: off [[32mINFO [0m|#30079] Anonymous request to /home/icon/tom?size=20 [[0;37mDEBUG[0m|#30079] Cache hit: configuration ({:expires_in=>1800 seconds}) [[0;37mDEBUG[0m|#30079] Cache hit: home_face_tom_20 ({:expires_in=>18000 seconds}) [[32mINFO [0m|#30079] Completed in 4ms (View: 0, DB: 0, API: 0, XML: 0) | 200 OK [http://localhost/home/icon/tom?size=20] [[32mINFO [0m|#30079] Processing SearchController#index (for 127.0.0.1 at 2012-05-02 19:00:55) [GET] [[0;37mDEBUG[0m|#30079] Setting return_to: "/search" [[0;37mDEBUG[0m|#30079] Authenticating with iChain mode: off [[32mINFO [0m|#30079] Anonymous request to /search [[0;37mDEBUG[0m|#30079] Cache hit: configuration ({:expires_in=>1800 seconds}) [[0;37mDEBUG[0m|#30079] Attribute.find( 1800 seconds, namespaces) [[0;37mDEBUG[0m|#30079] Cache hit: Attribute_abf073acf4f2b7187044a69dc776c36a ({:expires_in=>1800 seconds}) [[0;37mDEBUG[0m|#30079] method_missing -to_str- nil [[0;37mDEBUG[0m|#30079] method_missing -to_ary- nil [[0;37mDEBUG[0m|#30079] Attribute.find( 1800 seconds, attributes, {:namespace=>"NSTEST"}) [[0;37mDEBUG[0m|#30079] Cache hit: Attribute_acf162ca35af0c0b6e81adfd382303e0 ({:expires_in=>1800 seconds}) [[0;37mDEBUG[0m|#30079] method_missing -to_str- nil [[0;37mDEBUG[0m|#30079] method_missing -to_ary- nil [[0;37mDEBUG[0m|#30079] Attribute.find( 1800 seconds, attributes, {:namespace=>"OBS"}) [[0;37mDEBUG[0m|#30079] Cache hit: Attribute_8fb2549b00f45ceed5a606ef2fc64363 ({:expires_in=>1800 seconds}) [[0;37mDEBUG[0m|#30079] IssueTracker.find( 1800 seconds, all) [[0;37mDEBUG[0m|#30079] Cache hit: IssueTracker_c9f2a41f57195c17eafd205f9fae8094 ({:expires_in=>1800 seconds}) [[32mINFO [0m|#30079] Rendering template within layouts/application [[32mINFO [0m|#30079] Rendering search/index [[0;37mDEBUG[0m|#30079] Rendered layouts/_watch_and_search (0.2ms) [[0;37mDEBUG[0m|#30079] Rendered layouts/_header (0.7ms) [[0;37mDEBUG[0m|#30079] Rendered layouts/_personal_navigation (0.6ms) [[0;37mDEBUG[0m|#30079] Rendered layouts/_flash (0.0ms) [[0;37mDEBUG[0m|#30079] Rendered layouts/_sponsor (0.1ms) [[32mINFO [0m|#30079] Completed in 16ms (View: 6, DB: 0, API: 0, XML: 2) | 200 OK [http://localhost/search] [[32mINFO [0m|#30079] Processing UserController#login (for 127.0.0.1 at 2012-05-02 19:00:56) [GET] [[0;37mDEBUG[0m|#30079] Setting return_to: "/user/login" [[0;37mDEBUG[0m|#30079] Authenticating with iChain mode: off [[32mINFO [0m|#30079] Anonymous request to /user/login [[0;37mDEBUG[0m|#30079] Cache hit: configuration ({:expires_in=>1800 seconds}) [[32mINFO [0m|#30079] Rendering template within layouts/application [[32mINFO [0m|#30079] Rendering user/login [[0;37mDEBUG[0m|#30079] Rendered layouts/_watch_and_search (0.3ms) [[0;37mDEBUG[0m|#30079] Rendered layouts/_header (0.7ms) [[0;37mDEBUG[0m|#30079] Rendered layouts/_personal_navigation (0.6ms) [[0;37mDEBUG[0m|#30079] Rendered layouts/_flash (0.0ms) [[0;37mDEBUG[0m|#30079] Rendered layouts/_sponsor (0.0ms) [[32mINFO [0m|#30079] Completed in 12ms (View: 8, DB: 0, API: 0, XML: 0) | 200 OK [http://localhost/user/login] [[32mINFO [0m|#30079] Processing UserController#register_user (for 127.0.0.1 at 2012-05-02 19:00:56) [GET] [[0;37mDEBUG[0m|#30079] Setting return_to: "/user/register_user" [[0;37mDEBUG[0m|#30079] Authenticating with iChain mode: off [[32mINFO [0m|#30079] Anonymous request to /user/register_user [[0;37mDEBUG[0m|#30079] Cache hit: configuration ({:expires_in=>1800 seconds}) [[32mINFO [0m|#30079] Rendering template within layouts/application [[32mINFO [0m|#30079] Rendering user/register_user [[0;37mDEBUG[0m|#30079] Rendered layouts/_watch_and_search (0.3ms) [[0;37mDEBUG[0m|#30079] Rendered layouts/_header (0.7ms) [[0;37mDEBUG[0m|#30079] Rendered layouts/_personal_navigation (0.6ms) [[0;37mDEBUG[0m|#30079] Rendered layouts/_flash (0.1ms) [[0;37mDEBUG[0m|#30079] Rendered layouts/_sponsor (0.1ms) [[32mINFO [0m|#30079] Completed in 10ms (View: 5, DB: 0, API: 0, XML: 0) | 200 OK [http://localhost/user/register_user] + echo + cleanup + echo 'Killing backend processes' Killing backend processes + fuser -v http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/ws/ + egrep 'perl|ruby' USER PID ACCESS COMMAND http://ci.opensuse.org/job/obs_master_testsuite_webui-testsuite/ws/: jenkins ..c.. egrep jenkins ..c.. sh jenkins ..c.. sh + echo 'Remove log/tmp files to save disc space' Remove log/tmp files to save disc space + rm -rf src/api/log/backend_access.log src/api/log/test.log src/api/tmp/backend_config src/api/tmp/backend_data src/api/tmp/cache src/api/tmp/pids src/api/tmp/sessions src/api/tmp/sockets src/webui/log/test.log src/webui/tmp/cache src/webui/tmp/pids src/webui/tmp/sessions src/webui/tmp/sockets + exit 1 Build step 'Execute shell' marked build as failure [locks-and-latches] Releasing all the locks [locks-and-latches] All the locks released Recording test results -- To unsubscribe, e-mail: obs-tests+unsubscribe@opensuse.org To contact the owner, e-mail: obs-tests+owner@opensuse.org