[00:04:38.117] New invocation is queued and will start shortly
[00:04:39.198] Starting the invocation (attempt 1)
[00:04:39.260] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/a43116c44f78262057d182371d3d1132fc40f08d"
[00:04:39.260] Popped gitiles commit info from properties and tags
[00:04:39.260] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:04:39.260] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:04:39.265] Buildbucket request:
{
"requestId": "8923314592516931312",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-release-ffx_subtools"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8923314592516931312",
"job": "fuchsia/core.x64-release-ffx_subtools-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "4a3c153ef8c203f1bdb166d34130971bc370299f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@4a3c153ef8c203f1bdb166d34130971bc370299f",
"title": "4a3c153ef8c203f1bdb166d34130971bc370299f",
"url": "https://fuchsia.googlesource.com/integration/+/4a3c153ef8c203f1bdb166d34130971bc370299f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "35e3cd01b51b783fa30e7f1d72e856833d3728de"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@35e3cd01b51b783fa30e7f1d72e856833d3728de",
"title": "35e3cd01b51b783fa30e7f1d72e856833d3728de",
"url": "https://fuchsia.googlesource.com/integration/+/35e3cd01b51b783fa30e7f1d72e856833d3728de"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "92ef500c61768b6ffe2d907e16f0fb4d3d0480c3"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@92ef500c61768b6ffe2d907e16f0fb4d3d0480c3",
"title": "92ef500c61768b6ffe2d907e16f0fb4d3d0480c3",
"url": "https://fuchsia.googlesource.com/integration/+/92ef500c61768b6ffe2d907e16f0fb4d3d0480c3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "edab2253c03a4104a21c764d074f44b22e40d5fe"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@edab2253c03a4104a21c764d074f44b22e40d5fe",
"title": "edab2253c03a4104a21c764d074f44b22e40d5fe",
"url": "https://fuchsia.googlesource.com/integration/+/edab2253c03a4104a21c764d074f44b22e40d5fe"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c43dc5fa908a032bb0f4522d16d6249a96ad93bc"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c43dc5fa908a032bb0f4522d16d6249a96ad93bc",
"title": "c43dc5fa908a032bb0f4522d16d6249a96ad93bc",
"url": "https://fuchsia.googlesource.com/integration/+/c43dc5fa908a032bb0f4522d16d6249a96ad93bc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "64d3a81d77c1517fe9652cfffd2d112c892e2475"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@64d3a81d77c1517fe9652cfffd2d112c892e2475",
"title": "64d3a81d77c1517fe9652cfffd2d112c892e2475",
"url": "https://fuchsia.googlesource.com/integration/+/64d3a81d77c1517fe9652cfffd2d112c892e2475"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "5c06fa62ff765e9b02bd1728af2f1249943f1cd7"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@5c06fa62ff765e9b02bd1728af2f1249943f1cd7",
"title": "5c06fa62ff765e9b02bd1728af2f1249943f1cd7",
"url": "https://fuchsia.googlesource.com/integration/+/5c06fa62ff765e9b02bd1728af2f1249943f1cd7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f06e2d7b25c0bb12b89ccdb9efa75a3ec88f1169"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f06e2d7b25c0bb12b89ccdb9efa75a3ec88f1169",
"title": "f06e2d7b25c0bb12b89ccdb9efa75a3ec88f1169",
"url": "https://fuchsia.googlesource.com/integration/+/f06e2d7b25c0bb12b89ccdb9efa75a3ec88f1169"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a2811f3a060311be3563034a4358d9d66e6a95f3"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a2811f3a060311be3563034a4358d9d66e6a95f3",
"title": "a2811f3a060311be3563034a4358d9d66e6a95f3",
"url": "https://fuchsia.googlesource.com/integration/+/a2811f3a060311be3563034a4358d9d66e6a95f3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "26908d7d588e4d2c6bd80996c27d3a97ee02f679"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@26908d7d588e4d2c6bd80996c27d3a97ee02f679",
"title": "26908d7d588e4d2c6bd80996c27d3a97ee02f679",
"url": "https://fuchsia.googlesource.com/integration/+/26908d7d588e4d2c6bd80996c27d3a97ee02f679"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "676406c0bea3dd4548d59974ea24d911d37255cf"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@676406c0bea3dd4548d59974ea24d911d37255cf",
"title": "676406c0bea3dd4548d59974ea24d911d37255cf",
"url": "https://fuchsia.googlesource.com/integration/+/676406c0bea3dd4548d59974ea24d911d37255cf"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "437a973ea782d483c658d8741bc633833a2749a0"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@437a973ea782d483c658d8741bc633833a2749a0",
"title": "437a973ea782d483c658d8741bc633833a2749a0",
"url": "https://fuchsia.googlesource.com/integration/+/437a973ea782d483c658d8741bc633833a2749a0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "5bed8bd8445580ddbe210742ded5a8278e1b7ec3"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@5bed8bd8445580ddbe210742ded5a8278e1b7ec3",
"title": "5bed8bd8445580ddbe210742ded5a8278e1b7ec3",
"url": "https://fuchsia.googlesource.com/integration/+/5bed8bd8445580ddbe210742ded5a8278e1b7ec3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "57bb55dc2b3c37c14072f26c0bfd5bb29e3aa003"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@57bb55dc2b3c37c14072f26c0bfd5bb29e3aa003",
"title": "57bb55dc2b3c37c14072f26c0bfd5bb29e3aa003",
"url": "https://fuchsia.googlesource.com/integration/+/57bb55dc2b3c37c14072f26c0bfd5bb29e3aa003"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e548491160570ada6767dc4bf3e4ff95f612b0f5"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e548491160570ada6767dc4bf3e4ff95f612b0f5",
"title": "e548491160570ada6767dc4bf3e4ff95f612b0f5",
"url": "https://fuchsia.googlesource.com/integration/+/e548491160570ada6767dc4bf3e4ff95f612b0f5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d28e87fc7869c7be7acc29d1bdb773d12fde639c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d28e87fc7869c7be7acc29d1bdb773d12fde639c",
"title": "d28e87fc7869c7be7acc29d1bdb773d12fde639c",
"url": "https://fuchsia.googlesource.com/integration/+/d28e87fc7869c7be7acc29d1bdb773d12fde639c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "5bc6a89b9a4275ed00a160fbb8a6b699a651e6dd"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@5bc6a89b9a4275ed00a160fbb8a6b699a651e6dd",
"title": "5bc6a89b9a4275ed00a160fbb8a6b699a651e6dd",
"url": "https://fuchsia.googlesource.com/integration/+/5bc6a89b9a4275ed00a160fbb8a6b699a651e6dd"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a43116c44f78262057d182371d3d1132fc40f08d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a43116c44f78262057d182371d3d1132fc40f08d",
"title": "a43116c44f78262057d182371d3d1132fc40f08d",
"url": "https://fuchsia.googlesource.com/integration/+/a43116c44f78262057d182371d3d1132fc40f08d"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "a43116c44f78262057d182371d3d1132fc40f08d",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8923314592516931312"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/core.x64-release-ffx_subtools-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[00:04:39.783] Scheduled build:
{
"id": "8757884530542418337",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-release-ffx_subtools"
},
"createdBy": "project:fuchsia",
"createTime": "2024-01-26T00:04:39.378018508Z",
"updateTime": "2024-01-26T00:04:39.378018508Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "a43116c44f78262057d182371d3d1132fc40f08d",
"ref": "refs/heads/main"
}
},
"canary": true
}
[00:04:39.783] Task URL: https://cr-buildbucket.appspot.com/build/8757884530542418337
[00:04:39.786] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8923314592516931312:2:0) after 8m30s
[00:04:40.693] Received PubSub notification, asking Buildbucket for the build status
[00:04:40.867] Build status: SCHEDULED
[00:06:16.472] Received PubSub notification, asking Buildbucket for the build status
[00:06:16.499] Build status: STARTED
[00:13:09.800] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8923314592516931312:2:0)
[00:13:09.826] Build status: STARTED
[00:13:09.826] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8923314592516931312:5:0) after 5m35s
[00:18:44.840] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8923314592516931312:5:0)
[00:18:44.869] Build status: STARTED
[00:18:44.869] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8923314592516931312:6:0) after 9m6s
[00:27:50.897] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8923314592516931312:6:0)
[00:27:50.990] Build status: STARTED
[00:27:50.990] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8923314592516931312:7:0) after 3m37s
[00:31:28.006] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8923314592516931312:7:0)
[00:31:28.047] Build status: STARTED
[00:31:28.047] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8923314592516931312:8:0) after 7m48s
[00:39:16.111] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8923314592516931312:8:0)
[00:39:16.132] Build status: STARTED
[00:39:16.132] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8923314592516931312:9:0) after 6m54s
[00:46:10.528] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8923314592516931312:9:0)
[00:46:10.545] Build status: STARTED
[00:46:10.545] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8923314592516931312:10:0) after 1m47s
[00:47:57.616] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8923314592516931312:10:0)
[00:47:57.637] Build status: STARTED
[00:47:57.637] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8923314592516931312:11:0) after 4m20s
[00:52:17.654] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8923314592516931312:11:0)
[00:52:17.672] Build status: STARTED
[00:52:17.672] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8923314592516931312:12:0) after 7m22s
[00:59:39.694] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8923314592516931312:12:0)
[00:59:39.711] Build status: STARTED
[00:59:39.711] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8923314592516931312:13:0) after 4m24s
[01:04:03.855] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8923314592516931312:13:0)
[01:04:03.870] Build status: STARTED
[01:04:03.870] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8923314592516931312:14:0) after 5m1s
[01:09:05.180] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8923314592516931312:14:0)
[01:09:05.243] Build status: STARTED
[01:09:05.243] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8923314592516931312:15:0) after 2m47s
[01:11:52.297] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8923314592516931312:15:0)
[01:11:52.339] Build status: STARTED
[01:11:52.339] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8923314592516931312:16:0) after 1m40s
[01:13:32.429] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8923314592516931312:16:0)
[01:13:32.486] Build status: STARTED
[01:13:32.486] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8923314592516931312:17:0) after 6m7s
[01:19:39.501] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8923314592516931312:17:0)
[01:19:39.523] Build status: STARTED
[01:19:39.523] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8923314592516931312:18:0) after 5m2s
[01:24:41.864] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8923314592516931312:18:0)
[01:24:42.022] Build status: STARTED
[01:24:42.022] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-release-ffx_subtools-e5a0ec7f:8923314592516931312:19:0) after 9m30s
[01:30:41.869] Received PubSub notification, asking Buildbucket for the build status
[01:30:41.892] Build:
{
"id": "8757884530542418337",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-release-ffx_subtools"
},
"createdBy": "project:fuchsia",
"createTime": "2024-01-26T00:04:39.378018508Z",
"startTime": "2024-01-26T00:06:14.228095Z",
"endTime": "2024-01-26T01:30:40.285742Z",
"updateTime": "2024-01-26T01:30:40.285742Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "a43116c44f78262057d182371d3d1132fc40f08d",
"ref": "refs/heads/main"
}
},
"canary": true
}
[01:30:41.892] Invocation finished in 1h26m3.781222245s with status SUCCEEDED