New subject: [obs-tests] Jenkins build is back to normal : obs_master_testsuite_webui #895

19 Apr 2013


      See <http://ci.opensuse.org/job/obs_master_testsuite_webui/894/changes>

Changes:

[mls] [backend] put remote_getrev results in the cache, use the cache for project sourceinfo as well

[mls] [backend] also put old revisions in the cache

------------------------------------------
[...truncated 651839 lines...]
[[0;37mDEBUG[0m|#17433] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] )
[[0;37mDEBUG[0m|#17433] params {:is_current=>"true", :login=>"tom"}
[[0;37mDEBUG[0m|#17433] uri is: http://localhost:3203/person/:login
[[0;37mDEBUG[0m|#17433] http_do #1: method: get url: http://localhost:3203/person/tom
[[0;37mDEBUG[0m|#17433] RT http://localhost:3203/person/tom {"view"=>0.644017, "db"=>1.7832959999999998, "backend"=>0, "xml"=>0, :runtime=>14.436, :all=>18.393546999999998}
[[0;37mDEBUG[0m|#17433] returning [{:name=>"BaseDistro"}] from rails cache Project_f726314c8d941efeb9d0b888fe97b37e
[[0;37mDEBUG[0m|#17433] free_cache [{:project=>"BaseDistro", :view=>"summary"}] Buildresult_81f2400bb79dc620ddf8b0bf87e74935
[[0;37mDEBUG[0m|#17433] [REST] find( Buildresult, [{:project=>"BaseDistro", :view=>"summary"}] )
[[0;37mDEBUG[0m|#17433] params {:project=>"BaseDistro", :view=>"summary"}
[[0;37mDEBUG[0m|#17433] uri is: http://localhost:3203/build/:project/_result?:view&:package&:code&:lastbuild&:arch&:repository
[[0;37mDEBUG[0m|#17433] http_do #1: method: get url: http://localhost:3203/build/BaseDistro/_result?view=summary
[[0;37mDEBUG[0m|#17433] RT http://localhost:3203/build/BaseDistro/_result?view=summary {"view"=>nil, "db"=>0.9863419999999998, "backend"=>0, "xml"=>0, :runtime=>26.329, :all=>67.46529}
[[32mINFO [0m|#17433]   Rendered project/_buildstatus.html.erb (0.1ms)
[[32mINFO [0m|#17433] Completed 200 OK in 89ms (Views: 0.6ms | ActiveRecord: 0.0ms | API: 85.9ms (XML: 0.0ms , View: 0.6ms , Backend: 0.0ms , DB: 2.8ms , HTTP: 45.1ms) | XML: 0.3ms)
[[32mINFO [0m|#17433] Started GET "/project/show?project=My%3AMaintenance" for 127.0.0.1 at 2013-04-19 11:32:47 +0000
[[32mINFO [0m|#17433] Processing by ProjectController#show as HTML
[[32mINFO [0m|#17433]   Parameters: {"project"=>"My:Maintenance"}
[[0;37mDEBUG[0m|#17433] Setting return_to: "/project/show?project=My%3AMaintenance"
[[0;37mDEBUG[0m|#17433] Authenticating with iChain mode: off
[[32mINFO [0m|#17433] Authenticated request to "/project/show?project=My%3AMaintenance" from tom
[[0;37mDEBUG[0m|#17433] free_cache [{:is_current=>"true", :login=>"tom"}] Person_3c475779e8513d9b679039491ed37e83
[[0;37mDEBUG[0m|#17433] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] )
[[0;37mDEBUG[0m|#17433] params {:is_current=>"true", :login=>"tom"}
[[0;37mDEBUG[0m|#17433] uri is: http://localhost:3203/person/:login
[[0;37mDEBUG[0m|#17433] http_do #1: method: get url: http://localhost:3203/person/tom
[[0;37mDEBUG[0m|#17433] RT http://localhost:3203/person/tom {"view"=>0.481249, "db"=>1.5332540000000001, "backend"=>0, "xml"=>0, :runtime=>13.025, :all=>53.051126}
[[0;37mDEBUG[0m|#17433] http_do #1: method: get url: http://localhost:3203/webui/person_requests_that_need_work?login=tom
[[0;37mDEBUG[0m|#17433] RT http://localhost:3203/webui/person_requests_that_need_work?login=tom {"view"=>0.260263, "db"=>4.553209000000001, "backend"=>0, "xml"=>0, :runtime=>36.528999999999996, :all=>78.690543}
[[0;37mDEBUG[0m|#17433] data {
  "declined": [

  ],
  "new": [

  ],
  "reviews": [
    1000
  ]
}
[[0;37mDEBUG[0m|#17433] http_do #1: method: get url: http://localhost:3203/webui/project_infos?project=My%3AMaintenance
[[0;37mDEBUG[0m|#17433] RT http://localhost:3203/webui/project_infos?project=My%3AMaintenance {"view"=>0.418372, "db"=>3.7620019999999994, "backend"=>0, "xml"=>0, :runtime=>35.594, :all=>75.45690300000001}
[[0;37mDEBUG[0m|#17433] data {
  "name": "My:Maintenance",
  "packages": [

  ],
  "xml": "<project name=\"My:Maintenance\" kind=\"maintenance\">\n  <title>official maintenance space</title>\n  <description>official maintenance space, maintained by maintenance ;)</description>\n  <person userid=\"maintenance_coord\" role=\"maintainer\"/>\n  <maintenance>\n    <maintains project=\"BaseDistro\"/>\n    <maintains project=\"BaseDistro2.0:LinkedUpdateProject\"/>\n    <maintains project=\"BaseDistro3\"/>\n  </maintenance>\n</project>\n",
  "incidents": [

  ],
  "maintained_projects": [
    "BaseDistro",
    "BaseDistro2.0:LinkedUpdateProject",
    "BaseDistro3"
  ],
  "linking_projects": [

  ],
  "requests": [

  ],
  "nr_of_problem_packages": 0
}
[[0;37mDEBUG[0m|#17433] returning [{:login=>"maintenance_coord"}] from rails cache Person_11966180e3080c963c5f78cfc0951d02
[[32mINFO [0m|#17433]   Rendered project/_tabs.html.erb (2.9ms)
[[32mINFO [0m|#17433]   Rendered shared/_open_requests.html.erb (0.0ms)
[[32mINFO [0m|#17433]   Rendered layouts/_watch_and_search.html.erb (1.2ms)
[[32mINFO [0m|#17433]   Rendered layouts/_header.html.erb (2.1ms)
[[32mINFO [0m|#17433]   Rendered layouts/_personal_navigation.html.erb (1.0ms)
[[32mINFO [0m|#17433]   Rendered layouts/_flash.html.erb (0.0ms)
[[32mINFO [0m|#17433] Completed 200 OK in 227ms (Views: 14.5ms | ActiveRecord: 0.0ms | API: 207.2ms (XML: 0.0ms , View: 1.2ms , Backend: 0.0ms , DB: 9.8ms , HTTP: 122.1ms) | XML: 0.6ms)
[[32mINFO [0m|#17433] Started GET "/project/toggle_watch?project=My%3AMaintenance" for 127.0.0.1 at 2013-04-19 11:32:47 +0000
[[32mINFO [0m|#17433] Processing by ProjectController#toggle_watch as HTML
[[32mINFO [0m|#17433]   Parameters: {"project"=>"My:Maintenance"}
[[0;37mDEBUG[0m|#17433] Setting return_to: "/project/toggle_watch?project=My%3AMaintenance"
[[0;37mDEBUG[0m|#17433] Authenticating with iChain mode: off
[[32mINFO [0m|#17433] Authenticated request to "/project/toggle_watch?project=My%3AMaintenance" from tom
[[0;37mDEBUG[0m|#17433] free_cache [{:is_current=>"true", :login=>"tom"}] Person_3c475779e8513d9b679039491ed37e83
[[0;37mDEBUG[0m|#17433] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] )
[[0;37mDEBUG[0m|#17433] params {:is_current=>"true", :login=>"tom"}
[[0;37mDEBUG[0m|#17433] uri is: http://localhost:3203/person/:login
[[0;37mDEBUG[0m|#17433] http_do #1: method: get url: http://localhost:3203/person/tom
[[0;37mDEBUG[0m|#17433] RT http://localhost:3203/person/tom {"view"=>0.488513, "db"=>1.5731149999999998, "backend"=>0, "xml"=>0, :runtime=>13.697000000000001, :all=>17.095330999999998}
[[0;37mDEBUG[0m|#17433] http_do #1: method: get url: http://localhost:3203/webui/person_requests_that_need_work?login=tom
[[0;37mDEBUG[0m|#17433] RT http://localhost:3203/webui/person_requests_that_need_work?login=tom {"view"=>0.38398000000000004, "db"=>3.9053130000000005, "backend"=>0, "xml"=>0, :runtime=>30.411, :all=>69.489229}
[[0;37mDEBUG[0m|#17433] data {
  "declined": [

  ],
  "new": [

  ],
  "reviews": [
    1000
  ]
}
[[0;37mDEBUG[0m|#17433] returning [{:name=>"My:Maintenance"}] from rails cache Project_ce8cfb5ef2a35543461dbbb6b22579cb
[[0;37mDEBUG[0m|#17433] Remove My:Maintenance from watchlist for tom
[[0;37mDEBUG[0m|#17433] user 'tom' removes project 'My:Maintenance' from watchlist
[[0;37mDEBUG[0m|#17433] saving object Person ({:is_current=>"true", :login=>"tom"}) to api:
 <person>
  <login>tom</login>
  <email>tschmidt@suse.de</email>
  <realname>Thor</realname>
  <state>confirmed</state>
  <watchlist>
    
  </watchlist>
</person>
[[0;37mDEBUG[0m|#17433] http_do #1: method: put url: http://localhost:3203/person/tom
[[0;37mDEBUG[0m|#17433] RT http://localhost:3203/person/tom {"view"=>1.023857, "db"=>2.24026, "backend"=>0, "xml"=>0, :runtime=>17.46, :all=>23.43833}
[[0;37mDEBUG[0m|#17433] [REST] find( PersonGroup, [{:login=>"tom"}] )
[[0;37mDEBUG[0m|#17433] params {:login=>"tom"}
[[0;37mDEBUG[0m|#17433] uri is: http://localhost:3203/person/:login/group
[[0;37mDEBUG[0m|#17433] http_do #1: method: get url: http://localhost:3203/person/tom/group
[[0;37mDEBUG[0m|#17433] RT http://localhost:3203/person/tom/group {"view"=>0.359362, "db"=>0.7213529999999999, "backend"=>0, "xml"=>0, :runtime=>6.5360000000000005, :all=>10.292343}
[[0;37mDEBUG[0m|#17433] free_cache [:id, {:what=>"project", :predicate=>"person/@userid='tom'"}] Collection_8c1f4f93580a7c15e1f5fefa4bf90a08
[[0;37mDEBUG[0m|#17433] free_cache [:id, {:what=>"package", :predicate=>"person/@userid='tom'"}] Collection_09c0d884aeab5856e9b6a54d49db8a54
[[32mINFO [0m|#17433] Redirected to http://127.0.0.1:55188/project/show?project=My%3AMaintenance
[[32mINFO [0m|#17433] Completed 302 Found in 125ms (ActiveRecord: 0.0ms | API: 120.3ms (XML: 0.0ms , View: 2.3ms , Backend: 0.0ms , DB: 8.4ms , HTTP: 52.2ms) | XML: 0.5ms)
[[32mINFO [0m|#17433] Started GET "/project/show?project=My%3AMaintenance" for 127.0.0.1 at 2013-04-19 11:32:48 +0000
[[32mINFO [0m|#17433] Processing by ProjectController#show as HTML
[[32mINFO [0m|#17433]   Parameters: {"project"=>"My:Maintenance"}
[[0;37mDEBUG[0m|#17433] Setting return_to: "/project/show?project=My%3AMaintenance"
[[0;37mDEBUG[0m|#17433] Authenticating with iChain mode: off
[[32mINFO [0m|#17433] Authenticated request to "/project/show?project=My%3AMaintenance" from tom
[[0;37mDEBUG[0m|#17433] free_cache [{:is_current=>"true", :login=>"tom"}] Person_3c475779e8513d9b679039491ed37e83
[[0;37mDEBUG[0m|#17433] [REST] find( Person, [{:is_current=>"true", :login=>"tom"}] )
[[0;37mDEBUG[0m|#17433] params {:is_current=>"true", :login=>"tom"}
[[0;37mDEBUG[0m|#17433] uri is: http://localhost:3203/person/:login
[[0;37mDEBUG[0m|#17433] http_do #1: method: get url: http://localhost:3203/person/tom
[[0;37mDEBUG[0m|#17433] RT http://localhost:3203/person/tom {"view"=>0.436849, "db"=>0.9248500000000001, "backend"=>0, "xml"=>0, :runtime=>7.965, :all=>47.622605}
[[0;37mDEBUG[0m|#17433] http_do #1: method: get url: http://localhost:3203/webui/person_requests_that_need_work?login=tom
[[0;37mDEBUG[0m|#17433] RT http://localhost:3203/webui/person_requests_that_need_work?login=tom {"view"=>0.303773, "db"=>5.555675, "backend"=>0, "xml"=>0, :runtime=>37.083999999999996, :all=>79.032746}
[[0;37mDEBUG[0m|#17433] data {
  "declined": [

  ],
  "new": [

  ],
  "reviews": [
    1000
  ]
}
[[0;37mDEBUG[0m|#17433] http_do #1: method: get url: http://localhost:3203/webui/project_infos?project=My%3AMaintenance
[[0;37mDEBUG[0m|#17433] RT http://localhost:3203/webui/project_infos?project=My%3AMaintenance {"view"=>0.425343, "db"=>3.854682, "backend"=>0, "xml"=>0, :runtime=>34.61, :all=>76.163461}
[[0;37mDEBUG[0m|#17433] data {
  "name": "My:Maintenance",
  "packages": [

  ],
  "xml": "<project name=\"My:Maintenance\" kind=\"maintenance\">\n  <title>official maintenance space</title>\n  <description>official maintenance space, maintained by maintenance ;)</description>\n  <person userid=\"maintenance_coord\" role=\"maintainer\"/>\n  <maintenance>\n    <maintains project=\"BaseDistro\"/>\n    <maintains project=\"BaseDistro2.0:LinkedUpdateProject\"/>\n    <maintains project=\"BaseDistro3\"/>\n  </maintenance>\n</project>\n",
  "incidents": [

  ],
  "maintained_projects": [
    "BaseDistro",
    "BaseDistro2.0:LinkedUpdateProject",
    "BaseDistro3"
  ],
  "linking_projects": [

  ],
  "requests": [

  ],
  "nr_of_problem_packages": 0
}
[[0;37mDEBUG[0m|#17433] returning [{:login=>"maintenance_coord"}] from rails cache Person_11966180e3080c963c5f78cfc0951d02
[[32mINFO [0m|#17433]   Rendered project/_tabs.html.erb (2.2ms)
[[32mINFO [0m|#17433]   Rendered shared/_open_requests.html.erb (0.0ms)
[[32mINFO [0m|#17433]   Rendered layouts/_watch_and_search.html.erb (0.8ms)
[[32mINFO [0m|#17433]   Rendered layouts/_header.html.erb (1.5ms)
[[32mINFO [0m|#17433]   Rendered layouts/_personal_navigation.html.erb (1.0ms)
[[32mINFO [0m|#17433]   Rendered layouts/_flash.html.erb (0.0ms)
[[32mINFO [0m|#17433] Completed 200 OK in 219ms (Views: 11.8ms | ActiveRecord: 0.0ms | API: 202.8ms (XML: 0.0ms , View: 1.2ms , Backend: 0.0ms , DB: 10.3ms , HTTP: 123.2ms) | XML: 0.5ms)
[[32mINFO [0m|#17433] Started POST "/user/logout" for 127.0.0.1 at 2013-04-19 11:32:48 +0000
[[32mINFO [0m|#17433] Processing by UserController#logout as HTML
[[0;37mDEBUG[0m|#17433] Setting return_to: "/user/logout"
[[0;37mDEBUG[0m|#17433] Authenticating with iChain mode: off
[[32mINFO [0m|#17433] Authenticated request to "/user/logout" from tom
[[32mINFO [0m|#17433] Logging out: tom
[[32mINFO [0m|#17433] Redirected to http://127.0.0.1:55188/
[[0;37mDEBUG[0m|#17433] free_cache [{}] Person_f7754490a4ed0ddff3ff300384cc6faa
[[32mINFO [0m|#17433] Completed 302 Found in 12ms (ActiveRecord: 9.9ms | XML: 0.0ms)
[[32mINFO [0m|#17433] Started GET "/" for 127.0.0.1 at 2013-04-19 11:32:48 +0000
[[32mINFO [0m|#17433] Processing by MainController#index as HTML
[[0;37mDEBUG[0m|#17433] Setting return_to: "/"
[[0;37mDEBUG[0m|#17433] Authenticating with iChain mode: off
[[32mINFO [0m|#17433] Anonymous request to /
[[0;37mDEBUG[0m|#17433] free_cache [{:conditions=>"deleted_at IS NULL", :order=>"create_at DESC", :limit=>"4"}] Statusmessage_2fe508b303b3ed792c174b7580cb295c
[[0;37mDEBUG[0m|#17433] [REST] find( Statusmessage, [{:conditions=>"deleted_at IS NULL", :order=>"create_at DESC", :limit=>"4"}] )
[[0;37mDEBUG[0m|#17433] params {:conditions=>"deleted_at IS NULL", :order=>"create_at DESC", :limit=>"4"}
[[0;37mDEBUG[0m|#17433] uri is: http://localhost:3203/status/messages/:id/?:limit
[[0;37mDEBUG[0m|#17433] http_do #1: method: get url: http://localhost:3203/status/messages/?limit=4
[[0;37mDEBUG[0m|#17433] RT http://localhost:3203/status/messages/?limit=4 {"view"=>0.8659129999999999, "db"=>0.572638, "backend"=>0, "xml"=>0, :runtime=>6.35, :all=>9.637397}
[[0;37mDEBUG[0m|#17433] free_cache [{:limit=>"6", :shared=>"true"}] LatestUpdated_ee34770dc62e51dca62baed2d5258b33
[[0;37mDEBUG[0m|#17433] [REST] find( LatestUpdated, [{:limit=>"6", :shared=>"true"}] )
[[0;37mDEBUG[0m|#17433] params {:limit=>"6", :shared=>"true"}
[[0;37mDEBUG[0m|#17433] uri is: http://localhost:3203/statistics/latest_updated?:limit
[[0;37mDEBUG[0m|#17433] http_do #1: method: get url: http://localhost:3203/statistics/latest_updated?limit=6
[[0;37mDEBUG[0m|#17433] RT http://localhost:3203/statistics/latest_updated?limit=6 {"view"=>1.506103, "db"=>2.5853820000000005, "backend"=>0, "xml"=>0, :runtime=>19.609, :all=>60.299946}
[[32mINFO [0m|#17433]   Rendered shared/_sign_up.html.erb (0.9ms)
[[32mINFO [0m|#17433]   Rendered main/_sponsors.html.erb (0.1ms)
[[32mINFO [0m|#17433]   Rendered main/_news.html.erb (0.2ms)
[[32mINFO [0m|#17433]   Rendered main/_latest_updates.erb (4.4ms)
[[32mINFO [0m|#17433]   Rendered layouts/_watch_and_search.html.erb (0.5ms)
[[32mINFO [0m|#17433]   Rendered layouts/_header.html.erb (1.1ms)
[[32mINFO [0m|#17433]   Rendered layouts/_personal_navigation.html.erb (1.2ms)
[[32mINFO [0m|#17433]   Rendered layouts/_flash.html.erb (0.1ms)
[[32mINFO [0m|#17433] Completed 200 OK in 89ms (Views: 15.2ms | ActiveRecord: 0.0ms | API: 69.9ms (XML: 0.0ms , View: 2.4ms , Backend: 0.0ms , DB: 3.2ms , HTTP: 44.0ms) | XML: 1.0ms)
[[32mINFO [0m|#17433] Started GET "/main/systemstatus" for 127.0.0.1 at 2013-04-19 11:32:48 +0000
[[32mINFO [0m|#17433] Processing by MainController#systemstatus as */*
[[0;37mDEBUG[0m|#17433] Setting return_to: "/main/systemstatus"
[[0;37mDEBUG[0m|#17433] Authenticating with iChain mode: off
[[32mINFO [0m|#17433] Anonymous request to /main/systemstatus
[[0;37mDEBUG[0m|#17433] returning [{:shared=>"true"}] from rails cache GlobalCounters_2899b016f456189a42bc9c5c8fc7013a
[[0;37mDEBUG[0m|#17433] returning [:available] from rails cache Architecture_82f3aea44c00f161487e3359eb33cff4
[[32mINFO [0m|#17433]   Rendered main/_systemstatus.html.erb (1.7ms)
[[32mINFO [0m|#17433] Completed 200 OK in 4ms (Views: 2.4ms | ActiveRecord: 0.0ms | XML: 0.5ms)
[[0;37mDEBUG[0m|#17433] http_do #1: method: post url: http://localhost:3203/test/test_end
[[0;37mDEBUG[0m|#17433] RT http://localhost:3203/test/test_end {"view"=>1.6567070000000002, "db"=>14.19258, "backend"=>0, "xml"=>0, :runtime=>21.117, :all=>26.055877000000002}
+ echo

+ cleanup
+ echo 'Killing backend processes'
Killing backend processes
+ fuser -v <http://ci.opensuse.org/job/obs_master_testsuite_webui/ws/>
+ egrep 'perl|ruby'
+ 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/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 0
[locks-and-latches] Releasing all the locks
[locks-and-latches] All the locks released
Looks like the node went offline during the build. Check the slave log for the details.FATAL: null
java.lang.NullPointerException
	at hudson.plugins.timestamper.annotator.TimestampAnnotatorFactory.getOffset(TimestampAnnotatorFactory.java:65)
	at hudson.plugins.timestamper.annotator.TimestampAnnotatorFactory.newInstance(TimestampAnnotatorFactory.java:52)
	at hudson.console.ConsoleAnnotator._for(ConsoleAnnotator.java:143)
	at hudson.console.ConsoleAnnotator.initial(ConsoleAnnotator.java:133)
	at hudson.console.AnnotatedLargeText.createAnnotator(AnnotatedLargeText.java:140)
	at hudson.console.AnnotatedLargeText.writeHtmlTo(AnnotatedLargeText.java:157)
	at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:593)
	at hudson.model.Run.execute(Run.java:1575)
	at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:46)
	at hudson.model.ResourceController.execute(ResourceController.java:88)
	at hudson.model.Executor.run(Executor.java:237)
-- 
To unsubscribe, e-mail: obs-tests+unsubscribe@opensuse.org
To contact the owner, e-mail: obs-tests+owner@opensuse.org

[obs-tests] Build failed in Jenkins: obs_master_testsuite_webui #894

admin＠opensuse.org

admin＠opensuse.org

tags

participants (1)