[01:17:16.983] New invocation is queued and will start shortly
[01:17:18.114] Starting the invocation (attempt 1)
[01:17:18.145] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/03ba4127d820b83372966591bded532e63854c5d"
[01:17:18.145] Popped gitiles commit info from properties and tags
[01:17:18.145] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:17:18.145] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:17:18.146] Buildbucket request:
{
"requestId": "8892235262447346464",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-asan"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8892235262447346464",
"job": "fuchsia/core.arm64-asan-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "6e5157c3a063cbb69af16c5642760822cb935e7c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@6e5157c3a063cbb69af16c5642760822cb935e7c",
"title": "6e5157c3a063cbb69af16c5642760822cb935e7c",
"url": "https://fuchsia.googlesource.com/integration/+/6e5157c3a063cbb69af16c5642760822cb935e7c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "71580c6e96e1ead0ac6f486cec1afc52bd6c2977"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@71580c6e96e1ead0ac6f486cec1afc52bd6c2977",
"title": "71580c6e96e1ead0ac6f486cec1afc52bd6c2977",
"url": "https://fuchsia.googlesource.com/integration/+/71580c6e96e1ead0ac6f486cec1afc52bd6c2977"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "28b956d88e2ab40f2db65f554273f808b16b35de"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@28b956d88e2ab40f2db65f554273f808b16b35de",
"title": "28b956d88e2ab40f2db65f554273f808b16b35de",
"url": "https://fuchsia.googlesource.com/integration/+/28b956d88e2ab40f2db65f554273f808b16b35de"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "14c99351f810b48e39ec32fc1c8e8a02da0a3b6c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@14c99351f810b48e39ec32fc1c8e8a02da0a3b6c",
"title": "14c99351f810b48e39ec32fc1c8e8a02da0a3b6c",
"url": "https://fuchsia.googlesource.com/integration/+/14c99351f810b48e39ec32fc1c8e8a02da0a3b6c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b4f7562b9e2948eee28cd1f1afe8323c2a10af31"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b4f7562b9e2948eee28cd1f1afe8323c2a10af31",
"title": "b4f7562b9e2948eee28cd1f1afe8323c2a10af31",
"url": "https://fuchsia.googlesource.com/integration/+/b4f7562b9e2948eee28cd1f1afe8323c2a10af31"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "91ef2eca542851aeac8f3780d7c9c42055dc7952"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@91ef2eca542851aeac8f3780d7c9c42055dc7952",
"title": "91ef2eca542851aeac8f3780d7c9c42055dc7952",
"url": "https://fuchsia.googlesource.com/integration/+/91ef2eca542851aeac8f3780d7c9c42055dc7952"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "03ba4127d820b83372966591bded532e63854c5d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@03ba4127d820b83372966591bded532e63854c5d",
"title": "03ba4127d820b83372966591bded532e63854c5d",
"url": "https://fuchsia.googlesource.com/integration/+/03ba4127d820b83372966591bded532e63854c5d"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "03ba4127d820b83372966591bded532e63854c5d",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8892235262447346464"
},
{
"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"
}
}
[01:17:18.520] Scheduled build:
{
"id": "8726805200494128401",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-asan"
},
"createdBy": "project:fuchsia",
"createTime": "2025-01-03T01:17:18.216729726Z",
"updateTime": "2025-01-03T01:17:18.216729726Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "03ba4127d820b83372966591bded532e63854c5d",
"ref": "refs/heads/main"
}
}
}
[01:17:18.520] Task URL: https://cr-buildbucket.appspot.com/build/8726805200494128401
[01:17:18.521] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8892235262447346464:2:0) after 1m52s
[01:17:19.491] Received PubSub notification, asking Buildbucket for the build status
[01:17:19.555] Build status: SCHEDULED
[01:17:24.265] Received PubSub notification, asking Buildbucket for the build status
[01:17:24.289] Build status: STARTED
[01:19:10.495] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8892235262447346464:2:0)
[01:19:10.515] Build status: STARTED
[01:19:10.515] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8892235262447346464:5:0) after 5m21s
[01:24:31.534] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8892235262447346464:5:0)
[01:24:31.573] Build status: STARTED
[01:24:31.573] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8892235262447346464:6:0) after 9m34s
[01:34:05.590] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8892235262447346464:6:0)
[01:34:05.742] Build status: STARTED
[01:34:05.742] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8892235262447346464:7:0) after 1m24s
[01:35:29.759] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8892235262447346464:7:0)
[01:35:29.792] Build status: STARTED
[01:35:29.792] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8892235262447346464:8:0) after 4m37s
[01:40:06.814] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8892235262447346464:8:0)
[01:40:06.852] Build status: STARTED
[01:40:06.852] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8892235262447346464:9:0) after 8m44s
[01:48:50.920] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8892235262447346464:9:0)
[01:48:50.943] Build status: STARTED
[01:48:50.943] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8892235262447346464:10:0) after 5m11s
[01:54:01.963] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8892235262447346464:10:0)
[01:54:01.982] Build status: STARTED
[01:54:01.982] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8892235262447346464:11:0) after 5m15s
[01:59:17.008] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8892235262447346464:11:0)
[01:59:17.029] Build status: STARTED
[01:59:17.029] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8892235262447346464:12:0) after 8m25s
[02:07:42.201] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8892235262447346464:12:0)
[02:07:42.227] Build status: STARTED
[02:07:42.227] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8892235262447346464:13:0) after 3m54s
[02:11:36.412] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8892235262447346464:13:0)
[02:11:36.437] Build status: STARTED
[02:11:36.437] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8892235262447346464:14:0) after 6m5s
[02:17:41.833] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8892235262447346464:14:0)
[02:17:41.851] Build status: STARTED
[02:17:41.851] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8892235262447346464:15:0) after 3m49s
[02:21:30.879] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8892235262447346464:15:0)
[02:21:30.898] Build status: STARTED
[02:21:30.898] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8892235262447346464:16:0) after 2m33s
[02:24:04.017] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8892235262447346464:16:0)
[02:24:04.080] Build status: STARTED
[02:24:04.080] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8892235262447346464:17:0) after 2m57s
[02:27:01.276] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8892235262447346464:17:0)
[02:27:01.307] Build status: STARTED
[02:27:01.307] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8892235262447346464:18:0) after 6m11s
[02:33:12.476] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8892235262447346464:18:0)
[02:33:12.491] Build status: STARTED
[02:33:12.491] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8892235262447346464:19:0) after 9m21s
[02:42:33.560] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8892235262447346464:19:0)
[02:42:33.579] Build status: STARTED
[02:42:33.579] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8892235262447346464:20:0) after 1m2s
[02:43:35.599] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8892235262447346464:20:0)
[02:43:35.615] Build status: STARTED
[02:43:35.615] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8892235262447346464:21:0) after 6m13s
[02:49:48.635] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8892235262447346464:21:0)
[02:49:48.658] Build status: STARTED
[02:49:48.658] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8892235262447346464:22:0) after 5m45s
[02:55:33.685] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8892235262447346464:22:0)
[02:55:33.703] Build status: STARTED
[02:55:33.703] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8892235262447346464:23:0) after 5m36s
[03:01:09.696] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8892235262447346464:23:0)
[03:01:09.734] Build status: STARTED
[03:01:09.734] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8892235262447346464:24:0) after 1m19s
[03:02:28.755] Handling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8892235262447346464:24:0)
[03:02:28.772] Build status: STARTED
[03:02:28.772] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.arm64-asan-e5a0ec7f:8892235262447346464:25:0) after 6m47s
[03:05:52.787] Received PubSub notification, asking Buildbucket for the build status
[03:05:52.813] Build:
{
"id": "8726805200494128401",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.arm64-asan"
},
"createdBy": "project:fuchsia",
"createTime": "2025-01-03T01:17:18.216729726Z",
"startTime": "2025-01-03T01:17:24.055588187Z",
"endTime": "2025-01-03T03:05:52.216909244Z",
"updateTime": "2025-01-03T03:05:52.216909244Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "03ba4127d820b83372966591bded532e63854c5d",
"ref": "refs/heads/main"
}
}
}
[03:05:52.813] Invocation finished in 1h48m35.844495151s with status SUCCEEDED