[08:04:18.819] New invocation is queued and will start shortly
[08:04:19.941] Starting the invocation (attempt 1)
[08:04:19.978] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ae41e7dcdd1ed9b5a0e38a705488d99eaaf97fa5"
[08:04:19.978] Popped gitiles commit info from properties and tags
[08:04:19.978] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:04:19.978] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:04:19.979] Buildbucket request:
{
"requestId": "9003190938137824672",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Android"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "ae41e7dcdd1ed9b5a0e38a705488d99eaaf97fa5",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9003190938137824672"
},
{
"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"
}
}
[08:04:21.427] Scheduled build:
{
"id": "8837760876191083409",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Android"
},
"number": 9449,
"createdBy": "project:chromium",
"createTime": "2021-08-27T08:04:20.044390149Z",
"updateTime": "2021-08-27T08:04:20.271114517Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "ae41e7dcdd1ed9b5a0e38a705488d99eaaf97fa5",
"ref": "refs/heads/main"
}
}
}
[08:04:21.427] Task URL: https://cr-buildbucket.appspot.com/build/8837760876191083409
[08:04:21.427] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:2:0) after 1m58s
[08:06:19.450] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:2:0)
[08:06:19.487] Build status: SCHEDULED
[08:06:19.487] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:3:0) after 3m25s
[08:09:44.506] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:3:0)
[08:09:44.542] Build status: SCHEDULED
[08:09:44.542] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:4:0) after 3m35s
[08:13:19.563] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:4:0)
[08:13:19.690] Build status: SCHEDULED
[08:13:19.690] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:5:0) after 5m13s
[08:18:32.783] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:5:0)
[08:18:32.841] Build status: SCHEDULED
[08:18:32.841] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:6:0) after 8m12s
[08:26:44.857] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:6:0)
[08:26:44.891] Build status: SCHEDULED
[08:26:44.891] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:7:0) after 6m16s
[08:28:59.571] Received PubSub notification, asking Buildbucket for the build status
[08:28:59.618] Build status: STARTED
[08:33:00.917] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:7:0)
[08:33:00.957] Build status: STARTED
[08:33:00.957] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:9:0) after 8m21s
[08:41:22.025] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:9:0)
[08:41:22.056] Build status: STARTED
[08:41:22.056] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:10:0) after 4m14s
[08:45:36.078] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:10:0)
[08:45:36.153] Build status: STARTED
[08:45:36.154] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:11:0) after 3m55s
[08:49:31.181] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:11:0)
[08:49:31.206] Build status: STARTED
[08:49:31.207] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:12:0) after 1m25s
[08:50:56.383] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:12:0)
[08:50:56.488] Build status: STARTED
[08:50:56.488] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:13:0) after 1m28s
[08:52:24.563] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:13:0)
[08:52:24.605] Build status: STARTED
[08:52:24.605] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:14:0) after 6m25s
[08:58:49.633] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:14:0)
[08:58:49.668] Build status: STARTED
[08:58:49.668] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:15:0) after 8m2s
[09:06:51.682] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:15:0)
[09:06:51.713] Build status: STARTED
[09:06:51.713] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:16:0) after 7m37s
[09:14:28.835] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:16:0)
[09:14:28.875] Build status: STARTED
[09:14:28.875] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:17:0) after 4m34s
[09:19:02.983] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:17:0)
[09:19:03.009] Build status: STARTED
[09:19:03.009] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:18:0) after 7m21s
[09:26:24.129] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:18:0)
[09:26:24.156] Build status: STARTED
[09:26:24.156] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:19:0) after 7m9s
[09:33:33.175] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:19:0)
[09:33:33.210] Build status: STARTED
[09:33:33.210] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:20:0) after 7m44s
[09:41:17.232] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:20:0)
[09:41:17.260] Build status: STARTED
[09:41:17.260] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:21:0) after 3m21s
[09:44:38.268] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:21:0)
[09:44:38.300] Build status: STARTED
[09:44:38.300] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:22:0) after 5m58s
[09:50:36.459] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:22:0)
[09:50:36.489] Build status: STARTED
[09:50:36.489] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:23:0) after 3m32s
[09:54:08.639] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:23:0)
[09:54:08.688] Build status: STARTED
[09:54:08.688] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:24:0) after 1m8s
[09:55:16.718] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:24:0)
[09:55:16.770] Build status: STARTED
[09:55:16.770] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:25:0) after 2m44s
[09:58:00.823] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:25:0)
[09:58:00.859] Build status: STARTED
[09:58:00.860] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:26:0) after 8m14s
[10:06:14.928] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:26:0)
[10:06:14.959] Build status: STARTED
[10:06:14.959] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:27:0) after 2m23s
[10:08:37.990] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:27:0)
[10:08:38.029] Build status: STARTED
[10:08:38.029] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:28:0) after 2m0s
[10:10:38.027] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:28:0)
[10:10:38.070] Build status: STARTED
[10:10:38.070] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:29:0) after 3m54s
[10:14:32.088] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:29:0)
[10:14:32.120] Build status: STARTED
[10:14:32.120] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:30:0) after 3m3s
[10:17:35.401] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:30:0)
[10:17:35.431] Build status: STARTED
[10:17:35.431] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:31:0) after 2m28s
[10:20:03.504] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:31:0)
[10:20:03.530] Build status: STARTED
[10:20:03.530] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:32:0) after 1m32s
[10:21:35.570] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:32:0)
[10:21:35.601] Build status: STARTED
[10:21:35.601] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:33:0) after 1m55s
[10:23:30.620] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:33:0)
[10:23:30.862] Build status: STARTED
[10:23:30.862] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:34:0) after 4m7s
[10:27:37.890] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:34:0)
[10:27:37.933] Build status: STARTED
[10:27:37.933] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:35:0) after 2m21s
[10:29:59.079] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:35:0)
[10:29:59.114] Build status: STARTED
[10:29:59.114] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:36:0) after 5m34s
[10:35:33.143] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:36:0)
[10:35:33.177] Build status: STARTED
[10:35:33.177] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:37:0) after 7m24s
[10:42:57.237] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:37:0)
[10:42:57.268] Build status: STARTED
[10:42:57.268] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:38:0) after 1m12s
[10:44:09.282] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:38:0)
[10:44:09.313] Build status: STARTED
[10:44:09.313] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:39:0) after 2m16s
[10:46:25.461] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:39:0)
[10:46:25.496] Build status: STARTED
[10:46:25.496] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:40:0) after 8m56s
[10:55:21.514] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:40:0)
[10:55:21.551] Build status: STARTED
[10:55:21.551] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:41:0) after 7m51s
[11:03:12.571] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:41:0)
[11:03:12.599] Build status: STARTED
[11:03:12.599] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android:9003190938137824672:42:0) after 3m53s
[11:04:52.180] Received PubSub notification, asking Buildbucket for the build status
[11:04:52.252] Build:
{
"id": "8837760876191083409",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Android"
},
"number": 9449,
"createdBy": "project:chromium",
"createTime": "2021-08-27T08:04:20.044390149Z",
"startTime": "2021-08-27T08:28:57.887399Z",
"endTime": "2021-08-27T11:04:51.502090925Z",
"updateTime": "2021-08-27T11:04:51.502477195Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "ae41e7dcdd1ed9b5a0e38a705488d99eaaf97fa5",
"ref": "refs/heads/main"
}
}
}
[11:04:52.252] Invocation finished in 3h0m33.448133768s with status SUCCEEDED