[18:34:00.726] New invocation is queued and will start shortly
[18:34:02.573] Starting the invocation (attempt 1)
[18:34:02.615] Popped buildset tag "buildset:commit/gitiles/gnu.googlesource.com/binutils-gdb/+/44d469c5f85a4243462b8966722dafa62b602bf5"
[18:34:02.615] Popped gitiles commit info from properties and tags
[18:34:02.615] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:34:02.615] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:34:02.615] Buildbucket request:
{
"requestId": "8978055961242809696",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "gcc-prod-trigger"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8978055961242809696",
"job": "fuchsia/gcc-prod-trigger-3e002780",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://gnu.googlesource.com/binutils-gdb",
"revision": "2918df9368abdedffe2e2be1c93c760d85d545ab"
},
"id": "https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@2918df9368abdedffe2e2be1c93c760d85d545ab",
"title": "2918df9368abdedffe2e2be1c93c760d85d545ab",
"url": "https://gnu.googlesource.com/binutils-gdb/+/2918df9368abdedffe2e2be1c93c760d85d545ab"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://gnu.googlesource.com/binutils-gdb",
"revision": "74a04e5b2a496b18bad615fc19a621c0ff1452df"
},
"id": "https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@74a04e5b2a496b18bad615fc19a621c0ff1452df",
"title": "74a04e5b2a496b18bad615fc19a621c0ff1452df",
"url": "https://gnu.googlesource.com/binutils-gdb/+/74a04e5b2a496b18bad615fc19a621c0ff1452df"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://gnu.googlesource.com/binutils-gdb",
"revision": "e953510c3bfda9a39f1da76e00ab810917e32b78"
},
"id": "https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@e953510c3bfda9a39f1da76e00ab810917e32b78",
"title": "e953510c3bfda9a39f1da76e00ab810917e32b78",
"url": "https://gnu.googlesource.com/binutils-gdb/+/e953510c3bfda9a39f1da76e00ab810917e32b78"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://gnu.googlesource.com/binutils-gdb",
"revision": "30596893ab5c82081bfdf81cebe0b973f4e7c516"
},
"id": "https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@30596893ab5c82081bfdf81cebe0b973f4e7c516",
"title": "30596893ab5c82081bfdf81cebe0b973f4e7c516",
"url": "https://gnu.googlesource.com/binutils-gdb/+/30596893ab5c82081bfdf81cebe0b973f4e7c516"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://gnu.googlesource.com/binutils-gdb",
"revision": "44d469c5f85a4243462b8966722dafa62b602bf5"
},
"id": "https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@44d469c5f85a4243462b8966722dafa62b602bf5",
"title": "44d469c5f85a4243462b8966722dafa62b602bf5",
"url": "https://gnu.googlesource.com/binutils-gdb/+/44d469c5f85a4243462b8966722dafa62b602bf5"
}
]
}
},
"gitilesCommit": {
"host": "gnu.googlesource.com",
"project": "binutils-gdb",
"id": "44d469c5f85a4243462b8966722dafa62b602bf5",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8978055961242809696"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/gcc-prod-trigger-3e002780"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[18:34:03.297] Scheduled build:
{
"id": "8812625898472254641",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "gcc-prod-trigger"
},
"createdBy": "project:fuchsia",
"createTime": "2022-05-31T18:34:02.738901278Z",
"updateTime": "2022-05-31T18:34:02.738901278Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "gnu.googlesource.com",
"project": "binutils-gdb",
"id": "44d469c5f85a4243462b8966722dafa62b602bf5",
"ref": "refs/heads/master"
}
}
}
[18:34:03.297] Task URL: https://cr-buildbucket.appspot.com/build/8812625898472254641
[18:34:03.297] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8978055961242809696:2:0) after 7m8s
[18:34:11.833] Received PubSub notification, asking Buildbucket for the build status
[18:34:11.908] Build status: STARTED
[18:41:11.488] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8978055961242809696:2:0)
[18:41:11.519] Build status: STARTED
[18:41:11.519] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8978055961242809696:4:0) after 7m12s
[18:48:23.545] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8978055961242809696:4:0)
[18:48:23.576] Build status: STARTED
[18:48:23.576] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8978055961242809696:5:0) after 6m10s
[18:54:33.594] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8978055961242809696:5:0)
[18:54:33.627] Build status: STARTED
[18:54:33.627] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8978055961242809696:6:0) after 5m2s
[18:59:35.644] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8978055961242809696:6:0)
[18:59:35.670] Build status: STARTED
[18:59:35.670] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8978055961242809696:7:0) after 4m11s
[19:03:46.685] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8978055961242809696:7:0)
[19:03:46.708] Build status: STARTED
[19:03:46.708] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8978055961242809696:8:0) after 2m53s
[19:06:39.934] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8978055961242809696:8:0)
[19:06:39.965] Build status: STARTED
[19:06:39.965] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8978055961242809696:9:0) after 2m44s
[19:09:24.083] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8978055961242809696:9:0)
[19:09:24.115] Build status: STARTED
[19:09:24.115] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8978055961242809696:10:0) after 8m32s
[19:17:56.296] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8978055961242809696:10:0)
[19:17:56.326] Build status: STARTED
[19:17:56.326] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8978055961242809696:11:0) after 2m49s
[19:20:45.488] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8978055961242809696:11:0)
[19:20:45.518] Build status: STARTED
[19:20:45.518] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8978055961242809696:12:0) after 1m55s
[19:22:40.536] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8978055961242809696:12:0)
[19:22:40.607] Build status: STARTED
[19:22:40.607] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8978055961242809696:13:0) after 7m2s
[19:29:42.706] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8978055961242809696:13:0)
[19:29:42.771] Build status: STARTED
[19:29:42.771] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8978055961242809696:14:0) after 5m21s
[19:35:03.788] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8978055961242809696:14:0)
[19:35:03.820] Build status: STARTED
[19:35:03.820] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8978055961242809696:15:0) after 9m56s
[19:44:59.908] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8978055961242809696:15:0)
[19:44:59.937] Build status: STARTED
[19:44:59.937] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8978055961242809696:16:0) after 4m14s
[19:49:13.991] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8978055961242809696:16:0)
[19:49:14.035] Build status: STARTED
[19:49:14.035] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8978055961242809696:17:0) after 3m25s
[19:52:39.174] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8978055961242809696:17:0)
[19:52:39.209] Build status: STARTED
[19:52:39.209] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8978055961242809696:18:0) after 8m37s
[20:00:09.715] Received PubSub notification, asking Buildbucket for the build status
[20:00:09.751] Build:
{
"id": "8812625898472254641",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "gcc-prod-trigger"
},
"createdBy": "project:fuchsia",
"createTime": "2022-05-31T18:34:02.738901278Z",
"startTime": "2022-05-31T18:34:09.497620Z",
"endTime": "2022-05-31T20:00:09.271672965Z",
"updateTime": "2022-05-31T20:00:09.271672965Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "gnu.googlesource.com",
"project": "binutils-gdb",
"id": "44d469c5f85a4243462b8966722dafa62b602bf5",
"ref": "refs/heads/master"
}
}
}
[20:00:09.751] Invocation finished in 1h26m9.03893098s with status SUCCEEDED