[07:08:34.422] New invocation is queued and will start shortly
[07:08:35.546] Starting the invocation (attempt 1)
[07:08:35.576] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6b5c3002272fc9a2d6644b9cefbec338999c4f17"
[07:08:35.576] Popped gitiles commit info from properties and tags
[07:08:35.576] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:08:35.577] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:08:35.577] Buildbucket request:
{
"requestId": "9012797723432070320",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Android"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "6b5c3002272fc9a2d6644b9cefbec338999c4f17",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9012797723432070320"
},
{
"key": "scheduler_job_id",
"value": "chromium/Deterministic Android"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[07:08:36.778] Scheduled build:
{
"id": "8847367661442957264",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Android"
},
"number": 8959,
"createdBy": "project:chromium",
"createTime": "2021-05-13T07:08:35.686740Z",
"updateTime": "2021-05-13T07:08:36.564842Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "6b5c3002272fc9a2d6644b9cefbec338999c4f17",
"ref": "refs/heads/master"
}
}
}
[07:08:36.778] Task URL: https://cr-buildbucket.appspot.com/build/8847367661442957264
[07:08:36.778] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:2:0) after 4m15s
[07:08:44.538] Received PubSub notification, asking Buildbucket for the build status
[07:08:44.567] Build status: STARTED
[07:12:51.828] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:2:0)
[07:12:51.854] Build status: STARTED
[07:12:51.854] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:4:0) after 8m50s
[07:21:41.880] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:4:0)
[07:21:41.903] Build status: STARTED
[07:21:41.903] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:5:0) after 8m13s
[07:29:55.093] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:5:0)
[07:29:55.123] Build status: STARTED
[07:29:55.123] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:6:0) after 5m4s
[07:34:59.139] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:6:0)
[07:34:59.199] Build status: STARTED
[07:34:59.200] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:7:0) after 7m32s
[07:42:31.266] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:7:0)
[07:42:31.301] Build status: STARTED
[07:42:31.301] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:8:0) after 6m49s
[07:49:20.419] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:8:0)
[07:49:20.447] Build status: STARTED
[07:49:20.447] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:9:0) after 1m46s
[07:51:06.522] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:9:0)
[07:51:06.595] Build status: STARTED
[07:51:06.595] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:10:0) after 4m6s
[07:55:12.724] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:10:0)
[07:55:12.756] Build status: STARTED
[07:55:12.756] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:11:0) after 9m12s
[08:04:24.833] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:11:0)
[08:04:24.859] Build status: STARTED
[08:04:24.859] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:12:0) after 6m24s
[08:10:48.892] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:12:0)
[08:10:48.955] Build status: STARTED
[08:10:48.955] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:13:0) after 3m45s
[08:14:34.116] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:13:0)
[08:14:34.145] Build status: STARTED
[08:14:34.145] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:14:0) after 6m41s
[08:21:15.209] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:14:0)
[08:21:15.238] Build status: STARTED
[08:21:15.238] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:15:0) after 1m2s
[08:22:17.274] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:15:0)
[08:22:17.304] Build status: STARTED
[08:22:17.304] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:16:0) after 5m14s
[08:27:31.326] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:16:0)
[08:27:31.428] Build status: STARTED
[08:27:31.428] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:17:0) after 1m27s
[08:28:58.455] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:17:0)
[08:28:58.506] Build status: STARTED
[08:28:58.506] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:18:0) after 8m40s
[08:37:38.672] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:18:0)
[08:37:38.700] Build status: STARTED
[08:37:38.700] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:19:0) after 7m40s
[08:45:18.928] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:19:0)
[08:45:18.974] Build status: STARTED
[08:45:18.974] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:20:0) after 8m19s
[08:53:38.104] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:20:0)
[08:53:38.131] Build status: STARTED
[08:53:38.131] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:21:0) after 6m4s
[08:59:42.153] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:21:0)
[08:59:42.182] Build status: STARTED
[08:59:42.182] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:22:0) after 7m50s
[09:07:32.234] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:22:0)
[09:07:32.261] Build status: STARTED
[09:07:32.262] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:23:0) after 8m53s
[09:16:25.528] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:23:0)
[09:16:25.556] Build status: STARTED
[09:16:25.556] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:24:0) after 1m7s
[09:17:32.594] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:24:0)
[09:17:32.732] Build status: STARTED
[09:17:32.732] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:25:0) after 3m47s
[09:21:19.772] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:25:0)
[09:21:19.808] Build status: STARTED
[09:21:19.808] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:26:0) after 5m42s
[09:27:02.033] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:26:0)
[09:27:02.065] Build status: STARTED
[09:27:02.065] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:27:0) after 8m20s
[09:35:22.287] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:27:0)
[09:35:22.313] Build status: STARTED
[09:35:22.313] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:28:0) after 4m55s
[09:40:17.338] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:28:0)
[09:40:17.365] Build status: STARTED
[09:40:17.365] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:29:0) after 1m39s
[09:41:56.389] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:29:0)
[09:41:56.420] Build status: STARTED
[09:41:56.420] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:30:0) after 8m42s
[09:50:38.461] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:30:0)
[09:50:38.489] Build status: STARTED
[09:50:38.489] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:31:0) after 5m13s
[09:55:51.692] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:31:0)
[09:55:51.719] Build status: STARTED
[09:55:51.719] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:32:0) after 4m11s
[10:00:02.741] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:32:0)
[10:00:02.776] Build status: STARTED
[10:00:02.776] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:33:0) after 2m16s
[10:02:18.984] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:33:0)
[10:02:19.018] Build status: STARTED
[10:02:19.018] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:34:0) after 1m2s
[10:03:21.108] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:34:0)
[10:03:21.145] Build status: STARTED
[10:03:21.145] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:35:0) after 6m5s
[10:09:26.365] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:35:0)
[10:09:26.390] Build status: STARTED
[10:09:26.390] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:36:0) after 6m11s
[10:15:37.413] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:36:0)
[10:15:37.443] Build status: STARTED
[10:15:37.443] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:37:0) after 5m35s
[10:21:12.902] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:37:0)
[10:21:12.931] Build status: STARTED
[10:21:12.931] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:38:0) after 5m24s
[10:26:36.961] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:38:0)
[10:26:37.006] Build status: STARTED
[10:26:37.006] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:39:0) after 7m58s
[10:34:35.032] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:39:0)
[10:34:35.064] Build status: STARTED
[10:34:35.064] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:40:0) after 6m53s
[10:41:28.094] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:40:0)
[10:41:28.131] Build status: STARTED
[10:41:28.131] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:41:0) after 2m22s
[10:43:50.404] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:41:0)
[10:43:50.441] Build status: STARTED
[10:43:50.441] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:42:0) after 8m52s
[10:52:43.065] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:42:0)
[10:52:43.094] Build status: STARTED
[10:52:43.094] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:43:0) after 9m21s
[11:02:04.131] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:43:0)
[11:02:04.161] Build status: STARTED
[11:02:04.161] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:44:0) after 6m4s
[11:08:08.507] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:44:0)
[11:08:08.554] Build status: STARTED
[11:08:08.554] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:45:0) after 4m28s
[11:12:36.783] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:45:0)
[11:12:36.808] Build status: STARTED
[11:12:36.808] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:46:0) after 3m15s
[11:15:51.831] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:46:0)
[11:15:51.857] Build status: STARTED
[11:15:51.857] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:47:0) after 3m38s
[11:19:29.877] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:47:0)
[11:19:29.908] Build status: STARTED
[11:19:29.908] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:48:0) after 8m42s
[11:28:12.170] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:48:0)
[11:28:12.241] Build status: STARTED
[11:28:12.241] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:49:0) after 3m17s
[11:31:29.982] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:49:0)
[11:31:30.011] Build status: STARTED
[11:31:30.011] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:50:0) after 3m43s
[11:35:13.237] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:50:0)
[11:35:13.262] Build status: STARTED
[11:35:13.262] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:51:0) after 7m42s
[11:42:55.283] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:51:0)
[11:42:55.313] Build status: STARTED
[11:42:55.313] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:52:0) after 2m29s
[11:45:24.337] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:52:0)
[11:45:24.363] Build status: STARTED
[11:45:24.363] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:53:0) after 8m35s
[11:53:59.687] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:53:0)
[11:53:59.712] Build status: STARTED
[11:53:59.712] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:54:0) after 2m17s
[11:56:16.935] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:54:0)
[11:56:16.967] Build status: STARTED
[11:56:16.967] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:55:0) after 1m34s
[11:57:50.997] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:55:0)
[11:57:51.031] Build status: STARTED
[11:57:51.031] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:56:0) after 4m1s
[12:01:52.103] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:56:0)
[12:01:52.161] Build status: STARTED
[12:01:52.161] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:57:0) after 2m33s
[12:04:25.938] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:57:0)
[12:04:26.021] Build status: STARTED
[12:04:26.021] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:58:0) after 2m22s
[12:06:48.046] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:58:0)
[12:06:48.075] Build status: STARTED
[12:06:48.075] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:59:0) after 2m32s
[12:09:20.358] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:59:0)
[12:09:20.400] Build status: STARTED
[12:09:20.400] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:60:0) after 9m8s
[12:18:28.422] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:60:0)
[12:18:28.492] Build status: STARTED
[12:18:28.492] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:61:0) after 9m27s
[12:27:55.520] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:61:0)
[12:27:55.546] Build status: STARTED
[12:27:55.546] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:62:0) after 3m34s
[12:31:29.642] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:62:0)
[12:31:29.667] Build status: STARTED
[12:31:29.667] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:63:0) after 1m59s
[12:33:28.882] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:63:0)
[12:33:28.921] Build status: STARTED
[12:33:28.921] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:64:0) after 3m27s
[12:36:56.004] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:64:0)
[12:36:56.034] Build status: STARTED
[12:36:56.034] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:65:0) after 8m2s
[12:44:58.361] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:65:0)
[12:44:58.386] Build status: STARTED
[12:44:58.386] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:66:0) after 6m27s
[12:51:25.493] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:66:0)
[12:51:25.523] Build status: STARTED
[12:51:25.523] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:67:0) after 4m21s
[12:55:46.545] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:67:0)
[12:55:46.571] Build status: STARTED
[12:55:46.571] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:68:0) after 9m48s
[13:05:34.659] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:68:0)
[13:05:34.689] Build status: STARTED
[13:05:34.689] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9012797723432070320:69:0) after 6m47s
[13:10:03.455] Received PubSub notification, asking Buildbucket for the build status
[13:10:03.487] Build:
{
"id": "8847367661442957264",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Android"
},
"number": 8959,
"createdBy": "project:chromium",
"createTime": "2021-05-13T07:08:35.686740Z",
"startTime": "2021-05-13T07:08:43.959715Z",
"endTime": "2021-05-13T13:09:54.541726Z",
"updateTime": "2021-05-13T13:09:54.858688Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "6b5c3002272fc9a2d6644b9cefbec338999c4f17",
"ref": "refs/heads/master"
}
}
}
[13:10:03.487] Invocation finished in 6h1m29.081542677s with status SUCCEEDED