Closed Bug 1940078 Opened 1 month ago Closed 5 days ago

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

Categories

(Testing :: mozperftest, defect, P5)

defect

Tracking

(Not tracked)

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

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


[task 2025-01-06T15:08:32.123Z] Collecting cpu info at 600s for 18730
[task 2025-01-06T15:08:32.123Z] Collecting cpu info at 600s for 18962
[task 2025-01-06T15:08:32.123Z] Collecting cpu info at 600s for 18989
[task 2025-01-06T15:08:32.123Z] Collecting cpu info at 600s for 19042
[task 2025-01-06T15:08:32.123Z] Collecting test resource usage...
[task 2025-01-06T15:08:32.123Z] Finished collecting mem info for 600s
[task 2025-01-06T15:08:32.123Z] Traceback (most recent call last):
[task 2025-01-06T15:08:32.123Z] File "/builds/task_173617529484961/checkouts/gecko/testing/performance/android-resource/parse_resource_usage.py", line 281, in <module>
[task 2025-01-06T15:08:32.123Z] main()
[task 2025-01-06T15:08:32.123Z] File "/builds/task_173617529484961/checkouts/gecko/testing/performance/android-resource/parse_resource_usage.py", line 213, in main
[task 2025-01-06T15:08:32.123Z] cpu_measures = parse_cpu_usage(cpu_info_files[i], binary)
[task 2025-01-06T15:08:32.123Z] File "/builds/task_173617529484961/checkouts/gecko/testing/performance/android-resource/parse_resource_usage.py", line 186, in parse_cpu_usage
[task 2025-01-06T15:08:32.123Z] dt = strptime(time, "%H:%M:%S")
[task 2025-01-06T15:08:32.123Z] File "/builds/task_173617529484961/fetches/python/lib/python3.8/_strptime.py", line 562, in _strptime_time
[task 2025-01-06T15:08:32.123Z] tt = _strptime(data_string, format)[0]
[task 2025-01-06T15:08:32.123Z] File "/builds/task_173617529484961/fetches/python/lib/python3.8/_strptime.py", line 349, in _strptime
[task 2025-01-06T15:08:32.123Z] raise ValueError("time data %r does not match format %r" %
[task 2025-01-06T15:08:32.123Z] ValueError: time data '1.4' does not match format '%H:%M:%S'
[task 2025-01-06T15:08:32.123Z] Traceback (most recent call last):
[task 2025-01-06T15:08:32.123Z]   File "python/mozperftest/mozperftest/runner.py", line 305, in <module>
[task 2025-01-06T15:08:32.123Z]     sys.exit(main())
[task 2025-01-06T15:08:32.123Z]   File "python/mozperftest/mozperftest/runner.py", line 301, in main
[task 2025-01-06T15:08:32.123Z]     run_tests(mach_cmd, args, user_args)
[task 2025-01-06T15:08:32.123Z]   File "python/mozperftest/mozperftest/runner.py", line 188, in run_tests
[task 2025-01-06T15:08:32.123Z]     e.run(metadata)
[task 2025-01-06T15:08:32.123Z]   File "/builds/task_173617529484961/checkouts/gecko/python/mozperftest/mozperftest/environment.py", line 96, in run
[task 2025-01-06T15:08:32.123Z]     metadata = metrics(metadata)
[task 2025-01-06T15:08:32.123Z]   File "/builds/task_173617529484961/checkouts/gecko/python/mozperftest/mozperftest/layers.py", line 157, in __call__
[task 2025-01-06T15:08:32.123Z]     metadata = layer(metadata)
[task 2025-01-06T15:08:32.123Z]   File "/builds/task_173617529484961/checkouts/gecko/python/mozperftest/mozperftest/layers.py", line 69, in __call__
[task 2025-01-06T15:08:32.123Z]     metadata = self.run(metadata)
[task 2025-01-06T15:08:32.123Z]   File "/builds/task_173617529484961/checkouts/gecko/python/mozperftest/mozperftest/metrics/perfherder.py", line 76, in run
[task 2025-01-06T15:08:32.123Z]     results, fullsettings = filtered_metrics(
[task 2025-01-06T15:08:32.123Z]   File "/builds/task_173617529484961/checkouts/gecko/python/mozperftest/mozperftest/metrics/common.py", line 341, in filtered_metrics
[task 2025-01-06T15:08:32.123Z]     results = storage.filtered_metrics(
[task 2025-01-06T15:08:32.123Z]   File "/builds/task_173617529484961/checkouts/gecko/python/mozperftest/mozperftest/metrics/common.py", line 220, in filtered_metrics
[task 2025-01-06T15:08:32.123Z]     results = self.get_standardized_data(
[task 2025-01-06T15:08:32.123Z]   File "/builds/task_173617529484961/checkouts/gecko/python/mozperftest/mozperftest/metrics/common.py", line 188, in get_standardized_data
[task 2025-01-06T15:08:32.123Z]     r = ptnb.process(**data_info["options"])
[task 2025-01-06T15:08:32.123Z]   File "/builds/task_173617529484961/checkouts/gecko/python/mozperftest/mozperftest/metrics/notebook/perftestetl.py", line 143, in process
[task 2025-01-06T15:08:32.123Z]     files = self.parse_file_grouping(files)
[task 2025-01-06T15:08:32.123Z]   File "/builds/task_173617529484961/checkouts/gecko/python/mozperftest/mozperftest/metrics/notebook/perftestetl.py", line 115, in parse_file_grouping
[task 2025-01-06T15:08:32.123Z]     raise Exception(
[task 2025-01-06T15:08:32.123Z] Exception: Could not find any files in this configuration: []
[task 2025-01-06T15:08:32.123Z] script.py: command finished
[task 2025-01-06T15:08:32.815Z] 
[task 2025-01-06T15:08:32.816Z] netstat -aop
[task 2025-01-06T15:08:32.816Z] Active Internet connections (servers and established)
[task 2025-01-06T15:08:32.816Z] Proto Recv-Q Send-Q Local Address           Foreign Address         State       PID/Program name     Timer
[task 2025-01-06T15:08:32.816Z] tcp        0      0 127.0.0.11:32923        0.0.0.0:*               LISTEN      -                    off (0.00/0/0)
[task 2025-01-06T15:08:32.816Z] tcp        0      0 localhost:8099          0.0.0.0:*               LISTEN      75/taskcluster-prox  off (0.00/0/0)
[task 2025-01-06T15:08:32.816Z] tcp        0      0 localhost:5037          localhost:43441         TIME_WAIT   -                    timewait (44.57/0/0)
[task 2025-01-06T15:08:32.816Z] tcp        0      0 localhost:5037          localhost:58399         TIME_WAIT   -                    timewait (54.39/0/0)
[task 2025-01-06T15:08:32.816Z] tcp        0      0 localhost:5037          localhost:39375         TIME_WAIT   -                    timewait (44.50/0/0)
[task 2025-01-06T15:08:32.816Z] tcp        0      0 localhost:5037          localhost:38827         TIME_WAIT   -                    timewait (44.40/0/0)
[task 2025-01-06T15:08:32.816Z] tcp        0      0 localhost:5037          localhost:48529         TIME_WAIT   -                    timewait (44.56/0/0)
[task 2025-01-06T15:08:32.816Z] tcp        0      0 localhost:5037          localhost:42767         TIME_WAIT   -                    timewait (44.50/0/0)
[task 2025-01-06T15:08:32.816Z] tcp        0      0 localhost:5037          localhost:43799         TIME_WAIT   -                    timewait (44.56/0/0)
[task 2025-01-06T15:08:32.816Z] tcp        0      0 localhost:5037          localhost:54633         TIME_WAIT   -                    timewait (44.50/0/0)
[task 2025-01-06T15:08:32.816Z] tcp        0      0 localhost:5037          localhost:33999         TIME_WAIT   -                    timewait (46.14/0/0)
[task 2025-01-06T15:08:32.816Z] tcp        0      0 localhost:5037          localhost:58999         TIME_WAIT   -                    timewait (44.50/0/0)
[task 2025-01-06T15:08:32.816Z] tcp        0      0 localhost:5037          localhost:53347         TIME_WAIT   -                    timewait (44.40/0/0)
[task 2025-01-06T15:08:32.816Z] tcp        0      0 localhost:5037          localhost:46303         TIME_WAIT   -                    timewait (44.50/0/0)
[task 2025-01-06T15:08:32.816Z] tcp        0      0 localhost:5037          localhost:41931         TIME_WAIT   -                    timewait (44.59/0/0)
[task 2025-01-06T15:08:32.816Z] tcp        0      0 localhost:5037          localhost:57083         TIME_WAIT   -                    timewait (59.83/0/0)
[task 2025-01-06T15:08:32.816Z] tcp        0      0 localhost:5037          localhost:38249         TIME_WAIT   -                    timewait (44.40/0/0)
[task 2025-01-06T15:08:32.816Z] tcp        0      0 localhost:5037          localhost:43507         TIME_WAIT   -                    timewait (54.55/0/0)
[task 2025-01-06T15:08:32.816Z] tcp        0      0 localhost:5037          localhost:39051         TIME_WAIT   -                    timewait (44.50/0/0)
[task 2025-01-06T15:08:32.816Z] tcp        0      0 bitbar-ubuntu-171:54038 137.80.201.35.bc.:https ESTABLISHED 27/generic-worker    keepalive (13.50/0/0)
[task 2025-01-06T15:08:32.816Z] tcp        0      0 localhost:5037          localhost:47285         TIME_WAIT   -                    timewait (44.50/0/0)
[task 2025-01-06T15:08:32.816Z] tcp        0      0 localhost:5037          localhost:58209         TIME_WAIT   -                    timewait (54.39/0/0)
[task 2025-01-06T15:08:32.816Z] tcp        0      0 localhost:5037          localhost:33899         TIME_WAIT   -                    timewait (44.40/0/0)
[task 2025-01-06T15:08:32.816Z] tcp        0      0 localhost:5037          localhost:57313         TIME_WAIT   -                    timewait (44.50/0/0)
[task 2025-01-06T15:08:32.816Z] tcp        0      0 localhost:5037          localhost:54827         TIME_WAIT   -                    timewait (44.50/0/0)
[task 2025-01-06T15:08:32.816Z] tcp6       0      0 [::]:60098              [::]:*                  LISTEN      67/livelog           off (0.00/0/0)
[task 2025-01-06T15:08:32.816Z] tcp6       0      0 [::]:60099              [::]:*                  LISTEN      67/livelog           off (0.00/0/0)
[task 2025-01-06T15:08:32.816Z] tcp6       0      0 localhost:45236         localhost:60098         ESTABLISHED 27/generic-worker    keepalive (9.80/0/0)
[task 2025-01-06T15:08:32.816Z] tcp6       0      0 localhost:60098         localhost:45236         ESTABLISHED 67/livelog           keepalive (9.62/0/0)
[task 2025-01-06T15:08:32.816Z] udp        0      0 127.0.0.11:54759        0.0.0.0:*                           -                    off (0.00/0/0)
[task 2025-01-06T15:08:32.816Z] Active UNIX domain sockets (servers and established)
[task 2025-01-06T15:08:32.816Z] Proto RefCnt Flags       Type       State         I-Node   PID/Program name     Path
[task 2025-01-06T15:08:32.816Z] 
[task 2025-01-06T15:08:32.816Z] 
[task 2025-01-06T15:08:32.816Z] 
[task 2025-01-06T15:08:32.816Z] 
[task 2025-01-06T15:08:32.816Z] df -h
[task 2025-01-06T15:08:32.816Z] Filesystem                         Size  Used Avail Use% Mounted on
[task 2025-01-06T15:08:32.816Z] overlay                            914G   47G  830G   6% /
[task 2025-01-06T15:08:32.816Z] tmpfs                               64M     0   64M   0% /dev
[task 2025-01-06T15:08:32.816Z] shm                                 64M     0   64M   0% /dev/shm
[task 2025-01-06T15:08:32.816Z] /dev/mapper/ubuntu--vg-ubuntu--lv  914G   47G  830G   6% /test
[task 2025-01-06T15:08:32.816Z] tmpfs                               32G     0   32G   0% /proc/asound
[task 2025-01-06T15:08:32.816Z] tmpfs                               32G     0   32G   0% /proc/acpi
[task 2025-01-06T15:08:32.816Z] tmpfs                               32G     0   32G   0% /proc/scsi
[task 2025-01-06T15:08:32.816Z] tmpfs                               32G     0   32G   0% /sys/firmware
[task 2025-01-06T15:08:32.816Z] tmpfs                               32G     0   32G   0% /sys/devices/virtual/powercap
[task 2025-01-06T15:08:32.816Z] 
[task 2025-01-06T15:08:32.816Z] 
[task 2025-01-06T15:08:32.816Z] 
[task 2025-01-06T15:08:32.816Z] script.py: exiting with exitcode 1.
[taskcluster 2025-01-06T15:08:32.935Z]    Exit Code: 1
[taskcluster 2025-01-06T15:08:32.935Z]    User Time: 2m32.252239s
[taskcluster 2025-01-06T15:08:32.935Z]  Kernel Time: 47.671805s
[taskcluster 2025-01-06T15:08:32.935Z]    Wall Time: 13m37.430909722s
[taskcluster 2025-01-06T15:08:32.935Z]       Result: FAILED
[taskcluster 2025-01-06T15:08:32.936Z] === Task Finished ===
[taskcluster 2025-01-06T15:08:32.936Z] Task Duration: 13m37.433489978s
[taskcluster 2025-01-06T15:08:32.995Z] 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-04-06T14:51:58.646Z
[taskcluster 2025-01-06T15:08:33.265Z] [mounts] Preserving cache: Moving "/builds/task_173617529484961/checkouts" to "caches/Yjv-kWmQQhOOROgF200CZw"
[taskcluster 2025-01-06T15:08:33.304Z] Uploading redirect artifact public/logs/live.log to URL https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/VxURnPaWRoOIL6e72FBH1w/runs/0/artifacts/public%2Flogs%2Flive_backing.log with mime type "text/plain; charset=utf-8" and expiry 2025-04-06T14:51:58.646Z
[taskcluster:error] exit status 1
Status: NEW → RESOLVED
Closed: 5 days ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.