builder: mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s slave: talos-linux64-ix-025 starttime: 1501786290.8 results: success (0) revision: 36ad88e6b7b248c2f2ae59b80477e5474dd653dc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-08-03 11:51:30.801895) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-08-03 11:51:30.802413) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-03 11:51:30.802702) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-08-03 11:51:30.844619) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-03 11:51:30.844977) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=232498196343f89f466a4a9a000001c8-1501784528.42972-1940684460 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-08-03 11:51:31-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.4M=0s 2017-08-03 11:51:31 (46.4 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.132883 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-08-03 11:51:31.015166) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-03 11:51:31.018516) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=232498196343f89f466a4a9a000001c8-1501784528.42972-1940684460 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021424 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-08-03 11:51:31.068094) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 12 secs) (at 2017-08-03 11:51:31.071450) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 36ad88e6b7b248c2f2ae59b80477e5474dd653dc --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 36ad88e6b7b248c2f2ae59b80477e5474dd653dc --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=232498196343f89f466a4a9a000001c8-1501784528.42972-1940684460 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-08-03 11:51:31,276 truncating revision to first 12 chars 2017-08-03 11:51:31,276 Setting DEBUG logging. 2017-08-03 11:51:31,276 attempt 1/10 2017-08-03 11:51:31,276 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/36ad88e6b7b2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-08-03 11:51:32,655 attempt 1/10 2017-08-03 11:51:33,364 current task status: no status available at this point. state: STARTED 2017-08-03 11:51:33,364 sleeping for 10.00s (attempt 1/10) 2017-08-03 11:51:43,375 attempt 2/10 2017-08-03 11:51:43,709 current task status: Task completed! Check 's3_urls' for upload locations. state: SUCCESS 2017-08-03 11:51:43,948 unpacking tar archive at: mozilla-central-36ad88e6b7b2/testing/mozharness/ program finished with exit code 0 elapsedTime=12.984849 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 12 secs) (at 2017-08-03 11:51:44.070290) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-03 11:51:44.070631) ========= script_repo_revision: 36ad88e6b7b248c2f2ae59b80477e5474dd653dc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-08-03 11:51:44.071017) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-03 11:51:44.071319) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-08-03 11:51:44.081180) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-08-03 11:51:44.081496) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=232498196343f89f466a4a9a000001c8-1501784528.42972-1940684460 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:51:44 INFO - MultiFileLogger online at 20170803 11:51:44 in /builds/slave/test 11:51:44 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 11:51:44 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:51:44 INFO - {'append_to_log': False, 11:51:44 INFO - 'base_work_dir': '/builds/slave/test', 11:51:44 INFO - 'blob_upload_branch': 'Firefox', 11:51:44 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:51:44 INFO - 'branch': 'Firefox', 11:51:44 INFO - 'buildbot_json_path': 'buildprops.json', 11:51:44 INFO - 'code_coverage': False, 11:51:44 INFO - 'config_files': ('talos/linux_config.py',), 11:51:44 INFO - 'default_actions': ('clobber', 11:51:44 INFO - 'read-buildbot-config', 11:51:44 INFO - 'download-and-extract', 11:51:44 INFO - 'populate-webroot', 11:51:44 INFO - 'create-virtualenv', 11:51:44 INFO - 'install', 11:51:44 INFO - 'setup-mitmproxy', 11:51:44 INFO - 'run-tests'), 11:51:44 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:51:44 INFO - 'disable_ccov_upload': False, 11:51:44 INFO - 'download_minidump_stackwalk': True, 11:51:44 INFO - 'download_symbols': 'ondemand', 11:51:44 INFO - 'enable_stylo': False, 11:51:44 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:51:44 INFO - 'tooltool.py': '/tools/tooltool.py', 11:51:44 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:51:44 INFO - '/tools/misc-python/virtualenv.py')}, 11:51:44 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:51:44 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:51:44 INFO - 'gecko_profile': False, 11:51:44 INFO - 'gecko_profile_interval': 0, 11:51:44 INFO - 'installer_path': 'installer.exe', 11:51:44 INFO - 'log_level': 'info', 11:51:44 INFO - 'log_name': 'talos', 11:51:44 INFO - 'log_to_console': True, 11:51:44 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:51:44 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:51:44 INFO - 'opt_config_files': (), 11:51:44 INFO - 'pip_index': False, 11:51:44 INFO - 'suite': 'dromaeojs-e10s', 11:51:44 INFO - 'system_bits': '32', 11:51:44 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:51:44 INFO - 'title': 'talos-linux64-ix-025', 11:51:44 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:51:44 INFO - 'use_talos_json': True, 11:51:44 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:51:44 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:51:44 INFO - 'work_dir': 'build'} 11:51:44 INFO - [mozharness: 2017-08-03 18:51:44.479521Z] Running clobber step. 11:51:44 INFO - Running pre-action listener: _resource_record_pre_action 11:51:44 INFO - Running main action method: clobber 11:51:44 INFO - rmtree: /builds/slave/test/build 11:51:44 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:51:45 INFO - Running post-action listener: _resource_record_post_action 11:51:45 INFO - [mozharness: 2017-08-03 18:51:45.469738Z] Finished clobber step (success) 11:51:45 INFO - [mozharness: 2017-08-03 18:51:45.469840Z] Running read-buildbot-config step. 11:51:45 INFO - Running pre-action listener: _resource_record_pre_action 11:51:45 INFO - Running main action method: read_buildbot_config 11:51:45 INFO - Using buildbot properties: 11:51:45 INFO - { 11:51:45 INFO - "project": "", 11:51:45 INFO - "product": "firefox", 11:51:45 INFO - "who": "gszorc@mozilla.com", 11:51:45 INFO - "installer_path": "public/build/target.tar.bz2", 11:51:45 INFO - "builddir": "mozilla-central_ubuntu64_hw_styloseq_test-dromaeojs-e10s", 11:51:45 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 11:51:45 INFO - "buildername": "Ubuntu HW 12.04 x64 stylo-sequential mozilla-central talos dromaeojs-e10s", 11:51:45 INFO - "stage_platform": "linux64-stylosequential", 11:51:45 INFO - "basedir": "/builds/slave/test", 11:51:45 INFO - "buildnumber": 135, 11:51:45 INFO - "platform": "ubuntu64_hw_styloseq", 11:51:45 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 11:51:45 INFO - "slavebuilddir": "test", 11:51:45 INFO - "taskId": "dxM4QmsWSdiNVGcAYceOLg", 11:51:45 INFO - "branch": "mozilla-central", 11:51:45 INFO - "script_repo_revision": "production", 11:51:45 INFO - "revision": "36ad88e6b7b248c2f2ae59b80477e5474dd653dc", 11:51:45 INFO - "slavename": "talos-linux64-ix-025", 11:51:45 INFO - "repo_path": "mozilla-central" 11:51:45 INFO - } 11:51:45 INFO - Finding installer, test and symbols from parent task. 11:51:45 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dxM4QmsWSdiNVGcAYceOLg'}, attempt #1 11:51:45 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dxM4QmsWSdiNVGcAYceOLg'}, attempt #1 11:51:46 INFO - Task dependencies: cLOXij6DRK-49sIx-uH2bw 11:51:46 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cLOXij6DRK-49sIx-uH2bw'}, attempt #1 11:51:46 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/cLOXij6DRK-49sIx-uH2bw/artifacts/public/build/target.tar.bz2 11:51:46 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/cLOXij6DRK-49sIx-uH2bw/artifacts/public/build/target.test_packages.json 11:51:46 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/cLOXij6DRK-49sIx-uH2bw/artifacts/public/build/target.crashreporter-symbols.zip 11:51:46 INFO - Running post-action listener: _resource_record_post_action 11:51:46 INFO - [mozharness: 2017-08-03 18:51:46.992044Z] Finished read-buildbot-config step (success) 11:51:46 INFO - [mozharness: 2017-08-03 18:51:46.992267Z] Running download-and-extract step. 11:51:46 INFO - Running pre-action listener: _resource_record_pre_action 11:51:46 INFO - Running main action method: download_and_extract 11:51:46 INFO - mkdir: /builds/slave/test/build/tests 11:51:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:51:46 INFO - trying https://queue.taskcluster.net/v1/task/cLOXij6DRK-49sIx-uH2bw/artifacts/public/build/target.test_packages.json 11:51:46 INFO - Downloading https://queue.taskcluster.net/v1/task/cLOXij6DRK-49sIx-uH2bw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 11:51:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cLOXij6DRK-49sIx-uH2bw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 11:51:47 INFO - Downloaded 989 bytes. 11:51:47 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 11:51:47 INFO - Using the following test package requirements: 11:51:47 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 11:51:47 INFO - u'common': [u'target.common.tests.zip'], 11:51:47 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 11:51:47 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 11:51:47 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 11:51:47 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 11:51:47 INFO - u'mozbase': [u'target.common.tests.zip'], 11:51:47 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 11:51:47 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 11:51:47 INFO - u'web-platform': [u'target.common.tests.zip', 11:51:47 INFO - u'target.web-platform.tests.tar.gz'], 11:51:47 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 11:51:47 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 11:51:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cLOXij6DRK-49sIx-uH2bw/artifacts/public/build/target.common.tests.zip 11:51:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cLOXij6DRK-49sIx-uH2bw/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:51:47 INFO - Fetch https://queue.taskcluster.net/v1/task/cLOXij6DRK-49sIx-uH2bw/artifacts/public/build/target.common.tests.zip into memory 11:51:48 INFO - Content-Length response header: 54278471 11:51:48 INFO - Bytes received: 54278471 11:51:59 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 11:51:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cLOXij6DRK-49sIx-uH2bw/artifacts/public/build/target.common.tests.zip 11:51:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cLOXij6DRK-49sIx-uH2bw/artifacts/public/build/target.common.tests.zip'}, attempt #1 11:51:59 INFO - Fetch https://queue.taskcluster.net/v1/task/cLOXij6DRK-49sIx-uH2bw/artifacts/public/build/target.common.tests.zip into memory 11:52:00 INFO - Content-Length response header: 54278471 11:52:00 INFO - Bytes received: 54278471 11:52:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cLOXij6DRK-49sIx-uH2bw/artifacts/public/build/target.talos.tests.zip 11:52:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cLOXij6DRK-49sIx-uH2bw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 11:52:13 INFO - Fetch https://queue.taskcluster.net/v1/task/cLOXij6DRK-49sIx-uH2bw/artifacts/public/build/target.talos.tests.zip into memory 11:52:14 INFO - Content-Length response header: 14496464 11:52:14 INFO - Bytes received: 14496464 11:52:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:52:15 INFO - trying https://queue.taskcluster.net/v1/task/cLOXij6DRK-49sIx-uH2bw/artifacts/public/build/target.tar.bz2 11:52:15 INFO - Downloading https://queue.taskcluster.net/v1/task/cLOXij6DRK-49sIx-uH2bw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 11:52:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cLOXij6DRK-49sIx-uH2bw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 11:52:16 INFO - Downloaded 62214486 bytes. 11:52:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cLOXij6DRK-49sIx-uH2bw/artifacts/public/build/target.tar.bz2 11:52:16 INFO - mkdir: /builds/slave/test/properties 11:52:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:52:16 INFO - Writing to file /builds/slave/test/properties/build_url 11:52:16 INFO - Contents: 11:52:16 INFO - build_url:https://queue.taskcluster.net/v1/task/cLOXij6DRK-49sIx-uH2bw/artifacts/public/build/target.tar.bz2 11:52:16 INFO - Running post-action listener: _resource_record_post_action 11:52:16 INFO - Running post-action listener: set_extra_try_arguments 11:52:16 INFO - [mozharness: 2017-08-03 18:52:16.391649Z] Finished download-and-extract step (success) 11:52:16 INFO - [mozharness: 2017-08-03 18:52:16.391748Z] Running populate-webroot step. 11:52:16 INFO - Running pre-action listener: _resource_record_pre_action 11:52:16 INFO - Running main action method: populate_webroot 11:52:16 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:52:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:52:16 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:52:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:52:16 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:52:16 INFO - 'tests': ['damp', 'tps']}, 11:52:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:52:16 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 11:52:16 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 11:52:16 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:52:16 INFO - 'other-e10s': {'tests': ['a11yr', 11:52:16 INFO - 'ts_paint', 11:52:16 INFO - 'tpaint', 11:52:16 INFO - 'sessionrestore', 11:52:16 INFO - 'sessionrestore_many_windows', 11:52:16 INFO - 'sessionrestore_no_auto_restore', 11:52:16 INFO - 'tabpaint']}, 11:52:16 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic']}, 11:52:16 INFO - 'perf-reftest-singletons-e10s': {'tests': ['bloom_basic_singleton']}, 11:52:16 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:52:16 INFO - 'talos_options': ['--mitmproxy', 11:52:16 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:52:16 INFO - '--firstNonBlankPaint'], 11:52:16 INFO - 'tests': ['quantum_pageload_google', 11:52:16 INFO - 'quantum_pageload_youtube', 11:52:16 INFO - 'quantum_pageload_amazon', 11:52:16 INFO - 'quantum_pageload_facebook']}, 11:52:16 INFO - 'quantum-pageload-stylo-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:52:16 INFO - 'talos_options': ['--stylo', 11:52:16 INFO - '--mitmproxy', 11:52:16 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:52:16 INFO - '--firstNonBlankPaint'], 11:52:16 INFO - 'tests': ['quantum_pageload_google', 11:52:16 INFO - 'quantum_pageload_youtube', 11:52:16 INFO - 'quantum_pageload_amazon', 11:52:16 INFO - 'quantum_pageload_facebook']}, 11:52:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:52:16 INFO - 'tsvgr_opacity', 11:52:16 INFO - 'tart', 11:52:16 INFO - 'tscrollx', 11:52:16 INFO - 'cart', 11:52:16 INFO - 'tsvg_static']}, 11:52:16 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:52:16 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:52:16 INFO - 'talos_options': ['--stylo', 11:52:16 INFO - '--stylo-threads=1', 11:52:16 INFO - '--mitmproxy', 11:52:16 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:52:16 INFO - '--firstNonBlankPaint'], 11:52:16 INFO - 'tests': ['tp6_google', 11:52:16 INFO - 'tp6_youtube', 11:52:16 INFO - 'tp6_amazon', 11:52:16 INFO - 'tp6_facebook']}, 11:52:16 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:52:16 INFO - 'talos_options': ['--xperf_path', 11:52:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:52:16 INFO - 'tests': ['tp5n']}}} 11:52:16 INFO - Running post-action listener: _resource_record_post_action 11:52:16 INFO - [mozharness: 2017-08-03 18:52:16.400360Z] Finished populate-webroot step (success) 11:52:16 INFO - [mozharness: 2017-08-03 18:52:16.400450Z] Running create-virtualenv step. 11:52:16 INFO - Running pre-action listener: _resource_record_pre_action 11:52:16 INFO - Running main action method: create_virtualenv 11:52:16 INFO - Creating virtualenv /builds/slave/test/build/venv 11:52:16 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 11:52:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:52:16 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 11:52:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:52:16 INFO - Using real prefix '/usr' 11:52:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:52:17 INFO - Installing distribute.............................................................................................................................................................................................done. 11:52:19 INFO - Installing pip.................done. 11:52:19 INFO - Return code: 0 11:52:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:52:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:52:19 INFO - Reading from file tmpfile_stdout 11:52:19 INFO - Output received: 11:52:19 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 11:52:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:52:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:52:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:52:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:52:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:52:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26c91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2895a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a38d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a35eb0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a36db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a37240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1501784528.42972-1940684460', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:52:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:52:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:52:19 INFO - Using env: {'DISPLAY': ':0', 11:52:19 INFO - 'HOME': '/home/cltbld', 11:52:19 INFO - 'LANG': 'en_US.UTF-8', 11:52:19 INFO - 'LANGUAGE': 'en_US:en', 11:52:19 INFO - 'LOGNAME': 'cltbld', 11:52:19 INFO - 'MAIL': '/var/mail/cltbld', 11:52:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:52:19 INFO - 'MOZ_NO_REMOTE': '1', 11:52:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:52:19 INFO - 'NO_EM_RESTART': '1', 11:52:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:52:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:52:19 INFO - 'PWD': '/builds/slave/test', 11:52:19 INFO - 'SHELL': '/bin/bash', 11:52:19 INFO - 'SHLVL': '1', 11:52:19 INFO - 'TERM': 'linux', 11:52:19 INFO - 'TMOUT': '86400', 11:52:19 INFO - 'USER': 'cltbld', 11:52:19 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1501784528.42972-1940684460', 11:52:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:52:19 INFO - '_': '/tools/buildbot/bin/python'} 11:52:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:52:19 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 11:52:19 INFO - Cleaning up... 11:52:19 INFO - Return code: 0 11:52:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:52:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:52:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:52:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:52:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:52:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26c91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2895a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a38d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a35eb0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a36db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a37240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1501784528.42972-1940684460', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:52:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:52:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:52:19 INFO - Using env: {'DISPLAY': ':0', 11:52:19 INFO - 'HOME': '/home/cltbld', 11:52:19 INFO - 'LANG': 'en_US.UTF-8', 11:52:19 INFO - 'LANGUAGE': 'en_US:en', 11:52:19 INFO - 'LOGNAME': 'cltbld', 11:52:19 INFO - 'MAIL': '/var/mail/cltbld', 11:52:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:52:19 INFO - 'MOZ_NO_REMOTE': '1', 11:52:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:52:19 INFO - 'NO_EM_RESTART': '1', 11:52:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:52:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:52:19 INFO - 'PWD': '/builds/slave/test', 11:52:19 INFO - 'SHELL': '/bin/bash', 11:52:19 INFO - 'SHLVL': '1', 11:52:19 INFO - 'TERM': 'linux', 11:52:19 INFO - 'TMOUT': '86400', 11:52:19 INFO - 'USER': 'cltbld', 11:52:19 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1501784528.42972-1940684460', 11:52:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:52:19 INFO - '_': '/tools/buildbot/bin/python'} 11:52:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:52:19 INFO - Downloading/unpacking psutil>=3.1.1 11:52:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:52:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:52:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:52:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:52:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:52:21 INFO - Installing collected packages: psutil 11:52:21 INFO - Running setup.py install for psutil 11:52:21 INFO - building 'psutil._psutil_linux' extension 11:52:21 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 11:52:22 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 11:52:22 INFO - building 'psutil._psutil_posix' extension 11:52:22 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 11:52:22 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 11:52:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:52:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:52:22 INFO - Successfully installed psutil 11:52:22 INFO - Cleaning up... 11:52:22 INFO - Return code: 0 11:52:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:52:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:52:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:52:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:52:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:52:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26c91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2895a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a38d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a35eb0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a36db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a37240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1501784528.42972-1940684460', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:52:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:52:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:52:22 INFO - Using env: {'DISPLAY': ':0', 11:52:22 INFO - 'HOME': '/home/cltbld', 11:52:22 INFO - 'LANG': 'en_US.UTF-8', 11:52:22 INFO - 'LANGUAGE': 'en_US:en', 11:52:22 INFO - 'LOGNAME': 'cltbld', 11:52:22 INFO - 'MAIL': '/var/mail/cltbld', 11:52:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:52:22 INFO - 'MOZ_NO_REMOTE': '1', 11:52:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:52:22 INFO - 'NO_EM_RESTART': '1', 11:52:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:52:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:52:22 INFO - 'PWD': '/builds/slave/test', 11:52:22 INFO - 'SHELL': '/bin/bash', 11:52:22 INFO - 'SHLVL': '1', 11:52:22 INFO - 'TERM': 'linux', 11:52:22 INFO - 'TMOUT': '86400', 11:52:22 INFO - 'USER': 'cltbld', 11:52:22 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1501784528.42972-1940684460', 11:52:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:52:22 INFO - '_': '/tools/buildbot/bin/python'} 11:52:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:52:22 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:52:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:52:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:52:23 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:52:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:52:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:52:23 INFO - Installing collected packages: mozsystemmonitor 11:52:23 INFO - Running setup.py install for mozsystemmonitor 11:52:23 INFO - Successfully installed mozsystemmonitor 11:52:23 INFO - Cleaning up... 11:52:24 INFO - Return code: 0 11:52:24 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:52:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:52:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:52:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:52:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:52:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26c91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2895a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a38d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a35eb0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a36db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a37240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1501784528.42972-1940684460', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:52:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 11:52:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 11:52:24 INFO - Using env: {'DISPLAY': ':0', 11:52:24 INFO - 'HOME': '/home/cltbld', 11:52:24 INFO - 'LANG': 'en_US.UTF-8', 11:52:24 INFO - 'LANGUAGE': 'en_US:en', 11:52:24 INFO - 'LOGNAME': 'cltbld', 11:52:24 INFO - 'MAIL': '/var/mail/cltbld', 11:52:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:52:24 INFO - 'MOZ_NO_REMOTE': '1', 11:52:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:52:24 INFO - 'NO_EM_RESTART': '1', 11:52:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:52:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:52:24 INFO - 'PWD': '/builds/slave/test', 11:52:24 INFO - 'SHELL': '/bin/bash', 11:52:24 INFO - 'SHLVL': '1', 11:52:24 INFO - 'TERM': 'linux', 11:52:24 INFO - 'TMOUT': '86400', 11:52:24 INFO - 'USER': 'cltbld', 11:52:24 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1501784528.42972-1940684460', 11:52:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:52:24 INFO - '_': '/tools/buildbot/bin/python'} 11:52:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:52:24 INFO - Downloading/unpacking jsonschema==2.5.1 11:52:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:52:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:52:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:52:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:52:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:52:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:52:25 INFO - Downloading functools32-3.2.3-2.tar.gz 11:52:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:52:25 INFO - warning: no files found matching '*.txt' 11:52:25 INFO - no previously-included directories found matching 'build' 11:52:25 INFO - no previously-included directories found matching 'dist' 11:52:25 INFO - no previously-included directories found matching '.git*' 11:52:25 INFO - Installing collected packages: jsonschema, functools32 11:52:25 INFO - Running setup.py install for functools32 11:52:25 INFO - warning: no files found matching '*.txt' 11:52:25 INFO - no previously-included directories found matching 'build' 11:52:25 INFO - no previously-included directories found matching 'dist' 11:52:25 INFO - no previously-included directories found matching '.git*' 11:52:25 INFO - Successfully installed jsonschema functools32 11:52:25 INFO - Cleaning up... 11:52:25 INFO - Return code: 0 11:52:25 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:52:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:52:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:52:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:52:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:52:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26c91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2895a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a38d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a35eb0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a36db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a37240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1501784528.42972-1940684460', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:52:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 11:52:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 11:52:25 INFO - Using env: {'DISPLAY': ':0', 11:52:25 INFO - 'HOME': '/home/cltbld', 11:52:25 INFO - 'LANG': 'en_US.UTF-8', 11:52:25 INFO - 'LANGUAGE': 'en_US:en', 11:52:25 INFO - 'LOGNAME': 'cltbld', 11:52:25 INFO - 'MAIL': '/var/mail/cltbld', 11:52:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:52:25 INFO - 'MOZ_NO_REMOTE': '1', 11:52:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:52:25 INFO - 'NO_EM_RESTART': '1', 11:52:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:52:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:52:25 INFO - 'PWD': '/builds/slave/test', 11:52:25 INFO - 'SHELL': '/bin/bash', 11:52:25 INFO - 'SHLVL': '1', 11:52:25 INFO - 'TERM': 'linux', 11:52:25 INFO - 'TMOUT': '86400', 11:52:25 INFO - 'USER': 'cltbld', 11:52:25 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1501784528.42972-1940684460', 11:52:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:52:25 INFO - '_': '/tools/buildbot/bin/python'} 11:52:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:52:25 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:52:25 INFO - Cleaning up... 11:52:25 INFO - Return code: 0 11:52:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:52:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:52:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:52:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:52:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:52:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26c91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2895a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a38d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a35eb0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a36db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a37240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1501784528.42972-1940684460', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:52:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:52:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:52:25 INFO - Using env: {'DISPLAY': ':0', 11:52:25 INFO - 'HOME': '/home/cltbld', 11:52:25 INFO - 'LANG': 'en_US.UTF-8', 11:52:25 INFO - 'LANGUAGE': 'en_US:en', 11:52:25 INFO - 'LOGNAME': 'cltbld', 11:52:25 INFO - 'MAIL': '/var/mail/cltbld', 11:52:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:52:25 INFO - 'MOZ_NO_REMOTE': '1', 11:52:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:52:25 INFO - 'NO_EM_RESTART': '1', 11:52:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:52:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:52:25 INFO - 'PWD': '/builds/slave/test', 11:52:25 INFO - 'SHELL': '/bin/bash', 11:52:25 INFO - 'SHLVL': '1', 11:52:25 INFO - 'TERM': 'linux', 11:52:25 INFO - 'TMOUT': '86400', 11:52:25 INFO - 'USER': 'cltbld', 11:52:25 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1501784528.42972-1940684460', 11:52:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:52:25 INFO - '_': '/tools/buildbot/bin/python'} 11:52:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:52:26 INFO - Downloading/unpacking blobuploader==1.2.4 11:52:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:52:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:52:27 INFO - Downloading blobuploader-1.2.4.tar.gz 11:52:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:52:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:52:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:52:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:52:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:52:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:52:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:52:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:52:27 INFO - Downloading docopt-0.6.1.tar.gz 11:52:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:52:28 INFO - Installing collected packages: blobuploader, requests, docopt 11:52:28 INFO - Running setup.py install for blobuploader 11:52:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:52:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:52:28 INFO - Running setup.py install for requests 11:52:28 INFO - Running setup.py install for docopt 11:52:28 INFO - Successfully installed blobuploader requests docopt 11:52:28 INFO - Cleaning up... 11:52:28 INFO - Return code: 0 11:52:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:52:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:52:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:52:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:52:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:52:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26c91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2895a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a38d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a35eb0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a36db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a37240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1501784528.42972-1940684460', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:52:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:52:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:52:28 INFO - Using env: {'DISPLAY': ':0', 11:52:28 INFO - 'HOME': '/home/cltbld', 11:52:28 INFO - 'LANG': 'en_US.UTF-8', 11:52:28 INFO - 'LANGUAGE': 'en_US:en', 11:52:28 INFO - 'LOGNAME': 'cltbld', 11:52:28 INFO - 'MAIL': '/var/mail/cltbld', 11:52:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:52:28 INFO - 'MOZ_NO_REMOTE': '1', 11:52:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:52:28 INFO - 'NO_EM_RESTART': '1', 11:52:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:52:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:52:28 INFO - 'PWD': '/builds/slave/test', 11:52:28 INFO - 'SHELL': '/bin/bash', 11:52:28 INFO - 'SHLVL': '1', 11:52:28 INFO - 'TERM': 'linux', 11:52:28 INFO - 'TMOUT': '86400', 11:52:28 INFO - 'USER': 'cltbld', 11:52:28 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1501784528.42972-1940684460', 11:52:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:52:28 INFO - '_': '/tools/buildbot/bin/python'} 11:52:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:52:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:52:28 INFO - Running setup.py (path:/tmp/pip-in1LPx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:52:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:52:28 INFO - Running setup.py (path:/tmp/pip-K8dmrj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:52:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:52:28 INFO - Running setup.py (path:/tmp/pip-fAUDmi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:52:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:52:29 INFO - Running setup.py (path:/tmp/pip-bH8YP1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:52:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:52:29 INFO - Running setup.py (path:/tmp/pip-7XqgMx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:52:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:52:29 INFO - Running setup.py (path:/tmp/pip-H1P3KC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:52:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:52:29 INFO - Running setup.py (path:/tmp/pip-DDKn6z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:52:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:52:29 INFO - Running setup.py (path:/tmp/pip-jK0G7g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:52:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:52:29 INFO - Running setup.py (path:/tmp/pip-vDAHgJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:52:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:52:29 INFO - Running setup.py (path:/tmp/pip-VnJhUd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:52:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:52:29 INFO - Running setup.py (path:/tmp/pip-qkaaCN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:52:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:52:29 INFO - Running setup.py (path:/tmp/pip-QtQAYv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:52:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:52:29 INFO - Running setup.py (path:/tmp/pip-3VUSiZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:52:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:52:29 INFO - Running setup.py (path:/tmp/pip-dYe5V0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:52:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:52:29 INFO - Running setup.py (path:/tmp/pip-vnX_6E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:52:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:52:29 INFO - Running setup.py (path:/tmp/pip-4fLsnH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:52:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:52:30 INFO - Running setup.py (path:/tmp/pip-BeaiVq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:52:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:52:30 INFO - Running setup.py install for manifestparser 11:52:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:52:30 INFO - Running setup.py install for mozcrash 11:52:30 INFO - Running setup.py install for mozdebug 11:52:30 INFO - Running setup.py install for mozdevice 11:52:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:52:30 INFO - Running setup.py install for mozfile 11:52:30 INFO - Running setup.py install for mozhttpd 11:52:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:52:30 INFO - Running setup.py install for mozinfo 11:52:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:52:30 INFO - Running setup.py install for mozInstall 11:52:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:52:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:52:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:52:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:52:30 INFO - Running setup.py install for mozleak 11:52:30 INFO - Running setup.py install for mozlog 11:52:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:52:31 INFO - Running setup.py install for moznetwork 11:52:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:52:31 INFO - Running setup.py install for mozprocess 11:52:31 INFO - Running setup.py install for mozprofile 11:52:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:52:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:52:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:52:31 INFO - Running setup.py install for mozrunner 11:52:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:52:31 INFO - Running setup.py install for mozscreenshot 11:52:31 INFO - Running setup.py install for moztest 11:52:31 INFO - Running setup.py install for mozversion 11:52:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:52:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:52:31 INFO - Cleaning up... 11:52:31 INFO - Return code: 0 11:52:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:52:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:52:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:52:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:52:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:52:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26c91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2895a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a38d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a35eb0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a36db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a37240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1501784528.42972-1940684460', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:52:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:52:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:52:31 INFO - Using env: {'DISPLAY': ':0', 11:52:31 INFO - 'HOME': '/home/cltbld', 11:52:31 INFO - 'LANG': 'en_US.UTF-8', 11:52:31 INFO - 'LANGUAGE': 'en_US:en', 11:52:31 INFO - 'LOGNAME': 'cltbld', 11:52:31 INFO - 'MAIL': '/var/mail/cltbld', 11:52:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:52:31 INFO - 'MOZ_NO_REMOTE': '1', 11:52:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:52:31 INFO - 'NO_EM_RESTART': '1', 11:52:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:52:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:52:31 INFO - 'PWD': '/builds/slave/test', 11:52:31 INFO - 'SHELL': '/bin/bash', 11:52:31 INFO - 'SHLVL': '1', 11:52:31 INFO - 'TERM': 'linux', 11:52:31 INFO - 'TMOUT': '86400', 11:52:31 INFO - 'USER': 'cltbld', 11:52:31 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1501784528.42972-1940684460', 11:52:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:52:31 INFO - '_': '/tools/buildbot/bin/python'} 11:52:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:52:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:52:32 INFO - Running setup.py (path:/tmp/pip-mpZFVF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:52:32 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 11:52:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:52:32 INFO - Running setup.py (path:/tmp/pip-e61pfI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:52:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:52:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:52:32 INFO - Running setup.py (path:/tmp/pip-IXvAuP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:52:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:52:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:52:32 INFO - Running setup.py (path:/tmp/pip-H7Zfbh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:52:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:52:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:52:32 INFO - Running setup.py (path:/tmp/pip-cCngGV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:52:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 11:52:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:52:32 INFO - Running setup.py (path:/tmp/pip-rY6JMu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:52:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 11:52:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:52:32 INFO - Running setup.py (path:/tmp/pip-DVNWGm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:52:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 11:52:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:52:32 INFO - Running setup.py (path:/tmp/pip-I4gZbf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:52:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 11:52:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:52:32 INFO - Running setup.py (path:/tmp/pip-cZJyNm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:52:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 11:52:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:52:32 INFO - Running setup.py (path:/tmp/pip-_2jKOY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:52:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:52:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:52:32 INFO - Running setup.py (path:/tmp/pip-kQB4zW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:52:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 11:52:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:52:32 INFO - Running setup.py (path:/tmp/pip-eubVXW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:52:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 11:52:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:52:32 INFO - Running setup.py (path:/tmp/pip-h_tlwU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:52:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 11:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:52:33 INFO - Running setup.py (path:/tmp/pip-dab2NW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:52:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 11:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:52:33 INFO - Running setup.py (path:/tmp/pip-b80XV3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:52:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 11:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:52:33 INFO - Running setup.py (path:/tmp/pip-DdYCl0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:52:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 11:52:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:52:33 INFO - Running setup.py (path:/tmp/pip-WmODXz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:52:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 11:52:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:52:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:52:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:52:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:52:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:52:33 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 11:52:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:52:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:52:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:52:34 INFO - Downloading blessings-1.6.tar.gz 11:52:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:52:34 INFO - Installing collected packages: blessings 11:52:34 INFO - Running setup.py install for blessings 11:52:34 INFO - Successfully installed blessings 11:52:34 INFO - Cleaning up... 11:52:34 INFO - Return code: 0 11:52:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:52:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:52:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:52:34 INFO - Reading from file tmpfile_stdout 11:52:34 INFO - Current package versions: 11:52:34 INFO - argparse == 1.2.1 11:52:34 INFO - blessings == 1.6 11:52:34 INFO - blobuploader == 1.2.4 11:52:34 INFO - docopt == 0.6.1 11:52:34 INFO - functools32 == 3.2.3-2 11:52:34 INFO - jsonschema == 2.5.1 11:52:34 INFO - manifestparser == 1.1 11:52:34 INFO - mozInstall == 1.13 11:52:34 INFO - mozcrash == 1.0 11:52:34 INFO - mozdebug == 0.1 11:52:34 INFO - mozdevice == 0.51 11:52:34 INFO - mozfile == 1.2 11:52:34 INFO - mozhttpd == 0.7 11:52:34 INFO - mozinfo == 0.10 11:52:34 INFO - mozleak == 0.1 11:52:34 INFO - mozlog == 3.5 11:52:34 INFO - moznetwork == 0.27 11:52:34 INFO - mozprocess == 0.25 11:52:34 INFO - mozprofile == 0.28 11:52:34 INFO - mozrunner == 6.13 11:52:34 INFO - mozscreenshot == 0.1 11:52:34 INFO - mozsystemmonitor == 0.3 11:52:34 INFO - moztest == 0.8 11:52:34 INFO - mozversion == 1.4 11:52:34 INFO - psutil == 3.1.1 11:52:34 INFO - requests == 1.2.3 11:52:34 INFO - wsgiref == 0.1.2 11:52:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:52:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:52:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:52:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:52:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:52:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26c91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2895a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a38d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a35eb0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a36db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a37240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1501784528.42972-1940684460', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:52:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 11:52:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:52:34 INFO - Using env: {'DISPLAY': ':0', 11:52:34 INFO - 'HOME': '/home/cltbld', 11:52:34 INFO - 'LANG': 'en_US.UTF-8', 11:52:34 INFO - 'LANGUAGE': 'en_US:en', 11:52:34 INFO - 'LOGNAME': 'cltbld', 11:52:34 INFO - 'MAIL': '/var/mail/cltbld', 11:52:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:52:34 INFO - 'MOZ_NO_REMOTE': '1', 11:52:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:52:34 INFO - 'NO_EM_RESTART': '1', 11:52:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:52:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:52:34 INFO - 'PWD': '/builds/slave/test', 11:52:34 INFO - 'SHELL': '/bin/bash', 11:52:34 INFO - 'SHLVL': '1', 11:52:34 INFO - 'TERM': 'linux', 11:52:34 INFO - 'TMOUT': '86400', 11:52:34 INFO - 'USER': 'cltbld', 11:52:34 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1501784528.42972-1940684460', 11:52:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:52:34 INFO - '_': '/tools/buildbot/bin/python'} 11:52:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:52:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:52:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 11:52:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 11:52:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:52:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 11:52:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 11:52:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 11:52:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 11:52:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 11:52:35 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 11:52:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:52:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:52:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:52:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 11:52:36 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:52:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:52:36 INFO - Installing collected packages: simplejson 11:52:36 INFO - Running setup.py install for simplejson 11:52:36 INFO - building 'simplejson._speedups' extension 11:52:36 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 11:52:37 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 11:52:37 INFO - Successfully installed simplejson 11:52:37 INFO - Cleaning up... 11:52:37 INFO - Return code: 0 11:52:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:52:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:52:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:52:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:52:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:52:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x26c91f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2895a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2a38d10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a35eb0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a36db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a37240>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1501784528.42972-1940684460', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 11:52:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:52:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:52:37 INFO - Using env: {'DISPLAY': ':0', 11:52:37 INFO - 'HOME': '/home/cltbld', 11:52:37 INFO - 'LANG': 'en_US.UTF-8', 11:52:37 INFO - 'LANGUAGE': 'en_US:en', 11:52:37 INFO - 'LOGNAME': 'cltbld', 11:52:37 INFO - 'MAIL': '/var/mail/cltbld', 11:52:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:52:37 INFO - 'MOZ_NO_REMOTE': '1', 11:52:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:52:37 INFO - 'NO_EM_RESTART': '1', 11:52:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:52:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:52:37 INFO - 'PWD': '/builds/slave/test', 11:52:37 INFO - 'SHELL': '/bin/bash', 11:52:37 INFO - 'SHLVL': '1', 11:52:37 INFO - 'TERM': 'linux', 11:52:37 INFO - 'TMOUT': '86400', 11:52:37 INFO - 'USER': 'cltbld', 11:52:37 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1501784528.42972-1940684460', 11:52:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:52:37 INFO - '_': '/tools/buildbot/bin/python'} 11:52:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:52:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:52:37 INFO - Cleaning up... 11:52:37 INFO - Return code: 0 11:52:37 INFO - Running post-action listener: _resource_record_post_action 11:52:37 INFO - Running post-action listener: _start_resource_monitoring 11:52:37 INFO - Starting resource monitoring. 11:52:37 INFO - [mozharness: 2017-08-03 18:52:37.645033Z] Finished create-virtualenv step (success) 11:52:37 INFO - [mozharness: 2017-08-03 18:52:37.646105Z] Running install step. 11:52:37 INFO - Running pre-action listener: _resource_record_pre_action 11:52:37 INFO - Running main action method: install 11:52:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:52:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:52:37 INFO - Reading from file tmpfile_stdout 11:52:37 INFO - Detecting whether we're running mozinstall >=1.0... 11:52:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:52:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:52:38 INFO - Reading from file tmpfile_stdout 11:52:38 INFO - Output received: 11:52:38 INFO - Usage: mozinstall [options] installer 11:52:38 INFO - Options: 11:52:38 INFO - -h, --help show this help message and exit 11:52:38 INFO - -d DEST, --destination=DEST 11:52:38 INFO - Directory to install application into. [default: 11:52:38 INFO - "/builds/slave/test"] 11:52:38 INFO - --app=APP Application being installed. [default: firefox] 11:52:38 INFO - mkdir: /builds/slave/test/build/application 11:52:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 11:52:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 11:52:56 INFO - Reading from file tmpfile_stdout 11:52:56 INFO - Output received: 11:52:56 INFO - /builds/slave/test/build/application/firefox/firefox 11:52:56 INFO - Running post-action listener: _resource_record_post_action 11:52:56 INFO - [mozharness: 2017-08-03 18:52:56.047014Z] Finished install step (success) 11:52:56 INFO - [mozharness: 2017-08-03 18:52:56.047269Z] Running setup-mitmproxy step. 11:52:56 INFO - Running pre-action listener: _resource_record_pre_action 11:52:56 INFO - Running main action method: setup_mitmproxy 11:52:56 INFO - Skipping: mitmproxy is not required 11:52:56 INFO - Running post-action listener: _resource_record_post_action 11:52:56 INFO - [mozharness: 2017-08-03 18:52:56.048400Z] Finished setup-mitmproxy step (success) 11:52:56 INFO - [mozharness: 2017-08-03 18:52:56.048623Z] Running run-tests step. 11:52:56 INFO - Running pre-action listener: _resource_record_pre_action 11:52:56 INFO - Running pre-action listener: _set_gcov_prefix 11:52:56 INFO - Running main action method: run_tests 11:52:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:52:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:52:56 INFO - Python 2.7.3 11:52:56 INFO - Return code: 0 11:52:56 INFO - grabbing minidump binary from tooltool 11:52:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:52:56 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a35eb0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a36db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2a37240>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:52:56 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 11:52:56 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 11:52:56 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 11:52:56 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:52:56 INFO - Return code: 0 11:52:56 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:52:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:52:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:52:56 INFO - ENV: RUST_BACKTRACE is now 1 11:52:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:52:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:52:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cLOXij6DRK-49sIx-uH2bw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-025', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 11:52:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/cLOXij6DRK-49sIx-uH2bw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-025 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 11:52:56 INFO - Using env: {'DISPLAY': ':0', 11:52:56 INFO - 'HOME': '/home/cltbld', 11:52:56 INFO - 'LANG': 'en_US.UTF-8', 11:52:56 INFO - 'LANGUAGE': 'en_US:en', 11:52:56 INFO - 'LOGNAME': 'cltbld', 11:52:56 INFO - 'MAIL': '/var/mail/cltbld', 11:52:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:52:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:52:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:52:56 INFO - 'MOZ_NO_REMOTE': '1', 11:52:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:52:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:52:56 INFO - 'NO_EM_RESTART': '1', 11:52:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:52:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:52:56 INFO - 'PWD': '/builds/slave/test', 11:52:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:52:56 INFO - 'RUST_BACKTRACE': '1', 11:52:56 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:52:56 INFO - 'SHELL': '/bin/bash', 11:52:56 INFO - 'SHLVL': '1', 11:52:56 INFO - 'STYLO_FORCE_ENABLED': '1', 11:52:56 INFO - 'TERM': 'linux', 11:52:56 INFO - 'TMOUT': '86400', 11:52:56 INFO - 'USER': 'cltbld', 11:52:56 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1501784528.42972-1940684460', 11:52:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:52:56 INFO - '_': '/tools/buildbot/bin/python'} 11:52:56 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cLOXij6DRK-49sIx-uH2bw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-025', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 11:52:56 INFO - mozversion application_buildid: 20170803181106 11:52:56 INFO - mozversion application_changeset: 36ad88e6b7b248c2f2ae59b80477e5474dd653dc 11:52:56 INFO - mozversion application_display_name: Nightly 11:52:56 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:52:56 INFO - mozversion application_name: Firefox 11:52:56 INFO - mozversion application_remotingname: firefox 11:52:56 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:52:56 INFO - mozversion application_vendor: Mozilla 11:52:56 INFO - mozversion application_version: 57.0a1 11:52:56 INFO - mozversion platform_buildid: 20170803181106 11:52:56 INFO - mozversion platform_changeset: 36ad88e6b7b248c2f2ae59b80477e5474dd653dc 11:52:56 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:52:56 INFO - mozversion platform_version: 57.0a1 11:52:56 INFO - using testdate: 1501786376 11:52:56 INFO - actual date: 1501786376 11:52:56 INFO - starting webserver on 'localhost:46914' 11:52:56 INFO - SUITE-START | Running 2 tests 11:52:56 INFO - TEST-START | dromaeo_css 11:52:56 INFO - Initialising browser for dromaeo_css test... 11:52:56 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:46914/getInfo.html -profile /tmp/tmpY9Jt_0/profile 11:52:56 INFO - TEST-INFO | started process 3351 (/builds/slave/test/build/application/firefox/firefox http://localhost:46914/getInfo.html) 11:53:05 INFO - TEST-INFO | 3351: exit 0 11:53:05 INFO - Browser initialized. 11:53:05 INFO - Running cycle 1/1 for dromaeo_css test... 11:53:05 INFO - TEST-INFO | started process 3576 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpY9Jt_0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 11:53:06 INFO - PID 3576 | 11:53:06 INFO - PID 3576 | (/builds/slave/test/build/application/firefox/firefox:3630): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 11:53:06 INFO - PID 3576 | 11:53:16 INFO - PID 3576 | RSS: Main: 174227456 11:53:16 INFO - PID 3576 | 11:56:16 INFO - PID 3576 | Cycle 1(1): loaded http://localhost:46914/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46914/tests/dromaeo/cssquery-ext.html) 11:56:17 INFO - PID 3576 | RSS: Main: 175910912 11:56:17 INFO - PID 3576 | 11:59:19 INFO - PID 3576 | Cycle 1(1): loaded http://localhost:46914/tests/dromaeo/cssquery-ext.html (next: http://localhost:46914/tests/dromaeo/cssquery-jquery.html) 11:59:19 INFO - PID 3576 | RSS: Main: 172027904 11:59:19 INFO - PID 3576 | 12:02:21 INFO - PID 3576 | Cycle 1(1): loaded http://localhost:46914/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46914/tests/dromaeo/cssquery-mootools.html) 12:02:22 INFO - PID 3576 | RSS: Main: 175382528 12:02:22 INFO - PID 3576 | 12:05:24 INFO - PID 3576 | Cycle 1(1): loaded http://localhost:46914/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46914/tests/dromaeo/cssquery-prototype.html) 12:05:24 INFO - PID 3576 | RSS: Main: 174026752 12:05:24 INFO - PID 3576 | 12:08:28 INFO - PID 3576 | Cycle 1(1): loaded http://localhost:46914/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46914/tests/dromaeo/cssquery-yui.html) 12:08:29 INFO - PID 3576 | RSS: Main: 177287168 12:08:29 INFO - PID 3576 | 12:11:31 INFO - PID 3576 | Cycle 1(1): loaded http://localhost:46914/tests/dromaeo/cssquery-yui.html (next: http://localhost:46914/tests/dromaeo/cssquery-dojo.html) 12:11:31 INFO - PID 3576 | RSS: Main: 177909760 12:11:31 INFO - PID 3576 | 12:11:31 INFO - PID 3576 | __start_tp_report 12:11:31 INFO - PID 3576 | _x_x_mozilla_page_load 12:11:31 INFO - PID 3576 | _x_x_mozilla_page_load_details 12:11:31 INFO - PID 3576 | |i|pagename|runs| 12:11:31 INFO - PID 3576 | |0;dojo.html;3112.315290636059;3118.8596513156904;3158.652548219696;3170.540271660609;3198.152489590259;6108.572399932006;6194.814155575329;6237.157983672203;6301.117843501914;6349.333320001402;6254.43710066094;6289.811305660837;6327.968360158193;6334.049892516114;6384.616922984613;5629.183768353586;5659.207710920475;5747.453991870766;5759.366469688331;5807.912881306784;5598.048331783586;5725.255716756816;5746.195532625436;5748;5764.5676574257095;5607.831765047034;5624.100143976964;5677.886442271131;5704.714764261791;5712.1431785232135;5565.9443405565835;5600.803971860965;5657.745401456944;5660.575456840712;5684.317881854163;5613.7193140342815;5615.747291371898;5644.435556444323;5662.320521537401;5664.603477756558;6063.393660633946;6150.938490615082;6154.5384096192965;6155.353687862799;6166.568340216187;6632.83417914556;6699;6712.093867327897;6721.798346049626;6725.09211256479;6663.433608143314;6692.564983276072;6701.966490167567;6758.662066896635;6791.252962174157;6106.938930610726;6166.445019948182;6208.1308616793685;6209;6302.023186406114;6879.415249703831;6924.099867017357;6933.687984040629;6960.617166055916;6972.954056891507;6546.214454265519;6550.246721627074;6633;6635.071090047397;6645.800625981132;6626.237982631963;6631.933680663228;6657.167854018247;6706.564073335315;6729.730810767514;6630.933690663128;6633.170853643294;6679.532432729759;6683.632400217937;6721.66391680414;6569.441597464173;6631.23740769808;6739.191297044386;6741.157355330583;6741.224759152662;5318.631586260226;5320.654157479751;5406.810761623403;5412.945870541323;5416.241726158341;4531.546845315442;4605.194091034123;4608.239640459286;4614.4693360264;4630.536946305342;5774.509166720792;5786.218860453869;5792.507636850914;5803.5357171426185;5818.44724751148;6655.6339401332425;6689.331066893369;6718.596884186964;6746.123004009447;6774.051632771416;5793.362730099686;5853.502452291517;5870.559708021916;5874.647521148745;5891.351951285355;5856.677882716492;5860.383531405829;5930.495907847881;5938.168656388109;5942;6420.422162005442;6482.027695845661;6494.6103233806125;6505.447037001813;6554.213494380705;6629.900551491828;6655.301193374626;6739.427148692416;6768.289329620319;6768.56004359715;6494.285628580947;6561.606303621798;6575.079488871466;6587.308332625005;6592.077109204619;6600.372964568551;6609.438197753245;6696.966515167393;6698.330166983263;6705.8994115087335;6559.311272316338;6638.767643132467;6693;6740.090087838275;6804.795856124324;6497.122888410004;6501.1548498694865;6509.804705858832;6512.934870651422;6524.445422139172;6432.903506447314;6545.37818907203;6561.114249576246;6565;6567.507436942153;6374.968125159345;6377.107204991212;6410.166678331787;6429.58207716497;6508.576942498722;6382.5532212744665;6513.1532900722605;6522.543421960418;6569;6586.913159328848;6305.716242769178;6311.400416960381;6321.778737744157;6322.209723784527;6325.841853953504 12:11:31 INFO - PID 3576 | |1;ext.html;845.3237410071941;881.8809460722802;901.238453506787;905.3843386497183;1170.391396473833;32010.35979280413;42730.35904461431;43814.7809260954;43869.34195987059;44008;44162;44619;44643.553564464346;44726.32910506337;44875.77562112193;7642.465027448095;7792.45452818303;7801.687932482694;7844.333231675315;7851.097123830747;7746.341560967325;7858.764237072897;7891.684332626688;7895.447318687694;7906.367490600738;7410.37011853993;7908.52548847071;7918.049834019926;7918.604069796516;7944.086430060559;7943.523388596674;8022.157673444321;8023.759287221393;8025.357971362277;8026.919730802676;7846.803829904241;7888.763337099839;7922.049354077548;7929.563873986928;7933.167017463141;40886;41119.383209251886;41944.79027604873;42000.57999419997;42140.367894481606;3612.638736126393;3640.199156185635;3659.26814637071;3660.377735784923;3661.7986010769396;33552.32895342104;33585;33649.83175084133;33748.83125584381;34027.149321266916;4909.0672772173175;4915.631327650405;4936.284238785356;4970.6520543561965;4970.801167953277;204890;205312;205845;206770;207238.96380518153;51123.48876511262;51163.232551511755;51216.74391628018;51222.74388628033;51291.23063154056;53071.73464132732;53258;53446.46553534415;53480.73259633755;53591;16337.509874703777;16784.496465106065;16939.830601694073;16952.23714932831;16976.06631635272;13493.797593036112;13976.650583735285;13992.09051411655;14050.227237501831;14052.648683782987;15052.623684407978;15207.163606001775;15245.3901843925;15287.541373758804;15392.384304627689;5998;6012.729427175787;6026.789062382795;6044.727987240584;6180.289266734293;1000.8248556502583;1018.5671089786811;1026.6150193677372;1030.6907927621683;1043.3739756146404;23737.643935340984;23903.282901512983;24085.27744167678;24091.51816963651;24107.39731506691;1122.1079242002627;1126.6563698072002;1130.7060164357163;1140.8288756686563;1170.3796987596588;12436.502539898303;12456.252624842538;12485.687857803627;12583.433745481292;12685.556005539762;234890;235431;235981;236407;236747;48649.02701945941;49328;55952.16071758927;56314;56856.71571642115;1506.5932198306396;1512.3572481695455;1522.2464879884528;1522.436698421569;1527.8395768444375;27806.165815025583;28082.85958570194;28194.2951426216;28731.42537149287;28857.134285971453;47651;48578;48606.513934860195;48625.513744863514;48633.270500941806;215878;216869;216944;217130;217377;37287;37884;37985.81007094947;38215.80892095522;38286.6171338283;44640.10719785651;46172;46336;46511;46996.29505557351;33285.16787080342;33772;34233.65766342404;34362.48456273108;34742.13144671403;33946.66053339534;34051.829740851135;34114.31771364509;34764.65235347614;35232.823835880656;35858.64141358553;37111.6288837119;37130.257394852495;37327.44008839815;37348;32483.512747309283;32819;34867.65132348746;36237;36267.637323626426;25283;25540.744592553838;25569.872150639127;25957.870210648827;26020 12:11:31 INFO - PID 3576 | |2;jquery.html;573.3664300947453;586.548357764521;588.2029849553854;594.9048152295633;598.3926314790489;22755.658665120038;23132.30603081908;23879.64180537289;23895.76104238957;23978.88010559947;24940.75059249407;26856.5971510427;26860.59709104365;26887.32781680459;27027.189184324452;6596.637184954813;6741.359570840762;6741.662916854162;6752.763653272136;6762.357576030269;6220.8444788880415;6482.124913136737;6519.445847103002;6694.029365741964;6751.696173672172;6466.773662921799;6477.611343319392;6479.481641468695;6486.805395838132;6497.545171837974;6477.935220647804;6487.935120648804;6491.610503369813;6499.025146228057;6505;6057.606255593417;6059;6061.787837425713;6078.665673387962;6081.300650425169;27606.033788817294;30421.718371122253;30925.690743092506;31084.844575777202;31432.214194645087;1878.2017642501883;1914.9138288776899;1917.3672688012923;1918.2327069172304;1919.299455698674;19068.33260835864;19190.904045479685;19200.519987000436;19272.61454770911;19377.903110484498;2332.125452955142;2364.4207169243473;2370.5258948210426;2453.202709119543;2456.9631455528183;137601;145096;146421.2678936609;146491;146611;14918.328675209641;15080.321385537678;15095.32071056805;15302.846971530142;15744.212789360487;23562.646560301608;24531.037516998596;24615.876920615283;24616.384590385027;24667.25998220056;10547.525361358757;10612.416317102634;10762.085222755997;10792.946035269773;10888.67333979982;9997.100260976547;10021.348612340174;10025.799484010426;10053.59785608582;10143.797124057477;9593.328467007383;9824;9832.705018849447;9934.056264654846;9944.751381215527;2644.338915271182;2645.708972013077;2717.212008517508;2749.5325794614964;2780.386436749449;960.2701946520555;969.3505351414569;969.3989726369555;977.9804403911882;987.1707765477033;19594.80405195958;19919;19977;20085.39743807688;20413.183472661225;1036.2953191829477;1040.4121671255716;1052.8683914510686;1063.622414043019;1063.6808957312776;11117.666470005914;11163.72090697739;11207.943960280147;11384;11403.372814495116;470098;492459;499543;499693;521258;108185;110064.4496777511;110559;110787;111281.44359278152;1210.588399944041;1219.5792451604182;1248.1013670157472;1249.4065318973596;1252.7181384188666;17477.563060923578;17895.821041789415;18224.908875455538;18412.26350945947;18520.629587408446;52752.736236318575;59153.40846591479;60352.39647603643;61073.38926610677;61987.38012619996;73218.26781732327;73693.631531842;74184;74492;74823;14992;15213.6196595086;15323.46367877119;15331.846681533489;15480.922595386952;24083.75916240815;24138.87930560336;24226.394340141636;24371;24601.753982459948;18886.811131888506;19209.90395048016;19297.807021929602;19325.806741932964;19632.607347852678;15316.923415382851;16805.91597042007;16916;17517.912410437868;17623.471295861145;18060.638787224445;18962.810371896103;19451.61096778028;19703.11335989855;19709;14849.554513364616;15048.473303434328;15066.924665376602;15101;15128.39486420531;17658.205380758154;19163.61672766509;19187.136578854264;19241.90379048096;19289.710654339917 12:11:31 INFO - PID 3576 | |3;mootools.html;1372.087561771422;1425.9928700356497;1430.3205977160849;1436.411071460701;1441.9062760920547;13842.653933651663;14069.015168938178;14504.854951450483;14547.709045819078;14563.199024053672;14649.487267945624;14940.327685254162;14942.10347379158;14942.327595258212;14944.10335379878;2690.811643184973;2758.158761577718;2760.917172484819;2762.737539933713;2765.5575107982727;2649.3906401527565;2687.8252913560655;2728.5497892847657;2730.5085084684747;2732.740389662989;2659.4415172813733;2700.162949485656;2708.4989276983724;2735.39821239327;2748.1480740970364;2578.987105064472;2592.144592284551;2602.1542998525365;2613.5556955317643;2628.8422694638193;2585.0693750249893;2617.476504699068;2619.174959887648;2639.709631940504;2642.1280977277643;13569.118007329493;13916.51292204768;13924.164550127025;13925.930370348084;13929.094608850392;7579.128400233989;7827.295543401121;7876.960615196887;7878.80302992424;7926.722564710265;5057.721825299607;5154.587632989352;5198.194279886624;5269.446708095671;5304.018756530054;1733.9913300433543;1752.5618595351161;1768.602064535482;1796.4341232511847;1805.4764118405778;22951.426214344774;23091;23114;23129.88435057814;23648.290551283488;6356.555041147168;6452.580582262138;6509.251436084816;6535.640539770263;6597.901031484532;14869.553913382615;15277.923610381877;15415.922920385327;15450.9227453862;15457.38170473191;7631;7833.451658383859;7835.569043702537;7845.843083138306;7851.332636725828;7082.610456424843;7107.928920710727;7249.456290778213;7259.854802904018;7291.963540182372;4923.089228492669;5153.510416510426;5183.637145399861;5203.933193695283;5244.973775131176;1496.7904493370936;1501.9474318399023;1510.7507261301982;1527.3050761903307;1539.2996186735008;874.5059041641452;902.3232575568323;911.0525053943957;916.637928018429;923.4920793563648;8276.462029968034;8364.832703346021;8402.915970840213;8418.410711250153;8424.957875210584;728.1007955175354;728.9526180798338;728.9963550182215;737.1080991999634;740.1303468424579;4856.178932900162;5201.141811601119;5376.677399356051;5381.4349493303425;5398.433164517748;18896.71654925177;19524.609507809764;19643.90178049129;19660.803391965896;19736.30922917691;18477.7228341575;19001.334953276844;19249.32627358036;19334;19629.21483140687;10706.518206680717;10972.122230221723;10991.945040274748;10994.615188468366;11000.669979900616;8131.186881311822;8448.873266900879;8548.829023419621;8884.77788055278;8947.59735811877;16937.15314234308;16975.830241697426;17070.658586828442;17088.743668844727;17152.228149733044;17736.556586084935;17745;17746.5563360917;17766.91116544409;17848.643027139125;11508.88491115078;11655.825162622737;11671.474783634587;11688.47401866901;11698.590549330733;12996.350182491027;13095.672608184872;13133.34333283351;13235.139715918434;13298.867011329763;10454.738631534272;10651.840222396826;10664.253502254767;10715.196360272856;10863.565457381615;10196;10334.638287659896;10363.222758293308;10380.896191037993;10569.524371403459;9418.811623767624;9532.189763869872;9623.663171788954;9630.374025688589;9632.95183524078;7131.607761573163;7147.749828755968;7158.821029474305;7176.641167941478;7201.063861698155;6792.592444453348;6940.305969403019;6948.305169483011;7004.299570042955;7018.894716579153 12:11:31 INFO - PID 3576 | |4;prototype.html;497.35344052731415;503.7632611937692;516.251435418643;519.3352508788751;531.7926008856547;6142.447179753821;6274;6291.748330066797;6294.181756371671;6331.4618257448055;5925.911111333334;6160.907586386208;6164.291106522751;6173.567850250484;6211.9068213976825;5006.098902197603;5024.095662780698;5037.118504261749;5040.445550989388;5046.965372098711;5008.824691135811;5021.57316628087;5033.194688849785;5050.611081952456;5054.797808087672;4779.665423420362;4911.435184953741;4944.1594928862;4951.950480495185;4965.131102057144;4931.26031095335;4933.248696712669;4956.132676781567;4958.677685950402;4961.355023846877;4411.514733379326;4424.87605949143;4440.578145076245;4455.240180128831;4459;6028.216331876872;6079.848003799896;6092.080994486722;6111.51107911366;6132;5575.358833734132;5680.943190568124;5684.602077854552;5721;5721.4850663440075;6439.613623182624;6527.379898909596;6576.309487503791;6583.473322134218;6637.1703537057865;4769.284607308931;4789.401324834395;4801.327814106028;4840;4850.199717046683;6874;7014.19336776267;7039.612821294827;7039.648017599099;7049.753258635923;5011.448740638527;5046.873828154252;5053.974730126326;5064.860406408528;5083.2375143727995;5087.949120508822;5117.590592752571;5137.768800403991;5142.046022511497;5147.198480531859;5709.25779648643;5716.628419152742;5721.227634269404;5783.161441590946;5803.332616749043;6487.740490380426;6578.1846529013055;6611.206655201407;6650.86698266032;6718.361755633207;1698.8848883140322;1753.903535305545;1760.10234780263;1767.1959258537336;1787.1421717575377;1157.4444266752007;1181.9657799425502;1443.4154167562153;1463.4219483304034;1473.2339183624456;5724.427557244242;5767.538596912237;5777.826665200051;5783.59514833966;5826.82519524415;6553.344665533408;6593.76921807744;6635.86728265432;6721.663916804238;6770.492213084039;5661.830145095653;5719.542436605062;5754.597178197486;5756.7121643917635;5846.503047241032;3539.469079638075;3566.6789988900605;3573.838502486702;3610.032532269919;3611.3138503684218;6395.936040639534;6539.182602174728;6556.737730490822;6576.605403675795;6589.736410543621;6897.2757860423935;6921.411680007155;6973.895391569131;7013.89479157813;7024.297570243037;6529.706163222666;6558.1802274715665;6558.508111891627;6562.540622156499;6594.736210551524;6676.198856137295;6677.632730199885;6714.462842972648;6776.254611992776;6790.388865002174;6555.2133743951035;6590.5716128451495;6637;6641.800745977628;6649.900251496135;6587.604743715392;6588.2658696642275;6621.867562648624;6627.138471998609;6645.368689974444;5077.238414237894;5090.949090509195;5118.846434606968;5125.718085505333;5136.229565565195;6747.325267473213;6796.864062718817;6804.387605115563;6814.897776533455;6825.419839313516;6540.345965403421;6545.181852268466;6551.475881929529;6552.148220731275;6556;6477.190351206099;6599.53803233769;6625.370589793962;6629.6685165740555;6640.269570347355;6217.564770466024;6480.416762491398;6509.7396104155305;6520.706568204537;6540.574862633914;6341;6525.869482610226;6540.444062254762;6589.901151482827;6601.504887133388;6301.243850737941;6325.335839736946;6330;6394.680265986589;6394.680265986775 12:11:31 INFO - PID 3576 | |5;yui.html;884.3676771108658;909.7134402663165;920.1442658327754;922.2575826459697;922.6678395777515;17695.823041769596;18678;18682.25270989159;18703.71944420833;20565.588688226228;19542.70685939712;19720.112594933227;19725.60548789027;19760;19845.70231446526;1031.2677998620989;1056.3872953686844;1057.1912486947492;1058.4813441413728;1062.0229388962173;1069.2942657845824;1070.9143268538537;1071.282240898596;1071.7106381277051;1072.2762135558505;1018.27193556607;1114;1120.1151090638386;1122.3770807202;1122.7473818390838;1096.1450068946235;1100.2188446203206;1107.0258172807917;1108.1965574958172;1109.1016276815806;798.6925033862005;803.9799005024863;805.0781854776307;805.907320658126;806.0971711682894;18981.145848436714;19074.23703051876;19098.52253693669;19292.614147717108;19916.302929397545;20329.49176270605;20721.68917466239;21250.25624103149;21256.14975400982;21455.892720536453;15771.974821636672;16479.83520164795;16520.09139497327;16616.50150495487;16653.084080375575;2956.7634589233026;2961.422522608092;2962.303858593229;2964.955525667117;2979.2998645318335;121696.39151804273;124175;124356.37821810924;125905;127837.36081319627;16000.679986400206;16176.433824816075;16562.58593535147;16682.499525014027;16688.582785430463;29883.25291867677;30897.073087807403;30908.22729431782;30923.536146957813;30935.69064309328;7698.884516732253;8027.117017128111;8253.380996425292;8644.135586441305;8887.200151986352;4228.1966426378885;4296.591823776736;4299.505556861001;4304.48346198452;4305.203537345601;5002;5045.369328833895;5073.086844367976;5121.4622464641425;5122.590192784568;1022.8033201154667;1033.7570670892335;1045.6026709850207;1046.0899017854363;1051.3271506236015;691.778630838127;704.2042491984025;713.3615414204259;713.4078714666814;718.992810071903;11193.66419007431;12159.756804863853;12452.750944981231;12485.375731213584;12860.678483037998;690.7409721354492;696.811860797592;711.7686751805684;712.4834494991194;713.1976526407791;5526.392096869341;5531.585131115183;5624.072028115315;5638;5643.5203007743985;104244;105053;105607.47196264123;107317.46341268244;108491.45754271335;95218.52390738002;95957;96732.51633741926;97535;100155;78962.81555776668;79068;80414.59792701116;81079.18920810835;83063;10890.836637450602;10910.945445272722;11016.559337626406;11094.556217751522;11253.66239012831;36862;37019;37031.444528332635;37964.810175948944;38845.223095538495;44936.550634494546;45439.77280113578;45710.54289457063;45905.54094459146;46875.765621171675;14989.400423983354;15160.696786063996;15288.312025958636;15297.541073767805;19109.61780764405;20318.984050797695;20523.179072837516;21164.78835211628;21204.78795212028;21310.893445532674;17318.913405432893;17537.210825512626;17841.46475605682;18626.81373186251;20020.59958800845;17472.213750381517;17516.562085947953;17676.646467070845;18269.81730182681;19116.713249300992;17477.82522174762;17672.116394180495;17720.55698607545;19176;19745.40763777089;13294.268815215257;13606.455741770615;13630.04589678713;13712.17726936387;14067.788983165465;12638.810417843924;13184.60446186616;13305.46778128864;13430.798538022133;13651 12:11:31 INFO - PID 3576 | __end_tp_report 12:11:31 INFO - PID 3576 | __start_cc_report 12:11:31 INFO - PID 3576 | _x_x_mozilla_cycle_collect,376 12:11:31 INFO - PID 3576 | __end_cc_report 12:11:31 INFO - PID 3576 | __startTimestamp1501787491253__endTimestamp 12:11:31 INFO - PID 3576 | 12:11:31 INFO - PID 3576 | ------- Summary: start ------- 12:11:31 INFO - PID 3576 | Number of tests: 6 12:11:31 INFO - PID 3576 | 12:11:31 INFO - PID 3576 | [#0] dojo.html Cycles:165 Average:6153.32 Median:6384.62 stddev:736.19 (11.5%) stddev-sans-first:698.73 12:11:31 INFO - PID 3576 | Values: 3112.3 3118.9 3158.7 3170.5 3198.2 6108.6 6194.8 6237.2 6301.1 6349.3 6254.4 6289.8 6328.0 6334.0 6384.6 5629.2 5659.2 5747.5 5759.4 5807.9 5598.0 5725.3 5746.2 5748.0 5764.6 5607.8 5624.1 5677.9 5704.7 5712.1 5565.9 5600.8 5657.7 5660.6 5684.3 5613.7 5615.7 5644.4 5662.3 5664.6 6063.4 6150.9 6154.5 6155.4 6166.6 6632.8 6699.0 6712.1 6721.8 6725.1 6663.4 6692.6 6702.0 6758.7 6791.3 6106.9 6166.4 6208.1 6209.0 6302.0 6879.4 6924.1 6933.7 6960.6 6973.0 6546.2 6550.2 6633.0 6635.1 6645.8 6626.2 6631.9 6657.2 6706.6 6729.7 6630.9 6633.2 6679.5 6683.6 6721.7 6569.4 6631.2 6739.2 6741.2 6741.2 5318.6 5320.7 5406.8 5412.9 5416.2 4531.5 4605.2 4608.2 4614.5 4630.5 5774.5 5786.2 5792.5 5803.5 5818.4 6655.6 6689.3 6718.6 6746.1 6774.1 5793.4 5853.5 5870.6 5874.6 5891.4 5856.7 5860.4 5930.5 5938.2 5942.0 6420.4 6482.0 6494.6 6505.4 6554.2 6629.9 6655.3 6739.4 6768.3 6768.6 6494.3 6561.6 6575.1 6587.3 6592.1 6600.4 6609.4 6697.0 6698.3 6705.9 6559.3 6638.8 6693.0 6740.1 6804.8 6497.1 6501.2 6509.8 6512.9 6524.4 6432.9 6545.4 6561.1 6565.0 6567.5 6375.0 6377.1 6410.2 6429.6 6508.6 6382.6 6513.2 6522.5 6569.0 6586.9 6305.7 6311.4 6321.8 6322.2 6325.8 12:11:31 INFO - PID 3576 | 12:11:31 INFO - PID 3576 | [#1] ext.html Cycles:180 Average:40153.94 Median:3659.82 stddev:56946.72 (1556.0%) stddev-sans-first:57029.98 12:11:31 INFO - PID 3576 | Values: 845.3 881.9 901.2 905.4 1170.4 32010.4 42730.4 43814.8 43869.3 44008.0 44162.0 44619.0 44643.6 44726.3 44875.8 7642.5 7792.5 7801.7 7844.3 7851.1 7746.3 7858.8 7891.7 7895.4 7906.4 7410.4 7908.5 7918.0 7918.6 7944.1 7943.5 8022.2 8023.8 8025.4 8026.9 7846.8 7888.8 7922.0 7929.6 7933.2 40886.0 41119.4 41944.8 42000.6 42140.4 3612.6 3640.2 3659.3 3660.4 3661.8 33552.3 33585.0 33649.8 33748.8 34027.1 4909.1 4915.6 4936.3 4970.7 4970.8 204890.0 205312.0 205845.0 206770.0 207239.0 51123.5 51163.2 51216.7 51222.7 51291.2 53071.7 53258.0 53446.5 53480.7 53591.0 16337.5 16784.5 16939.8 16952.2 16976.1 13493.8 13976.7 13992.1 14050.2 14052.6 15052.6 15207.2 15245.4 15287.5 15392.4 5998.0 6012.7 6026.8 6044.7 6180.3 1000.8 1018.6 1026.6 1030.7 1043.4 23737.6 23903.3 24085.3 24091.5 24107.4 1122.1 1126.7 1130.7 1140.8 1170.4 12436.5 12456.3 12485.7 12583.4 12685.6 234890.0 235431.0 235981.0 236407.0 236747.0 48649.0 49328.0 55952.2 56314.0 56856.7 1506.6 1512.4 1522.2 1522.4 1527.8 27806.2 28082.9 28194.3 28731.4 28857.1 47651.0 48578.0 48606.5 48625.5 48633.3 215878.0 216869.0 216944.0 217130.0 217377.0 37287.0 37884.0 37985.8 38215.8 38286.6 44640.1 46172.0 46336.0 46511.0 46996.3 33285.2 33772.0 34233.7 34362.5 34742.1 33946.7 34051.8 34114.3 34764.7 35232.8 35858.6 37111.6 37130.3 37327.4 37348.0 32483.5 32819.0 34867.7 36237.0 36267.6 25283.0 25540.7 25569.9 25957.9 26020.0 12:11:31 INFO - PID 3576 | 12:11:31 INFO - PID 3576 | [#2] jquery.html Cycles:180 Average:35429.30 Median:12732.22 stddev:83717.31 (657.5%) stddev-sans-first:83911.26 12:11:31 INFO - PID 3576 | Values: 573.4 586.5 588.2 594.9 598.4 22755.7 23132.3 23879.6 23895.8 23978.9 24940.8 26856.6 26860.6 26887.3 27027.2 6596.6 6741.4 6741.7 6752.8 6762.4 6220.8 6482.1 6519.4 6694.0 6751.7 6466.8 6477.6 6479.5 6486.8 6497.5 6477.9 6487.9 6491.6 6499.0 6505.0 6057.6 6059.0 6061.8 6078.7 6081.3 27606.0 30421.7 30925.7 31084.8 31432.2 1878.2 1914.9 1917.4 1918.2 1919.3 19068.3 19190.9 19200.5 19272.6 19377.9 2332.1 2364.4 2370.5 2453.2 2457.0 137601.0 145096.0 146421.3 146491.0 146611.0 14918.3 15080.3 15095.3 15302.8 15744.2 23562.6 24531.0 24615.9 24616.4 24667.3 10547.5 10612.4 10762.1 10792.9 10888.7 9997.1 10021.3 10025.8 10053.6 10143.8 9593.3 9824.0 9832.7 9934.1 9944.8 2644.3 2645.7 2717.2 2749.5 2780.4 960.3 969.4 969.4 978.0 987.2 19594.8 19919.0 19977.0 20085.4 20413.2 1036.3 1040.4 1052.9 1063.6 1063.7 11117.7 11163.7 11207.9 11384.0 11403.4 470098.0 492459.0 499543.0 499693.0 521258.0 108185.0 110064.4 110559.0 110787.0 111281.4 1210.6 1219.6 1248.1 1249.4 1252.7 17477.6 17895.8 18224.9 18412.3 18520.6 52752.7 59153.4 60352.4 61073.4 61987.4 73218.3 73693.6 74184.0 74492.0 74823.0 14992.0 15213.6 15323.5 15331.8 15480.9 24083.8 24138.9 24226.4 24371.0 24601.8 18886.8 19209.9 19297.8 19325.8 19632.6 15316.9 16805.9 16916.0 17517.9 17623.5 18060.6 18962.8 19451.6 19703.1 19709.0 14849.6 15048.5 15066.9 15101.0 15128.4 17658.2 19163.6 19187.1 19241.9 19289.7 12:11:31 INFO - PID 3576 | 12:11:31 INFO - PID 3576 | [#3] mootools.html Cycles:180 Average:8805.53 Median:2618.33 stddev:5973.67 (228.1%) stddev-sans-first:5964.32 12:11:31 INFO - PID 3576 | Values: 1372.1 1426.0 1430.3 1436.4 1441.9 13842.7 14069.0 14504.9 14547.7 14563.2 14649.5 14940.3 14942.1 14942.3 14944.1 2690.8 2758.2 2760.9 2762.7 2765.6 2649.4 2687.8 2728.5 2730.5 2732.7 2659.4 2700.2 2708.5 2735.4 2748.1 2579.0 2592.1 2602.2 2613.6 2628.8 2585.1 2617.5 2619.2 2639.7 2642.1 13569.1 13916.5 13924.2 13925.9 13929.1 7579.1 7827.3 7877.0 7878.8 7926.7 5057.7 5154.6 5198.2 5269.4 5304.0 1734.0 1752.6 1768.6 1796.4 1805.5 22951.4 23091.0 23114.0 23129.9 23648.3 6356.6 6452.6 6509.3 6535.6 6597.9 14869.6 15277.9 15415.9 15450.9 15457.4 7631.0 7833.5 7835.6 7845.8 7851.3 7082.6 7107.9 7249.5 7259.9 7292.0 4923.1 5153.5 5183.6 5203.9 5245.0 1496.8 1501.9 1510.8 1527.3 1539.3 874.5 902.3 911.1 916.6 923.5 8276.5 8364.8 8402.9 8418.4 8425.0 728.1 729.0 729.0 737.1 740.1 4856.2 5201.1 5376.7 5381.4 5398.4 18896.7 19524.6 19643.9 19660.8 19736.3 18477.7 19001.3 19249.3 19334.0 19629.2 10706.5 10972.1 10991.9 10994.6 11000.7 8131.2 8448.9 8548.8 8884.8 8947.6 16937.2 16975.8 17070.7 17088.7 17152.2 17736.6 17745.0 17746.6 17766.9 17848.6 11508.9 11655.8 11671.5 11688.5 11698.6 12996.4 13095.7 13133.3 13235.1 13298.9 10454.7 10651.8 10664.3 10715.2 10863.6 10196.0 10334.6 10363.2 10380.9 10569.5 9418.8 9532.2 9623.7 9630.4 9633.0 7131.6 7147.7 7158.8 7176.6 7201.1 6792.6 6940.3 6948.3 7004.3 7018.9 12:11:31 INFO - PID 3576 | 12:11:31 INFO - PID 3576 | [#4] prototype.html Cycles:180 Average:5532.79 Median:6137.22 stddev:1546.26 (25.2%) stddev-sans-first:1503.70 12:11:31 INFO - PID 3576 | Values: 497.4 503.8 516.3 519.3 531.8 6142.4 6274.0 6291.7 6294.2 6331.5 5925.9 6160.9 6164.3 6173.6 6211.9 5006.1 5024.1 5037.1 5040.4 5047.0 5008.8 5021.6 5033.2 5050.6 5054.8 4779.7 4911.4 4944.2 4952.0 4965.1 4931.3 4933.2 4956.1 4958.7 4961.4 4411.5 4424.9 4440.6 4455.2 4459.0 6028.2 6079.8 6092.1 6111.5 6132.0 5575.4 5680.9 5684.6 5721.0 5721.5 6439.6 6527.4 6576.3 6583.5 6637.2 4769.3 4789.4 4801.3 4840.0 4850.2 6874.0 7014.2 7039.6 7039.6 7049.8 5011.4 5046.9 5054.0 5064.9 5083.2 5087.9 5117.6 5137.8 5142.0 5147.2 5709.3 5716.6 5721.2 5783.2 5803.3 6487.7 6578.2 6611.2 6650.9 6718.4 1698.9 1753.9 1760.1 1767.2 1787.1 1157.4 1182.0 1443.4 1463.4 1473.2 5724.4 5767.5 5777.8 5783.6 5826.8 6553.3 6593.8 6635.9 6721.7 6770.5 5661.8 5719.5 5754.6 5756.7 5846.5 3539.5 3566.7 3573.8 3610.0 3611.3 6395.9 6539.2 6556.7 6576.6 6589.7 6897.3 6921.4 6973.9 7013.9 7024.3 6529.7 6558.2 6558.5 6562.5 6594.7 6676.2 6677.6 6714.5 6776.3 6790.4 6555.2 6590.6 6637.0 6641.8 6649.9 6587.6 6588.3 6621.9 6627.1 6645.4 5077.2 5090.9 5118.8 5125.7 5136.2 6747.3 6796.9 6804.4 6814.9 6825.4 6540.3 6545.2 6551.5 6552.1 6556.0 6477.2 6599.5 6625.4 6629.7 6640.3 6217.6 6480.4 6509.7 6520.7 6540.6 6341.0 6525.9 6540.4 6589.9 6601.5 6301.2 6325.3 6330.0 6394.7 6394.7 12:11:31 INFO - PID 3576 | 12:11:31 INFO - PID 3576 | [#5] yui.html Cycles:180 Average:22519.08 Median:19234.31 stddev:30610.57 (159.1%) stddev-sans-first:30653.33 12:11:31 INFO - PID 3576 | Values: 884.4 909.7 920.1 922.3 922.7 17695.8 18678.0 18682.3 18703.7 20565.6 19542.7 19720.1 19725.6 19760.0 19845.7 1031.3 1056.4 1057.2 1058.5 1062.0 1069.3 1070.9 1071.3 1071.7 1072.3 1018.3 1114.0 1120.1 1122.4 1122.7 1096.1 1100.2 1107.0 1108.2 1109.1 798.7 804.0 805.1 805.9 806.1 18981.1 19074.2 19098.5 19292.6 19916.3 20329.5 20721.7 21250.3 21256.1 21455.9 15772.0 16479.8 16520.1 16616.5 16653.1 2956.8 2961.4 2962.3 2965.0 2979.3 121696.4 124175.0 124356.4 125905.0 127837.4 16000.7 16176.4 16562.6 16682.5 16688.6 29883.3 30897.1 30908.2 30923.5 30935.7 7698.9 8027.1 8253.4 8644.1 8887.2 4228.2 4296.6 4299.5 4304.5 4305.2 5002.0 5045.4 5073.1 5121.5 5122.6 1022.8 1033.8 1045.6 1046.1 1051.3 691.8 704.2 713.4 713.4 719.0 11193.7 12159.8 12452.8 12485.4 12860.7 690.7 696.8 711.8 712.5 713.2 5526.4 5531.6 5624.1 5638.0 5643.5 104244.0 105053.0 105607.5 107317.5 108491.5 95218.5 95957.0 96732.5 97535.0 100155.0 78962.8 79068.0 80414.6 81079.2 83063.0 10890.8 10910.9 11016.6 11094.6 11253.7 36862.0 37019.0 37031.4 37964.8 38845.2 44936.6 45439.8 45710.5 45905.5 46875.8 14989.4 15160.7 15288.3 15297.5 19109.6 20319.0 20523.2 21164.8 21204.8 21310.9 17318.9 17537.2 17841.5 18626.8 20020.6 17472.2 17516.6 17676.6 18269.8 19116.7 17477.8 17672.1 17720.6 19176.0 19745.4 13294.3 13606.5 13630.0 13712.2 14067.8 12638.8 13184.6 13305.5 13430.8 13651.0 12:11:31 INFO - PID 3576 | -------- Summary: end -------- 12:11:31 INFO - PID 3576 | 12:11:31 INFO - TEST-INFO | 3576: exit 0 12:11:31 INFO - TEST-OK | dromaeo_css | took 1115479ms 12:11:31 INFO - TEST-START | kraken 12:11:31 INFO - Initialising browser for kraken test... 12:11:31 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:46914/getInfo.html -profile /tmp/tmpCFWXd0/profile 12:11:31 INFO - TEST-INFO | started process 3954 (/builds/slave/test/build/application/firefox/firefox http://localhost:46914/getInfo.html) 12:11:38 INFO - TEST-INFO | 3954: exit 0 12:11:38 INFO - Browser initialized. 12:11:38 INFO - Running cycle 1/1 for kraken test... 12:11:38 INFO - TEST-INFO | started process 4182 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCFWXd0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 12:11:39 INFO - PID 4182 | 12:11:39 INFO - PID 4182 | (/builds/slave/test/build/application/firefox/firefox:4236): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 12:11:39 INFO - PID 4182 | 12:11:49 INFO - PID 4182 | RSS: Main: 174260224 12:11:49 INFO - PID 4182 | 12:11:52 INFO - PID 4182 | Cycle 1(1): loaded http://localhost:46914/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46914/tests/kraken/driver.html?testName=audio-beat-detection) 12:11:53 INFO - PID 4182 | RSS: Main: 179269632 12:11:53 INFO - PID 4182 | 12:11:55 INFO - PID 4182 | Cycle 1(1): loaded http://localhost:46914/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46914/tests/kraken/driver.html?testName=audio-dft) 12:11:56 INFO - PID 4182 | RSS: Main: 179224576 12:11:56 INFO - PID 4182 | 12:11:59 INFO - PID 4182 | Cycle 1(1): loaded http://localhost:46914/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46914/tests/kraken/driver.html?testName=audio-fft) 12:12:00 INFO - PID 4182 | RSS: Main: 174632960 12:12:00 INFO - PID 4182 | 12:12:02 INFO - PID 4182 | Cycle 1(1): loaded http://localhost:46914/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46914/tests/kraken/driver.html?testName=audio-oscillator) 12:12:03 INFO - PID 4182 | RSS: Main: 175108096 12:12:03 INFO - PID 4182 | 12:12:05 INFO - PID 4182 | Cycle 1(1): loaded http://localhost:46914/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46914/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:12:05 INFO - PID 4182 | RSS: Main: 175067136 12:12:05 INFO - PID 4182 | 12:12:09 INFO - PID 4182 | Cycle 1(1): loaded http://localhost:46914/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46914/tests/kraken/driver.html?testName=imaging-darkroom) 12:12:09 INFO - PID 4182 | RSS: Main: 177831936 12:12:09 INFO - PID 4182 | 12:12:13 INFO - PID 4182 | Cycle 1(1): loaded http://localhost:46914/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46914/tests/kraken/driver.html?testName=imaging-desaturate) 12:12:13 INFO - PID 4182 | RSS: Main: 177684480 12:12:13 INFO - PID 4182 | 12:12:16 INFO - PID 4182 | Cycle 1(1): loaded http://localhost:46914/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46914/tests/kraken/driver.html?testName=json-parse-financial) 12:12:17 INFO - PID 4182 | RSS: Main: 178544640 12:12:17 INFO - PID 4182 | 12:12:19 INFO - PID 4182 | Cycle 1(1): loaded http://localhost:46914/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46914/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:12:19 INFO - PID 4182 | RSS: Main: 178495488 12:12:19 INFO - PID 4182 | 12:12:21 INFO - PID 4182 | Cycle 1(1): loaded http://localhost:46914/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46914/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:12:22 INFO - PID 4182 | RSS: Main: 175751168 12:12:22 INFO - PID 4182 | 12:12:24 INFO - PID 4182 | Cycle 1(1): loaded http://localhost:46914/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46914/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:12:25 INFO - PID 4182 | RSS: Main: 175722496 12:12:25 INFO - PID 4182 | 12:12:27 INFO - PID 4182 | Cycle 1(1): loaded http://localhost:46914/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46914/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:12:28 INFO - PID 4182 | RSS: Main: 176214016 12:12:28 INFO - PID 4182 | 12:12:31 INFO - PID 4182 | Cycle 1(1): loaded http://localhost:46914/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46914/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:12:32 INFO - PID 4182 | RSS: Main: 176173056 12:12:32 INFO - PID 4182 | 12:12:34 INFO - PID 4182 | Cycle 1(1): loaded http://localhost:46914/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46914/tests/kraken/driver.html?testName=ai-astar) 12:12:34 INFO - PID 4182 | RSS: Main: 177487872 12:12:34 INFO - PID 4182 | 12:12:34 INFO - PID 4182 | __start_tp_report 12:12:34 INFO - PID 4182 | _x_x_mozilla_page_load 12:12:34 INFO - PID 4182 | _x_x_mozilla_page_load_details 12:12:34 INFO - PID 4182 | |i|pagename|runs| 12:12:34 INFO - PID 4182 | |0;ai-astar;110;118;102;109;108;112;110;99;110;109 12:12:34 INFO - PID 4182 | |1;audio-beat-detection;152;117;144;178;122;164;153;117;166;121 12:12:34 INFO - PID 4182 | |2;audio-dft;166;163;164;163;164;170;163;166;163;163 12:12:34 INFO - PID 4182 | |3;audio-fft;81;74;74;174;77;82;78;75;161;75 12:12:34 INFO - PID 4182 | |4;audio-oscillator;84;85;140;79;79;78;122;79;79;128 12:12:34 INFO - PID 4182 | |5;imaging-gaussian-blur;119;114;113;115;113;117;115;116;114;115 12:12:34 INFO - PID 4182 | |6;imaging-darkroom;154;151;150;151;151;151;153;152;151;151 12:12:34 INFO - PID 4182 | |7;imaging-desaturate;95;94;99;98;94;94;95;95;94;94 12:12:34 INFO - PID 4182 | |8;json-parse-financial;58;58;55;62;56;56;60;55;60;56 12:12:34 INFO - PID 4182 | |9;json-stringify-tinderbox;53;53;54;53;56;54;57;54;61;53 12:12:34 INFO - PID 4182 | |10;stanford-crypto-aes;82;78;77;88;80;96;86;84;78;76 12:12:34 INFO - PID 4182 | |11;stanford-crypto-ccm;142;145;130;140;129;124;129;124;130;163 12:12:34 INFO - PID 4182 | |12;stanford-crypto-pbkdf2;177;176;180;193;177;181;175;178;176;178 12:12:34 INFO - PID 4182 | |13;stanford-crypto-sha256-iterative;67;73;68;70;69;69;73;66;68;72 12:12:34 INFO - PID 4182 | __end_tp_report 12:12:34 INFO - PID 4182 | __start_cc_report 12:12:34 INFO - PID 4182 | _x_x_mozilla_cycle_collect,966 12:12:34 INFO - PID 4182 | __end_cc_report 12:12:34 INFO - PID 4182 | __startTimestamp1501787554569__endTimestamp 12:12:34 INFO - PID 4182 | 12:12:34 INFO - PID 4182 | ------- Summary: start ------- 12:12:34 INFO - PID 4182 | Number of tests: 14 12:12:34 INFO - PID 4182 | 12:12:34 INFO - PID 4182 | [#0] ai-astar Cycles:10 Average:108.70 Median:110.00 stddev:5.19 (4.7%) stddev-sans-first:5.48 12:12:34 INFO - PID 4182 | Values: 110.0 118.0 102.0 109.0 108.0 112.0 110.0 99.0 110.0 109.0 12:12:34 INFO - PID 4182 | 12:12:34 INFO - PID 4182 | [#1] audio-beat-detection Cycles:10 Average:143.40 Median:152.50 stddev:22.74 (14.9%) stddev-sans-first:23.90 12:12:34 INFO - PID 4182 | Values: 152.0 117.0 144.0 178.0 122.0 164.0 153.0 117.0 166.0 121.0 12:12:34 INFO - PID 4182 | 12:12:34 INFO - PID 4182 | [#2] audio-dft Cycles:10 Average:164.50 Median:164.00 stddev:2.27 (1.4%) stddev-sans-first:2.35 12:12:34 INFO - PID 4182 | Values: 166.0 163.0 164.0 163.0 164.0 170.0 163.0 166.0 163.0 163.0 12:12:34 INFO - PID 4182 | 12:12:34 INFO - PID 4182 | [#3] audio-fft Cycles:10 Average:95.10 Median:76.00 stddev:38.38 (50.5%) stddev-sans-first:40.37 12:12:34 INFO - PID 4182 | Values: 81.0 74.0 74.0 174.0 77.0 82.0 78.0 75.0 161.0 75.0 12:12:34 INFO - PID 4182 | 12:12:34 INFO - PID 4182 | [#4] audio-oscillator Cycles:10 Average:95.30 Median:79.00 stddev:24.44 (30.9%) stddev-sans-first:25.58 12:12:34 INFO - PID 4182 | Values: 84.0 85.0 140.0 79.0 79.0 78.0 122.0 79.0 79.0 128.0 12:12:34 INFO - PID 4182 | 12:12:34 INFO - PID 4182 | [#5] imaging-gaussian-blur Cycles:10 Average:115.10 Median:115.00 stddev:1.85 (1.6%) stddev-sans-first:1.32 12:12:34 INFO - PID 4182 | Values: 119.0 114.0 113.0 115.0 113.0 117.0 115.0 116.0 114.0 115.0 12:12:34 INFO - PID 4182 | 12:12:34 INFO - PID 4182 | [#6] imaging-darkroom Cycles:10 Average:151.50 Median:151.00 stddev:1.18 (0.8%) stddev-sans-first:0.83 12:12:34 INFO - PID 4182 | Values: 154.0 151.0 150.0 151.0 151.0 151.0 153.0 152.0 151.0 151.0 12:12:34 INFO - PID 4182 | 12:12:34 INFO - PID 4182 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:1.81 (1.9%) stddev-sans-first:1.92 12:12:34 INFO - PID 4182 | Values: 95.0 94.0 99.0 98.0 94.0 94.0 95.0 95.0 94.0 94.0 12:12:34 INFO - PID 4182 | 12:12:34 INFO - PID 4182 | [#8] json-parse-financial Cycles:10 Average:57.60 Median:58.00 stddev:2.41 (4.2%) stddev-sans-first:2.55 12:12:34 INFO - PID 4182 | Values: 58.0 58.0 55.0 62.0 56.0 56.0 60.0 55.0 60.0 56.0 12:12:34 INFO - PID 4182 | 12:12:34 INFO - PID 4182 | [#9] json-stringify-tinderbox Cycles:10 Average:54.80 Median:54.00 stddev:2.57 (4.8%) stddev-sans-first:2.65 12:12:34 INFO - PID 4182 | Values: 53.0 53.0 54.0 53.0 56.0 54.0 57.0 54.0 61.0 53.0 12:12:34 INFO - PID 4182 | 12:12:34 INFO - PID 4182 | [#10] stanford-crypto-aes Cycles:10 Average:82.50 Median:83.00 stddev:6.20 (7.5%) stddev-sans-first:6.58 12:12:34 INFO - PID 4182 | Values: 82.0 78.0 77.0 88.0 80.0 96.0 86.0 84.0 78.0 76.0 12:12:34 INFO - PID 4182 | 12:12:34 INFO - PID 4182 | [#11] stanford-crypto-ccm Cycles:10 Average:135.60 Median:135.00 stddev:12.10 (9.0%) stddev-sans-first:12.61 12:12:34 INFO - PID 4182 | Values: 142.0 145.0 130.0 140.0 129.0 124.0 129.0 124.0 130.0 163.0 12:12:34 INFO - PID 4182 | 12:12:34 INFO - PID 4182 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.10 Median:178.00 stddev:5.22 (2.9%) stddev-sans-first:5.48 12:12:34 INFO - PID 4182 | Values: 177.0 176.0 180.0 193.0 177.0 181.0 175.0 178.0 176.0 178.0 12:12:34 INFO - PID 4182 | 12:12:34 INFO - PID 4182 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.50 Median:69.50 stddev:2.46 (3.5%) stddev-sans-first:2.44 12:12:34 INFO - PID 4182 | Values: 67.0 73.0 68.0 70.0 69.0 69.0 73.0 66.0 68.0 72.0 12:12:34 INFO - PID 4182 | -------- Summary: end -------- 12:12:34 INFO - PID 4182 | 12:12:34 INFO - PID 4182 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 12:12:35 INFO - TEST-INFO | 4182: exit 0 12:12:35 INFO - TEST-OK | kraken | took 63325ms 12:12:35 INFO - SUITE-END | took 1178s 12:12:35 INFO - Completed test suite (00:19:39) 12:12:35 INFO - javascript benchmark 12:12:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8560.267920262637, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3112.315290636059, 3118.8596513156904, 3158.652548219696, 3170.540271660609, 3198.152489590259, 6108.572399932006, 6194.814155575329, 6237.157983672203, 6301.117843501914, 6349.333320001402, 6254.43710066094, 6289.811305660837, 6327.968360158193, 6334.049892516114, 6384.616922984613, 5629.183768353586, 5659.207710920475, 5747.453991870766, 5759.366469688331, 5807.912881306784, 5598.048331783586, 5725.255716756816, 5746.195532625436, 5748.0, 5764.5676574257095, 5607.831765047034, 5624.100143976964, 5677.886442271131, 5704.714764261791, 5712.1431785232135, 5565.9443405565835, 5600.803971860965, 5657.745401456944, 5660.575456840712, 5684.317881854163, 5613.7193140342815, 5615.747291371898, 5644.435556444323, 5662.320521537401, 5664.603477756558, 6063.393660633946, 6150.938490615082, 6154.5384096192965, 6155.353687862799, 6166.568340216187, 6632.83417914556, 6699.0, 6712.093867327897, 6721.798346049626, 6725.09211256479, 6663.433608143314, 6692.564983276072, 6701.966490167567, 6758.662066896635, 6791.252962174157, 6106.938930610726, 6166.445019948182, 6208.1308616793685, 6209.0, 6302.023186406114, 6879.415249703831, 6924.099867017357, 6933.687984040629, 6960.617166055916, 6972.954056891507, 6546.214454265519, 6550.246721627074, 6633.0, 6635.071090047397, 6645.800625981132, 6626.237982631963, 6631.933680663228, 6657.167854018247, 6706.564073335315, 6729.730810767514, 6630.933690663128, 6633.170853643294, 6679.532432729759, 6683.632400217937, 6721.66391680414, 6569.441597464173, 6631.23740769808, 6739.191297044386, 6741.157355330583, 6741.224759152662, 5318.631586260226, 5320.654157479751, 5406.810761623403, 5412.945870541323, 5416.241726158341, 4531.546845315442, 4605.194091034123, 4608.239640459286, 4614.4693360264, 4630.536946305342, 5774.509166720792, 5786.218860453869, 5792.507636850914, 5803.5357171426185, 5818.44724751148, 6655.6339401332425, 6689.331066893369, 6718.596884186964, 6746.123004009447, 6774.051632771416, 5793.362730099686, 5853.502452291517, 5870.559708021916, 5874.647521148745, 5891.351951285355, 5856.677882716492, 5860.383531405829, 5930.495907847881, 5938.168656388109, 5942.0, 6420.422162005442, 6482.027695845661, 6494.6103233806125, 6505.447037001813, 6554.213494380705, 6629.900551491828, 6655.301193374626, 6739.427148692416, 6768.289329620319, 6768.56004359715, 6494.285628580947, 6561.606303621798, 6575.079488871466, 6587.308332625005, 6592.077109204619, 6600.372964568551, 6609.438197753245, 6696.966515167393, 6698.330166983263, 6705.8994115087335, 6559.311272316338, 6638.767643132467, 6693.0, 6740.090087838275, 6804.795856124324, 6497.122888410004, 6501.1548498694865, 6509.804705858832, 6512.934870651422, 6524.445422139172, 6432.903506447314, 6545.37818907203, 6561.114249576246, 6565.0, 6567.507436942153, 6374.968125159345, 6377.107204991212, 6410.166678331787, 6429.58207716497, 6508.576942498722, 6382.5532212744665, 6513.1532900722605, 6522.543421960418, 6569.0, 6586.913159328848, 6305.716242769178, 6311.400416960381, 6321.778737744157, 6322.209723784527, 6325.841853953504], "value": 6097.3943877467755, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [845.3237410071941, 881.8809460722802, 901.238453506787, 905.3843386497183, 1170.391396473833, 32010.35979280413, 42730.35904461431, 43814.7809260954, 43869.34195987059, 44008.0, 44162.0, 44619.0, 44643.553564464346, 44726.32910506337, 44875.77562112193, 7642.465027448095, 7792.45452818303, 7801.687932482694, 7844.333231675315, 7851.097123830747, 7746.341560967325, 7858.764237072897, 7891.684332626688, 7895.447318687694, 7906.367490600738, 7410.37011853993, 7908.52548847071, 7918.049834019926, 7918.604069796516, 7944.086430060559, 7943.523388596674, 8022.157673444321, 8023.759287221393, 8025.357971362277, 8026.919730802676, 7846.803829904241, 7888.763337099839, 7922.049354077548, 7929.563873986928, 7933.167017463141, 40886.0, 41119.383209251886, 41944.79027604873, 42000.57999419997, 42140.367894481606, 3612.638736126393, 3640.199156185635, 3659.26814637071, 3660.377735784923, 3661.7986010769396, 33552.32895342104, 33585.0, 33649.83175084133, 33748.83125584381, 34027.149321266916, 4909.0672772173175, 4915.631327650405, 4936.284238785356, 4970.6520543561965, 4970.801167953277, 204890.0, 205312.0, 205845.0, 206770.0, 207238.96380518153, 51123.48876511262, 51163.232551511755, 51216.74391628018, 51222.74388628033, 51291.23063154056, 53071.73464132732, 53258.0, 53446.46553534415, 53480.73259633755, 53591.0, 16337.509874703777, 16784.496465106065, 16939.830601694073, 16952.23714932831, 16976.06631635272, 13493.797593036112, 13976.650583735285, 13992.09051411655, 14050.227237501831, 14052.648683782987, 15052.623684407978, 15207.163606001775, 15245.3901843925, 15287.541373758804, 15392.384304627689, 5998.0, 6012.729427175787, 6026.789062382795, 6044.727987240584, 6180.289266734293, 1000.8248556502583, 1018.5671089786811, 1026.6150193677372, 1030.6907927621683, 1043.3739756146404, 23737.643935340984, 23903.282901512983, 24085.27744167678, 24091.51816963651, 24107.39731506691, 1122.1079242002627, 1126.6563698072002, 1130.7060164357163, 1140.8288756686563, 1170.3796987596588, 12436.502539898303, 12456.252624842538, 12485.687857803627, 12583.433745481292, 12685.556005539762, 234890.0, 235431.0, 235981.0, 236407.0, 236747.0, 48649.02701945941, 49328.0, 55952.16071758927, 56314.0, 56856.71571642115, 1506.5932198306396, 1512.3572481695455, 1522.2464879884528, 1522.436698421569, 1527.8395768444375, 27806.165815025583, 28082.85958570194, 28194.2951426216, 28731.42537149287, 28857.134285971453, 47651.0, 48578.0, 48606.513934860195, 48625.513744863514, 48633.270500941806, 215878.0, 216869.0, 216944.0, 217130.0, 217377.0, 37287.0, 37884.0, 37985.81007094947, 38215.80892095522, 38286.6171338283, 44640.10719785651, 46172.0, 46336.0, 46511.0, 46996.29505557351, 33285.16787080342, 33772.0, 34233.65766342404, 34362.48456273108, 34742.13144671403, 33946.66053339534, 34051.829740851135, 34114.31771364509, 34764.65235347614, 35232.823835880656, 35858.64141358553, 37111.6288837119, 37130.257394852495, 37327.44008839815, 37348.0, 32483.512747309283, 32819.0, 34867.65132348746, 36237.0, 36267.637323626426, 25283.0, 25540.744592553838, 25569.872150639127, 25957.870210648827, 26020.0], "value": 18168.814866469762, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [573.3664300947453, 586.548357764521, 588.2029849553854, 594.9048152295633, 598.3926314790489, 22755.658665120038, 23132.30603081908, 23879.64180537289, 23895.76104238957, 23978.88010559947, 24940.75059249407, 26856.5971510427, 26860.59709104365, 26887.32781680459, 27027.189184324452, 6596.637184954813, 6741.359570840762, 6741.662916854162, 6752.763653272136, 6762.357576030269, 6220.8444788880415, 6482.124913136737, 6519.445847103002, 6694.029365741964, 6751.696173672172, 6466.773662921799, 6477.611343319392, 6479.481641468695, 6486.805395838132, 6497.545171837974, 6477.935220647804, 6487.935120648804, 6491.610503369813, 6499.025146228057, 6505.0, 6057.606255593417, 6059.0, 6061.787837425713, 6078.665673387962, 6081.300650425169, 27606.033788817294, 30421.718371122253, 30925.690743092506, 31084.844575777202, 31432.214194645087, 1878.2017642501883, 1914.9138288776899, 1917.3672688012923, 1918.2327069172304, 1919.299455698674, 19068.33260835864, 19190.904045479685, 19200.519987000436, 19272.61454770911, 19377.903110484498, 2332.125452955142, 2364.4207169243473, 2370.5258948210426, 2453.202709119543, 2456.9631455528183, 137601.0, 145096.0, 146421.2678936609, 146491.0, 146611.0, 14918.328675209641, 15080.321385537678, 15095.32071056805, 15302.846971530142, 15744.212789360487, 23562.646560301608, 24531.037516998596, 24615.876920615283, 24616.384590385027, 24667.25998220056, 10547.525361358757, 10612.416317102634, 10762.085222755997, 10792.946035269773, 10888.67333979982, 9997.100260976547, 10021.348612340174, 10025.799484010426, 10053.59785608582, 10143.797124057477, 9593.328467007383, 9824.0, 9832.705018849447, 9934.056264654846, 9944.751381215527, 2644.338915271182, 2645.708972013077, 2717.212008517508, 2749.5325794614964, 2780.386436749449, 960.2701946520555, 969.3505351414569, 969.3989726369555, 977.9804403911882, 987.1707765477033, 19594.80405195958, 19919.0, 19977.0, 20085.39743807688, 20413.183472661225, 1036.2953191829477, 1040.4121671255716, 1052.8683914510686, 1063.622414043019, 1063.6808957312776, 11117.666470005914, 11163.72090697739, 11207.943960280147, 11384.0, 11403.372814495116, 470098.0, 492459.0, 499543.0, 499693.0, 521258.0, 108185.0, 110064.4496777511, 110559.0, 110787.0, 111281.44359278152, 1210.588399944041, 1219.5792451604182, 1248.1013670157472, 1249.4065318973596, 1252.7181384188666, 17477.563060923578, 17895.821041789415, 18224.908875455538, 18412.26350945947, 18520.629587408446, 52752.736236318575, 59153.40846591479, 60352.39647603643, 61073.38926610677, 61987.38012619996, 73218.26781732327, 73693.631531842, 74184.0, 74492.0, 74823.0, 14992.0, 15213.6196595086, 15323.46367877119, 15331.846681533489, 15480.922595386952, 24083.75916240815, 24138.87930560336, 24226.394340141636, 24371.0, 24601.753982459948, 18886.811131888506, 19209.90395048016, 19297.807021929602, 19325.806741932964, 19632.607347852678, 15316.923415382851, 16805.91597042007, 16916.0, 17517.912410437868, 17623.471295861145, 18060.638787224445, 18962.810371896103, 19451.61096778028, 19703.11335989855, 19709.0, 14849.554513364616, 15048.473303434328, 15066.924665376602, 15101.0, 15128.39486420531, 17658.205380758154, 19163.61672766509, 19187.136578854264, 19241.90379048096, 19289.710654339917], "value": 12221.76549965109, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1372.087561771422, 1425.9928700356497, 1430.3205977160849, 1436.411071460701, 1441.9062760920547, 13842.653933651663, 14069.015168938178, 14504.854951450483, 14547.709045819078, 14563.199024053672, 14649.487267945624, 14940.327685254162, 14942.10347379158, 14942.327595258212, 14944.10335379878, 2690.811643184973, 2758.158761577718, 2760.917172484819, 2762.737539933713, 2765.5575107982727, 2649.3906401527565, 2687.8252913560655, 2728.5497892847657, 2730.5085084684747, 2732.740389662989, 2659.4415172813733, 2700.162949485656, 2708.4989276983724, 2735.39821239327, 2748.1480740970364, 2578.987105064472, 2592.144592284551, 2602.1542998525365, 2613.5556955317643, 2628.8422694638193, 2585.0693750249893, 2617.476504699068, 2619.174959887648, 2639.709631940504, 2642.1280977277643, 13569.118007329493, 13916.51292204768, 13924.164550127025, 13925.930370348084, 13929.094608850392, 7579.128400233989, 7827.295543401121, 7876.960615196887, 7878.80302992424, 7926.722564710265, 5057.721825299607, 5154.587632989352, 5198.194279886624, 5269.446708095671, 5304.018756530054, 1733.9913300433543, 1752.5618595351161, 1768.602064535482, 1796.4341232511847, 1805.4764118405778, 22951.426214344774, 23091.0, 23114.0, 23129.88435057814, 23648.290551283488, 6356.555041147168, 6452.580582262138, 6509.251436084816, 6535.640539770263, 6597.901031484532, 14869.553913382615, 15277.923610381877, 15415.922920385327, 15450.9227453862, 15457.38170473191, 7631.0, 7833.451658383859, 7835.569043702537, 7845.843083138306, 7851.332636725828, 7082.610456424843, 7107.928920710727, 7249.456290778213, 7259.854802904018, 7291.963540182372, 4923.089228492669, 5153.510416510426, 5183.637145399861, 5203.933193695283, 5244.973775131176, 1496.7904493370936, 1501.9474318399023, 1510.7507261301982, 1527.3050761903307, 1539.2996186735008, 874.5059041641452, 902.3232575568323, 911.0525053943957, 916.637928018429, 923.4920793563648, 8276.462029968034, 8364.832703346021, 8402.915970840213, 8418.410711250153, 8424.957875210584, 728.1007955175354, 728.9526180798338, 728.9963550182215, 737.1080991999634, 740.1303468424579, 4856.178932900162, 5201.141811601119, 5376.677399356051, 5381.4349493303425, 5398.433164517748, 18896.71654925177, 19524.609507809764, 19643.90178049129, 19660.803391965896, 19736.30922917691, 18477.7228341575, 19001.334953276844, 19249.32627358036, 19334.0, 19629.21483140687, 10706.518206680717, 10972.122230221723, 10991.945040274748, 10994.615188468366, 11000.669979900616, 8131.186881311822, 8448.873266900879, 8548.829023419621, 8884.77788055278, 8947.59735811877, 16937.15314234308, 16975.830241697426, 17070.658586828442, 17088.743668844727, 17152.228149733044, 17736.556586084935, 17745.0, 17746.5563360917, 17766.91116544409, 17848.643027139125, 11508.88491115078, 11655.825162622737, 11671.474783634587, 11688.47401866901, 11698.590549330733, 12996.350182491027, 13095.672608184872, 13133.34333283351, 13235.139715918434, 13298.867011329763, 10454.738631534272, 10651.840222396826, 10664.253502254767, 10715.196360272856, 10863.565457381615, 10196.0, 10334.638287659896, 10363.222758293308, 10380.896191037993, 10569.524371403459, 9418.811623767624, 9532.189763869872, 9623.663171788954, 9630.374025688589, 9632.95183524078, 7131.607761573163, 7147.749828755968, 7158.821029474305, 7176.641167941478, 7201.063861698155, 6792.592444453348, 6940.305969403019, 6948.305169483011, 7004.299570042955, 7018.894716579153], "value": 6398.536569160355, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [497.35344052731415, 503.7632611937692, 516.251435418643, 519.3352508788751, 531.7926008856547, 6142.447179753821, 6274.0, 6291.748330066797, 6294.181756371671, 6331.4618257448055, 5925.911111333334, 6160.907586386208, 6164.291106522751, 6173.567850250484, 6211.9068213976825, 5006.098902197603, 5024.095662780698, 5037.118504261749, 5040.445550989388, 5046.965372098711, 5008.824691135811, 5021.57316628087, 5033.194688849785, 5050.611081952456, 5054.797808087672, 4779.665423420362, 4911.435184953741, 4944.1594928862, 4951.950480495185, 4965.131102057144, 4931.26031095335, 4933.248696712669, 4956.132676781567, 4958.677685950402, 4961.355023846877, 4411.514733379326, 4424.87605949143, 4440.578145076245, 4455.240180128831, 4459.0, 6028.216331876872, 6079.848003799896, 6092.080994486722, 6111.51107911366, 6132.0, 5575.358833734132, 5680.943190568124, 5684.602077854552, 5721.0, 5721.4850663440075, 6439.613623182624, 6527.379898909596, 6576.309487503791, 6583.473322134218, 6637.1703537057865, 4769.284607308931, 4789.401324834395, 4801.327814106028, 4840.0, 4850.199717046683, 6874.0, 7014.19336776267, 7039.612821294827, 7039.648017599099, 7049.753258635923, 5011.448740638527, 5046.873828154252, 5053.974730126326, 5064.860406408528, 5083.2375143727995, 5087.949120508822, 5117.590592752571, 5137.768800403991, 5142.046022511497, 5147.198480531859, 5709.25779648643, 5716.628419152742, 5721.227634269404, 5783.161441590946, 5803.332616749043, 6487.740490380426, 6578.1846529013055, 6611.206655201407, 6650.86698266032, 6718.361755633207, 1698.8848883140322, 1753.903535305545, 1760.10234780263, 1767.1959258537336, 1787.1421717575377, 1157.4444266752007, 1181.9657799425502, 1443.4154167562153, 1463.4219483304034, 1473.2339183624456, 5724.427557244242, 5767.538596912237, 5777.826665200051, 5783.59514833966, 5826.82519524415, 6553.344665533408, 6593.76921807744, 6635.86728265432, 6721.663916804238, 6770.492213084039, 5661.830145095653, 5719.542436605062, 5754.597178197486, 5756.7121643917635, 5846.503047241032, 3539.469079638075, 3566.6789988900605, 3573.838502486702, 3610.032532269919, 3611.3138503684218, 6395.936040639534, 6539.182602174728, 6556.737730490822, 6576.605403675795, 6589.736410543621, 6897.2757860423935, 6921.411680007155, 6973.895391569131, 7013.89479157813, 7024.297570243037, 6529.706163222666, 6558.1802274715665, 6558.508111891627, 6562.540622156499, 6594.736210551524, 6676.198856137295, 6677.632730199885, 6714.462842972648, 6776.254611992776, 6790.388865002174, 6555.2133743951035, 6590.5716128451495, 6637.0, 6641.800745977628, 6649.900251496135, 6587.604743715392, 6588.2658696642275, 6621.867562648624, 6627.138471998609, 6645.368689974444, 5077.238414237894, 5090.949090509195, 5118.846434606968, 5125.718085505333, 5136.229565565195, 6747.325267473213, 6796.864062718817, 6804.387605115563, 6814.897776533455, 6825.419839313516, 6540.345965403421, 6545.181852268466, 6551.475881929529, 6552.148220731275, 6556.0, 6477.190351206099, 6599.53803233769, 6625.370589793962, 6629.6685165740555, 6640.269570347355, 6217.564770466024, 6480.416762491398, 6509.7396104155305, 6520.706568204537, 6540.574862633914, 6341.0, 6525.869482610226, 6540.444062254762, 6589.901151482827, 6601.504887133388, 6301.243850737941, 6325.335839736946, 6330.0, 6394.680265986589, 6394.680265986775], "value": 5084.409615474487, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [884.3676771108658, 909.7134402663165, 920.1442658327754, 922.2575826459697, 922.6678395777515, 17695.823041769596, 18678.0, 18682.25270989159, 18703.71944420833, 20565.588688226228, 19542.70685939712, 19720.112594933227, 19725.60548789027, 19760.0, 19845.70231446526, 1031.2677998620989, 1056.3872953686844, 1057.1912486947492, 1058.4813441413728, 1062.0229388962173, 1069.2942657845824, 1070.9143268538537, 1071.282240898596, 1071.7106381277051, 1072.2762135558505, 1018.27193556607, 1114.0, 1120.1151090638386, 1122.3770807202, 1122.7473818390838, 1096.1450068946235, 1100.2188446203206, 1107.0258172807917, 1108.1965574958172, 1109.1016276815806, 798.6925033862005, 803.9799005024863, 805.0781854776307, 805.907320658126, 806.0971711682894, 18981.145848436714, 19074.23703051876, 19098.52253693669, 19292.614147717108, 19916.302929397545, 20329.49176270605, 20721.68917466239, 21250.25624103149, 21256.14975400982, 21455.892720536453, 15771.974821636672, 16479.83520164795, 16520.09139497327, 16616.50150495487, 16653.084080375575, 2956.7634589233026, 2961.422522608092, 2962.303858593229, 2964.955525667117, 2979.2998645318335, 121696.39151804273, 124175.0, 124356.37821810924, 125905.0, 127837.36081319627, 16000.679986400206, 16176.433824816075, 16562.58593535147, 16682.499525014027, 16688.582785430463, 29883.25291867677, 30897.073087807403, 30908.22729431782, 30923.536146957813, 30935.69064309328, 7698.884516732253, 8027.117017128111, 8253.380996425292, 8644.135586441305, 8887.200151986352, 4228.1966426378885, 4296.591823776736, 4299.505556861001, 4304.48346198452, 4305.203537345601, 5002.0, 5045.369328833895, 5073.086844367976, 5121.4622464641425, 5122.590192784568, 1022.8033201154667, 1033.7570670892335, 1045.6026709850207, 1046.0899017854363, 1051.3271506236015, 691.778630838127, 704.2042491984025, 713.3615414204259, 713.4078714666814, 718.992810071903, 11193.66419007431, 12159.756804863853, 12452.750944981231, 12485.375731213584, 12860.678483037998, 690.7409721354492, 696.811860797592, 711.7686751805684, 712.4834494991194, 713.1976526407791, 5526.392096869341, 5531.585131115183, 5624.072028115315, 5638.0, 5643.5203007743985, 104244.0, 105053.0, 105607.47196264123, 107317.46341268244, 108491.45754271335, 95218.52390738002, 95957.0, 96732.51633741926, 97535.0, 100155.0, 78962.81555776668, 79068.0, 80414.59792701116, 81079.18920810835, 83063.0, 10890.836637450602, 10910.945445272722, 11016.559337626406, 11094.556217751522, 11253.66239012831, 36862.0, 37019.0, 37031.444528332635, 37964.810175948944, 38845.223095538495, 44936.550634494546, 45439.77280113578, 45710.54289457063, 45905.54094459146, 46875.765621171675, 14989.400423983354, 15160.696786063996, 15288.312025958636, 15297.541073767805, 19109.61780764405, 20318.984050797695, 20523.179072837516, 21164.78835211628, 21204.78795212028, 21310.893445532674, 17318.913405432893, 17537.210825512626, 17841.46475605682, 18626.81373186251, 20020.59958800845, 17472.213750381517, 17516.562085947953, 17676.646467070845, 18269.81730182681, 19116.713249300992, 17477.82522174762, 17672.116394180495, 17720.55698607545, 19176.0, 19745.40763777089, 13294.268815215257, 13606.455741770615, 13630.04589678713, 13712.17726936387, 14067.788983165465, 12638.810417843924, 13184.60446186616, 13305.46778128864, 13430.798538022133, 13651.0], "value": 8932.436929910815, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1547.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 118.0, 102.0, 109.0, 108.0, 112.0, 110.0, 99.0, 110.0, 109.0], "value": 108.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 117.0, 144.0, 178.0, 122.0, 164.0, 153.0, 117.0, 166.0, 121.0], "value": 143.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 163.0, 164.0, 163.0, 164.0, 170.0, 163.0, 166.0, 163.0, 163.0], "value": 164.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 74.0, 74.0, 174.0, 77.0, 82.0, 78.0, 75.0, 161.0, 75.0], "value": 95.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 140.0, 79.0, 79.0, 78.0, 122.0, 79.0, 79.0, 128.0], "value": 95.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 114.0, 113.0, 115.0, 113.0, 117.0, 115.0, 116.0, 114.0, 115.0], "value": 115.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 151.0, 150.0, 151.0, 151.0, 151.0, 153.0, 152.0, 151.0, 151.0], "value": 151.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 99.0, 98.0, 94.0, 94.0, 95.0, 95.0, 94.0, 94.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 55.0, 62.0, 56.0, 56.0, 60.0, 55.0, 60.0, 56.0], "value": 57.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 54.0, 53.0, 56.0, 54.0, 57.0, 54.0, 61.0, 53.0], "value": 54.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 78.0, 77.0, 88.0, 80.0, 96.0, 86.0, 84.0, 78.0, 76.0], "value": 82.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 145.0, 130.0, 140.0, 129.0, 124.0, 129.0, 124.0, 130.0, 163.0], "value": 135.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 176.0, 180.0, 193.0, 177.0, 181.0, 175.0, 178.0, 176.0, 178.0], "value": 179.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 73.0, 68.0, 70.0, 69.0, 69.0, 73.0, 66.0, 68.0, 72.0], "value": 69.5, "unit": "score"}]}]} 12:12:35 INFO - Return code: 0 12:12:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:12:35 INFO - # TBPL SUCCESS # 12:12:35 INFO - Running post-action listener: _package_coverage_data 12:12:35 INFO - Running post-action listener: _resource_record_post_action 12:12:35 INFO - [mozharness: 2017-08-03 19:12:35.843036Z] Finished run-tests step (success) 12:12:35 INFO - Running post-run listener: _resource_record_post_run 12:12:35 INFO - Total resource usage - Wall time: 1198s; CPU: 13.0%; Read bytes: 5627904; Write bytes: 443117568; Read time: 2024; Write time: 401184 12:12:35 INFO - TinderboxPrint: CPU usage
13.2% 12:12:35 INFO - TinderboxPrint: I/O read bytes / time
5,627,904 / 2,024 12:12:35 INFO - TinderboxPrint: I/O write bytes / time
443,117,568 / 401,184 12:12:35 INFO - TinderboxPrint: CPU idle
8,291.6 (86.8%) 12:12:35 INFO - TinderboxPrint: CPU user
1,205.3 (12.6%) 12:12:35 INFO - TinderboxPrint: Swap in / out
0 / 0 12:12:35 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 4096; Write bytes: 119242752; Read time: 44; Write time: 104608 12:12:35 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 12:12:35 INFO - run-tests - Wall time: 1180s; CPU: 13.0%; Read bytes: 3264512; Write bytes: 323874816; Read time: 1592; Write time: 296576 12:12:36 INFO - Running post-run listener: _upload_blobber_files 12:12:36 INFO - Blob upload gear active. 12:12:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:12:36 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 12:12:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:12:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:12:36 INFO - (blobuploader) - INFO - Open directory for files ... 12:12:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:12:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:12:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:12:43 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:12:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:12:43 INFO - (blobuploader) - INFO - Done attempting. 12:12:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:12:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:12:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:12:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:12:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:12:44 INFO - (blobuploader) - INFO - Done attempting. 12:12:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:12:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:12:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:12:45 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:12:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:12:45 INFO - (blobuploader) - INFO - Done attempting. 12:12:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:12:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:12:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:12:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:12:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:12:45 INFO - (blobuploader) - INFO - Done attempting. 12:12:45 INFO - (blobuploader) - INFO - Iteration through files over. 12:12:45 INFO - Return code: 0 12:12:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:12:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:12:45 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c3b5ae441eba2ed950421bc5280ede595625b43e6daface246cc34ed69880aebb01f969adb8cc67149fc7893d6f4549cd5746ab3ded104bf4d67f5dbcfc8b1ef", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2cdcc7ae27e9a856cfbc1f72c2819d51a8aec7ad2cadf83355ce12aa70786a0ce81556ef3b65e71ba168aa793a3d2ad61e9ce9e00f1fe608043b0bfc02b3eed7", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d29a5997811213723fd4d7cc4bb85bf8efdb9c1ec46c069f6429fb28835d8644b248a3e984864a331440301919668a78c6a6f31aed7fb9f50ea1d991f540351a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6482d3d89419e41dd3b8db944f12f14a906767a3ecda0877a0b97929ee1f3b660c6fbf123c8c460319012119942f00ec98094c5da17d240a3d5806f7cfdeb86c"} 12:12:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:12:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:12:45 INFO - Contents: 12:12:45 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c3b5ae441eba2ed950421bc5280ede595625b43e6daface246cc34ed69880aebb01f969adb8cc67149fc7893d6f4549cd5746ab3ded104bf4d67f5dbcfc8b1ef", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2cdcc7ae27e9a856cfbc1f72c2819d51a8aec7ad2cadf83355ce12aa70786a0ce81556ef3b65e71ba168aa793a3d2ad61e9ce9e00f1fe608043b0bfc02b3eed7", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d29a5997811213723fd4d7cc4bb85bf8efdb9c1ec46c069f6429fb28835d8644b248a3e984864a331440301919668a78c6a6f31aed7fb9f50ea1d991f540351a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6482d3d89419e41dd3b8db944f12f14a906767a3ecda0877a0b97929ee1f3b660c6fbf123c8c460319012119942f00ec98094c5da17d240a3d5806f7cfdeb86c"} 12:12:45 INFO - Running post-run listener: copy_logs_to_upload_dir 12:12:45 INFO - Copying logs to upload dir... 12:12:45 INFO - mkdir: /builds/slave/test/build/upload/logs 12:12:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.519523 ========= master_lag: 0.53 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-08-03 12:12:46.129584) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-03 12:12:46.133633) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=232498196343f89f466a4a9a000001c8-1501784528.42972-1940684460 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c3b5ae441eba2ed950421bc5280ede595625b43e6daface246cc34ed69880aebb01f969adb8cc67149fc7893d6f4549cd5746ab3ded104bf4d67f5dbcfc8b1ef", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2cdcc7ae27e9a856cfbc1f72c2819d51a8aec7ad2cadf83355ce12aa70786a0ce81556ef3b65e71ba168aa793a3d2ad61e9ce9e00f1fe608043b0bfc02b3eed7", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d29a5997811213723fd4d7cc4bb85bf8efdb9c1ec46c069f6429fb28835d8644b248a3e984864a331440301919668a78c6a6f31aed7fb9f50ea1d991f540351a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6482d3d89419e41dd3b8db944f12f14a906767a3ecda0877a0b97929ee1f3b660c6fbf123c8c460319012119942f00ec98094c5da17d240a3d5806f7cfdeb86c"} build_url:https://queue.taskcluster.net/v1/task/cLOXij6DRK-49sIx-uH2bw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.009956 build_url: 'https://queue.taskcluster.net/v1/task/cLOXij6DRK-49sIx-uH2bw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c3b5ae441eba2ed950421bc5280ede595625b43e6daface246cc34ed69880aebb01f969adb8cc67149fc7893d6f4549cd5746ab3ded104bf4d67f5dbcfc8b1ef", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2cdcc7ae27e9a856cfbc1f72c2819d51a8aec7ad2cadf83355ce12aa70786a0ce81556ef3b65e71ba168aa793a3d2ad61e9ce9e00f1fe608043b0bfc02b3eed7", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d29a5997811213723fd4d7cc4bb85bf8efdb9c1ec46c069f6429fb28835d8644b248a3e984864a331440301919668a78c6a6f31aed7fb9f50ea1d991f540351a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6482d3d89419e41dd3b8db944f12f14a906767a3ecda0877a0b97929ee1f3b660c6fbf123c8c460319012119942f00ec98094c5da17d240a3d5806f7cfdeb86c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-08-03 12:12:46.168557) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-03 12:12:46.168977) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=232498196343f89f466a4a9a000001c8-1501784528.42972-1940684460 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004479 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-08-03 12:12:46.227225) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-03 12:12:46.227533) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-08-03 12:12:46.227818) ========= ========= Total master_lag: 0.69 =========