[18:33:52.187] New invocation is queued and will start shortly
[18:33:56.265] Starting the invocation (attempt 1)
[18:33:56.293] Popped buildset tag "buildset:commit/gitiles/gnu.googlesource.com/binutils-gdb/+/778164cffebba2bb48b983ff6164b04e03eb5153"
[18:33:56.293] Popped gitiles commit info from properties and tags
[18:33:56.293] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:33:56.293] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:33:56.294] Buildbucket request:
{
"requestId": "8873960055792967392",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "gcc-prod-trigger"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8873960055792967392",
"job": "fuchsia/prod-gcc-prod-trigger-3e002780",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://gnu.googlesource.com/binutils-gdb",
"revision": "83eeaf917873a550656faf9a38cd14e0f4c521b1"
},
"id": "https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@83eeaf917873a550656faf9a38cd14e0f4c521b1",
"title": "83eeaf917873a550656faf9a38cd14e0f4c521b1",
"url": "https://gnu.googlesource.com/binutils-gdb/+/83eeaf917873a550656faf9a38cd14e0f4c521b1"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://gnu.googlesource.com/binutils-gdb",
"revision": "00d853d1fe7885d0a685584f32b7ed70485c6def"
},
"id": "https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@00d853d1fe7885d0a685584f32b7ed70485c6def",
"title": "00d853d1fe7885d0a685584f32b7ed70485c6def",
"url": "https://gnu.googlesource.com/binutils-gdb/+/00d853d1fe7885d0a685584f32b7ed70485c6def"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://gnu.googlesource.com/binutils-gdb",
"revision": "3ff8956e4bf46ffd638bf12ea4788a858e905023"
},
"id": "https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@3ff8956e4bf46ffd638bf12ea4788a858e905023",
"title": "3ff8956e4bf46ffd638bf12ea4788a858e905023",
"url": "https://gnu.googlesource.com/binutils-gdb/+/3ff8956e4bf46ffd638bf12ea4788a858e905023"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://gnu.googlesource.com/binutils-gdb",
"revision": "4214ca9036c8f5d01025d0505ff1167700af5f98"
},
"id": "https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@4214ca9036c8f5d01025d0505ff1167700af5f98",
"title": "4214ca9036c8f5d01025d0505ff1167700af5f98",
"url": "https://gnu.googlesource.com/binutils-gdb/+/4214ca9036c8f5d01025d0505ff1167700af5f98"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://gnu.googlesource.com/binutils-gdb",
"revision": "4983bb052d3a57fe1b43334071ba61a8443bb5aa"
},
"id": "https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@4983bb052d3a57fe1b43334071ba61a8443bb5aa",
"title": "4983bb052d3a57fe1b43334071ba61a8443bb5aa",
"url": "https://gnu.googlesource.com/binutils-gdb/+/4983bb052d3a57fe1b43334071ba61a8443bb5aa"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://gnu.googlesource.com/binutils-gdb",
"revision": "ae114fb523efe908f9e807359e2f494ee64d2801"
},
"id": "https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@ae114fb523efe908f9e807359e2f494ee64d2801",
"title": "ae114fb523efe908f9e807359e2f494ee64d2801",
"url": "https://gnu.googlesource.com/binutils-gdb/+/ae114fb523efe908f9e807359e2f494ee64d2801"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://gnu.googlesource.com/binutils-gdb",
"revision": "778164cffebba2bb48b983ff6164b04e03eb5153"
},
"id": "https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@778164cffebba2bb48b983ff6164b04e03eb5153",
"title": "778164cffebba2bb48b983ff6164b04e03eb5153",
"url": "https://gnu.googlesource.com/binutils-gdb/+/778164cffebba2bb48b983ff6164b04e03eb5153"
}
]
}
},
"gitilesCommit": {
"host": "gnu.googlesource.com",
"project": "binutils-gdb",
"id": "778164cffebba2bb48b983ff6164b04e03eb5153",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8873960055792967392"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/prod-gcc-prod-trigger-3e002780"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[18:33:56.711] Scheduled build:
{
"id": "8708529990735682977",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "gcc-prod-trigger"
},
"createdBy": "project:fuchsia",
"createTime": "2025-07-23T18:33:56.389278882Z",
"updateTime": "2025-07-23T18:33:56.389278882Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "gnu.googlesource.com",
"project": "binutils-gdb",
"id": "778164cffebba2bb48b983ff6164b04e03eb5153",
"ref": "refs/heads/master"
}
}
}
[18:33:56.711] Task URL: https://cr-buildbucket.appspot.com/build/8708529990735682977
[18:33:56.711] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-prod-trigger-3e002780:8873960055792967392:2:0) after 8m40s
[18:34:17.822] Received PubSub notification, asking Buildbucket for the build status
[18:34:17.854] Build status: SCHEDULED
[18:42:36.733] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-prod-trigger-3e002780:8873960055792967392:2:0)
[18:42:36.778] Build status: SCHEDULED
[18:42:36.778] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-prod-trigger-3e002780:8873960055792967392:4:0) after 2m46s
[18:45:22.840] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-prod-trigger-3e002780:8873960055792967392:4:0)
[18:45:23.110] Build status: SCHEDULED
[18:45:23.110] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-prod-trigger-3e002780:8873960055792967392:5:0) after 2m54s
[18:48:17.147] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-prod-trigger-3e002780:8873960055792967392:5:0)
[18:48:17.177] Build status: SCHEDULED
[18:48:17.177] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-prod-trigger-3e002780:8873960055792967392:6:0) after 5m48s
[18:54:05.197] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-prod-trigger-3e002780:8873960055792967392:6:0)
[18:54:05.222] Build status: SCHEDULED
[18:54:05.222] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-prod-trigger-3e002780:8873960055792967392:7:0) after 7m53s
[19:01:58.274] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-prod-trigger-3e002780:8873960055792967392:7:0)
[19:01:58.311] Build status: SCHEDULED
[19:01:58.311] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-prod-trigger-3e002780:8873960055792967392:8:0) after 9m58s
[19:11:56.409] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-prod-trigger-3e002780:8873960055792967392:8:0)
[19:11:56.433] Build status: SCHEDULED
[19:11:56.433] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-prod-trigger-3e002780:8873960055792967392:9:0) after 4m33s
[19:16:29.628] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-prod-trigger-3e002780:8873960055792967392:9:0)
[19:16:29.659] Build status: SCHEDULED
[19:16:29.659] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-prod-trigger-3e002780:8873960055792967392:10:0) after 7m24s
[19:21:49.217] Received PubSub notification, asking Buildbucket for the build status
[19:21:49.251] Build status: STARTED
[19:23:53.676] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-prod-trigger-3e002780:8873960055792967392:10:0)
[19:23:53.704] Build status: STARTED
[19:23:53.704] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-prod-trigger-3e002780:8873960055792967392:12:0) after 8m5s
[19:31:58.782] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-prod-trigger-3e002780:8873960055792967392:12:0)
[19:31:58.803] Build status: STARTED
[19:31:58.803] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-prod-trigger-3e002780:8873960055792967392:13:0) after 8m8s
[19:40:06.831] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-prod-trigger-3e002780:8873960055792967392:13:0)
[19:40:06.856] Build status: STARTED
[19:40:06.856] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-prod-trigger-3e002780:8873960055792967392:14:0) after 1m37s
[19:41:43.872] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-prod-trigger-3e002780:8873960055792967392:14:0)
[19:41:43.889] Build status: STARTED
[19:41:43.889] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-prod-trigger-3e002780:8873960055792967392:15:0) after 3m23s
[19:45:06.910] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-prod-trigger-3e002780:8873960055792967392:15:0)
[19:45:06.969] Build status: STARTED
[19:45:06.969] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-prod-trigger-3e002780:8873960055792967392:16:0) after 2m51s
[19:47:57.989] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-prod-trigger-3e002780:8873960055792967392:16:0)
[19:47:58.033] Build status: STARTED
[19:47:58.033] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-prod-trigger-3e002780:8873960055792967392:17:0) after 7m7s
[19:55:05.113] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-prod-trigger-3e002780:8873960055792967392:17:0)
[19:55:05.134] Build status: STARTED
[19:55:05.134] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-prod-trigger-3e002780:8873960055792967392:18:0) after 7m54s
[20:02:59.227] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-prod-trigger-3e002780:8873960055792967392:18:0)
[20:02:59.421] Build status: STARTED
[20:02:59.421] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-prod-trigger-3e002780:8873960055792967392:19:0) after 6m56s
[20:09:55.619] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-prod-trigger-3e002780:8873960055792967392:19:0)
[20:09:55.715] Build status: STARTED
[20:09:55.716] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-prod-trigger-3e002780:8873960055792967392:20:0) after 8m20s
[20:18:15.815] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-prod-trigger-3e002780:8873960055792967392:20:0)
[20:18:15.932] Build status: STARTED
[20:18:15.932] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-prod-trigger-3e002780:8873960055792967392:21:0) after 5m1s
[20:23:16.989] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-prod-trigger-3e002780:8873960055792967392:21:0)
[20:23:17.010] Build status: STARTED
[20:23:17.010] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-prod-trigger-3e002780:8873960055792967392:22:0) after 5m24s
[20:28:41.034] Handling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-prod-trigger-3e002780:8873960055792967392:22:0)
[20:28:41.161] Build status: STARTED
[20:28:41.161] Scheduling timer "check-buildbucket-build-status" (fuchsia/prod-gcc-prod-trigger-3e002780:8873960055792967392:23:0) after 7m40s
[20:29:54.164] Received PubSub notification, asking Buildbucket for the build status
[20:29:54.198] Build:
{
"id": "8708529990735682977",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "gcc-prod-trigger"
},
"createdBy": "project:fuchsia",
"createTime": "2025-07-23T18:33:56.389278882Z",
"startTime": "2025-07-23T19:21:49.019989871Z",
"endTime": "2025-07-23T20:29:53.893721052Z",
"updateTime": "2025-07-23T20:29:53.893721052Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "gnu.googlesource.com",
"project": "binutils-gdb",
"id": "778164cffebba2bb48b983ff6164b04e03eb5153",
"ref": "refs/heads/master"
}
}
}
[20:29:54.198] Invocation finished in 1h56m2.015193458s with status SUCCEEDED