[19:18:09.316] New invocation is queued and will start shortly
[19:18:09.316] Triggered by user:fuchsia-ci-builder@fuchsia-infra.iam.gserviceaccount.com
[19:18:10.534] Starting the invocation (attempt 1)
[19:18:10.572] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:18:10.572] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:18:10.573] Buildbucket request:
{
"bucket": "luci.fuchsia.ci",
"client_operation_id": "9019455997643451136",
"parameters_json": "{\"builder_name\":\"gcc_toolchain.bringup.arm64-gcc\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"buildbucket\":{\"properties\":{\"build.gcc_toolchain\":{\"instance\":\"c82d2d738b496e654fb09265681ac1230807acf7\",\"type\":\"isolated\"},\"checkout.buildset\":\"commit/gitiles/fuchsia.googlesource.com/fuchsia/+/5a93d615061a3fe8a26521b9667b48e6851f78da\",\"gcc_toolchain\":{\"git_repository\":\"https://gnu.googlesource.com/gcc,https://gnu.googlesource.com/binutils-gdb\",\"git_revision\":\"23c1d29ccea7c7525b119577857e495e114035c6,1dbde357be3ce2641595b10436822e699abe32a0\"}},\"tags\":[\"buildset:commit/gitiles/gnu.googlesource.com/gcc/+/23c1d29ccea7c7525b119577857e495e114035c6\",\"gitiles_ref:refs/heads/master\",\"parent_buildername:gcc-x64-linux\",\"user_agent:recipe\"]},\"id\":\"01d6340d-2905-4a92-aed0-f55907c39007\",\"title\":\"gcc-x64-linux/0\"}]},\"build.gcc_toolchain\":{\"instance\":\"c82d2d738b496e654fb09265681ac1230807acf7\",\"type\":\"isolated\"},\"checkout.buildset\":\"commit/gitiles/fuchsia.googlesource.com/fuchsia/+/5a93d615061a3fe8a26521b9667b48e6851f78da\",\"gcc_toolchain\":{\"git_repository\":\"https://gnu.googlesource.com/gcc,https://gnu.googlesource.com/binutils-gdb\",\"git_revision\":\"23c1d29ccea7c7525b119577857e495e114035c6,1dbde357be3ce2641595b10436822e699abe32a0\"}}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:gcc_toolchain.bringup.arm64-gcc",
"scheduler_invocation_id:9019455997643451136",
"scheduler_job_id:fuchsia/gcc_toolchain.bringup.arm64-gcc",
"user_agent:luci-scheduler",
"buildset:commit/gitiles/gnu.googlesource.com/gcc/+/23c1d29ccea7c7525b119577857e495e114035c6",
"gitiles_ref:refs/heads/master",
"parent_buildername:gcc-x64-linux",
"user_agent:recipe"
]
}
[19:18:12.202] Buildbucket response:
{
"build": {
"bucket": "luci.fuchsia.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:fuchsia",
"created_ts": "1614539890797405",
"id": "8854025935425581920",
"parameters_json": "{\"builder_name\": \"gcc_toolchain.bringup.arm64-gcc\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"build.gcc_toolchain\": {\"instance\": \"c82d2d738b496e654fb09265681ac1230807acf7\", \"type\": \"isolated\"}, \"checkout.buildset\": \"commit/gitiles/fuchsia.googlesource.com/fuchsia/+/5a93d615061a3fe8a26521b9667b48e6851f78da\", \"gcc_toolchain\": {\"git_repository\": \"https://gnu.googlesource.com/gcc,https://gnu.googlesource.com/binutils-gdb\", \"git_revision\": \"23c1d29ccea7c7525b119577857e495e114035c6,1dbde357be3ce2641595b10436822e699abe32a0\"}}, \"tags\": [\"buildset:commit/gitiles/gnu.googlesource.com/gcc/+/23c1d29ccea7c7525b119577857e495e114035c6\", \"gitiles_ref:refs/heads/master\", \"parent_buildername:gcc-x64-linux\", \"user_agent:recipe\"]}, \"id\": \"01d6340d-2905-4a92-aed0-f55907c39007\", \"title\": \"gcc-x64-linux/0\"}]}, \"build.gcc_toolchain\": {\"instance\": \"c82d2d738b496e654fb09265681ac1230807acf7\", \"type\": \"isolated\"}, \"checkout.buildset\": \"commit/gitiles/fuchsia.googlesource.com/fuchsia/+/5a93d615061a3fe8a26521b9667b48e6851f78da\", \"gcc_toolchain\": {\"git_repository\": \"https://gnu.googlesource.com/gcc,https://gnu.googlesource.com/binutils-gdb\", \"git_revision\": \"23c1d29ccea7c7525b119577857e495e114035c6,1dbde357be3ce2641595b10436822e699abe32a0\"}}}",
"project": "fuchsia",
"result_details_json": "{\"properties\": {}}",
"service_account": "fuchsia-ci-builder@fuchsia-infra.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614539891880086",
"tags": [
"builder:gcc_toolchain.bringup.arm64-gcc",
"buildset:commit/gitiles/gnu.googlesource.com/gcc/+/23c1d29ccea7c7525b119577857e495e114035c6",
"gitiles_ref:refs/heads/master",
"parent_buildername:gcc-x64-linux",
"scheduler_invocation_id:9019455997643451136",
"scheduler_job_id:fuchsia/gcc_toolchain.bringup.arm64-gcc",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/fuchsia/buildbucket/cr-buildbucket.appspot.com/8854025935425581920/+/annotations",
"swarming_tag:luci_project:fuchsia",
"swarming_tag:recipe_name:fuchsia/fuchsia",
"swarming_tag:recipe_package:fuchsia/infra/recipe_bootstrap/${platform}",
"swarming_task_id:",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1614539891881880",
"url": "https://ci.chromium.org/b/8854025935425581920",
"utcnow_ts": "1614539892195321"
}
}
[19:18:12.202] Task URL: https://ci.chromium.org/b/8854025935425581920
[19:18:12.202] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc_toolchain.bringup.arm64-gcc:9019455997643451136:2:0) after 1m0s
[19:18:35.178] Received PubSub notification, asking Buildbucket for the build status
[19:18:35.410] Build 8854025935425581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:12.319] Handling timer "check-buildbucket-build-status" (fuchsia/gcc_toolchain.bringup.arm64-gcc:9019455997643451136:2:0)
[19:19:12.319] Timer tick, asking Buildbucket for the build status
[19:19:12.723] Build 8854025935425581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:12.723] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc_toolchain.bringup.arm64-gcc:9019455997643451136:4:0) after 1m0s
[19:20:12.736] Handling timer "check-buildbucket-build-status" (fuchsia/gcc_toolchain.bringup.arm64-gcc:9019455997643451136:4:0)
[19:20:12.736] Timer tick, asking Buildbucket for the build status
[19:20:12.832] Build 8854025935425581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:12.832] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc_toolchain.bringup.arm64-gcc:9019455997643451136:5:0) after 1m0s
[19:21:12.847] Handling timer "check-buildbucket-build-status" (fuchsia/gcc_toolchain.bringup.arm64-gcc:9019455997643451136:5:0)
[19:21:12.847] Timer tick, asking Buildbucket for the build status
[19:21:13.054] Build 8854025935425581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:13.054] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc_toolchain.bringup.arm64-gcc:9019455997643451136:6:0) after 1m0s
[19:22:13.071] Handling timer "check-buildbucket-build-status" (fuchsia/gcc_toolchain.bringup.arm64-gcc:9019455997643451136:6:0)
[19:22:13.071] Timer tick, asking Buildbucket for the build status
[19:22:13.146] Build 8854025935425581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:13.146] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc_toolchain.bringup.arm64-gcc:9019455997643451136:7:0) after 1m0s
[19:23:13.248] Handling timer "check-buildbucket-build-status" (fuchsia/gcc_toolchain.bringup.arm64-gcc:9019455997643451136:7:0)
[19:23:13.248] Timer tick, asking Buildbucket for the build status
[19:23:13.337] Build 8854025935425581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:13.337] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc_toolchain.bringup.arm64-gcc:9019455997643451136:8:0) after 1m0s
[19:24:13.355] Handling timer "check-buildbucket-build-status" (fuchsia/gcc_toolchain.bringup.arm64-gcc:9019455997643451136:8:0)
[19:24:13.355] Timer tick, asking Buildbucket for the build status
[19:24:13.447] Build 8854025935425581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:13.447] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc_toolchain.bringup.arm64-gcc:9019455997643451136:9:0) after 1m0s
[19:25:13.462] Handling timer "check-buildbucket-build-status" (fuchsia/gcc_toolchain.bringup.arm64-gcc:9019455997643451136:9:0)
[19:25:13.462] Timer tick, asking Buildbucket for the build status
[19:25:13.725] Build 8854025935425581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:13.725] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc_toolchain.bringup.arm64-gcc:9019455997643451136:10:0) after 1m0s
[19:26:13.740] Handling timer "check-buildbucket-build-status" (fuchsia/gcc_toolchain.bringup.arm64-gcc:9019455997643451136:10:0)
[19:26:13.740] Timer tick, asking Buildbucket for the build status
[19:26:13.840] Build 8854025935425581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:13.840] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc_toolchain.bringup.arm64-gcc:9019455997643451136:11:0) after 1m0s
[19:27:13.855] Handling timer "check-buildbucket-build-status" (fuchsia/gcc_toolchain.bringup.arm64-gcc:9019455997643451136:11:0)
[19:27:13.855] Timer tick, asking Buildbucket for the build status
[19:27:14.046] Build 8854025935425581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:14.046] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc_toolchain.bringup.arm64-gcc:9019455997643451136:12:0) after 1m0s
[19:28:14.064] Handling timer "check-buildbucket-build-status" (fuchsia/gcc_toolchain.bringup.arm64-gcc:9019455997643451136:12:0)
[19:28:14.064] Timer tick, asking Buildbucket for the build status
[19:28:14.150] Build 8854025935425581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:14.150] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc_toolchain.bringup.arm64-gcc:9019455997643451136:13:0) after 1m0s
[19:29:14.281] Handling timer "check-buildbucket-build-status" (fuchsia/gcc_toolchain.bringup.arm64-gcc:9019455997643451136:13:0)
[19:29:14.281] Timer tick, asking Buildbucket for the build status
[19:29:14.438] Build 8854025935425581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:14.438] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc_toolchain.bringup.arm64-gcc:9019455997643451136:14:0) after 1m0s
[19:30:14.454] Handling timer "check-buildbucket-build-status" (fuchsia/gcc_toolchain.bringup.arm64-gcc:9019455997643451136:14:0)
[19:30:14.454] Timer tick, asking Buildbucket for the build status
[19:30:14.839] Build 8854025935425581920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:14.839] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc_toolchain.bringup.arm64-gcc:9019455997643451136:15:0) after 1m0s
[19:30:44.149] Received PubSub notification, asking Buildbucket for the build status
[19:30:44.506] Build 8854025935425581920: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[19:30:44.506] Buildbucket build:
{
"build": {
"bucket": "luci.fuchsia.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1614540631415778",
"created_by": "project:fuchsia",
"created_ts": "1614539890797405",
"id": "8854025935425581920",
"parameters_json": "{\"builder_name\": \"gcc_toolchain.bringup.arm64-gcc\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"buildbucket\": {\"properties\": {\"build.gcc_toolchain\": {\"instance\": \"c82d2d738b496e654fb09265681ac1230807acf7\", \"type\": \"isolated\"}, \"checkout.buildset\": \"commit/gitiles/fuchsia.googlesource.com/fuchsia/+/5a93d615061a3fe8a26521b9667b48e6851f78da\", \"gcc_toolchain\": {\"git_repository\": \"https://gnu.googlesource.com/gcc,https://gnu.googlesource.com/binutils-gdb\", \"git_revision\": \"23c1d29ccea7c7525b119577857e495e114035c6,1dbde357be3ce2641595b10436822e699abe32a0\"}}, \"tags\": [\"buildset:commit/gitiles/gnu.googlesource.com/gcc/+/23c1d29ccea7c7525b119577857e495e114035c6\", \"gitiles_ref:refs/heads/master\", \"parent_buildername:gcc-x64-linux\", \"user_agent:recipe\"]}, \"id\": \"01d6340d-2905-4a92-aed0-f55907c39007\", \"title\": \"gcc-x64-linux/0\"}]}, \"build.gcc_toolchain\": {\"instance\": \"c82d2d738b496e654fb09265681ac1230807acf7\", \"type\": \"isolated\"}, \"checkout.buildset\": \"commit/gitiles/fuchsia.googlesource.com/fuchsia/+/5a93d615061a3fe8a26521b9667b48e6851f78da\", \"gcc_toolchain\": {\"git_repository\": \"https://gnu.googlesource.com/gcc,https://gnu.googlesource.com/binutils-gdb\", \"git_revision\": \"23c1d29ccea7c7525b119577857e495e114035c6,1dbde357be3ce2641595b10436822e699abe32a0\"}}}",
"project": "fuchsia",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"artifact_gcs_bucket\": \"fuchsia-artifacts\", \"checkout_root\": \"/b/s/w/ir/x/w/fuchsia\", \"child_build_id\": \"8854025898508795568\", \"failed_test_names\": [], \"gcs_bucket\": \"fuchsia-build\", \"got_revision\": \"2468da4d9684b06226cdb85931e31ac97b41d8b0\", \"integration-revision-count\": 40136.0, \"perf_dataset_hash\": \"302cae96fd9c6b9a87625997ba6269899888d636\", \"test-swarming-task-ids\": [\"5202eea386eae710\"], \"test_orchestration_inputs_hash\": \"df4420188f15003a45779b6ae6c480ac02535d99\"}}",
"service_account": "fuchsia-ci-builder@fuchsia-infra.iam.gserviceaccount.com",
"started_ts": "1614539914593035",
"status": "COMPLETED",
"status_changed_ts": "1614539891880086",
"tags": [
"builder:gcc_toolchain.bringup.arm64-gcc",
"buildset:commit/gitiles/gnu.googlesource.com/gcc/+/23c1d29ccea7c7525b119577857e495e114035c6",
"gitiles_ref:refs/heads/master",
"parent_buildername:gcc-x64-linux",
"scheduler_invocation_id:9019455997643451136",
"scheduler_job_id:fuchsia/gcc_toolchain.bringup.arm64-gcc",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/fuchsia/buildbucket/cr-buildbucket.appspot.com/8854025935425581920/+/annotations",
"swarming_tag:luci_project:fuchsia",
"swarming_tag:recipe_name:fuchsia/fuchsia",
"swarming_tag:recipe_package:fuchsia/infra/recipe_bootstrap/${platform}",
"swarming_task_id:5202e555f5d57c10",
"user_agent:luci-scheduler",
"user_agent:recipe"
],
"updated_ts": "1614540631416250",
"url": "https://ci.chromium.org/b/8854025935425581920",
"utcnow_ts": "1614540644461872"
}
}
[19:30:44.506] Invocation finished in 12m35.207338472s with status SUCCEEDED