[12:03:30.774] New invocation is queued and will start shortly
[12:03:30.774] Triggered by user:infra-codesearch@chops-service-accounts.iam.gserviceaccount.com
[12:03:31.842] Starting the invocation (attempt 1)
[12:03:31.895] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:03:31.895] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:03:31.895] Buildbucket request:
{
"bucket": "luci.infra.codesearch",
"client_operation_id": "9017490209713892800",
"parameters_json": "{\"builder_name\":\"codesearch-gen-chromium-android\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"codesearch_mirror_revision\":\"1cf9bbe113c45bc48a58b0af790cd0b22a258e4e\",\"codesearch_mirror_revision_timestamp\":1616414566,\"root_solution_revision\":\"b7f0a0c1117de7119bd087283f9fea8da1e4d481\",\"root_solution_revision_timestamp\":1616414498},\"tags\":[\"parent_buildername:codesearch-gen-chromium-initiator\",\"parent_buildnumber:12591\",\"user_agent:recipe\"]},\"id\":\"45700422-6dfa-43d9-b131-90b43a1052dd\",\"title\":\"codesearch-gen-chromium-initiator/12591\"}]},\"codesearch_mirror_revision\":\"1cf9bbe113c45bc48a58b0af790cd0b22a258e4e\",\"codesearch_mirror_revision_timestamp\":1616414566,\"root_solution_revision\":\"b7f0a0c1117de7119bd087283f9fea8da1e4d481\",\"root_solution_revision_timestamp\":1616414498}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:codesearch-gen-chromium-android",
"scheduler_invocation_id:9017490209713892800",
"scheduler_job_id:infra/codesearch-gen-chromium-android",
"user_agent:luci-scheduler",
"parent_buildername:codesearch-gen-chromium-initiator",
"parent_buildnumber:12591",
"user_agent:recipe"
]
}
[12:03:32.598] Buildbucket response:
{
"build": {
"bucket": "luci.infra.codesearch",
"canary_preference": "PROD",
"created_by": "project:infra",
"created_ts": "1616414612063270",
"id": "8852060147699986704",
"parameters_json": "{\"builder_name\": \"codesearch-gen-chromium-android\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"codesearch_mirror_revision\": \"1cf9bbe113c45bc48a58b0af790cd0b22a258e4e\", \"codesearch_mirror_revision_timestamp\": 1616414566.0, \"root_solution_revision\": \"b7f0a0c1117de7119bd087283f9fea8da1e4d481\", \"root_solution_revision_timestamp\": 1616414498.0}, \"tags\": [\"parent_buildername:codesearch-gen-chromium-initiator\", \"parent_buildnumber:12591\", \"user_agent:recipe\"]}, \"id\": \"45700422-6dfa-43d9-b131-90b43a1052dd\", \"title\": \"codesearch-gen-chromium-initiator/12591\"}]}, \"codesearch_mirror_revision\": \"1cf9bbe113c45bc48a58b0af790cd0b22a258e4e\", \"codesearch_mirror_revision_timestamp\": 1616414566.0, \"root_solution_revision\": \"b7f0a0c1117de7119bd087283f9fea8da1e4d481\", \"root_solution_revision_timestamp\": 1616414498.0}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "infra-codesearch@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1616414612373940",
"tags": [
"build_address:luci.infra.codesearch/codesearch-gen-chromium-android/9331",
"builder:codesearch-gen-chromium-android",
"parent_buildername:codesearch-gen-chromium-initiator",
"parent_buildnumber:12591",
"scheduler_invocation_id:9017490209713892800",
"scheduler_job_id:infra/codesearch-gen-chromium-android",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/infra/buildbucket/cr-buildbucket.appspot.com/8852060147699986704/+/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": "1616414612374130",
"url": "https://ci.chromium.org/b/8852060147699986704",
"utcnow_ts": "1616414612586900"
}
}
[12:03:32.598] Task URL: https://ci.chromium.org/b/8852060147699986704
[12:03:32.598] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:2:0) after 1m0s
[12:04:05.861] Received PubSub notification, asking Buildbucket for the build status
[12:04:05.887] Build status: STARTED
[12:04:32.612] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:2:0)
[12:04:32.612] Timer tick, asking Buildbucket for the build status
[12:04:32.679] Build 8852060147699986704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:04:32.679] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:4:0) after 1m0s
[12:05:32.695] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:4:0)
[12:05:32.695] Timer tick, asking Buildbucket for the build status
[12:05:32.774] Build 8852060147699986704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:05:32.774] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:5:0) after 1m0s
[12:06:32.788] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:5:0)
[12:06:32.821] Build status: STARTED
[12:06:32.821] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:6:0) after 8m38s
[12:15:11.078] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:6:0)
[12:15:11.105] Build status: STARTED
[12:15:11.105] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:7:0) after 6m35s
[12:21:46.171] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:7:0)
[12:21:46.171] Timer tick, asking Buildbucket for the build status
[12:21:46.253] Build 8852060147699986704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:46.253] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:8:0) after 1m0s
[12:22:46.578] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:8:0)
[12:22:46.601] Build status: STARTED
[12:22:46.601] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:9:0) after 8m4s
[12:30:50.620] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:9:0)
[12:30:50.620] Timer tick, asking Buildbucket for the build status
[12:30:50.752] Build 8852060147699986704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:50.752] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:10:0) after 1m0s
[12:31:50.736] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:10:0)
[12:31:50.736] Timer tick, asking Buildbucket for the build status
[12:31:50.994] Build 8852060147699986704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:50.994] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:11:0) after 1m0s
[12:32:51.013] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:11:0)
[12:32:51.013] Timer tick, asking Buildbucket for the build status
[12:32:51.096] Build 8852060147699986704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:51.096] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:12:0) after 1m0s
[12:33:51.117] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:12:0)
[12:33:51.117] Timer tick, asking Buildbucket for the build status
[12:33:51.181] Build 8852060147699986704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:51.181] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:13:0) after 1m0s
[12:34:51.197] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:13:0)
[12:34:51.260] Build status: STARTED
[12:34:51.260] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:14:0) after 5m58s
[12:40:49.325] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:14:0)
[12:40:49.325] Timer tick, asking Buildbucket for the build status
[12:40:50.062] Build 8852060147699986704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:50.062] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:15:0) after 1m0s
[12:41:50.086] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:15:0)
[12:41:50.113] Build status: STARTED
[12:41:50.113] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:16:0) after 7m26s
[12:49:16.102] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:16:0)
[12:49:16.103] Timer tick, asking Buildbucket for the build status
[12:49:16.394] Build 8852060147699986704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:16.394] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:17:0) after 1m0s
[12:50:16.392] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:17:0)
[12:50:16.392] Timer tick, asking Buildbucket for the build status
[12:50:16.485] Build 8852060147699986704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:16.485] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:18:0) after 1m0s
[12:51:16.550] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:18:0)
[12:51:16.575] Build status: STARTED
[12:51:16.575] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:19:0) after 8m38s
[12:59:54.592] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:19:0)
[12:59:54.635] Build status: STARTED
[12:59:54.635] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:20:0) after 7m22s
[13:07:16.753] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:20:0)
[13:07:16.753] Timer tick, asking Buildbucket for the build status
[13:07:17.074] Build 8852060147699986704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:17.074] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:21:0) after 1m0s
[13:08:17.044] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:21:0)
[13:08:17.044] Timer tick, asking Buildbucket for the build status
[13:08:17.126] Build 8852060147699986704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:17.126] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:22:0) after 1m0s
[13:09:17.216] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:22:0)
[13:09:17.216] Timer tick, asking Buildbucket for the build status
[13:09:17.287] Build 8852060147699986704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:17.287] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:23:0) after 1m0s
[13:10:17.314] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:23:0)
[13:10:17.314] Timer tick, asking Buildbucket for the build status
[13:10:17.375] Build 8852060147699986704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:17.375] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:24:0) after 1m0s
[13:11:17.407] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:24:0)
[13:11:17.443] Build status: STARTED
[13:11:17.443] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:25:0) after 5m38s
[13:16:55.423] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:25:0)
[13:16:55.423] Timer tick, asking Buildbucket for the build status
[13:16:55.510] Build 8852060147699986704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:55.510] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:26:0) after 1m0s
[13:17:55.532] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:26:0)
[13:17:55.532] Timer tick, asking Buildbucket for the build status
[13:17:55.604] Build 8852060147699986704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:55.604] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:27:0) after 1m0s
[13:18:55.631] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:27:0)
[13:18:55.631] Timer tick, asking Buildbucket for the build status
[13:18:55.806] Build 8852060147699986704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:55.806] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:28:0) after 1m0s
[13:19:55.870] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:28:0)
[13:19:55.906] Build status: STARTED
[13:19:55.906] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:29:0) after 3m8s
[13:23:03.925] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:29:0)
[13:23:03.925] Timer tick, asking Buildbucket for the build status
[13:23:03.987] Build 8852060147699986704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:03.987] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:30:0) after 1m0s
[13:24:04.107] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:30:0)
[13:24:04.143] Build status: STARTED
[13:24:04.143] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:31:0) after 9m27s
[13:33:31.167] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:31:0)
[13:33:31.192] Build status: STARTED
[13:33:31.192] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:32:0) after 5m42s
[13:39:13.213] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:32:0)
[13:39:13.238] Build status: STARTED
[13:39:13.238] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:33:0) after 4m58s
[13:44:11.523] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:33:0)
[13:44:11.553] Build status: STARTED
[13:44:11.553] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-android:9017490209713892800:34:0) after 9m47s
[13:51:09.415] Received PubSub notification, asking Buildbucket for the build status
[13:51:09.440] Build:
{
"id": "8852060147699986704",
"builder": {
"project": "infra",
"bucket": "codesearch",
"builder": "codesearch-gen-chromium-android"
},
"number": 9331,
"createdBy": "project:infra",
"createTime": "2021-03-22T12:03:32.063270Z",
"startTime": "2021-03-22T12:04:05.117154Z",
"endTime": "2021-03-22T13:51:08.155726351Z",
"updateTime": "2021-03-22T13:51:08.156210119Z",
"status": "SUCCESS",
"input": {
}
}
[13:51:09.440] Invocation finished in 1h47m38.681070052s with status SUCCEEDED