[22:58:30.439] New invocation is queued and will start shortly
[22:58:31.545] Starting the invocation (attempt 1)
[22:58:31.580] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/52b4d78c25067bc1f8a95a6796379f3a71f1bd5e"
[22:58:31.580] Popped gitiles commit info from properties and tags
[22:58:31.580] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:58:31.580] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:58:31.581] Buildbucket request:
{
"requestId": "8937814267552496544",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8937814267552496544",
"job": "fuchsia/core.x64-debug-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d98620145050d4bd8c94938366df8913bc83965e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d98620145050d4bd8c94938366df8913bc83965e",
"title": "d98620145050d4bd8c94938366df8913bc83965e",
"url": "https://fuchsia.googlesource.com/integration/+/d98620145050d4bd8c94938366df8913bc83965e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "1ef12a5ee2d0eff3685864099bbc40309976e842"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@1ef12a5ee2d0eff3685864099bbc40309976e842",
"title": "1ef12a5ee2d0eff3685864099bbc40309976e842",
"url": "https://fuchsia.googlesource.com/integration/+/1ef12a5ee2d0eff3685864099bbc40309976e842"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d7d4be8b5af0809bca385e8a66aa7964b2b9abf6"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d7d4be8b5af0809bca385e8a66aa7964b2b9abf6",
"title": "d7d4be8b5af0809bca385e8a66aa7964b2b9abf6",
"url": "https://fuchsia.googlesource.com/integration/+/d7d4be8b5af0809bca385e8a66aa7964b2b9abf6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "7429de814553a9c0deedb8c69c4bca155c43722d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@7429de814553a9c0deedb8c69c4bca155c43722d",
"title": "7429de814553a9c0deedb8c69c4bca155c43722d",
"url": "https://fuchsia.googlesource.com/integration/+/7429de814553a9c0deedb8c69c4bca155c43722d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e3cf9a83076deb76f4c82e3c829bbc85e0cb3f5f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e3cf9a83076deb76f4c82e3c829bbc85e0cb3f5f",
"title": "e3cf9a83076deb76f4c82e3c829bbc85e0cb3f5f",
"url": "https://fuchsia.googlesource.com/integration/+/e3cf9a83076deb76f4c82e3c829bbc85e0cb3f5f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b13fd1b21f8811d92014c6b8403a52d5c34cd667"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b13fd1b21f8811d92014c6b8403a52d5c34cd667",
"title": "b13fd1b21f8811d92014c6b8403a52d5c34cd667",
"url": "https://fuchsia.googlesource.com/integration/+/b13fd1b21f8811d92014c6b8403a52d5c34cd667"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "320aa7bd4bb9576e4fd0dd981891de2ec28ba418"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@320aa7bd4bb9576e4fd0dd981891de2ec28ba418",
"title": "320aa7bd4bb9576e4fd0dd981891de2ec28ba418",
"url": "https://fuchsia.googlesource.com/integration/+/320aa7bd4bb9576e4fd0dd981891de2ec28ba418"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d41efadd16daa553467f0a852abfee0f55674ff8"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d41efadd16daa553467f0a852abfee0f55674ff8",
"title": "d41efadd16daa553467f0a852abfee0f55674ff8",
"url": "https://fuchsia.googlesource.com/integration/+/d41efadd16daa553467f0a852abfee0f55674ff8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "cdbc1e6fc650fa64d44098a17bc5663508091a68"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@cdbc1e6fc650fa64d44098a17bc5663508091a68",
"title": "cdbc1e6fc650fa64d44098a17bc5663508091a68",
"url": "https://fuchsia.googlesource.com/integration/+/cdbc1e6fc650fa64d44098a17bc5663508091a68"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "76b44d70f8886179d3affa96f54117dfcaefbee7"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@76b44d70f8886179d3affa96f54117dfcaefbee7",
"title": "76b44d70f8886179d3affa96f54117dfcaefbee7",
"url": "https://fuchsia.googlesource.com/integration/+/76b44d70f8886179d3affa96f54117dfcaefbee7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c961fd669b8175e96c63a6fc960b24926f9d2563"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c961fd669b8175e96c63a6fc960b24926f9d2563",
"title": "c961fd669b8175e96c63a6fc960b24926f9d2563",
"url": "https://fuchsia.googlesource.com/integration/+/c961fd669b8175e96c63a6fc960b24926f9d2563"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "03bfb159264a070487c5e8cbea18b95076c0b18b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@03bfb159264a070487c5e8cbea18b95076c0b18b",
"title": "03bfb159264a070487c5e8cbea18b95076c0b18b",
"url": "https://fuchsia.googlesource.com/integration/+/03bfb159264a070487c5e8cbea18b95076c0b18b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a01be2530745cb56c67b96b00febca768d22f150"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a01be2530745cb56c67b96b00febca768d22f150",
"title": "a01be2530745cb56c67b96b00febca768d22f150",
"url": "https://fuchsia.googlesource.com/integration/+/a01be2530745cb56c67b96b00febca768d22f150"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c4cd48dcbb722c53cff494a965253583575a7af5"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c4cd48dcbb722c53cff494a965253583575a7af5",
"title": "c4cd48dcbb722c53cff494a965253583575a7af5",
"url": "https://fuchsia.googlesource.com/integration/+/c4cd48dcbb722c53cff494a965253583575a7af5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "bf0d79e64d4df0ad4c7e562cfeabbdaab2d5dd75"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@bf0d79e64d4df0ad4c7e562cfeabbdaab2d5dd75",
"title": "bf0d79e64d4df0ad4c7e562cfeabbdaab2d5dd75",
"url": "https://fuchsia.googlesource.com/integration/+/bf0d79e64d4df0ad4c7e562cfeabbdaab2d5dd75"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "30ec4609103e334dd608ee6865e2084ec425f7e4"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@30ec4609103e334dd608ee6865e2084ec425f7e4",
"title": "30ec4609103e334dd608ee6865e2084ec425f7e4",
"url": "https://fuchsia.googlesource.com/integration/+/30ec4609103e334dd608ee6865e2084ec425f7e4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "9b7a4d56304152b192dfbf3491f0c49988169a80"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@9b7a4d56304152b192dfbf3491f0c49988169a80",
"title": "9b7a4d56304152b192dfbf3491f0c49988169a80",
"url": "https://fuchsia.googlesource.com/integration/+/9b7a4d56304152b192dfbf3491f0c49988169a80"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "52b4d78c25067bc1f8a95a6796379f3a71f1bd5e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@52b4d78c25067bc1f8a95a6796379f3a71f1bd5e",
"title": "52b4d78c25067bc1f8a95a6796379f3a71f1bd5e",
"url": "https://fuchsia.googlesource.com/integration/+/52b4d78c25067bc1f8a95a6796379f3a71f1bd5e"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "52b4d78c25067bc1f8a95a6796379f3a71f1bd5e",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8937814267552496544"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/core.x64-debug-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[22:58:32.063] Scheduled build:
{
"id": "8772384205638450881",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-08-18T22:58:31.642468232Z",
"updateTime": "2023-08-18T22:58:31.642468232Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "52b4d78c25067bc1f8a95a6796379f3a71f1bd5e",
"ref": "refs/heads/main"
}
}
}
[22:58:32.063] Task URL: https://cr-buildbucket.appspot.com/build/8772384205638450881
[22:58:32.063] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937814267552496544:2:0) after 7m3s
[22:58:32.974] Received PubSub notification, asking Buildbucket for the build status
[22:58:32.991] Build status: SCHEDULED
[22:59:45.021] Received PubSub notification, asking Buildbucket for the build status
[22:59:45.052] Build status: STARTED
[23:05:35.428] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937814267552496544:2:0)
[23:05:35.452] Build status: STARTED
[23:05:35.452] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937814267552496544:5:0) after 5m32s
[23:11:07.449] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937814267552496544:5:0)
[23:11:07.481] Build status: STARTED
[23:11:07.481] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937814267552496544:6:0) after 4m22s
[23:15:29.519] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937814267552496544:6:0)
[23:15:29.554] Build status: STARTED
[23:15:29.554] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937814267552496544:7:0) after 2m39s
[23:18:08.802] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937814267552496544:7:0)
[23:18:08.858] Build status: STARTED
[23:18:08.858] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937814267552496544:8:0) after 4m16s
[23:22:24.937] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937814267552496544:8:0)
[23:22:24.963] Build status: STARTED
[23:22:24.963] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937814267552496544:9:0) after 2m55s
[23:25:19.984] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937814267552496544:9:0)
[23:25:20.001] Build status: STARTED
[23:25:20.001] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937814267552496544:10:0) after 9m32s
[23:34:52.334] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937814267552496544:10:0)
[23:34:52.365] Build status: STARTED
[23:34:52.365] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937814267552496544:11:0) after 6m15s
[23:41:07.381] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937814267552496544:11:0)
[23:41:07.401] Build status: STARTED
[23:41:07.401] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937814267552496544:12:0) after 1m29s
[23:42:37.202] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937814267552496544:12:0)
[23:42:37.220] Build status: STARTED
[23:42:37.220] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937814267552496544:13:0) after 5m48s
[23:48:25.293] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937814267552496544:13:0)
[23:48:25.309] Build status: STARTED
[23:48:25.309] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937814267552496544:14:0) after 3m37s
[23:52:02.336] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937814267552496544:14:0)
[23:52:02.364] Build status: STARTED
[23:52:02.364] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937814267552496544:15:0) after 9m56s
[00:01:58.461] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937814267552496544:15:0)
[00:01:58.637] Build status: STARTED
[00:01:58.637] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937814267552496544:16:0) after 6m53s
[00:08:52.129] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937814267552496544:16:0)
[00:08:52.145] Build status: STARTED
[00:08:52.145] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937814267552496544:17:0) after 7m50s
[00:16:42.239] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937814267552496544:17:0)
[00:16:42.266] Build status: STARTED
[00:16:42.266] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8937814267552496544:18:0) after 5m33s
[00:19:08.496] Received PubSub notification, asking Buildbucket for the build status
[00:19:08.520] Build:
{
"id": "8772384205638450881",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-08-18T22:58:31.642468232Z",
"startTime": "2023-08-18T22:59:44.316131Z",
"endTime": "2023-08-19T00:19:07.970561953Z",
"updateTime": "2023-08-19T00:19:07.970561953Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "52b4d78c25067bc1f8a95a6796379f3a71f1bd5e",
"ref": "refs/heads/main"
}
}
}
[00:19:08.520] Invocation finished in 1h20m38.08749975s with status SUCCEEDED