[15:15:44.744] New invocation is queued and will start shortly
[15:15:46.001] Starting the invocation (attempt 1)
[15:15:46.028] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/b6f7b7d0e4e836f7c7818aade302ccafe3e1f0ce"
[15:15:46.028] Popped gitiles commit info from properties and tags
[15:15:46.028] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:15:46.028] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:15:46.028] Buildbucket request:
{
"requestId": "8901332804544510704",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-asan-cxx_determinism-build_only"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8901332804544510704",
"job": "fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "95c672a39954de07b1dd91edfc1e50b2f8d2c481"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@95c672a39954de07b1dd91edfc1e50b2f8d2c481",
"title": "95c672a39954de07b1dd91edfc1e50b2f8d2c481",
"url": "https://fuchsia.googlesource.com/integration/+/95c672a39954de07b1dd91edfc1e50b2f8d2c481"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c1a6c1d9f2fd8823cde468b2ce7133ae9c310801"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c1a6c1d9f2fd8823cde468b2ce7133ae9c310801",
"title": "c1a6c1d9f2fd8823cde468b2ce7133ae9c310801",
"url": "https://fuchsia.googlesource.com/integration/+/c1a6c1d9f2fd8823cde468b2ce7133ae9c310801"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "5226ba6e595255386eaed0669ebb96e89b33c337"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@5226ba6e595255386eaed0669ebb96e89b33c337",
"title": "5226ba6e595255386eaed0669ebb96e89b33c337",
"url": "https://fuchsia.googlesource.com/integration/+/5226ba6e595255386eaed0669ebb96e89b33c337"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "18df912477c2112e44668e036bc55d796a1e7678"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@18df912477c2112e44668e036bc55d796a1e7678",
"title": "18df912477c2112e44668e036bc55d796a1e7678",
"url": "https://fuchsia.googlesource.com/integration/+/18df912477c2112e44668e036bc55d796a1e7678"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "9eaf40c8c044d357f4df5f966e56eb95f8d9df7a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@9eaf40c8c044d357f4df5f966e56eb95f8d9df7a",
"title": "9eaf40c8c044d357f4df5f966e56eb95f8d9df7a",
"url": "https://fuchsia.googlesource.com/integration/+/9eaf40c8c044d357f4df5f966e56eb95f8d9df7a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b6f7b7d0e4e836f7c7818aade302ccafe3e1f0ce"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b6f7b7d0e4e836f7c7818aade302ccafe3e1f0ce",
"title": "b6f7b7d0e4e836f7c7818aade302ccafe3e1f0ce",
"url": "https://fuchsia.googlesource.com/integration/+/b6f7b7d0e4e836f7c7818aade302ccafe3e1f0ce"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "b6f7b7d0e4e836f7c7818aade302ccafe3e1f0ce",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8901332804544510704"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[15:15:46.475] Scheduled build:
{
"id": "8735902742468634241",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-asan-cxx_determinism-build_only"
},
"createdBy": "project:fuchsia",
"createTime": "2024-09-24T15:15:46.092838684Z",
"updateTime": "2024-09-24T15:15:46.092838684Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "b6f7b7d0e4e836f7c7818aade302ccafe3e1f0ce",
"ref": "refs/heads/main"
}
}
}
[15:15:46.475] Task URL: https://cr-buildbucket.appspot.com/build/8735902742468634241
[15:15:46.475] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:2:0) after 2m16s
[15:15:47.492] Received PubSub notification, asking Buildbucket for the build status
[15:15:47.521] Build status: SCHEDULED
[15:15:52.756] Received PubSub notification, asking Buildbucket for the build status
[15:15:52.775] Build status: STARTED
[15:18:02.497] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:2:0)
[15:18:02.622] Build status: STARTED
[15:18:02.622] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:5:0) after 1m54s
[15:19:56.742] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:5:0)
[15:19:56.759] Build status: STARTED
[15:19:56.759] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:6:0) after 4m27s
[15:24:23.766] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:6:0)
[15:24:23.793] Build status: STARTED
[15:24:23.793] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:7:0) after 7m39s
[15:32:02.811] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:7:0)
[15:32:02.899] Build status: STARTED
[15:32:02.899] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:8:0) after 5m52s
[15:37:54.917] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:8:0)
[15:37:54.946] Build status: STARTED
[15:37:54.946] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:9:0) after 6m27s
[15:44:21.956] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:9:0)
[15:44:22.016] Build status: STARTED
[15:44:22.016] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:10:0) after 7m14s
[15:51:36.080] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:10:0)
[15:51:36.099] Build status: STARTED
[15:51:36.099] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:11:0) after 2m19s
[15:53:55.104] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:11:0)
[15:53:55.128] Build status: STARTED
[15:53:55.128] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:12:0) after 1m6s
[15:55:01.192] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:12:0)
[15:55:01.220] Build status: STARTED
[15:55:01.220] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:13:0) after 1m30s
[15:56:31.227] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:13:0)
[15:56:31.252] Build status: STARTED
[15:56:31.252] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:14:0) after 5m1s
[16:01:32.324] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:14:0)
[16:01:32.354] Build status: STARTED
[16:01:32.354] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:15:0) after 7m20s
[16:08:52.373] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:15:0)
[16:08:52.406] Build status: STARTED
[16:08:52.406] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:16:0) after 4m9s
[16:13:01.475] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:16:0)
[16:13:01.524] Build status: STARTED
[16:13:01.524] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:17:0) after 8m50s
[16:21:51.621] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:17:0)
[16:21:51.644] Build status: STARTED
[16:21:51.644] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:18:0) after 5m27s
[16:27:18.756] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:18:0)
[16:27:18.779] Build status: STARTED
[16:27:18.779] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:19:0) after 4m2s
[16:31:20.799] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:19:0)
[16:31:20.834] Build status: STARTED
[16:31:20.834] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:20:0) after 5m14s
[16:36:34.859] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:20:0)
[16:36:34.879] Build status: STARTED
[16:36:34.886] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:21:0) after 1m48s
[16:38:22.961] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:21:0)
[16:38:22.985] Build status: STARTED
[16:38:22.985] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:22:0) after 8m50s
[16:47:13.004] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:22:0)
[16:47:13.073] Build status: STARTED
[16:47:13.073] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:23:0) after 3m8s
[16:50:21.126] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:23:0)
[16:50:21.144] Build status: STARTED
[16:50:21.144] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:24:0) after 9m16s
[16:59:37.161] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:24:0)
[16:59:37.181] Build status: STARTED
[16:59:37.181] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:25:0) after 8m5s
[17:07:42.200] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:25:0)
[17:07:42.218] Build status: STARTED
[17:07:42.218] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:26:0) after 9m43s
[17:17:25.255] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:26:0)
[17:17:25.280] Build status: STARTED
[17:17:25.280] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:27:0) after 3m45s
[17:21:10.302] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:27:0)
[17:21:10.392] Build status: STARTED
[17:21:10.392] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:28:0) after 1m38s
[17:22:48.397] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:28:0)
[17:22:48.420] Build status: STARTED
[17:22:48.420] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:29:0) after 2m12s
[17:25:00.493] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:29:0)
[17:25:00.547] Build status: STARTED
[17:25:00.547] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:30:0) after 4m27s
[17:29:27.616] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:30:0)
[17:29:27.638] Build status: STARTED
[17:29:27.638] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:31:0) after 4m42s
[17:34:09.714] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:31:0)
[17:34:09.731] Build status: STARTED
[17:34:09.731] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:32:0) after 4m19s
[17:38:28.766] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:32:0)
[17:38:28.784] Build status: STARTED
[17:38:28.784] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:33:0) after 9m18s
[17:47:46.887] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:33:0)
[17:47:46.938] Build status: STARTED
[17:47:46.938] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:34:0) after 8m42s
[17:56:29.020] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:34:0)
[17:56:29.042] Build status: STARTED
[17:56:29.042] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:35:0) after 8m40s
[18:05:09.062] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:35:0)
[18:05:09.086] Build status: STARTED
[18:05:09.086] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:36:0) after 7m34s
[18:12:43.103] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:36:0)
[18:12:43.126] Build status: STARTED
[18:12:43.126] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:37:0) after 6m20s
[18:19:03.213] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:37:0)
[18:19:03.234] Build status: STARTED
[18:19:03.234] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:38:0) after 9m57s
[18:29:00.252] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:38:0)
[18:29:00.271] Build status: STARTED
[18:29:00.271] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:39:0) after 1m57s
[18:30:57.339] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:39:0)
[18:30:57.357] Build status: STARTED
[18:30:57.357] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:40:0) after 2m27s
[18:33:24.412] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:40:0)
[18:33:24.500] Build status: STARTED
[18:33:24.500] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:41:0) after 6m53s
[18:40:17.521] Handling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:41:0)
[18:40:17.540] Build status: STARTED
[18:40:17.540] Scheduling timer "check-buildbucket-build-status" (fuchsia/core.x64-asan-cxx_determinism-build_only-e5a0ec7f:8901332804544510704:42:0) after 9m45s
[18:44:47.162] Received PubSub notification, asking Buildbucket for the build status
[18:44:47.186] Build:
{
"id": "8735902742468634241",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "core.x64-asan-cxx_determinism-build_only"
},
"createdBy": "project:fuchsia",
"createTime": "2024-09-24T15:15:46.092838684Z",
"startTime": "2024-09-24T15:15:52.530399406Z",
"endTime": "2024-09-24T18:44:46.795067213Z",
"updateTime": "2024-09-24T18:44:46.795067213Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "b6f7b7d0e4e836f7c7818aade302ccafe3e1f0ce",
"ref": "refs/heads/main"
}
}
}
[18:44:47.186] Invocation finished in 3h29m2.457074131s with status SUCCEEDED