[20:12:08.721] New invocation is queued and will start shortly
[20:12:09.806] Starting the invocation (attempt 1)
[20:12:09.842] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/94b8a4742a5756ca39c0fb5ded7471793ae93589"
[20:12:09.842] Popped gitiles commit info from properties and tags
[20:12:09.842] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:12:09.842] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:12:09.842] Buildbucket request:
{
"requestId": "8961379945412435904",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Android (dbg)"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "94b8a4742a5756ca39c0fb5ded7471793ae93589",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8961379945412435904"
},
{
"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"
}
}
[20:12:10.334] Scheduled build:
{
"id": "8795949883505755905",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Android (dbg)"
},
"number": 12321,
"createdBy": "project:chromium",
"createTime": "2022-12-01T20:12:09.912226672Z",
"updateTime": "2022-12-01T20:12:09.912226672Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "94b8a4742a5756ca39c0fb5ded7471793ae93589",
"ref": "refs/heads/main"
}
}
}
[20:12:10.334] Task URL: https://cr-buildbucket.appspot.com/build/8795949883505755905
[20:12:10.334] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:2:0) after 4m26s
[20:12:58.319] Received PubSub notification, asking Buildbucket for the build status
[20:12:58.346] Build status: STARTED
[20:16:36.359] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:2:0)
[20:16:36.386] Build status: STARTED
[20:16:36.386] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:4:0) after 6m9s
[20:22:45.410] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:4:0)
[20:22:45.443] Build status: STARTED
[20:22:45.443] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:5:0) after 3m52s
[20:26:37.470] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:5:0)
[20:26:37.512] Build status: STARTED
[20:26:37.512] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:6:0) after 8m53s
[20:35:30.679] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:6:0)
[20:35:30.708] Build status: STARTED
[20:35:30.708] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:7:0) after 8m20s
[20:43:50.881] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:7:0)
[20:43:50.902] Build status: STARTED
[20:43:50.902] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:8:0) after 8m20s
[20:52:10.969] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:8:0)
[20:52:11.018] Build status: STARTED
[20:52:11.018] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:9:0) after 9m36s
[21:01:47.041] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:9:0)
[21:01:47.219] Build status: STARTED
[21:01:47.219] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:10:0) after 1m55s
[21:03:42.266] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:10:0)
[21:03:42.298] Build status: STARTED
[21:03:42.298] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:11:0) after 3m4s
[21:06:46.336] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:11:0)
[21:06:46.377] Build status: STARTED
[21:06:46.377] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:12:0) after 9m58s
[21:16:44.454] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:12:0)
[21:16:44.476] Build status: STARTED
[21:16:44.476] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:13:0) after 7m48s
[21:24:32.958] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:13:0)
[21:24:32.982] Build status: STARTED
[21:24:32.982] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:14:0) after 1m10s
[21:25:43.065] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:14:0)
[21:25:43.096] Build status: STARTED
[21:25:43.096] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:15:0) after 3m15s
[21:28:58.119] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:15:0)
[21:28:58.143] Build status: STARTED
[21:28:58.143] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:16:0) after 5m59s
[21:34:57.876] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:16:0)
[21:34:57.908] Build status: STARTED
[21:34:57.909] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:17:0) after 3m43s
[21:38:40.919] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:17:0)
[21:38:40.946] Build status: STARTED
[21:38:40.946] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:18:0) after 8m51s
[21:47:31.993] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:18:0)
[21:47:32.026] Build status: STARTED
[21:47:32.026] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:19:0) after 1m25s
[21:48:57.097] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:19:0)
[21:48:57.117] Build status: STARTED
[21:48:57.117] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:20:0) after 7m36s
[21:56:33.205] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:20:0)
[21:56:33.226] Build status: STARTED
[21:56:33.226] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:21:0) after 6m0s
[22:02:33.496] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:21:0)
[22:02:33.524] Build status: STARTED
[22:02:33.524] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:22:0) after 8m27s
[22:11:00.691] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:22:0)
[22:11:00.718] Build status: STARTED
[22:11:00.718] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:23:0) after 6m27s
[22:17:27.799] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:23:0)
[22:17:27.842] Build status: STARTED
[22:17:27.842] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:24:0) after 1m42s
[22:19:09.832] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:24:0)
[22:19:09.858] Build status: STARTED
[22:19:09.858] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:25:0) after 1m36s
[22:20:45.962] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:25:0)
[22:20:45.995] Build status: STARTED
[22:20:45.995] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:26:0) after 5m39s
[22:26:25.153] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:26:0)
[22:26:25.193] Build status: STARTED
[22:26:25.193] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:27:0) after 4m8s
[22:30:33.221] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:27:0)
[22:30:33.252] Build status: STARTED
[22:30:33.252] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:28:0) after 3m16s
[22:33:49.620] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:28:0)
[22:33:49.641] Build status: STARTED
[22:33:49.641] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:29:0) after 8m26s
[22:42:16.258] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:29:0)
[22:42:16.284] Build status: STARTED
[22:42:16.284] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:30:0) after 6m19s
[22:48:35.374] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:30:0)
[22:48:35.407] Build status: STARTED
[22:48:35.407] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:31:0) after 9m33s
[22:58:08.540] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:31:0)
[22:58:08.563] Build status: STARTED
[22:58:08.563] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:32:0) after 7m57s
[23:06:05.592] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:32:0)
[23:06:05.618] Build status: STARTED
[23:06:05.618] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:33:0) after 7m32s
[23:13:37.940] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:33:0)
[23:13:37.964] Build status: STARTED
[23:13:37.964] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:34:0) after 2m25s
[23:16:03.406] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:34:0)
[23:16:03.439] Build status: STARTED
[23:16:03.439] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:35:0) after 2m55s
[23:18:58.989] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:35:0)
[23:18:59.018] Build status: STARTED
[23:18:59.018] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:36:0) after 3m20s
[23:22:19.259] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:36:0)
[23:22:19.280] Build status: STARTED
[23:22:19.280] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:37:0) after 3m53s
[23:26:12.419] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:37:0)
[23:26:12.439] Build status: STARTED
[23:26:12.439] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:38:0) after 4m20s
[23:30:32.785] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:38:0)
[23:30:32.823] Build status: STARTED
[23:30:32.823] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:39:0) after 1m50s
[23:32:22.853] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:39:0)
[23:32:22.878] Build status: STARTED
[23:32:22.878] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:40:0) after 3m39s
[23:36:01.934] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:40:0)
[23:36:01.965] Build status: STARTED
[23:36:01.966] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:41:0) after 5m50s
[23:41:52.062] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:41:0)
[23:41:52.083] Build status: STARTED
[23:41:52.083] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:42:0) after 7m24s
[23:49:16.075] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:42:0)
[23:49:16.101] Build status: STARTED
[23:49:16.101] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:43:0) after 6m37s
[23:55:53.138] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:43:0)
[23:55:53.161] Build status: STARTED
[23:55:53.161] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:44:0) after 1m12s
[23:57:05.184] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:44:0)
[23:57:05.269] Build status: STARTED
[23:57:05.269] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8961379945412435904:45:0) after 9m7s
[00:01:06.377] Received PubSub notification, asking Buildbucket for the build status
[00:01:06.404] Build:
{
"id": "8795949883505755905",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Android (dbg)"
},
"number": 12321,
"createdBy": "project:chromium",
"createTime": "2022-12-01T20:12:09.912226672Z",
"startTime": "2022-12-01T20:12:56.745658Z",
"endTime": "2022-12-02T00:01:06.082432503Z",
"updateTime": "2022-12-02T00:01:06.082432503Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "94b8a4742a5756ca39c0fb5ded7471793ae93589",
"ref": "refs/heads/main"
}
}
}
[00:01:06.404] Invocation finished in 3h48m57.693631611s with status SUCCEEDED