[11:34:56.287] New invocation is queued and will start shortly
[11:34:57.444] Starting the invocation (attempt 1)
[11:34:57.467] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/80d989c2e710b1f4b52fc6ff83ba03d5c498c114"
[11:34:57.467] Popped gitiles commit info from properties and tags
[11:34:57.468] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:34:57.468] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:34:57.468] Buildbucket request:
{
  "requestId": "8869184773485010064",
  "builder": {
    "project": "chromium",
    "bucket": "ci",
    "builder": "Libfuzzer Upload Linux MSan"
  },
  "properties": {
    },
  "gitilesCommit": {
    "host": "chromium.googlesource.com",
    "project": "chromium/src",
    "id": "80d989c2e710b1f4b52fc6ff83ba03d5c498c114",
    "ref": "refs/heads/main"
  },
  "tags": [
    {
      "key": "scheduler_invocation_id",
      "value": "8869184773485010064"
    },
    {
      "key": "scheduler_job_id",
      "value": "chromium/Libfuzzer Upload Linux MSan"
    },
    {
      "key": "user_agent",
      "value": "luci-scheduler"
    }
  ],
  "notify": {
    "pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
  }
}
[11:34:57.965] Scheduled build:
{
  "id": "8703754711522251537",
  "builder": {
    "project": "chromium",
    "bucket": "ci",
    "builder": "Libfuzzer Upload Linux MSan"
  },
  "number": 218618,
  "createdBy": "project:chromium",
  "createTime": "2025-09-14T11:34:57.529040817Z",
  "updateTime": "2025-09-14T11:34:57.529040817Z",
  "status": "SCHEDULED",
  "input": {
    "gitilesCommit": {
      "host": "chromium.googlesource.com",
      "project": "chromium/src",
      "id": "80d989c2e710b1f4b52fc6ff83ba03d5c498c114",
      "ref": "refs/heads/main"
    }
  }
}
[11:34:57.965] Task URL: https://cr-buildbucket.appspot.com/build/8703754711522251537
[11:34:57.965] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869184773485010064:2:0) after 6m28s
[11:34:58.420] Received PubSub notification, asking Buildbucket for the build status
[11:34:58.435] Build status: SCHEDULED
[11:35:06.718] Received PubSub notification, asking Buildbucket for the build status
[11:35:06.743] Build status: STARTED
[11:41:25.999] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869184773485010064:2:0)
[11:41:26.033] Build status: STARTED
[11:41:26.033] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869184773485010064:5:0) after 3m54s
[11:45:20.260] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869184773485010064:5:0)
[11:45:20.290] Build status: STARTED
[11:45:20.290] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869184773485010064:6:0) after 6m49s
[11:52:09.302] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869184773485010064:6:0)
[11:52:09.337] Build status: STARTED
[11:52:09.337] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869184773485010064:7:0) after 7m20s
[11:59:29.384] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869184773485010064:7:0)
[11:59:29.402] Build status: STARTED
[11:59:29.402] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869184773485010064:8:0) after 9m10s
[12:08:39.415] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869184773485010064:8:0)
[12:08:39.478] Build status: STARTED
[12:08:39.478] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869184773485010064:9:0) after 1m26s
[12:10:05.491] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869184773485010064:9:0)
[12:10:05.511] Build status: STARTED
[12:10:05.511] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869184773485010064:10:0) after 3m10s
[12:13:15.644] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869184773485010064:10:0)
[12:13:15.670] Build status: STARTED
[12:13:15.670] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869184773485010064:11:0) after 2m6s
[12:15:21.684] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869184773485010064:11:0)
[12:15:21.706] Build status: STARTED
[12:15:21.706] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869184773485010064:12:0) after 1m38s
[12:16:59.820] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869184773485010064:12:0)
[12:16:59.840] Build status: STARTED
[12:16:59.840] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869184773485010064:13:0) after 4m49s
[12:21:48.916] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869184773485010064:13:0)
[12:21:48.933] Build status: STARTED
[12:21:48.933] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869184773485010064:14:0) after 1m27s
[12:23:15.946] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869184773485010064:14:0)
[12:23:15.962] Build status: STARTED
[12:23:15.962] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869184773485010064:15:0) after 3m9s
[12:26:24.985] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869184773485010064:15:0)
[12:26:25.005] Build status: STARTED
[12:26:25.005] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869184773485010064:16:0) after 7m48s
[12:34:13.022] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869184773485010064:16:0)
[12:34:13.045] Build status: STARTED
[12:34:13.045] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869184773485010064:17:0) after 1m32s
[12:35:45.137] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869184773485010064:17:0)
[12:35:45.160] Build status: STARTED
[12:35:45.160] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869184773485010064:18:0) after 8m16s
[12:44:01.231] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869184773485010064:18:0)
[12:44:01.286] Build status: STARTED
[12:44:01.286] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869184773485010064:19:0) after 9m58s
[12:53:59.490] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869184773485010064:19:0)
[12:53:59.505] Build status: STARTED
[12:53:59.505] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869184773485010064:20:0) after 1m34s
[12:55:34.212] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869184773485010064:20:0)
[12:55:34.246] Build status: STARTED
[12:55:34.246] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869184773485010064:21:0) after 3m14s
[12:58:48.324] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869184773485010064:21:0)
[12:58:48.340] Build status: STARTED
[12:58:48.340] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869184773485010064:22:0) after 6m6s
[13:04:54.355] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869184773485010064:22:0)
[13:04:54.372] Build status: STARTED
[13:04:54.372] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869184773485010064:23:0) after 1m2s
[13:05:56.391] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869184773485010064:23:0)
[13:05:56.413] Build status: STARTED
[13:05:56.413] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869184773485010064:24:0) after 6m49s
[13:12:45.736] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869184773485010064:24:0)
[13:12:45.759] Build status: STARTED
[13:12:45.759] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869184773485010064:25:0) after 2m3s
[13:14:48.773] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869184773485010064:25:0)
[13:14:48.789] Build status: STARTED
[13:14:48.790] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869184773485010064:26:0) after 3m16s
[13:18:04.791] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869184773485010064:26:0)
[13:18:04.865] Build status: STARTED
[13:18:04.865] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869184773485010064:27:0) after 7m12s
[13:25:16.982] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869184773485010064:27:0)
[13:25:16.997] Build status: STARTED
[13:25:16.997] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869184773485010064:28:0) after 3m56s
[13:29:13.012] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869184773485010064:28:0)
[13:29:13.034] Build status: STARTED
[13:29:13.034] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869184773485010064:29:0) after 6m35s
[13:35:48.002] Handling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869184773485010064:29:0)
[13:35:48.057] Build status: STARTED
[13:35:48.057] Scheduling timer "check-buildbucket-build-status" (chromium/Libfuzzer Upload Linux MSan:8869184773485010064:30:0) after 6m6s
[13:37:01.340] Received PubSub notification, asking Buildbucket for the build status
[13:37:01.361] Build:
{
  "id": "8703754711522251537",
  "builder": {
    "project": "chromium",
    "bucket": "ci",
    "builder": "Libfuzzer Upload Linux MSan"
  },
  "number": 218618,
  "createdBy": "project:chromium",
  "createTime": "2025-09-14T11:34:57.529040817Z",
  "startTime": "2025-09-14T11:35:06.430447720Z",
  "endTime": "2025-09-14T13:36:54.103512884Z",
  "updateTime": "2025-09-14T13:36:54.103512884Z",
  "status": "SUCCESS",
  "input": {
    "gitilesCommit": {
      "host": "chromium.googlesource.com",
      "project": "chromium/src",
      "id": "80d989c2e710b1f4b52fc6ff83ba03d5c498c114",
      "ref": "refs/heads/main"
    }
  }
}
[13:37:01.361] Invocation finished in 2h2m5.088004577s with status SUCCEEDED