[23:59:08.705] New invocation is queued and will start shortly
[23:59:09.884] Starting the invocation (attempt 1)
[23:59:09.909] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/5bc6a89b9a4275ed00a160fbb8a6b699a651e6dd"
[23:59:09.909] Popped gitiles commit info from properties and tags
[23:59:09.909] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:59:09.909] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:59:09.909] Buildbucket request:
{
"requestId": "8923314937936048208",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-asan"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8923314937936048208",
"job": "fuchsia/core.arm64-asan-e5a0ec7f",
"triggers": [
{
"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"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "5bc6a89b9a4275ed00a160fbb8a6b699a651e6dd",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8923314937936048208"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/core.arm64-asan-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[23:59:10.327] Scheduled build:
{
"id": "8757884875961983569",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-asan"
},
"createdBy": "project:fuchsia",
"createTime": "2024-01-25T23:59:09.960890052Z",
"updateTime": "2024-01-25T23:59:09.960890052Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "5bc6a89b9a4275ed00a160fbb8a6b699a651e6dd",
"ref": "refs/heads/main"
}
}
}
[23:59:10.327] Task URL: https://cr-buildbucket.appspot.com/build/8757884875961983569
[23:59:10.327] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8923314937936048208:2:0) after 4m38s
[23:59:11.702] Received PubSub notification, asking Buildbucket for the build status
[23:59:11.723] Build status: SCHEDULED
[23:59:14.842] Received PubSub notification, asking Buildbucket for the build status
[23:59:14.920] Build status: STARTED
[00:03:48.346] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8923314937936048208:2:0)
[00:03:48.363] Build status: STARTED
[00:03:48.363] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8923314937936048208:5:0) after 4m4s
[00:07:52.386] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8923314937936048208:5:0)
[00:07:52.404] Build status: STARTED
[00:07:52.404] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8923314937936048208:6:0) after 3m11s
[00:11:03.418] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8923314937936048208:6:0)
[00:11:03.439] Build status: STARTED
[00:11:03.439] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8923314937936048208:7:0) after 7m45s
[00:18:48.470] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8923314937936048208:7:0)
[00:18:48.491] Build status: STARTED
[00:18:48.491] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8923314937936048208:8:0) after 3m23s
[00:22:11.505] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8923314937936048208:8:0)
[00:22:11.524] Build status: STARTED
[00:22:11.524] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8923314937936048208:9:0) after 1m8s
[00:23:19.591] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8923314937936048208:9:0)
[00:23:19.618] Build status: STARTED
[00:23:19.618] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8923314937936048208:10:0) after 1m50s
[00:25:09.644] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8923314937936048208:10:0)
[00:25:09.714] Build status: STARTED
[00:25:09.715] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8923314937936048208:11:0) after 3m32s
[00:28:41.709] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8923314937936048208:11:0)
[00:28:41.738] Build status: STARTED
[00:28:41.738] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8923314937936048208:12:0) after 4m6s
[00:32:47.935] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8923314937936048208:12:0)
[00:32:47.953] Build status: STARTED
[00:32:47.953] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8923314937936048208:13:0) after 8m56s
[00:39:01.428] Received PubSub notification, asking Buildbucket for the build status
[00:39:01.452] Build:
{
"id": "8757884875961983569",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-asan"
},
"createdBy": "project:fuchsia",
"createTime": "2024-01-25T23:59:09.960890052Z",
"startTime": "2024-01-25T23:59:12.283527Z",
"endTime": "2024-01-26T00:39:00.486764Z",
"updateTime": "2024-01-26T00:39:00.486764Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "5bc6a89b9a4275ed00a160fbb8a6b699a651e6dd",
"ref": "refs/heads/main"
}
}
}
[00:39:01.452] Invocation finished in 39m52.758040178s with status SUCCEEDED