[21:02:00.721] New invocation is queued and will start shortly
[21:02:01.893] Starting the invocation (attempt 1)
[21:02:01.920] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/af471f523ee3fa42ffca501c1c5379dd6c4d3dd6"
[21:02:01.920] Popped gitiles commit info from properties and tags
[21:02:01.920] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:02:01.920] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:02:01.920] Buildbucket request:
{
"requestId": "8917980861168040160",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Android (dbg)"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "af471f523ee3fa42ffca501c1c5379dd6c4d3dd6",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8917980861168040160"
},
{
"key": "scheduler_job_id",
"value": "chromium/Deterministic Android (dbg)"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[21:02:02.477] Scheduled build:
{
"id": "8752550799182221617",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Android (dbg)"
},
"number": 15543,
"createdBy": "project:chromium",
"createTime": "2024-03-24T21:02:01.986874785Z",
"updateTime": "2024-03-24T21:02:01.986874785Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "af471f523ee3fa42ffca501c1c5379dd6c4d3dd6",
"ref": "refs/heads/main"
}
},
"canary": true
}
[21:02:02.477] Task URL: https://cr-buildbucket.appspot.com/build/8752550799182221617
[21:02:02.477] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:2:0) after 3m21s
[21:02:03.392] Received PubSub notification, asking Buildbucket for the build status
[21:02:03.486] Build status: SCHEDULED
[21:02:06.216] Received PubSub notification, asking Buildbucket for the build status
[21:02:06.236] Build status: STARTED
[21:05:23.495] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:2:0)
[21:05:23.513] Build status: STARTED
[21:05:23.513] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:5:0) after 5m37s
[21:11:00.542] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:5:0)
[21:11:00.671] Build status: STARTED
[21:11:00.671] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:6:0) after 2m22s
[21:13:22.789] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:6:0)
[21:13:22.810] Build status: STARTED
[21:13:22.810] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:7:0) after 4m40s
[21:18:03.130] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:7:0)
[21:18:03.425] Build status: STARTED
[21:18:03.425] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:8:0) after 5m57s
[21:24:00.418] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:8:0)
[21:24:00.447] Build status: STARTED
[21:24:00.447] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:9:0) after 1m42s
[21:25:42.798] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:9:0)
[21:25:42.838] Build status: STARTED
[21:25:42.838] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:10:0) after 1m27s
[21:27:09.985] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:10:0)
[21:27:10.072] Build status: STARTED
[21:27:10.072] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:11:0) after 1m17s
[21:28:27.094] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:11:0)
[21:28:27.117] Build status: STARTED
[21:28:27.118] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:12:0) after 1m41s
[21:30:08.175] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:12:0)
[21:30:08.202] Build status: STARTED
[21:30:08.203] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:13:0) after 3m50s
[21:33:58.254] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:13:0)
[21:33:58.336] Build status: STARTED
[21:33:58.336] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:14:0) after 6m11s
[21:40:09.502] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:14:0)
[21:40:09.516] Build status: STARTED
[21:40:09.516] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:15:0) after 3m46s
[21:43:55.534] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:15:0)
[21:43:55.621] Build status: STARTED
[21:43:55.621] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:16:0) after 4m10s
[21:48:05.644] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:16:0)
[21:48:05.666] Build status: STARTED
[21:48:05.666] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:17:0) after 3m50s
[21:51:55.691] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:17:0)
[21:51:55.718] Build status: STARTED
[21:51:55.718] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:18:0) after 4m5s
[21:56:00.823] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:18:0)
[21:56:00.855] Build status: STARTED
[21:56:00.855] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:19:0) after 5m57s
[22:01:57.907] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:19:0)
[22:01:57.968] Build status: STARTED
[22:01:57.968] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:20:0) after 4m53s
[22:06:51.130] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:20:0)
[22:06:51.162] Build status: STARTED
[22:06:51.162] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:21:0) after 1m36s
[22:08:27.257] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:21:0)
[22:08:27.283] Build status: STARTED
[22:08:27.284] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:22:0) after 3m25s
[22:11:52.312] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:22:0)
[22:11:52.333] Build status: STARTED
[22:11:52.333] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:23:0) after 6m26s
[22:18:18.459] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:23:0)
[22:18:18.492] Build status: STARTED
[22:18:18.492] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:24:0) after 9m45s
[22:28:03.544] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:24:0)
[22:28:03.609] Build status: STARTED
[22:28:03.609] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:25:0) after 1m48s
[22:29:51.888] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:25:0)
[22:29:51.907] Build status: STARTED
[22:29:51.907] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:26:0) after 3m48s
[22:33:40.944] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:26:0)
[22:33:40.972] Build status: STARTED
[22:33:40.972] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:27:0) after 3m37s
[22:37:17.986] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:27:0)
[22:37:18.067] Build status: STARTED
[22:37:18.067] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:28:0) after 2m16s
[22:39:34.094] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:28:0)
[22:39:34.129] Build status: STARTED
[22:39:34.129] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:29:0) after 1m40s
[22:41:14.148] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:29:0)
[22:41:14.166] Build status: STARTED
[22:41:14.166] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:30:0) after 8m23s
[22:49:37.360] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:30:0)
[22:49:37.381] Build status: STARTED
[22:49:37.381] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:31:0) after 3m29s
[22:53:07.092] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:31:0)
[22:53:07.109] Build status: STARTED
[22:53:07.109] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:32:0) after 5m34s
[22:58:41.335] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:32:0)
[22:58:41.354] Build status: STARTED
[22:58:41.354] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:33:0) after 7m40s
[23:06:21.626] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:33:0)
[23:06:21.750] Build status: STARTED
[23:06:21.750] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:34:0) after 3m51s
[23:10:12.776] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:34:0)
[23:10:12.799] Build status: STARTED
[23:10:12.799] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:35:0) after 4m36s
[23:14:48.818] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:35:0)
[23:14:48.840] Build status: STARTED
[23:14:48.840] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:36:0) after 9m56s
[23:24:44.840] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:36:0)
[23:24:44.861] Build status: STARTED
[23:24:44.861] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:37:0) after 7m45s
[23:32:29.982] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:37:0)
[23:32:30.002] Build status: STARTED
[23:32:30.002] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:38:0) after 8m5s
[23:40:35.099] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:38:0)
[23:40:35.247] Build status: STARTED
[23:40:35.247] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:39:0) after 7m20s
[23:47:55.308] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:39:0)
[23:47:55.369] Build status: STARTED
[23:47:55.369] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:40:0) after 6m22s
[23:54:17.483] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:40:0)
[23:54:17.504] Build status: STARTED
[23:54:17.504] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:41:0) after 7m28s
[00:01:45.630] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:41:0)
[00:01:45.661] Build status: STARTED
[00:01:45.661] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:42:0) after 3m21s
[00:05:06.889] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:42:0)
[00:05:06.942] Build status: STARTED
[00:05:06.945] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:43:0) after 1m6s
[00:06:13.165] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:43:0)
[00:06:13.411] Build status: STARTED
[00:06:13.411] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:44:0) after 2m41s
[00:08:54.639] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:44:0)
[00:08:54.763] Build status: STARTED
[00:08:54.763] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:45:0) after 3m19s
[00:12:13.788] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:45:0)
[00:12:13.811] Build status: STARTED
[00:12:13.811] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:46:0) after 8m4s
[00:20:17.833] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:46:0)
[00:20:17.854] Build status: STARTED
[00:20:17.854] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:47:0) after 2m58s
[00:23:16.043] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:47:0)
[00:23:16.068] Build status: STARTED
[00:23:16.068] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:48:0) after 3m6s
[00:26:22.274] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:48:0)
[00:26:22.389] Build status: STARTED
[00:26:22.389] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:49:0) after 7m52s
[00:34:14.449] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:49:0)
[00:34:14.465] Build status: STARTED
[00:34:14.465] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:50:0) after 2m40s
[00:36:54.568] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:50:0)
[00:36:54.592] Build status: STARTED
[00:36:54.593] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:51:0) after 1m52s
[00:38:46.610] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:51:0)
[00:38:46.629] Build status: STARTED
[00:38:46.629] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:52:0) after 8m37s
[00:47:23.841] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:52:0)
[00:47:23.868] Build status: STARTED
[00:47:23.868] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:53:0) after 3m14s
[00:50:37.997] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:53:0)
[00:50:38.016] Build status: STARTED
[00:50:38.017] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8917980861168040160:54:0) after 8m19s
[00:54:33.724] Received PubSub notification, asking Buildbucket for the build status
[00:54:33.740] Build:
{
"id": "8752550799182221617",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Android (dbg)"
},
"number": 15543,
"createdBy": "project:chromium",
"createTime": "2024-03-24T21:02:01.986874785Z",
"startTime": "2024-03-24T21:02:04.126792Z",
"endTime": "2024-03-25T00:54:32.897112Z",
"updateTime": "2024-03-25T00:54:32.897112Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "af471f523ee3fa42ffca501c1c5379dd6c4d3dd6",
"ref": "refs/heads/main"
}
},
"canary": true
}
[00:54:33.741] Invocation finished in 3h52m33.031543982s with status SUCCEEDED