[00:03:02.803] New invocation is queued and will start shortly
[00:03:02.803] Triggered by user:infra-codesearch@chops-service-accounts.iam.gserviceaccount.com
[00:03:03.940] Starting the invocation (attempt 1)
[00:03:03.972] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:03:03.973] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:03:03.973] Buildbucket request:
{
"bucket": "luci.infra.codesearch",
"client_operation_id": "9017626134496699808",
"parameters_json": "{\"builder_name\":\"codesearch-gen-chromium-linux\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"codesearch_mirror_revision\":\"4d5f03dd5fded35f97a1eb537bb8f89ec1176415\",\"codesearch_mirror_revision_timestamp\":1616283754,\"root_solution_revision\":\"846b2b9adba36d514928621228ddf991353000da\",\"root_solution_revision_timestamp\":1616283684},\"tags\":[\"parent_buildername:codesearch-gen-chromium-initiator\",\"parent_buildnumber:12582\",\"user_agent:recipe\"]},\"id\":\"8d12d6e0-62ae-4c19-b3f8-ad0761806880\",\"title\":\"codesearch-gen-chromium-initiator/12582\"}]},\"codesearch_mirror_revision\":\"4d5f03dd5fded35f97a1eb537bb8f89ec1176415\",\"codesearch_mirror_revision_timestamp\":1616283754,\"root_solution_revision\":\"846b2b9adba36d514928621228ddf991353000da\",\"root_solution_revision_timestamp\":1616283684}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:codesearch-gen-chromium-linux",
"scheduler_invocation_id:9017626134496699808",
"scheduler_job_id:infra/codesearch-gen-chromium-linux",
"user_agent:luci-scheduler",
"parent_buildername:codesearch-gen-chromium-initiator",
"parent_buildnumber:12582",
"user_agent:recipe"
]
}
[00:03:04.508] Buildbucket response:
{
"build": {
"bucket": "luci.infra.codesearch",
"canary_preference": "PROD",
"created_by": "project:infra",
"created_ts": "1616284984049453",
"id": "8852196072523972048",
"parameters_json": "{\"builder_name\": \"codesearch-gen-chromium-linux\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"codesearch_mirror_revision\": \"4d5f03dd5fded35f97a1eb537bb8f89ec1176415\", \"codesearch_mirror_revision_timestamp\": 1616283754.0, \"root_solution_revision\": \"846b2b9adba36d514928621228ddf991353000da\", \"root_solution_revision_timestamp\": 1616283684.0}, \"tags\": [\"parent_buildername:codesearch-gen-chromium-initiator\", \"parent_buildnumber:12582\", \"user_agent:recipe\"]}, \"id\": \"8d12d6e0-62ae-4c19-b3f8-ad0761806880\", \"title\": \"codesearch-gen-chromium-initiator/12582\"}]}, \"codesearch_mirror_revision\": \"4d5f03dd5fded35f97a1eb537bb8f89ec1176415\", \"codesearch_mirror_revision_timestamp\": 1616283754.0, \"root_solution_revision\": \"846b2b9adba36d514928621228ddf991353000da\", \"root_solution_revision_timestamp\": 1616283684.0}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "infra-codesearch@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1616284984362476",
"tags": [
"build_address:luci.infra.codesearch/codesearch-gen-chromium-linux/10243",
"builder:codesearch-gen-chromium-linux",
"parent_buildername:codesearch-gen-chromium-initiator",
"parent_buildnumber:12582",
"scheduler_invocation_id:9017626134496699808",
"scheduler_job_id:infra/codesearch-gen-chromium-linux",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/infra/buildbucket/cr-buildbucket.appspot.com/8852196072523972048/+/annotations",
"swarming_tag:luci_project:infra",
"swarming_tag:recipe_name:chromium_codesearch",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1616284984363927",
"url": "https://ci.chromium.org/b/8852196072523972048",
"utcnow_ts": "1616284984495927"
}
}
[00:03:04.508] Task URL: https://ci.chromium.org/b/8852196072523972048
[00:03:04.508] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017626134496699808:2:0) after 1m0s
[00:03:22.633] Received PubSub notification, asking Buildbucket for the build status
[00:03:22.657] Build status: STARTED
[00:04:04.523] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017626134496699808:2:0)
[00:04:04.523] Timer tick, asking Buildbucket for the build status
[00:04:04.649] Build 8852196072523972048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:04.649] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017626134496699808:4:0) after 1m0s
[00:05:04.693] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017626134496699808:4:0)
[00:05:04.723] Build status: STARTED
[00:05:04.723] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017626134496699808:5:0) after 6m4s
[00:11:08.738] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017626134496699808:5:0)
[00:11:08.738] Timer tick, asking Buildbucket for the build status
[00:11:08.818] Build 8852196072523972048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:08.818] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017626134496699808:6:0) after 1m0s
[00:12:08.940] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017626134496699808:6:0)
[00:12:08.974] Build status: STARTED
[00:12:08.974] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017626134496699808:7:0) after 9m41s
[00:21:50.116] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017626134496699808:7:0)
[00:21:50.116] Timer tick, asking Buildbucket for the build status
[00:21:50.252] Build 8852196072523972048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:50.252] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017626134496699808:8:0) after 1m0s
[00:22:50.266] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017626134496699808:8:0)
[00:22:50.291] Build status: STARTED
[00:22:50.291] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017626134496699808:9:0) after 9m18s
[00:32:08.304] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017626134496699808:9:0)
[00:32:08.334] Build status: STARTED
[00:32:08.334] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017626134496699808:10:0) after 8m52s
[00:41:00.353] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017626134496699808:10:0)
[00:41:00.353] Timer tick, asking Buildbucket for the build status
[00:41:00.439] Build 8852196072523972048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:00.439] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017626134496699808:11:0) after 1m0s
[00:42:00.760] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017626134496699808:11:0)
[00:42:00.785] Build status: STARTED
[00:42:00.785] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017626134496699808:12:0) after 3m58s
[00:45:58.972] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017626134496699808:12:0)
[00:45:58.972] Timer tick, asking Buildbucket for the build status
[00:46:01.283] Build 8852196072523972048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:01.283] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017626134496699808:13:0) after 1m0s
[00:47:01.620] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017626134496699808:13:0)
[00:47:01.620] Timer tick, asking Buildbucket for the build status
[00:47:01.857] Build 8852196072523972048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:01.857] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017626134496699808:14:0) after 1m0s
[00:48:02.106] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017626134496699808:14:0)
[00:48:02.133] Build status: STARTED
[00:48:02.133] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017626134496699808:15:0) after 7m14s
[00:55:16.216] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017626134496699808:15:0)
[00:55:16.216] Timer tick, asking Buildbucket for the build status
[00:55:16.382] Build 8852196072523972048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:16.382] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017626134496699808:16:0) after 1m0s
[00:56:16.406] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017626134496699808:16:0)
[00:56:16.431] Build status: STARTED
[00:56:16.431] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017626134496699808:17:0) after 8m31s
[01:04:47.420] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017626134496699808:17:0)
[01:04:47.446] Build status: STARTED
[01:04:47.446] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017626134496699808:18:0) after 3m51s
[01:08:38.517] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017626134496699808:18:0)
[01:08:38.542] Build status: STARTED
[01:08:38.542] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017626134496699808:19:0) after 8m55s
[01:17:33.575] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017626134496699808:19:0)
[01:17:33.575] Timer tick, asking Buildbucket for the build status
[01:17:33.659] Build 8852196072523972048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:33.659] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017626134496699808:20:0) after 1m0s
[01:18:33.800] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017626134496699808:20:0)
[01:18:33.800] Timer tick, asking Buildbucket for the build status
[01:18:33.957] Build 8852196072523972048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:33.957] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017626134496699808:21:0) after 1m0s
[01:19:34.094] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017626134496699808:21:0)
[01:19:34.094] Timer tick, asking Buildbucket for the build status
[01:19:34.172] Build 8852196072523972048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:34.172] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017626134496699808:22:0) after 1m0s
[01:20:34.185] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017626134496699808:22:0)
[01:20:34.185] Timer tick, asking Buildbucket for the build status
[01:20:34.252] Build 8852196072523972048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:34.252] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017626134496699808:23:0) after 1m0s
[01:21:34.270] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017626134496699808:23:0)
[01:21:34.296] Build status: STARTED
[01:21:34.296] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017626134496699808:24:0) after 5m8s
[01:26:42.367] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017626134496699808:24:0)
[01:26:42.368] Timer tick, asking Buildbucket for the build status
[01:26:42.457] Build 8852196072523972048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:42.457] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017626134496699808:25:0) after 1m0s
[01:27:42.796] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017626134496699808:25:0)
[01:27:42.827] Build status: STARTED
[01:27:42.827] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017626134496699808:26:0) after 9m46s
[01:37:28.928] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017626134496699808:26:0)
[01:37:28.928] Timer tick, asking Buildbucket for the build status
[01:37:29.007] Build 8852196072523972048: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:29.007] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017626134496699808:27:0) after 1m0s
[01:38:29.030] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017626134496699808:27:0)
[01:38:29.060] Build status: STARTED
[01:38:29.060] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017626134496699808:28:0) after 3m15s
[01:41:44.149] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017626134496699808:28:0)
[01:41:44.197] Build status: STARTED
[01:41:44.197] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017626134496699808:29:0) after 9m8s
[01:50:52.213] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017626134496699808:29:0)
[01:50:52.237] Build status: STARTED
[01:50:52.237] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017626134496699808:30:0) after 7m45s
[01:58:37.247] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017626134496699808:30:0)
[01:58:37.269] Build status: STARTED
[01:58:37.269] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017626134496699808:31:0) after 3m44s
[02:00:11.290] Received PubSub notification, asking Buildbucket for the build status
[02:00:11.365] Build 8852196072523972048: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[02:00:11.365] Buildbucket build:
{
"build": {
"bucket": "luci.infra.codesearch",
"canary_preference": "PROD",
"completed_ts": "1616292009133133",
"created_by": "project:infra",
"created_ts": "1616284984049453",
"id": "8852196072523972048",
"parameters_json": "{\"builder_name\": \"codesearch-gen-chromium-linux\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"codesearch_mirror_revision\": \"4d5f03dd5fded35f97a1eb537bb8f89ec1176415\", \"codesearch_mirror_revision_timestamp\": 1616283754.0, \"root_solution_revision\": \"846b2b9adba36d514928621228ddf991353000da\", \"root_solution_revision_timestamp\": 1616283684.0}, \"tags\": [\"parent_buildername:codesearch-gen-chromium-initiator\", \"parent_buildnumber:12582\", \"user_agent:recipe\"]}, \"id\": \"8d12d6e0-62ae-4c19-b3f8-ad0761806880\", \"title\": \"codesearch-gen-chromium-initiator/12582\"}]}, \"codesearch_mirror_revision\": \"4d5f03dd5fded35f97a1eb537bb8f89ec1176415\", \"codesearch_mirror_revision_timestamp\": 1616283754.0, \"root_solution_revision\": \"846b2b9adba36d514928621228ddf991353000da\", \"root_solution_revision_timestamp\": 1616283684.0}}",
"project": "infra",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"clang_revision\": \"llvmorg-13-init-4720-g7bafe336-1\", \"got_angle_revision\": \"135385a1ae86bf857be37475f927dcf78c9476b4\", \"got_dawn_revision\": \"6b80743d3f5bb389665590585c43eeb9cfa70a34\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"846b2b9adba36d514928621228ddf991353000da\", \"got_revision_cp\": \"refs/heads/master@{#864978}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"a98fb3f8380a3a5c9ae3e0175634ba88c85a434f\", \"got_v8_revision\": \"6092f990f654f2d7a4534ee8c5bbb5c4fb800bdc\", \"got_v8_revision_cp\": \"refs/heads/9.1.170@{#1}\", \"got_webrtc_revision\": \"8bf1cd1c665a538a366bce4770acbb990b025388\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33513}\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"builder_14b4f73c8b95a6615516bba597f721e10c68093aeabbb388e9a5697d2f7f84cc_v2\", \"builder_6e49e5890505ba1a083b1e9865e8d2349cbc056a1edd72191f2841b554eb368e_v2\", \"codesearch_git_genfiles_repo\", \"codesearch_update_submodules_mirror\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-infra-codesearch-xenial-7-xcr5\"], \"image\": [\"chrome-xenial-21011100-9488e34e558\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16\", \"Ubuntu-16.04\", \"Ubuntu-16.04.6\"], \"pool\": [\"luci.infra.codesearch\"], \"python\": [\"2\", \"2.7\", \"2.7.12\"], \"server_version\": [\"5776-cb9e157\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-east\", \"us-east1\", \"us-east1-a\"]}}}",
"service_account": "infra-codesearch@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1616285001896067",
"status": "COMPLETED",
"status_changed_ts": "1616285002400879",
"tags": [
"build_address:luci.infra.codesearch/codesearch-gen-chromium-linux/10243",
"builder:codesearch-gen-chromium-linux",
"parent_buildername:codesearch-gen-chromium-initiator",
"parent_buildnumber:12582",
"scheduler_invocation_id:9017626134496699808",
"scheduler_job_id:infra/codesearch-gen-chromium-linux",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/infra/buildbucket/cr-buildbucket.appspot.com/8852196072523972048/+/annotations",
"swarming_tag:luci_project:infra",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:chromium_codesearch",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:526ae92e0e78ca10",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1616292009133438",
"url": "https://ci.chromium.org/b/8852196072523972048",
"utcnow_ts": "1616292011358956"
}
}
[02:00:11.365] Invocation finished in 1h57m8.580517228s with status SUCCEEDED