[16:02:01.024] New invocation is queued and will start shortly
[16:02:01.024] Triggered by user:infra-codesearch@chops-service-accounts.iam.gserviceaccount.com
[16:02:02.162] Starting the invocation (attempt 1)
[16:02:02.206] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:02:02.206] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:02:02.206] Buildbucket request:
{
"bucket": "luci.infra.codesearch",
"client_operation_id": "9017565801294416096",
"parameters_json": "{\"builder_name\":\"codesearch-gen-chromium-linux\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"codesearch_mirror_revision\":\"ce83ee3f482ef49409bf97c28dd28cd8da8072c7\",\"codesearch_mirror_revision_timestamp\":1616342176,\"root_solution_revision\":\"ba53423beca34fa83510f1591988f2166f5a76d5\",\"root_solution_revision_timestamp\":1616342112},\"tags\":[\"parent_buildername:codesearch-gen-chromium-initiator\",\"parent_buildnumber:12586\",\"user_agent:recipe\"]},\"id\":\"2aaea91c-30e7-4a13-83a0-c44511d632c6\",\"title\":\"codesearch-gen-chromium-initiator/12586\"}]},\"codesearch_mirror_revision\":\"ce83ee3f482ef49409bf97c28dd28cd8da8072c7\",\"codesearch_mirror_revision_timestamp\":1616342176,\"root_solution_revision\":\"ba53423beca34fa83510f1591988f2166f5a76d5\",\"root_solution_revision_timestamp\":1616342112}}",
"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:9017565801294416096",
"scheduler_job_id:infra/codesearch-gen-chromium-linux",
"user_agent:luci-scheduler",
"parent_buildername:codesearch-gen-chromium-initiator",
"parent_buildnumber:12586",
"user_agent:recipe"
]
}
[16:02:02.755] Buildbucket response:
{
"build": {
"bucket": "luci.infra.codesearch",
"canary_preference": "PROD",
"created_by": "project:infra",
"created_ts": "1616342522290096",
"id": "8852135739304991984",
"parameters_json": "{\"builder_name\": \"codesearch-gen-chromium-linux\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"codesearch_mirror_revision\": \"ce83ee3f482ef49409bf97c28dd28cd8da8072c7\", \"codesearch_mirror_revision_timestamp\": 1616342176.0, \"root_solution_revision\": \"ba53423beca34fa83510f1591988f2166f5a76d5\", \"root_solution_revision_timestamp\": 1616342112.0}, \"tags\": [\"parent_buildername:codesearch-gen-chromium-initiator\", \"parent_buildnumber:12586\", \"user_agent:recipe\"]}, \"id\": \"2aaea91c-30e7-4a13-83a0-c44511d632c6\", \"title\": \"codesearch-gen-chromium-initiator/12586\"}]}, \"codesearch_mirror_revision\": \"ce83ee3f482ef49409bf97c28dd28cd8da8072c7\", \"codesearch_mirror_revision_timestamp\": 1616342176.0, \"root_solution_revision\": \"ba53423beca34fa83510f1591988f2166f5a76d5\", \"root_solution_revision_timestamp\": 1616342112.0}}",
"project": "infra",
"result_details_json": "{\"properties\": {}}",
"service_account": "infra-codesearch@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1616342522591711",
"tags": [
"build_address:luci.infra.codesearch/codesearch-gen-chromium-linux/10247",
"builder:codesearch-gen-chromium-linux",
"parent_buildername:codesearch-gen-chromium-initiator",
"parent_buildnumber:12586",
"scheduler_invocation_id:9017565801294416096",
"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/8852135739304991984/+/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": "1616342522591826",
"url": "https://ci.chromium.org/b/8852135739304991984",
"utcnow_ts": "1616342522748061"
}
}
[16:02:02.755] Task URL: https://ci.chromium.org/b/8852135739304991984
[16:02:02.755] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017565801294416096:2:0) after 1m0s
[16:02:13.574] Received PubSub notification, asking Buildbucket for the build status
[16:02:13.598] Build status: STARTED
[16:03:02.780] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017565801294416096:2:0)
[16:03:02.817] Build status: STARTED
[16:03:02.817] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017565801294416096:4:0) after 6m33s
[16:09:36.003] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017565801294416096:4:0)
[16:09:36.042] Build status: STARTED
[16:09:36.042] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017565801294416096:5:0) after 5m52s
[16:15:28.071] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017565801294416096:5:0)
[16:15:28.102] Build status: STARTED
[16:15:28.103] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017565801294416096:6:0) after 4m52s
[16:20:20.150] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017565801294416096:6:0)
[16:20:20.150] Timer tick, asking Buildbucket for the build status
[16:20:20.301] Build 8852135739304991984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:20.301] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017565801294416096:7:0) after 1m0s
[16:21:20.586] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017565801294416096:7:0)
[16:21:20.586] Timer tick, asking Buildbucket for the build status
[16:21:20.659] Build 8852135739304991984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:20.659] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017565801294416096:8:0) after 1m0s
[16:22:20.835] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017565801294416096:8:0)
[16:22:20.875] Build status: STARTED
[16:22:20.875] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017565801294416096:9:0) after 9m11s
[16:31:32.076] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017565801294416096:9:0)
[16:31:32.076] Timer tick, asking Buildbucket for the build status
[16:31:32.155] Build 8852135739304991984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:32.155] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017565801294416096:10:0) after 1m0s
[16:32:32.830] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017565801294416096:10:0)
[16:32:32.830] Timer tick, asking Buildbucket for the build status
[16:32:32.902] Build 8852135739304991984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:32.902] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017565801294416096:11:0) after 1m0s
[16:33:33.102] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017565801294416096:11:0)
[16:33:33.130] Build status: STARTED
[16:33:33.130] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017565801294416096:12:0) after 4m53s
[16:38:26.145] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017565801294416096:12:0)
[16:38:26.145] Timer tick, asking Buildbucket for the build status
[16:38:26.301] Build 8852135739304991984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:26.301] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017565801294416096:13:0) after 1m0s
[16:39:26.339] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017565801294416096:13:0)
[16:39:26.364] Build status: STARTED
[16:39:26.364] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017565801294416096:14:0) after 5m53s
[16:45:19.944] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017565801294416096:14:0)
[16:45:19.996] Build status: STARTED
[16:45:19.996] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017565801294416096:15:0) after 6m34s
[16:51:54.508] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017565801294416096:15:0)
[16:51:54.540] Build status: STARTED
[16:51:54.541] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017565801294416096:16:0) after 9m22s
[17:01:16.664] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017565801294416096:16:0)
[17:01:16.690] Build status: STARTED
[17:01:16.690] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017565801294416096:17:0) after 6m0s
[17:07:16.817] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017565801294416096:17:0)
[17:07:16.817] Timer tick, asking Buildbucket for the build status
[17:07:16.920] Build 8852135739304991984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:16.920] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017565801294416096:18:0) after 1m0s
[17:08:17.197] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017565801294416096:18:0)
[17:08:17.234] Build status: STARTED
[17:08:17.234] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017565801294416096:19:0) after 4m52s
[17:13:09.261] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017565801294416096:19:0)
[17:13:09.261] Timer tick, asking Buildbucket for the build status
[17:13:09.432] Build 8852135739304991984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:09.432] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017565801294416096:20:0) after 1m0s
[17:14:09.529] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017565801294416096:20:0)
[17:14:09.568] Build status: STARTED
[17:14:09.568] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017565801294416096:21:0) after 3m0s
[17:17:10.225] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017565801294416096:21:0)
[17:17:10.225] Timer tick, asking Buildbucket for the build status
[17:17:10.306] Build 8852135739304991984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:10.306] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017565801294416096:22:0) after 1m0s
[17:18:10.330] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017565801294416096:22:0)
[17:18:10.330] Timer tick, asking Buildbucket for the build status
[17:18:10.503] Build 8852135739304991984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:10.503] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017565801294416096:23:0) after 1m0s
[17:19:10.520] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017565801294416096:23:0)
[17:19:10.581] Build status: STARTED
[17:19:10.581] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017565801294416096:24:0) after 8m36s
[17:27:46.617] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017565801294416096:24:0)
[17:27:46.826] Build status: STARTED
[17:27:46.826] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017565801294416096:25:0) after 1m20s
[17:29:06.996] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017565801294416096:25:0)
[17:29:06.996] Timer tick, asking Buildbucket for the build status
[17:29:07.514] Build 8852135739304991984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:07.514] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017565801294416096:26:0) after 1m0s
[17:30:07.551] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017565801294416096:26:0)
[17:30:07.584] Build status: STARTED
[17:30:07.584] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017565801294416096:27:0) after 1m36s
[17:31:43.604] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017565801294416096:27:0)
[17:31:43.604] Timer tick, asking Buildbucket for the build status
[17:31:43.674] Build 8852135739304991984: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:43.674] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017565801294416096:28:0) after 1m0s
[17:32:44.050] Handling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017565801294416096:28:0)
[17:32:44.086] Build status: STARTED
[17:32:44.086] Scheduling timer "check-buildbucket-build-status" (infra/codesearch-gen-chromium-linux:9017565801294416096:29:0) after 6m46s
[17:37:49.797] Received PubSub notification, asking Buildbucket for the build status
[17:37:49.823] Build:
{
"id": "8852135739304991984",
"builder": {
"project": "infra",
"bucket": "codesearch",
"builder": "codesearch-gen-chromium-linux"
},
"number": 10247,
"createdBy": "project:infra",
"createTime": "2021-03-21T16:02:02.290096Z",
"startTime": "2021-03-21T16:02:12.576486Z",
"endTime": "2021-03-21T17:37:49.561577816Z",
"updateTime": "2021-03-21T17:37:49.561897189Z",
"status": "SUCCESS",
"input": {
}
}
[17:37:49.823] Invocation finished in 1h35m48.813159175s with status SUCCEEDED