[19:59:34.322] New invocation is queued and will start shortly
[19:59:35.861] Starting the invocation (attempt 1)
[19:59:35.892] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/23b20e5b89c406eda494ec07433dbed58bf41067"
[19:59:35.893] Popped gitiles commit info from properties and tags
[19:59:35.893] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:59:35.893] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:59:35.894] Buildbucket request:
{
"requestId": "8926500904388905216",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8926500904388905216",
"job": "fuchsia/core.x64-debug-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2c2dc138a9c73f2c364189586ae1c2081624e825"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2c2dc138a9c73f2c364189586ae1c2081624e825",
"title": "2c2dc138a9c73f2c364189586ae1c2081624e825",
"url": "https://fuchsia.googlesource.com/integration/+/2c2dc138a9c73f2c364189586ae1c2081624e825"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2e9acce1afb9114c682784ba4896ab499eba2d1b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2e9acce1afb9114c682784ba4896ab499eba2d1b",
"title": "2e9acce1afb9114c682784ba4896ab499eba2d1b",
"url": "https://fuchsia.googlesource.com/integration/+/2e9acce1afb9114c682784ba4896ab499eba2d1b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f549822aa3875a2d55020f0296d3e473b3418f9d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f549822aa3875a2d55020f0296d3e473b3418f9d",
"title": "f549822aa3875a2d55020f0296d3e473b3418f9d",
"url": "https://fuchsia.googlesource.com/integration/+/f549822aa3875a2d55020f0296d3e473b3418f9d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "4d95b1288401ad1dffe11de41f57e254f6d6c196"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@4d95b1288401ad1dffe11de41f57e254f6d6c196",
"title": "4d95b1288401ad1dffe11de41f57e254f6d6c196",
"url": "https://fuchsia.googlesource.com/integration/+/4d95b1288401ad1dffe11de41f57e254f6d6c196"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "3091d5429a776ce2aa6038083c763ff0f5286d4f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@3091d5429a776ce2aa6038083c763ff0f5286d4f",
"title": "3091d5429a776ce2aa6038083c763ff0f5286d4f",
"url": "https://fuchsia.googlesource.com/integration/+/3091d5429a776ce2aa6038083c763ff0f5286d4f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "77ecd736d0eccad6825f19687945f2616bd7a203"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@77ecd736d0eccad6825f19687945f2616bd7a203",
"title": "77ecd736d0eccad6825f19687945f2616bd7a203",
"url": "https://fuchsia.googlesource.com/integration/+/77ecd736d0eccad6825f19687945f2616bd7a203"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c83dfcba8f00296543b31e90dbd9d02c18814aa9"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c83dfcba8f00296543b31e90dbd9d02c18814aa9",
"title": "c83dfcba8f00296543b31e90dbd9d02c18814aa9",
"url": "https://fuchsia.googlesource.com/integration/+/c83dfcba8f00296543b31e90dbd9d02c18814aa9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f6fdaa18334983ffef682c56e339d185540cb2e6"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f6fdaa18334983ffef682c56e339d185540cb2e6",
"title": "f6fdaa18334983ffef682c56e339d185540cb2e6",
"url": "https://fuchsia.googlesource.com/integration/+/f6fdaa18334983ffef682c56e339d185540cb2e6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "18e6f700240276e4beeb4fd0c3afc5218eeb7797"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@18e6f700240276e4beeb4fd0c3afc5218eeb7797",
"title": "18e6f700240276e4beeb4fd0c3afc5218eeb7797",
"url": "https://fuchsia.googlesource.com/integration/+/18e6f700240276e4beeb4fd0c3afc5218eeb7797"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "23b20e5b89c406eda494ec07433dbed58bf41067"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@23b20e5b89c406eda494ec07433dbed58bf41067",
"title": "23b20e5b89c406eda494ec07433dbed58bf41067",
"url": "https://fuchsia.googlesource.com/integration/+/23b20e5b89c406eda494ec07433dbed58bf41067"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "23b20e5b89c406eda494ec07433dbed58bf41067",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8926500904388905216"
},
{
"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"
}
}
[19:59:36.304] Scheduled build:
{
"id": "8761070842032112305",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-12-21T19:59:35.946632335Z",
"updateTime": "2023-12-21T19:59:35.946632335Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "23b20e5b89c406eda494ec07433dbed58bf41067",
"ref": "refs/heads/main"
}
}
}
[19:59:36.304] Task URL: https://cr-buildbucket.appspot.com/build/8761070842032112305
[19:59:36.304] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8926500904388905216:2:0) after 9m19s
[19:59:37.248] Received PubSub notification, asking Buildbucket for the build status
[19:59:37.285] Build status: SCHEDULED
[19:59:41.037] Received PubSub notification, asking Buildbucket for the build status
[19:59:41.059] Build status: STARTED
[20:08:55.325] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8926500904388905216:2:0)
[20:08:55.343] Build status: STARTED
[20:08:55.343] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8926500904388905216:5:0) after 4m20s
[20:13:15.334] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8926500904388905216:5:0)
[20:13:15.382] Build status: STARTED
[20:13:15.382] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8926500904388905216:6:0) after 2m17s
[20:15:32.457] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8926500904388905216:6:0)
[20:15:32.493] Build status: STARTED
[20:15:32.493] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8926500904388905216:7:0) after 7m5s
[20:22:37.511] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8926500904388905216:7:0)
[20:22:37.534] Build status: STARTED
[20:22:37.534] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8926500904388905216:8:0) after 6m21s
[20:28:58.558] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8926500904388905216:8:0)
[20:28:58.580] Build status: STARTED
[20:28:58.580] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8926500904388905216:9:0) after 9m32s
[20:38:30.602] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8926500904388905216:9:0)
[20:38:30.623] Build status: STARTED
[20:38:30.623] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8926500904388905216:10:0) after 3m14s
[20:41:44.646] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8926500904388905216:10:0)
[20:41:44.671] Build status: STARTED
[20:41:44.671] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8926500904388905216:11:0) after 9m46s
[20:51:30.839] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8926500904388905216:11:0)
[20:51:30.895] Build status: STARTED
[20:51:30.895] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8926500904388905216:12:0) after 4m50s
[20:56:20.924] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8926500904388905216:12:0)
[20:56:20.963] Build status: STARTED
[20:56:20.963] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8926500904388905216:13:0) after 4m49s
[21:01:09.989] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8926500904388905216:13:0)
[21:01:10.033] Build status: STARTED
[21:01:10.033] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8926500904388905216:14:0) after 3m0s
[21:04:10.066] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8926500904388905216:14:0)
[21:04:10.097] Build status: STARTED
[21:04:10.097] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-debug-e5a0ec7f:8926500904388905216:15:0) after 8m20s
[21:10:07.852] Received PubSub notification, asking Buildbucket for the build status
[21:10:07.987] Build:
{
"id": "8761070842032112305",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-debug"
},
"createdBy": "project:fuchsia",
"createTime": "2023-12-21T19:59:35.946632335Z",
"startTime": "2023-12-21T19:59:40.560356Z",
"endTime": "2023-12-21T21:10:05.116058Z",
"updateTime": "2023-12-21T21:10:05.116058Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "23b20e5b89c406eda494ec07433dbed58bf41067",
"ref": "refs/heads/main"
}
}
}
[21:10:07.987] Invocation finished in 1h10m33.671232297s with status SUCCEEDED