[18:16:43.340] New invocation is queued and will start shortly
[18:16:44.979] Starting the invocation (attempt 1)
[18:16:45.018] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8a925ff73f4ed2944e0e8ddb15795ce2f7fe74cb"
[18:16:45.018] Popped gitiles commit info from properties and tags
[18:16:45.018] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:16:45.018] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:16:45.018] Buildbucket request:
{
"requestId": "8976245109694209712",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Fuchsia (dbg)"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "8a925ff73f4ed2944e0e8ddb15795ce2f7fe74cb",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8976245109694209712"
},
{
"key": "scheduler_job_id",
"value": "chromium/Deterministic Fuchsia (dbg)"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[18:16:45.578] Scheduled build:
{
"id": "8810815047208520129",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Fuchsia (dbg)"
},
"number": 12413,
"createdBy": "project:chromium",
"createTime": "2022-06-20T18:16:45.079349248Z",
"updateTime": "2022-06-20T18:16:45.079349248Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "8a925ff73f4ed2944e0e8ddb15795ce2f7fe74cb",
"ref": "refs/heads/main"
}
},
"canary": true
}
[18:16:45.578] Task URL: https://cr-buildbucket.appspot.com/build/8810815047208520129
[18:16:45.578] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:2:0) after 1m12s
[18:17:57.594] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:2:0)
[18:17:57.624] Build status: SCHEDULED
[18:17:57.624] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:3:0) after 1m7s
[18:19:04.721] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:3:0)
[18:19:04.740] Build status: STARTED
[18:19:04.740] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:4:0) after 4m18s
[18:19:05.866] Received PubSub notification, asking Buildbucket for the build status
[18:19:05.943] Build status: STARTED
[18:23:22.861] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:4:0)
[18:23:22.881] Build status: STARTED
[18:23:22.881] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:6:0) after 1m23s
[18:24:45.897] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:6:0)
[18:24:45.941] Build status: STARTED
[18:24:45.941] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:7:0) after 3m8s
[18:27:53.965] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:7:0)
[18:27:53.986] Build status: STARTED
[18:27:53.986] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:8:0) after 8m47s
[18:36:41.207] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:8:0)
[18:36:41.231] Build status: STARTED
[18:36:41.231] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:9:0) after 9m7s
[18:45:48.350] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:9:0)
[18:45:48.379] Build status: STARTED
[18:45:48.379] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:10:0) after 6m40s
[18:52:28.521] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:10:0)
[18:52:28.542] Build status: STARTED
[18:52:28.542] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:11:0) after 5m45s
[18:58:13.564] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:11:0)
[18:58:13.596] Build status: STARTED
[18:58:13.596] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:12:0) after 1m26s
[18:59:39.617] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:12:0)
[18:59:39.635] Build status: STARTED
[18:59:39.635] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:13:0) after 1m9s
[19:00:48.678] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:13:0)
[19:00:48.704] Build status: STARTED
[19:00:48.704] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:14:0) after 3m10s
[19:03:58.747] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:14:0)
[19:03:58.767] Build status: STARTED
[19:03:58.767] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:15:0) after 2m44s
[19:06:42.983] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:15:0)
[19:06:43.006] Build status: STARTED
[19:06:43.006] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:16:0) after 9m56s
[19:16:39.404] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:16:0)
[19:16:39.425] Build status: STARTED
[19:16:39.425] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:17:0) after 6m41s
[19:23:20.443] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:17:0)
[19:23:20.473] Build status: STARTED
[19:23:20.473] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:18:0) after 4m31s
[19:27:51.495] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:18:0)
[19:27:51.528] Build status: STARTED
[19:27:51.528] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:19:0) after 7m40s
[19:35:31.654] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:19:0)
[19:35:31.677] Build status: STARTED
[19:35:31.677] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:20:0) after 1m29s
[19:37:00.702] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:20:0)
[19:37:00.724] Build status: STARTED
[19:37:00.724] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:21:0) after 5m43s
[19:42:43.821] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:21:0)
[19:42:43.875] Build status: STARTED
[19:42:43.875] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:22:0) after 4m0s
[19:46:44.270] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:22:0)
[19:46:44.291] Build status: STARTED
[19:46:44.291] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:23:0) after 5m32s
[19:52:16.317] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:23:0)
[19:52:16.345] Build status: STARTED
[19:52:16.345] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:24:0) after 6m4s
[19:58:20.467] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:24:0)
[19:58:20.510] Build status: STARTED
[19:58:20.510] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:25:0) after 2m45s
[20:01:05.532] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:25:0)
[20:01:05.558] Build status: STARTED
[20:01:05.558] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:26:0) after 6m20s
[20:07:25.678] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:26:0)
[20:07:25.708] Build status: STARTED
[20:07:25.708] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:27:0) after 6m32s
[20:13:57.796] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:27:0)
[20:13:57.832] Build status: STARTED
[20:13:57.832] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:28:0) after 6m22s
[20:20:19.970] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:28:0)
[20:20:20.038] Build status: STARTED
[20:20:20.038] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:29:0) after 2m15s
[20:22:35.060] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:29:0)
[20:22:35.083] Build status: STARTED
[20:22:35.083] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:30:0) after 2m31s
[20:25:06.154] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:30:0)
[20:25:06.177] Build status: STARTED
[20:25:06.177] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:31:0) after 6m0s
[20:31:06.198] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:31:0)
[20:31:06.222] Build status: STARTED
[20:31:06.222] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:32:0) after 1m12s
[20:32:18.597] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:32:0)
[20:32:18.643] Build status: STARTED
[20:32:18.643] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:33:0) after 7m49s
[20:40:07.778] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:33:0)
[20:40:07.832] Build status: STARTED
[20:40:07.832] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:34:0) after 6m46s
[20:46:53.852] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:34:0)
[20:46:53.961] Build status: STARTED
[20:46:53.961] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:35:0) after 7m49s
[20:54:42.980] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:35:0)
[20:54:43.015] Build status: STARTED
[20:54:43.015] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:36:0) after 3m26s
[20:58:09.035] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:36:0)
[20:58:09.090] Build status: STARTED
[20:58:09.090] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:37:0) after 2m10s
[21:00:19.115] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:37:0)
[21:00:19.153] Build status: STARTED
[21:00:19.153] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:38:0) after 2m52s
[21:03:11.184] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:38:0)
[21:03:11.205] Build status: STARTED
[21:03:11.205] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:39:0) after 9m44s
[21:12:55.228] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:39:0)
[21:12:55.253] Build status: STARTED
[21:12:55.253] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:40:0) after 1m44s
[21:14:39.336] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:40:0)
[21:14:39.355] Build status: STARTED
[21:14:39.355] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8976245109694209712:41:0) after 9m6s
[21:18:55.818] Received PubSub notification, asking Buildbucket for the build status
[21:18:55.850] Build:
{
"id": "8810815047208520129",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Fuchsia (dbg)"
},
"number": 12413,
"createdBy": "project:chromium",
"createTime": "2022-06-20T18:16:45.079349248Z",
"startTime": "2022-06-20T18:19:03.767256Z",
"endTime": "2022-06-20T21:18:55.597621893Z",
"updateTime": "2022-06-20T21:18:55.597621893Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "8a925ff73f4ed2944e0e8ddb15795ce2f7fe74cb",
"ref": "refs/heads/main"
}
},
"canary": true
}
[21:18:55.850] Invocation finished in 3h2m12.524351622s with status SUCCEEDED