[18:33:43.549] New invocation is queued and will start shortly
[18:33:45.039] Starting the invocation (attempt 1)
[18:33:45.431] Popped buildset tag "buildset:commit/gitiles/gnu.googlesource.com/binutils-gdb/+/1e28eebbbc34ebec69ea4913f7e8d19352e35630"
[18:33:45.431] Popped gitiles commit info from properties and tags
[18:33:45.431] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:33:45.431] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:33:45.432] Buildbucket request:
{
"requestId": "8969721058347131456",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "gcc-prod-trigger"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8969721058347131456",
"job": "fuchsia/gcc-prod-trigger-3e002780",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://gnu.googlesource.com/binutils-gdb",
"revision": "945e66a74d6dd3a94bd5f1ee5b132de42337eb4a"
},
"id": "https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@945e66a74d6dd3a94bd5f1ee5b132de42337eb4a",
"title": "945e66a74d6dd3a94bd5f1ee5b132de42337eb4a",
"url": "https://gnu.googlesource.com/binutils-gdb/+/945e66a74d6dd3a94bd5f1ee5b132de42337eb4a"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://gnu.googlesource.com/binutils-gdb",
"revision": "6472b2302de5cd8753be629f58c8ce880d0c2e32"
},
"id": "https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@6472b2302de5cd8753be629f58c8ce880d0c2e32",
"title": "6472b2302de5cd8753be629f58c8ce880d0c2e32",
"url": "https://gnu.googlesource.com/binutils-gdb/+/6472b2302de5cd8753be629f58c8ce880d0c2e32"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://gnu.googlesource.com/binutils-gdb",
"revision": "3095d92634a938d447eed1ef2c5d59f40f44078e"
},
"id": "https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@3095d92634a938d447eed1ef2c5d59f40f44078e",
"title": "3095d92634a938d447eed1ef2c5d59f40f44078e",
"url": "https://gnu.googlesource.com/binutils-gdb/+/3095d92634a938d447eed1ef2c5d59f40f44078e"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://gnu.googlesource.com/binutils-gdb",
"revision": "4749b84b51bcaa59acfb8bda5d9e134432528cf9"
},
"id": "https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@4749b84b51bcaa59acfb8bda5d9e134432528cf9",
"title": "4749b84b51bcaa59acfb8bda5d9e134432528cf9",
"url": "https://gnu.googlesource.com/binutils-gdb/+/4749b84b51bcaa59acfb8bda5d9e134432528cf9"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://gnu.googlesource.com/binutils-gdb",
"revision": "1e28eebbbc34ebec69ea4913f7e8d19352e35630"
},
"id": "https://gnu.googlesource.com/binutils-gdb/+/refs/heads/master@1e28eebbbc34ebec69ea4913f7e8d19352e35630",
"title": "1e28eebbbc34ebec69ea4913f7e8d19352e35630",
"url": "https://gnu.googlesource.com/binutils-gdb/+/1e28eebbbc34ebec69ea4913f7e8d19352e35630"
}
]
}
},
"gitilesCommit": {
"host": "gnu.googlesource.com",
"project": "binutils-gdb",
"id": "1e28eebbbc34ebec69ea4913f7e8d19352e35630",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8969721058347131456"
},
{
"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:33:45.835] Scheduled build:
{
"id": "8804290995628326177",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "gcc-prod-trigger"
},
"createdBy": "project:fuchsia",
"createTime": "2022-08-31T18:33:45.510099209Z",
"updateTime": "2022-08-31T18:33:45.510099209Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "gnu.googlesource.com",
"project": "binutils-gdb",
"id": "1e28eebbbc34ebec69ea4913f7e8d19352e35630",
"ref": "refs/heads/master"
}
}
}
[18:33:45.835] Task URL: https://cr-buildbucket.appspot.com/build/8804290995628326177
[18:33:45.835] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8969721058347131456:2:0) after 7m9s
[18:34:26.526] Received PubSub notification, asking Buildbucket for the build status
[18:34:26.557] Build status: STARTED
[18:40:55.215] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8969721058347131456:2:0)
[18:40:55.238] Build status: STARTED
[18:40:55.238] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8969721058347131456:4:0) after 9m4s
[18:50:00.127] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8969721058347131456:4:0)
[18:50:00.153] Build status: STARTED
[18:50:00.153] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8969721058347131456:5:0) after 8m50s
[18:58:50.360] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8969721058347131456:5:0)
[18:58:50.381] Build status: STARTED
[18:58:50.381] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8969721058347131456:6:0) after 7m59s
[19:06:49.519] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8969721058347131456:6:0)
[19:06:49.546] Build status: STARTED
[19:06:49.546] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8969721058347131456:7:0) after 1m23s
[19:08:12.593] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8969721058347131456:7:0)
[19:08:12.629] Build status: STARTED
[19:08:12.629] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8969721058347131456:8:0) after 9m11s
[19:17:23.660] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8969721058347131456:8:0)
[19:17:23.735] Build status: STARTED
[19:17:23.735] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8969721058347131456:9:0) after 2m3s
[19:19:26.848] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8969721058347131456:9:0)
[19:19:26.955] Build status: STARTED
[19:19:26.955] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8969721058347131456:10:0) after 1m2s
[19:20:29.094] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8969721058347131456:10:0)
[19:20:29.164] Build status: STARTED
[19:20:29.164] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8969721058347131456:11:0) after 7m12s
[19:27:41.420] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8969721058347131456:11:0)
[19:27:41.443] Build status: STARTED
[19:27:41.443] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8969721058347131456:12:0) after 7m36s
[19:35:17.897] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8969721058347131456:12:0)
[19:35:17.921] Build status: STARTED
[19:35:17.921] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8969721058347131456:13:0) after 3m18s
[19:38:36.308] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8969721058347131456:13:0)
[19:38:36.508] Build status: STARTED
[19:38:36.508] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8969721058347131456:14:0) after 4m13s
[19:42:49.571] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8969721058347131456:14:0)
[19:42:49.600] Build status: STARTED
[19:42:49.601] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8969721058347131456:15:0) after 1m21s
[19:44:10.640] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8969721058347131456:15:0)
[19:44:10.663] Build status: STARTED
[19:44:10.663] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8969721058347131456:16:0) after 3m37s
[19:47:47.973] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8969721058347131456:16:0)
[19:47:48.004] Build status: STARTED
[19:47:48.004] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8969721058347131456:17:0) after 8m34s
[19:56:21.989] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8969721058347131456:17:0)
[19:56:22.012] Build status: STARTED
[19:56:22.012] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8969721058347131456:18:0) after 8m2s
[20:04:24.030] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8969721058347131456:18:0)
[20:04:24.053] Build status: STARTED
[20:04:24.053] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8969721058347131456:19:0) after 8m28s
[20:12:52.171] Handling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8969721058347131456:19:0)
[20:12:52.197] Build status: STARTED
[20:12:52.197] Scheduling timer "check-buildbucket-build-status" (fuchsia/gcc-prod-trigger-3e002780:8969721058347131456:20:0) after 9m36s
[20:18:42.062] Received PubSub notification, asking Buildbucket for the build status
[20:18:42.088] Build:
{
"id": "8804290995628326177",
"builder": {
"project": "fuchsia",
"bucket": "prod",
"builder": "gcc-prod-trigger"
},
"createdBy": "project:fuchsia",
"createTime": "2022-08-31T18:33:45.510099209Z",
"startTime": "2022-08-31T18:34:25.782294Z",
"endTime": "2022-08-31T20:18:41.701177630Z",
"updateTime": "2022-08-31T20:18:41.701177630Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "gnu.googlesource.com",
"project": "binutils-gdb",
"id": "1e28eebbbc34ebec69ea4913f7e8d19352e35630",
"ref": "refs/heads/master"
}
}
}
[20:18:42.088] Invocation finished in 1h44m58.553421146s with status SUCCEEDED