[01:29:25.026] New invocation is queued and will start shortly
[01:29:26.631] Starting the invocation (attempt 1)
[01:29:26.662] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/382c4d1352f33cbfd83508b4f4ade56e10ab5848"
[01:29:26.663] Popped gitiles commit info from properties and tags
[01:29:26.663] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:29:26.663] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:29:26.663] Buildbucket request:
{
"requestId": "8971235053730401680",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Android (dbg)"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "382c4d1352f33cbfd83508b4f4ade56e10ab5848",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8971235053730401680"
},
{
"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"
}
}
[01:29:27.226] Scheduled build:
{
"id": "8805804991299858673",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Android (dbg)"
},
"number": 11544,
"createdBy": "project:chromium",
"createTime": "2022-08-15T01:29:26.723963737Z",
"updateTime": "2022-08-15T01:29:26.723963737Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "382c4d1352f33cbfd83508b4f4ade56e10ab5848",
"ref": "refs/heads/main"
}
}
}
[01:29:27.226] Task URL: https://cr-buildbucket.appspot.com/build/8805804991299858673
[01:29:27.226] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:2:0) after 3m57s
[01:30:47.446] Received PubSub notification, asking Buildbucket for the build status
[01:30:47.471] Build status: STARTED
[01:33:24.339] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:2:0)
[01:33:24.360] Build status: STARTED
[01:33:24.360] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:4:0) after 9m26s
[01:42:50.449] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:4:0)
[01:42:50.468] Build status: STARTED
[01:42:50.468] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:5:0) after 6m2s
[01:48:52.608] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:5:0)
[01:48:52.629] Build status: STARTED
[01:48:52.629] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:6:0) after 8m34s
[01:57:26.642] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:6:0)
[01:57:26.675] Build status: STARTED
[01:57:26.675] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:7:0) after 7m23s
[02:04:49.721] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:7:0)
[02:04:49.744] Build status: STARTED
[02:04:49.744] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:8:0) after 3m48s
[02:08:38.016] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:8:0)
[02:08:38.035] Build status: STARTED
[02:08:38.035] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:9:0) after 3m47s
[02:12:25.425] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:9:0)
[02:12:25.455] Build status: STARTED
[02:12:25.456] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:10:0) after 9m55s
[02:22:20.470] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:10:0)
[02:22:20.491] Build status: STARTED
[02:22:20.491] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:11:0) after 3m46s
[02:26:06.514] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:11:0)
[02:26:06.547] Build status: STARTED
[02:26:06.547] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:12:0) after 4m24s
[02:30:30.915] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:12:0)
[02:30:30.937] Build status: STARTED
[02:30:30.937] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:13:0) after 9m8s
[02:39:38.939] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:13:0)
[02:39:38.976] Build status: STARTED
[02:39:38.976] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:14:0) after 5m47s
[02:45:25.964] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:14:0)
[02:45:25.986] Build status: STARTED
[02:45:25.986] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:15:0) after 4m13s
[02:49:39.009] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:15:0)
[02:49:39.048] Build status: STARTED
[02:49:39.048] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:16:0) after 9m17s
[02:58:56.062] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:16:0)
[02:58:56.093] Build status: STARTED
[02:58:56.093] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:17:0) after 2m14s
[03:01:10.202] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:17:0)
[03:01:10.243] Build status: STARTED
[03:01:10.243] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:18:0) after 7m34s
[03:08:44.259] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:18:0)
[03:08:44.279] Build status: STARTED
[03:08:44.279] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:19:0) after 6m35s
[03:15:19.417] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:19:0)
[03:15:19.438] Build status: STARTED
[03:15:19.438] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:20:0) after 4m40s
[03:19:59.658] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:20:0)
[03:19:59.681] Build status: STARTED
[03:19:59.681] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:21:0) after 2m7s
[03:22:06.801] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:21:0)
[03:22:06.828] Build status: STARTED
[03:22:06.828] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:22:0) after 5m36s
[03:27:42.845] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:22:0)
[03:27:42.863] Build status: STARTED
[03:27:42.863] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:23:0) after 1m39s
[03:29:21.888] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:23:0)
[03:29:21.907] Build status: STARTED
[03:29:21.907] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:24:0) after 6m43s
[03:36:04.921] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:24:0)
[03:36:04.942] Build status: STARTED
[03:36:04.942] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:25:0) after 9m4s
[03:45:09.216] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:25:0)
[03:45:09.254] Build status: STARTED
[03:45:09.254] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:26:0) after 4m21s
[03:49:30.343] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:26:0)
[03:49:30.364] Build status: STARTED
[03:49:30.364] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:27:0) after 4m48s
[03:54:18.390] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:27:0)
[03:54:18.412] Build status: STARTED
[03:54:18.412] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:28:0) after 5m51s
[04:00:09.428] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:28:0)
[04:00:09.460] Build status: STARTED
[04:00:09.460] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:29:0) after 1m55s
[04:02:04.529] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:29:0)
[04:02:04.558] Build status: STARTED
[04:02:04.558] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:30:0) after 7m51s
[04:09:55.575] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:30:0)
[04:09:55.597] Build status: STARTED
[04:09:55.597] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:31:0) after 6m43s
[04:16:38.671] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:31:0)
[04:16:38.694] Build status: STARTED
[04:16:38.694] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:32:0) after 5m2s
[04:21:40.789] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:32:0)
[04:21:40.868] Build status: STARTED
[04:21:40.868] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:33:0) after 3m57s
[04:25:37.968] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:33:0)
[04:25:38.171] Build status: STARTED
[04:25:38.171] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:34:0) after 4m9s
[04:29:47.287] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:34:0)
[04:29:47.306] Build status: STARTED
[04:29:47.306] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:35:0) after 8m31s
[04:38:18.560] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:35:0)
[04:38:18.582] Build status: STARTED
[04:38:18.582] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:36:0) after 2m39s
[04:40:57.626] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:36:0)
[04:40:57.650] Build status: STARTED
[04:40:57.650] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8971235053730401680:37:0) after 4m17s
[04:41:07.912] Received PubSub notification, asking Buildbucket for the build status
[04:41:07.931] Build:
{
"id": "8805804991299858673",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Android (dbg)"
},
"number": 11544,
"createdBy": "project:chromium",
"createTime": "2022-08-15T01:29:26.723963737Z",
"startTime": "2022-08-15T01:30:46.871259Z",
"endTime": "2022-08-15T04:41:07.561228683Z",
"updateTime": "2022-08-15T04:41:07.561228683Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "382c4d1352f33cbfd83508b4f4ade56e10ab5848",
"ref": "refs/heads/main"
}
}
}
[04:41:07.931] Invocation finished in 3h11m42.909915715s with status SUCCEEDED