Open Bug 1939462 Opened 1 year ago Updated 6 days ago

Intermittent [tier 2] ValueError: time data '0' does not match format '%H:%M:%S' | Exception: Could not find any files in this configuration: []

Categories

(Testing :: mozperftest, defect, P5)

defect

Tracking

(Not tracked)

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: amarc [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=488436648&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/IzW3gqC5S3uI7uCe9bXs4Q/runs/0/artifacts/public/logs/live_backing.log


[task 2024-12-30T09:48:32.941Z] Collecting test resource usage...
[task 2024-12-30T09:48:32.941Z] Collecting test resource usage...
[task 2024-12-30T09:48:32.941Z] Collecting test resource usage...
[task 2024-12-30T09:48:32.941Z] Collecting test resource usage...
[task 2024-12-30T09:48:32.941Z] Collecting test resource usage...
[task 2024-12-30T09:48:32.941Z] Collecting mem info at 600s
[task 2024-12-30T09:48:32.941Z] Collecting cpu info at 600s for 15046
[task 2024-12-30T09:48:32.941Z] Collecting cpu info at 600s for 15272
[task 2024-12-30T09:48:32.941Z] Collecting cpu info at 600s for 15305
[task 2024-12-30T09:48:32.942Z] Collecting cpu info at 600s for 15399
[task 2024-12-30T09:48:32.942Z] Collecting test resource usage...
[task 2024-12-30T09:48:32.942Z] Finished collecting mem info for 600s
[task 2024-12-30T09:48:32.942Z] Traceback (most recent call last):
[task 2024-12-30T09:48:32.942Z] File "/builds/task_173555126523648/checkouts/gecko/testing/performance/android-resource/parse_resource_usage.py", line 281, in <module>
[task 2024-12-30T09:48:32.942Z] main()
[task 2024-12-30T09:48:32.942Z] File "/builds/task_173555126523648/checkouts/gecko/testing/performance/android-resource/parse_resource_usage.py", line 213, in main
[task 2024-12-30T09:48:32.942Z] cpu_measures = parse_cpu_usage(cpu_info_files[i], binary)
[task 2024-12-30T09:48:32.942Z] File "/builds/task_173555126523648/checkouts/gecko/testing/performance/android-resource/parse_resource_usage.py", line 186, in parse_cpu_usage
[task 2024-12-30T09:48:32.942Z] dt = strptime(time, "%H:%M:%S")
[task 2024-12-30T09:48:32.942Z] File "/builds/task_173555126523648/fetches/python/lib/python3.8/_strptime.py", line 562, in _strptime_time
[task 2024-12-30T09:48:32.942Z] tt = _strptime(data_string, format)[0]
[task 2024-12-30T09:48:32.942Z] File "/builds/task_173555126523648/fetches/python/lib/python3.8/_strptime.py", line 349, in _strptime
[task 2024-12-30T09:48:32.942Z] raise ValueError("time data %r does not match format %r" %
[task 2024-12-30T09:48:32.942Z] ValueError: time data '0' does not match format '%H:%M:%S'
[task 2024-12-30T09:48:32.942Z] Traceback (most recent call last):
[task 2024-12-30T09:48:32.942Z]   File "python/mozperftest/mozperftest/runner.py", line 305, in <module>
[task 2024-12-30T09:48:32.942Z]     sys.exit(main())
[task 2024-12-30T09:48:32.942Z]   File "python/mozperftest/mozperftest/runner.py", line 301, in main
[task 2024-12-30T09:48:32.942Z]     run_tests(mach_cmd, args, user_args)
[task 2024-12-30T09:48:32.942Z]   File "python/mozperftest/mozperftest/runner.py", line 188, in run_tests
[task 2024-12-30T09:48:32.942Z]     e.run(metadata)
[task 2024-12-30T09:48:32.942Z]   File "/builds/task_173555126523648/checkouts/gecko/python/mozperftest/mozperftest/environment.py", line 96, in run
[task 2024-12-30T09:48:32.942Z]     metadata = metrics(metadata)
[task 2024-12-30T09:48:32.942Z]   File "/builds/task_173555126523648/checkouts/gecko/python/mozperftest/mozperftest/layers.py", line 157, in __call__
[task 2024-12-30T09:48:32.942Z]     metadata = layer(metadata)
[task 2024-12-30T09:48:32.942Z]   File "/builds/task_173555126523648/checkouts/gecko/python/mozperftest/mozperftest/layers.py", line 69, in __call__
[task 2024-12-30T09:48:32.942Z]     metadata = self.run(metadata)
[task 2024-12-30T09:48:32.942Z]   File "/builds/task_173555126523648/checkouts/gecko/python/mozperftest/mozperftest/metrics/perfherder.py", line 76, in run
[task 2024-12-30T09:48:32.942Z]     results, fullsettings = filtered_metrics(
[task 2024-12-30T09:48:32.942Z]   File "/builds/task_173555126523648/checkouts/gecko/python/mozperftest/mozperftest/metrics/common.py", line 341, in filtered_metrics
[task 2024-12-30T09:48:32.942Z]     results = storage.filtered_metrics(
[task 2024-12-30T09:48:32.942Z]   File "/builds/task_173555126523648/checkouts/gecko/python/mozperftest/mozperftest/metrics/common.py", line 220, in filtered_metrics
[task 2024-12-30T09:48:32.942Z]     results = self.get_standardized_data(
[task 2024-12-30T09:48:32.942Z]   File "/builds/task_173555126523648/checkouts/gecko/python/mozperftest/mozperftest/metrics/common.py", line 188, in get_standardized_data
[task 2024-12-30T09:48:32.942Z]     r = ptnb.process(**data_info["options"])
[task 2024-12-30T09:48:32.942Z]   File "/builds/task_173555126523648/checkouts/gecko/python/mozperftest/mozperftest/metrics/notebook/perftestetl.py", line 143, in process
[task 2024-12-30T09:48:32.942Z]     files = self.parse_file_grouping(files)
[task 2024-12-30T09:48:32.942Z]   File "/builds/task_173555126523648/checkouts/gecko/python/mozperftest/mozperftest/metrics/notebook/perftestetl.py", line 115, in parse_file_grouping
[task 2024-12-30T09:48:32.942Z]     raise Exception(
[task 2024-12-30T09:48:32.942Z] Exception: Could not find any files in this configuration: []
[task 2024-12-30T09:48:32.942Z] script.py: command finished
[task 2024-12-30T09:48:32.943Z] 
[task 2024-12-30T09:48:32.943Z] netstat -aop
[task 2024-12-30T09:48:32.943Z] Active Internet connections (servers and established)
[task 2024-12-30T09:48:32.943Z] Proto Recv-Q Send-Q Local Address           Foreign Address         State       PID/Program name     Timer
[task 2024-12-30T09:48:32.943Z] tcp        0      0 localhost:8099          0.0.0.0:*               LISTEN      79/taskcluster-prox  off (0.00/0/0)
[task 2024-12-30T09:48:32.943Z] tcp        0      0 127.0.0.11:46731        0.0.0.0:*               LISTEN      -                    off (0.00/0/0)
[task 2024-12-30T09:48:32.943Z] tcp        0      0 localhost:5037          localhost:52517         TIME_WAIT   -                    timewait (44.51/0/0)
[task 2024-12-30T09:48:32.943Z] tcp        0      0 localhost:5037          localhost:38463         TIME_WAIT   -                    timewait (44.68/0/0)
[task 2024-12-30T09:48:32.943Z] tcp        0      0 localhost:5037          localhost:43431         TIME_WAIT   -                    timewait (44.63/0/0)
[task 2024-12-30T09:48:32.943Z] tcp        0      0 localhost:5037          localhost:44907         TIME_WAIT   -                    timewait (44.63/0/0)
[task 2024-12-30T09:48:32.943Z] tcp        0      0 localhost:5037          localhost:35545         TIME_WAIT   -                    timewait (44.51/0/0)
[task 2024-12-30T09:48:32.943Z] tcp        0      0 localhost:5037          localhost:51907         TIME_WAIT   -                    timewait (54.51/0/0)
[task 2024-12-30T09:48:32.943Z] tcp        0      0 localhost:5037          localhost:34199         TIME_WAIT   -                    timewait (44.68/0/0)
[task 2024-12-30T09:48:32.943Z] tcp        0      0 localhost:5037          localhost:53879         TIME_WAIT   -                    timewait (60.00/0/0)
[task 2024-12-30T09:48:32.943Z] tcp        0      0 localhost:5037          localhost:33791         TIME_WAIT   -                    timewait (54.67/0/0)
[task 2024-12-30T09:48:32.943Z] tcp        0      0 localhost:5037          localhost:57465         TIME_WAIT   -                    timewait (44.63/0/0)
[task 2024-12-30T09:48:32.943Z] tcp        0      0 localhost:5037          localhost:52569         TIME_WAIT   -                    timewait (54.51/0/0)
[task 2024-12-30T09:48:32.943Z] tcp        0      0 localhost:5037          localhost:49097         TIME_WAIT   -                    timewait (44.62/0/0)
[task 2024-12-30T09:48:32.943Z] tcp        0      0 localhost:5037          localhost:42951         TIME_WAIT   -                    timewait (44.51/0/0)
[task 2024-12-30T09:48:32.943Z] tcp        0      0 localhost:5037          localhost:52861         TIME_WAIT   -                    timewait (44.63/0/0)
[task 2024-12-30T09:48:32.943Z] tcp        0      0 localhost:5037          localhost:59131         TIME_WAIT   -                    timewait (44.63/0/0)
[task 2024-12-30T09:48:32.943Z] tcp        0      0 localhost:5037          localhost:45911         TIME_WAIT   -                    timewait (44.63/0/0)
[task 2024-12-30T09:48:32.943Z] tcp        0      0 bitbar-ubuntu-146:59852 137.80.201.35.bc.:https ESTABLISHED 27/generic-worker    keepalive (3.13/0/0)
[task 2024-12-30T09:48:32.943Z] tcp        0      0 localhost:5037          localhost:33409         TIME_WAIT   -                    timewait (44.51/0/0)
[task 2024-12-30T09:48:32.943Z] tcp        0      0 localhost:5037          localhost:57307         TIME_WAIT   -                    timewait (44.63/0/0)
[task 2024-12-30T09:48:32.943Z] tcp        0      0 localhost:5037          localhost:51433         TIME_WAIT   -                    timewait (46.30/0/0)
[task 2024-12-30T09:48:32.943Z] tcp        0      0 localhost:5037          localhost:55305         TIME_WAIT   -                    timewait (44.69/0/0)
[task 2024-12-30T09:48:32.943Z] tcp        0      0 localhost:5037          localhost:34101         TIME_WAIT   -                    timewait (44.70/0/0)
[task 2024-12-30T09:48:32.943Z] tcp        0      0 localhost:5037          localhost:45863         TIME_WAIT   -                    timewait (44.63/0/0)
[task 2024-12-30T09:48:32.943Z] tcp6       0      0 [::]:60099              [::]:*                  LISTEN      69/livelog           off (0.00/0/0)
[task 2024-12-30T09:48:32.943Z] tcp6       0      0 [::]:60098              [::]:*                  LISTEN      69/livelog           off (0.00/0/0)
[task 2024-12-30T09:48:32.943Z] tcp6       0      0 localhost:60386         localhost:60098         ESTABLISHED 27/generic-worker    keepalive (7.58/0/0)
[task 2024-12-30T09:48:32.943Z] tcp6       0      0 localhost:60098         localhost:60386         ESTABLISHED 69/livelog           keepalive (9.70/0/0)
[task 2024-12-30T09:48:32.943Z] udp        0      0 127.0.0.11:58275        0.0.0.0:*                           -                    off (0.00/0/0)
[task 2024-12-30T09:48:32.943Z] Active UNIX domain sockets (servers and established)
[task 2024-12-30T09:48:32.943Z] Proto RefCnt Flags       Type       State         I-Node   PID/Program name     Path
[task 2024-12-30T09:48:32.943Z] 
[task 2024-12-30T09:48:32.943Z] 
[task 2024-12-30T09:48:32.943Z] 
[task 2024-12-30T09:48:32.943Z] 
[task 2024-12-30T09:48:32.943Z] df -h
[task 2024-12-30T09:48:32.943Z] Filesystem                         Size  Used Avail Use% Mounted on
[task 2024-12-30T09:48:32.943Z] overlay                            914G   47G  829G   6% /
[task 2024-12-30T09:48:32.943Z] tmpfs                               64M     0   64M   0% /dev
[task 2024-12-30T09:48:32.943Z] shm                                 64M     0   64M   0% /dev/shm
[task 2024-12-30T09:48:32.943Z] /dev/mapper/ubuntu--vg-ubuntu--lv  914G   47G  829G   6% /test
[task 2024-12-30T09:48:32.943Z] tmpfs                               32G     0   32G   0% /proc/asound
[task 2024-12-30T09:48:32.943Z] tmpfs                               32G     0   32G   0% /proc/acpi
[task 2024-12-30T09:48:32.943Z] tmpfs                               32G     0   32G   0% /proc/scsi
[task 2024-12-30T09:48:32.943Z] tmpfs                               32G     0   32G   0% /sys/firmware
[task 2024-12-30T09:48:32.943Z] tmpfs                               32G     0   32G   0% /sys/devices/virtual/powercap
[task 2024-12-30T09:48:32.943Z] 
[task 2024-12-30T09:48:32.943Z] 
[task 2024-12-30T09:48:32.943Z] 
[task 2024-12-30T09:48:32.943Z] script.py: exiting with exitcode 1.
[taskcluster 2024-12-30T09:48:33.045Z]    Exit Code: 1
[taskcluster 2024-12-30T09:48:33.045Z]    User Time: 2m36.433522s
[taskcluster 2024-12-30T09:48:33.045Z]  Kernel Time: 50.730301s
[taskcluster 2024-12-30T09:48:33.045Z]    Wall Time: 14m6.355151978s
[taskcluster 2024-12-30T09:48:33.045Z]       Result: FAILED
[taskcluster 2024-12-30T09:48:33.045Z] === Task Finished ===
[taskcluster 2024-12-30T09:48:33.045Z] Task Duration: 14m6.358642025s
[taskcluster 2024-12-30T09:48:33.356Z] Uploading artifact public/build/background-resource.tgz from file artifacts/background-resource.tgz with content encoding "identity", mime type "application/x-gtar-compressed" and expiry 2025-03-30T09:31:58.664Z
[taskcluster 2024-12-30T09:48:33.613Z] [mounts] Preserving cache: Moving "/builds/task_173555126523648/checkouts" to "caches/Va5y6OGDTcm1tTEfgWNaZA"
[taskcluster 2024-12-30T09:48:33.658Z] Uploading redirect artifact public/logs/live.log to URL https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/IzW3gqC5S3uI7uCe9bXs4Q/runs/0/artifacts/public%2Flogs%2Flive_backing.log with mime type "text/plain; charset=utf-8" and expiry 2025-03-30T09:31:58.664Z
[taskcluster:error] exit status 1

You need to log in before you can comment on or make changes to this bug.