[18:59:56.271] New invocation is queued and will start shortly
[18:59:57.414] Starting the invocation (attempt 1)
[18:59:57.445] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/8e118c64818058354b2f0db057ba6f0f8457aed6"
[18:59:57.445] Popped gitiles commit info from properties and tags
[18:59:57.445] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:59:57.445] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:59:57.446] Buildbucket request:
{
"requestId": "8936017337719586576",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8936017337719586576",
"job": "fuchsia/core.x64-debug-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e81883c458da6546fb6123ebfb657239861cd023"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e81883c458da6546fb6123ebfb657239861cd023",
"title": "e81883c458da6546fb6123ebfb657239861cd023",
"url": "https://fuchsia.googlesource.com/integration/+/e81883c458da6546fb6123ebfb657239861cd023"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "377a28512e25ba4f849ef0be284cb1bdcad39ac5"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@377a28512e25ba4f849ef0be284cb1bdcad39ac5",
"title": "377a28512e25ba4f849ef0be284cb1bdcad39ac5",
"url": "https://fuchsia.googlesource.com/integration/+/377a28512e25ba4f849ef0be284cb1bdcad39ac5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "bceeef1c22957fb87ae75ae64765c6e7042ee324"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@bceeef1c22957fb87ae75ae64765c6e7042ee324",
"title": "bceeef1c22957fb87ae75ae64765c6e7042ee324",
"url": "https://fuchsia.googlesource.com/integration/+/bceeef1c22957fb87ae75ae64765c6e7042ee324"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ee564872917cb528470474b3b89cf8a8cb3058c0"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ee564872917cb528470474b3b89cf8a8cb3058c0",
"title": "ee564872917cb528470474b3b89cf8a8cb3058c0",
"url": "https://fuchsia.googlesource.com/integration/+/ee564872917cb528470474b3b89cf8a8cb3058c0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "287637a373cb8cfdc092bb0fe926f10915386718"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@287637a373cb8cfdc092bb0fe926f10915386718",
"title": "287637a373cb8cfdc092bb0fe926f10915386718",
"url": "https://fuchsia.googlesource.com/integration/+/287637a373cb8cfdc092bb0fe926f10915386718"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "dc86d0bbc99734c0ff00b33080794b60c7f94e16"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@dc86d0bbc99734c0ff00b33080794b60c7f94e16",
"title": "dc86d0bbc99734c0ff00b33080794b60c7f94e16",
"url": "https://fuchsia.googlesource.com/integration/+/dc86d0bbc99734c0ff00b33080794b60c7f94e16"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f658ce9ae31d6e4a4e45913d0bbfbfd72a26e436"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f658ce9ae31d6e4a4e45913d0bbfbfd72a26e436",
"title": "f658ce9ae31d6e4a4e45913d0bbfbfd72a26e436",
"url": "https://fuchsia.googlesource.com/integration/+/f658ce9ae31d6e4a4e45913d0bbfbfd72a26e436"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "5998f0911558f238eb2ca4cfc0345156bc490e66"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@5998f0911558f238eb2ca4cfc0345156bc490e66",
"title": "5998f0911558f238eb2ca4cfc0345156bc490e66",
"url": "https://fuchsia.googlesource.com/integration/+/5998f0911558f238eb2ca4cfc0345156bc490e66"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "5f314dfa08b6d3c3f07aba714f9ef16d526046d7"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@5f314dfa08b6d3c3f07aba714f9ef16d526046d7",
"title": "5f314dfa08b6d3c3f07aba714f9ef16d526046d7",
"url": "https://fuchsia.googlesource.com/integration/+/5f314dfa08b6d3c3f07aba714f9ef16d526046d7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "08d00a6fbd91989224ca8ca2eef1cf2269723184"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@08d00a6fbd91989224ca8ca2eef1cf2269723184",
"title": "08d00a6fbd91989224ca8ca2eef1cf2269723184",
"url": "https://fuchsia.googlesource.com/integration/+/08d00a6fbd91989224ca8ca2eef1cf2269723184"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b222ef096b071ba3abf9ea8c91aaad31ef56fcbf"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b222ef096b071ba3abf9ea8c91aaad31ef56fcbf",
"title": "b222ef096b071ba3abf9ea8c91aaad31ef56fcbf",
"url": "https://fuchsia.googlesource.com/integration/+/b222ef096b071ba3abf9ea8c91aaad31ef56fcbf"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8915287a25ae27dc1f0e299ff4d162e4d1d0cd81"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8915287a25ae27dc1f0e299ff4d162e4d1d0cd81",
"title": "8915287a25ae27dc1f0e299ff4d162e4d1d0cd81",
"url": "https://fuchsia.googlesource.com/integration/+/8915287a25ae27dc1f0e299ff4d162e4d1d0cd81"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d056b799e0dfb50e3fcf2a97e6f42ead985200cc"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d056b799e0dfb50e3fcf2a97e6f42ead985200cc",
"title": "d056b799e0dfb50e3fcf2a97e6f42ead985200cc",
"url": "https://fuchsia.googlesource.com/integration/+/d056b799e0dfb50e3fcf2a97e6f42ead985200cc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "557abc3216587a300b22147059f59026f9b3be84"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@557abc3216587a300b22147059f59026f9b3be84",
"title": "557abc3216587a300b22147059f59026f9b3be84",
"url": "https://fuchsia.googlesource.com/integration/+/557abc3216587a300b22147059f59026f9b3be84"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b56825018163132bece9950d2fc9fe0e29f01156"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b56825018163132bece9950d2fc9fe0e29f01156",
"title": "b56825018163132bece9950d2fc9fe0e29f01156",
"url": "https://fuchsia.googlesource.com/integration/+/b56825018163132bece9950d2fc9fe0e29f01156"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f863eb0487ee40b6ac1f139523c941e6ca7fcd8e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f863eb0487ee40b6ac1f139523c941e6ca7fcd8e",
"title": "f863eb0487ee40b6ac1f139523c941e6ca7fcd8e",
"url": "https://fuchsia.googlesource.com/integration/+/f863eb0487ee40b6ac1f139523c941e6ca7fcd8e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "3140e2f4a0fc660b3cbe85b30b0bf0e3d6cd6dec"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@3140e2f4a0fc660b3cbe85b30b0bf0e3d6cd6dec",
"title": "3140e2f4a0fc660b3cbe85b30b0bf0e3d6cd6dec",
"url": "https://fuchsia.googlesource.com/integration/+/3140e2f4a0fc660b3cbe85b30b0bf0e3d6cd6dec"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ddc380d482ec47fe3dc44147b94a01b392d11dea"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ddc380d482ec47fe3dc44147b94a01b392d11dea",
"title": "ddc380d482ec47fe3dc44147b94a01b392d11dea",
"url": "https://fuchsia.googlesource.com/integration/+/ddc380d482ec47fe3dc44147b94a01b392d11dea"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f68dd578e0fa924b6abba27d723f5813b38d7d00"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f68dd578e0fa924b6abba27d723f5813b38d7d00",
"title": "f68dd578e0fa924b6abba27d723f5813b38d7d00",
"url": "https://fuchsia.googlesource.com/integration/+/f68dd578e0fa924b6abba27d723f5813b38d7d00"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8e118c64818058354b2f0db057ba6f0f8457aed6"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8e118c64818058354b2f0db057ba6f0f8457aed6",
"title": "8e118c64818058354b2f0db057ba6f0f8457aed6",
"url": "https://fuchsia.googlesource.com/integration/+/8e118c64818058354b2f0db057ba6f0f8457aed6"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "8e118c64818058354b2f0db057ba6f0f8457aed6",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8936017337719586576"
},
{
"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"
}
}
[18:59:58.086] Scheduled build:
{
"id": "8770587275772938801",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-09-07T18:59:57.503729952Z",
"updateTime": "2023-09-07T18:59:57.503729952Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "8e118c64818058354b2f0db057ba6f0f8457aed6",
"ref": "refs/heads/main"
}
}
}
[18:59:58.086] Task URL: https://cr-buildbucket.appspot.com/build/8770587275772938801
[18:59:58.086] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936017337719586576:2:0) after 7m54s
[18:59:58.908] Received PubSub notification, asking Buildbucket for the build status
[18:59:58.934] Build status: SCHEDULED
[19:00:18.808] Received PubSub notification, asking Buildbucket for the build status
[19:00:18.841] Build status: STARTED
[19:07:52.207] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936017337719586576:2:0)
[19:07:52.235] Build status: STARTED
[19:07:52.235] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936017337719586576:5:0) after 9m39s
[19:17:31.252] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936017337719586576:5:0)
[19:17:31.274] Build status: STARTED
[19:17:31.274] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936017337719586576:6:0) after 6m9s
[19:23:40.301] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936017337719586576:6:0)
[19:23:40.319] Build status: STARTED
[19:23:40.319] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936017337719586576:7:0) after 2m26s
[19:26:06.355] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936017337719586576:7:0)
[19:26:06.404] Build status: STARTED
[19:26:06.404] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936017337719586576:8:0) after 1m11s
[19:27:17.442] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936017337719586576:8:0)
[19:27:17.491] Build status: STARTED
[19:27:17.491] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936017337719586576:9:0) after 3m6s
[19:30:23.578] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936017337719586576:9:0)
[19:30:23.616] Build status: STARTED
[19:30:23.616] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936017337719586576:10:0) after 9m21s
[19:39:45.175] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936017337719586576:10:0)
[19:39:45.246] Build status: STARTED
[19:39:45.247] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936017337719586576:11:0) after 9m14s
[19:48:59.306] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936017337719586576:11:0)
[19:48:59.339] Build status: STARTED
[19:48:59.339] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936017337719586576:12:0) after 2m10s
[19:51:09.616] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936017337719586576:12:0)
[19:51:09.634] Build status: STARTED
[19:51:09.635] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936017337719586576:13:0) after 5m57s
[19:57:06.788] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936017337719586576:13:0)
[19:57:06.851] Build status: STARTED
[19:57:06.851] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936017337719586576:14:0) after 1m3s
[19:58:10.112] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936017337719586576:14:0)
[19:58:10.131] Build status: STARTED
[19:58:10.131] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936017337719586576:15:0) after 2m30s
[20:00:40.327] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936017337719586576:15:0)
[20:00:40.354] Build status: STARTED
[20:00:40.354] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936017337719586576:16:0) after 5m29s
[20:06:09.475] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936017337719586576:16:0)
[20:06:09.503] Build status: STARTED
[20:06:09.503] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936017337719586576:17:0) after 5m27s
[20:11:36.648] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936017337719586576:17:0)
[20:11:36.670] Build status: STARTED
[20:11:36.670] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8936017337719586576:18:0) after 1m19s
[20:12:34.315] Received PubSub notification, asking Buildbucket for the build status
[20:12:34.347] Build:
{
"id": "8770587275772938801",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-09-07T18:59:57.503729952Z",
"startTime": "2023-09-07T19:00:18.138499Z",
"endTime": "2023-09-07T20:12:33.483011377Z",
"updateTime": "2023-09-07T20:12:33.483011377Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "8e118c64818058354b2f0db057ba6f0f8457aed6",
"ref": "refs/heads/main"
}
}
}
[20:12:34.347] Invocation finished in 1h12m38.083799542s with status SUCCEEDED