builder: graphics_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-005 starttime: 1482524145.12 results: success (0) buildid: 20161223102637 builduid: e36686c1208045e5ab4375b601329c2a revision: a731b493e8c74008b3a63abaaf7da822122de793 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-23 12:15:45.116760) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-23 12:15:45.117274) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-23 12:15:45.117565) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-23 12:15:45.168767) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 12:15:45.169075) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1482522865.672488-1587695568 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-23 12:15:45-- 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% 23.9M=0s 2016-12-23 12:15:45 (23.9 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.073496 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 12:15:45.256395) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 12:15:45.259833) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1482522865.672488-1587695568 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015065 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 12:15:45.309139) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 12:15:45.312551) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev a731b493e8c74008b3a63abaaf7da822122de793 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/graphics --rev a731b493e8c74008b3a63abaaf7da822122de793 --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=7fc86f70bae03e018da0b1d8000001c4-1482522865.672488-1587695568 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-23 12:15:45,770 truncating revision to first 12 chars 2016-12-23 12:15:45,770 Setting DEBUG logging. 2016-12-23 12:15:45,770 attempt 1/10 2016-12-23 12:15:45,770 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/a731b493e8c7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-23 12:15:46,084 unpacking tar archive at: graphics-a731b493e8c7/testing/mozharness/ program finished with exit code 0 elapsedTime=0.624967 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 12:15:45.961039) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-23 12:15:45.961357) ========= script_repo_revision: a731b493e8c74008b3a63abaaf7da822122de793 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-23 12:15:45.961752) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-23 12:15:45.962016) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-23 12:15:45.975816) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-12-23 12:15:45.976142) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Graphics --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 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', 'Graphics', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] 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=7fc86f70bae03e018da0b1d8000001c4-1482522865.672488-1587695568 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:15:46 INFO - MultiFileLogger online at 20161223 12:15:46 in /builds/slave/test 12:15:46 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Graphics --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 12:15:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:15:46 INFO - {'append_to_log': False, 12:15:46 INFO - 'base_work_dir': '/builds/slave/test', 12:15:46 INFO - 'blob_upload_branch': 'Graphics', 12:15:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:15:46 INFO - 'branch': 'Graphics', 12:15:46 INFO - 'buildbot_json_path': 'buildprops.json', 12:15:46 INFO - 'config_files': ('talos/linux_config.py',), 12:15:46 INFO - 'default_actions': ('clobber', 12:15:46 INFO - 'read-buildbot-config', 12:15:46 INFO - 'download-and-extract', 12:15:46 INFO - 'populate-webroot', 12:15:46 INFO - 'create-virtualenv', 12:15:46 INFO - 'install', 12:15:46 INFO - 'run-tests'), 12:15:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:15:46 INFO - 'download_minidump_stackwalk': True, 12:15:46 INFO - 'download_symbols': 'ondemand', 12:15:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:15:46 INFO - 'tooltool.py': '/tools/tooltool.py', 12:15:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:15:46 INFO - '/tools/misc-python/virtualenv.py')}, 12:15:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:15:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:15:46 INFO - 'installer_path': 'installer.exe', 12:15:46 INFO - 'log_level': 'info', 12:15:46 INFO - 'log_name': 'talos', 12:15:46 INFO - 'log_to_console': True, 12:15:46 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:15:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:15:46 INFO - 'opt_config_files': (), 12:15:46 INFO - 'pip_index': False, 12:15:46 INFO - 'sps_profile': False, 12:15:46 INFO - 'sps_profile_interval': 0, 12:15:46 INFO - 'suite': 'dromaeojs-e10s', 12:15:46 INFO - 'system_bits': '32', 12:15:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:15:46 INFO - 'title': 'talos-linux64-ix-005', 12:15:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:15:46 INFO - 'use_talos_json': True, 12:15:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:15:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:15:46 INFO - 'work_dir': 'build'} 12:15:46 INFO - [mozharness: 2016-12-23 20:15:46.537177Z] Running clobber step. 12:15:46 INFO - Running pre-action listener: _resource_record_pre_action 12:15:46 INFO - Running main action method: clobber 12:15:46 INFO - rmtree: /builds/slave/test/build 12:15:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:15:47 INFO - Running post-action listener: _resource_record_post_action 12:15:47 INFO - [mozharness: 2016-12-23 20:15:47.524902Z] Finished clobber step (success) 12:15:47 INFO - [mozharness: 2016-12-23 20:15:47.525000Z] Running read-buildbot-config step. 12:15:47 INFO - Running pre-action listener: _resource_record_pre_action 12:15:47 INFO - Running main action method: read_buildbot_config 12:15:47 INFO - Using buildbot properties: 12:15:47 INFO - { 12:15:47 INFO - "project": "", 12:15:47 INFO - "product": "firefox", 12:15:47 INFO - "script_repo_revision": "default", 12:15:47 INFO - "builddir": "graphics_ubuntu64_hw_test-dromaeojs-e10s", 12:15:47 INFO - "repository": "", 12:15:47 INFO - "buildername": "Ubuntu HW 12.04 x64 graphics talos dromaeojs-e10s", 12:15:47 INFO - "buildid": "20161223102637", 12:15:47 INFO - "pgo_build": "False", 12:15:47 INFO - "basedir": "/builds/slave/test", 12:15:47 INFO - "buildnumber": 38, 12:15:47 INFO - "slavename": "talos-linux64-ix-005", 12:15:47 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 12:15:47 INFO - "platform": "ubuntu64_hw", 12:15:47 INFO - "scheduler": "tests-graphics-linux64-talos-8-3600", 12:15:47 INFO - "branch": "graphics", 12:15:47 INFO - "repo_path": "projects/graphics", 12:15:47 INFO - "revision": "a731b493e8c74008b3a63abaaf7da822122de793", 12:15:47 INFO - "stage_platform": "linux64", 12:15:47 INFO - "builduid": "e36686c1208045e5ab4375b601329c2a", 12:15:47 INFO - "slavebuilddir": "test" 12:15:47 INFO - } 12:15:47 INFO - Found installer url https://queue.taskcluster.net/v1/task/PhhrtpKkQ9yTnzc8Hs_mGA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 12:15:47 INFO - Running post-action listener: _resource_record_post_action 12:15:47 INFO - [mozharness: 2016-12-23 20:15:47.527429Z] Finished read-buildbot-config step (success) 12:15:47 INFO - [mozharness: 2016-12-23 20:15:47.527519Z] Running download-and-extract step. 12:15:47 INFO - Running pre-action listener: _resource_record_pre_action 12:15:47 INFO - Running main action method: download_and_extract 12:15:47 INFO - mkdir: /builds/slave/test/build/tests 12:15:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 12:15:47 INFO - trying https://queue.taskcluster.net/v1/task/PhhrtpKkQ9yTnzc8Hs_mGA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 12:15:47 INFO - Downloading https://queue.taskcluster.net/v1/task/PhhrtpKkQ9yTnzc8Hs_mGA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 12:15:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PhhrtpKkQ9yTnzc8Hs_mGA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 12:15:48 INFO - Downloaded 1361 bytes. 12:15:48 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 12:15:48 INFO - Using the following test package requirements: 12:15:48 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 12:15:48 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 12:15:48 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 12:15:48 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 12:15:48 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 12:15:48 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 12:15:48 INFO - u'jsshell-linux-x86_64.zip'], 12:15:48 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 12:15:48 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 12:15:48 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 12:15:48 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 12:15:48 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 12:15:48 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 12:15:48 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 12:15:48 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 12:15:48 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 12:15:48 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 12:15:48 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 12:15:48 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 12:15:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PhhrtpKkQ9yTnzc8Hs_mGA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 12:15:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PhhrtpKkQ9yTnzc8Hs_mGA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 12:15:48 INFO - Fetch https://queue.taskcluster.net/v1/task/PhhrtpKkQ9yTnzc8Hs_mGA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 12:15:48 INFO - Expected file size: 20236321 12:15:48 INFO - Obtained file size: 20236321 12:15:52 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 12:15:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PhhrtpKkQ9yTnzc8Hs_mGA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 12:15:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PhhrtpKkQ9yTnzc8Hs_mGA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 12:15:52 INFO - Fetch https://queue.taskcluster.net/v1/task/PhhrtpKkQ9yTnzc8Hs_mGA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 12:15:53 INFO - Expected file size: 20236321 12:15:53 INFO - Obtained file size: 20236321 12:15:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PhhrtpKkQ9yTnzc8Hs_mGA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 12:15:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PhhrtpKkQ9yTnzc8Hs_mGA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 12:15:58 INFO - Fetch https://queue.taskcluster.net/v1/task/PhhrtpKkQ9yTnzc8Hs_mGA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 12:15:58 INFO - Expected file size: 14409776 12:15:58 INFO - Obtained file size: 14409776 12:15:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 12:15:59 INFO - trying https://queue.taskcluster.net/v1/task/PhhrtpKkQ9yTnzc8Hs_mGA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 12:15:59 INFO - Downloading https://queue.taskcluster.net/v1/task/PhhrtpKkQ9yTnzc8Hs_mGA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 12:15:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PhhrtpKkQ9yTnzc8Hs_mGA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 12:16:00 INFO - Downloaded 55928057 bytes. 12:16:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PhhrtpKkQ9yTnzc8Hs_mGA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 12:16:00 INFO - mkdir: /builds/slave/test/properties 12:16:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:16:00 INFO - Writing to file /builds/slave/test/properties/build_url 12:16:00 INFO - Contents: 12:16:00 INFO - build_url:https://queue.taskcluster.net/v1/task/PhhrtpKkQ9yTnzc8Hs_mGA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 12:16:01 INFO - Running post-action listener: _resource_record_post_action 12:16:01 INFO - Running post-action listener: set_extra_try_arguments 12:16:01 INFO - [mozharness: 2016-12-23 20:16:01.049024Z] Finished download-and-extract step (success) 12:16:01 INFO - [mozharness: 2016-12-23 20:16:01.049289Z] Running populate-webroot step. 12:16:01 INFO - Running pre-action listener: _resource_record_pre_action 12:16:01 INFO - Running main action method: populate_webroot 12:16:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:16:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:16:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:16:01 INFO - '--tppagecycles', 12:16:01 INFO - '7'], 12:16:01 INFO - 'tests': ['tsvgm']}}, 12:16:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:16:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:16:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:16:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:16:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:16:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:16:01 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:16:01 INFO - 'talos_options': ['--disable-e10s'], 12:16:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:16:01 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:16:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:16:01 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:16:01 INFO - 'talos_options': ['--disable-e10s'], 12:16:01 INFO - 'tests': ['damp', 'tps']}, 12:16:01 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:16:01 INFO - 'tests': ['damp', 'tps']}, 12:16:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:16:01 INFO - 'tests': ['dromaeo_dom']}, 12:16:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:16:01 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:16:01 INFO - 'tests': ['basic_compositor_video']}, 12:16:01 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:16:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:16:01 INFO - 'tests': ['a11yr', 12:16:01 INFO - 'ts_paint', 12:16:01 INFO - 'tpaint', 12:16:01 INFO - 'sessionrestore', 12:16:01 INFO - 'sessionrestore_no_auto_restore', 12:16:01 INFO - 'tabpaint']}, 12:16:01 INFO - 'other-e10s': {'tests': ['a11yr', 12:16:01 INFO - 'ts_paint', 12:16:01 INFO - 'tpaint', 12:16:01 INFO - 'sessionrestore', 12:16:01 INFO - 'sessionrestore_no_auto_restore', 12:16:01 INFO - 'tabpaint']}, 12:16:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:16:01 INFO - 'ts_paint', 12:16:01 INFO - 'tpaint', 12:16:01 INFO - 'sessionrestore', 12:16:01 INFO - 'sessionrestore_no_auto_restore', 12:16:01 INFO - 'tabpaint']}, 12:16:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:16:01 INFO - 'ts_paint', 12:16:01 INFO - 'tpaint', 12:16:01 INFO - 'sessionrestore', 12:16:01 INFO - 'sessionrestore_no_auto_restore', 12:16:01 INFO - 'tabpaint']}, 12:16:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:16:01 INFO - 'tests': ['a11yr', 12:16:01 INFO - 'ts_paint', 12:16:01 INFO - 'tpaint', 12:16:01 INFO - 'sessionrestore', 12:16:01 INFO - 'sessionrestore_no_auto_restore', 12:16:01 INFO - 'tabpaint']}, 12:16:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:16:01 INFO - 'tests': ['a11yr', 12:16:01 INFO - 'ts_paint', 12:16:01 INFO - 'tpaint', 12:16:01 INFO - 'sessionrestore', 12:16:01 INFO - 'sessionrestore_no_auto_restore', 12:16:01 INFO - 'tabpaint']}, 12:16:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:16:01 INFO - 'tests': ['tsvgx', 12:16:01 INFO - 'tsvgr_opacity', 12:16:01 INFO - 'tart', 12:16:01 INFO - 'tscrollx', 12:16:01 INFO - 'cart', 12:16:01 INFO - 'tsvg_static']}, 12:16:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:16:01 INFO - 'tsvgr_opacity', 12:16:01 INFO - 'tart', 12:16:01 INFO - 'tscrollx', 12:16:01 INFO - 'cart', 12:16:01 INFO - 'tsvg_static']}, 12:16:01 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:16:01 INFO - 'talos_options': ['--disable-e10s'], 12:16:01 INFO - 'tests': ['tp5o']}, 12:16:01 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:16:01 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:16:01 INFO - 'talos_options': ['--disable-e10s', 12:16:01 INFO - '--xperf_path', 12:16:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:16:01 INFO - 'tests': ['tp5n']}, 12:16:01 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:16:01 INFO - 'talos_options': ['--xperf_path', 12:16:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:16:01 INFO - 'tests': ['tp5n']}}, 12:16:01 INFO - 'talos.zip': {'path': '', 12:16:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:16:01 INFO - Running post-action listener: _resource_record_post_action 12:16:01 INFO - [mozharness: 2016-12-23 20:16:01.069737Z] Finished populate-webroot step (success) 12:16:01 INFO - [mozharness: 2016-12-23 20:16:01.069825Z] Running create-virtualenv step. 12:16:01 INFO - Running pre-action listener: _resource_record_pre_action 12:16:01 INFO - Running main action method: create_virtualenv 12:16:01 INFO - Creating virtualenv /builds/slave/test/build/venv 12:16:01 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 12:16:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:16:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:16:01 INFO - Using real prefix '/usr' 12:16:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:16:01 INFO - Installing distribute.............................................................................................................................................................................................done. 12:16:03 INFO - Installing pip.................done. 12:16:03 INFO - Return code: 0 12:16:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:16:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:16:03 INFO - Reading from file tmpfile_stdout 12:16:03 INFO - Output received: 12:16:03 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) 12:16:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:16:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 12:16:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:16:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 12:16:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:16:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16:03 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x15da1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17a6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x194bc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19493f0>, '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 0x1949cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x194a1a0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1482522865.672488-1587695568', '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 12:16:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 12:16:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:16:03 INFO - Using env: {'DISPLAY': ':0', 12:16:03 INFO - 'HOME': '/home/cltbld', 12:16:03 INFO - 'LANG': 'en_US.UTF-8', 12:16:03 INFO - 'LANGUAGE': 'en_US:en', 12:16:03 INFO - 'LOGNAME': 'cltbld', 12:16:03 INFO - 'MAIL': '/var/mail/cltbld', 12:16:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:16:03 INFO - 'MOZ_NO_REMOTE': '1', 12:16:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:16:03 INFO - 'NO_EM_RESTART': '1', 12:16:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:16:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:16:03 INFO - 'PWD': '/builds/slave/test', 12:16:03 INFO - 'SHELL': '/bin/bash', 12:16:03 INFO - 'SHLVL': '1', 12:16:03 INFO - 'TERM': 'linux', 12:16:03 INFO - 'TMOUT': '86400', 12:16:03 INFO - 'USER': 'cltbld', 12:16:03 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1482522865.672488-1587695568', 12:16:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:16:03 INFO - '_': '/tools/buildbot/bin/python'} 12:16:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:16:04 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 12:16:04 INFO - Cleaning up... 12:16:04 INFO - Return code: 0 12:16:04 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:16:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 12:16:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:16:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 12:16:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:16:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16:04 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x15da1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17a6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x194bc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19493f0>, '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 0x1949cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x194a1a0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1482522865.672488-1587695568', '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 12:16:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 12:16:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:16:04 INFO - Using env: {'DISPLAY': ':0', 12:16:04 INFO - 'HOME': '/home/cltbld', 12:16:04 INFO - 'LANG': 'en_US.UTF-8', 12:16:04 INFO - 'LANGUAGE': 'en_US:en', 12:16:04 INFO - 'LOGNAME': 'cltbld', 12:16:04 INFO - 'MAIL': '/var/mail/cltbld', 12:16:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:16:04 INFO - 'MOZ_NO_REMOTE': '1', 12:16:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:16:04 INFO - 'NO_EM_RESTART': '1', 12:16:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:16:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:16:04 INFO - 'PWD': '/builds/slave/test', 12:16:04 INFO - 'SHELL': '/bin/bash', 12:16:04 INFO - 'SHLVL': '1', 12:16:04 INFO - 'TERM': 'linux', 12:16:04 INFO - 'TMOUT': '86400', 12:16:04 INFO - 'USER': 'cltbld', 12:16:04 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1482522865.672488-1587695568', 12:16:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:16:04 INFO - '_': '/tools/buildbot/bin/python'} 12:16:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:16:04 INFO - Downloading/unpacking psutil>=3.1.1 12:16:04 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 12:16:04 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 12:16:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:16:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:16:04 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 12:16:04 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 12:16:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:16:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:16:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:16:06 INFO - Installing collected packages: psutil 12:16:06 INFO - Running setup.py install for psutil 12:16:06 INFO - building 'psutil._psutil_linux' extension 12:16:06 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 12:16:06 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 12:16:06 INFO - building 'psutil._psutil_posix' extension 12:16:06 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 12:16:06 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 12:16:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:16:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:16:07 INFO - Successfully installed psutil 12:16:07 INFO - Cleaning up... 12:16:07 INFO - Return code: 0 12:16:07 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:16:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 12:16:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:16:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 12:16:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:16:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16:07 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x15da1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17a6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x194bc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19493f0>, '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 0x1949cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x194a1a0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1482522865.672488-1587695568', '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 12:16:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 12:16:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 12:16:07 INFO - Using env: {'DISPLAY': ':0', 12:16:07 INFO - 'HOME': '/home/cltbld', 12:16:07 INFO - 'LANG': 'en_US.UTF-8', 12:16:07 INFO - 'LANGUAGE': 'en_US:en', 12:16:07 INFO - 'LOGNAME': 'cltbld', 12:16:07 INFO - 'MAIL': '/var/mail/cltbld', 12:16:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:16:07 INFO - 'MOZ_NO_REMOTE': '1', 12:16:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:16:07 INFO - 'NO_EM_RESTART': '1', 12:16:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:16:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:16:07 INFO - 'PWD': '/builds/slave/test', 12:16:07 INFO - 'SHELL': '/bin/bash', 12:16:07 INFO - 'SHLVL': '1', 12:16:07 INFO - 'TERM': 'linux', 12:16:07 INFO - 'TMOUT': '86400', 12:16:07 INFO - 'USER': 'cltbld', 12:16:07 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1482522865.672488-1587695568', 12:16:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:16:07 INFO - '_': '/tools/buildbot/bin/python'} 12:16:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:16:07 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:16:07 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 12:16:07 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 12:16:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:16:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:16:07 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 12:16:07 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 12:16:09 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:16:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:16:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:16:09 INFO - Installing collected packages: mozsystemmonitor 12:16:09 INFO - Running setup.py install for mozsystemmonitor 12:16:09 INFO - Successfully installed mozsystemmonitor 12:16:09 INFO - Cleaning up... 12:16:09 INFO - Return code: 0 12:16:09 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:16:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 12:16:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:16:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 12:16:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:16:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16:09 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x15da1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17a6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x194bc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19493f0>, '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 0x1949cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x194a1a0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1482522865.672488-1587695568', '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 12:16:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 12:16:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 12:16:09 INFO - Using env: {'DISPLAY': ':0', 12:16:09 INFO - 'HOME': '/home/cltbld', 12:16:09 INFO - 'LANG': 'en_US.UTF-8', 12:16:09 INFO - 'LANGUAGE': 'en_US:en', 12:16:09 INFO - 'LOGNAME': 'cltbld', 12:16:09 INFO - 'MAIL': '/var/mail/cltbld', 12:16:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:16:09 INFO - 'MOZ_NO_REMOTE': '1', 12:16:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:16:09 INFO - 'NO_EM_RESTART': '1', 12:16:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:16:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:16:09 INFO - 'PWD': '/builds/slave/test', 12:16:09 INFO - 'SHELL': '/bin/bash', 12:16:09 INFO - 'SHLVL': '1', 12:16:09 INFO - 'TERM': 'linux', 12:16:09 INFO - 'TMOUT': '86400', 12:16:09 INFO - 'USER': 'cltbld', 12:16:09 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1482522865.672488-1587695568', 12:16:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:16:09 INFO - '_': '/tools/buildbot/bin/python'} 12:16:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:16:09 INFO - Downloading/unpacking jsonschema==2.5.1 12:16:09 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 12:16:09 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 12:16:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:16:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:16:09 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 12:16:09 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 12:16:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:16:11 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:16:11 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 12:16:11 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 12:16:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:16:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:16:11 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 12:16:11 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 12:16:12 INFO - Downloading functools32-3.2.3-2.tar.gz 12:16:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:16:12 INFO - warning: no files found matching '*.txt' 12:16:12 INFO - no previously-included directories found matching 'build' 12:16:12 INFO - no previously-included directories found matching 'dist' 12:16:12 INFO - no previously-included directories found matching '.git*' 12:16:12 INFO - Installing collected packages: jsonschema, functools32 12:16:12 INFO - Running setup.py install for functools32 12:16:12 INFO - warning: no files found matching '*.txt' 12:16:12 INFO - no previously-included directories found matching 'build' 12:16:12 INFO - no previously-included directories found matching 'dist' 12:16:12 INFO - no previously-included directories found matching '.git*' 12:16:12 INFO - Successfully installed jsonschema functools32 12:16:12 INFO - Cleaning up... 12:16:12 INFO - Return code: 0 12:16:12 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:16:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 12:16:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:16:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 12:16:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:16:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16:12 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x15da1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17a6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x194bc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19493f0>, '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 0x1949cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x194a1a0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1482522865.672488-1587695568', '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 12:16:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 12:16:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:16:12 INFO - Using env: {'DISPLAY': ':0', 12:16:12 INFO - 'HOME': '/home/cltbld', 12:16:12 INFO - 'LANG': 'en_US.UTF-8', 12:16:12 INFO - 'LANGUAGE': 'en_US:en', 12:16:12 INFO - 'LOGNAME': 'cltbld', 12:16:12 INFO - 'MAIL': '/var/mail/cltbld', 12:16:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:16:12 INFO - 'MOZ_NO_REMOTE': '1', 12:16:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:16:12 INFO - 'NO_EM_RESTART': '1', 12:16:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:16:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:16:12 INFO - 'PWD': '/builds/slave/test', 12:16:12 INFO - 'SHELL': '/bin/bash', 12:16:12 INFO - 'SHLVL': '1', 12:16:12 INFO - 'TERM': 'linux', 12:16:12 INFO - 'TMOUT': '86400', 12:16:12 INFO - 'USER': 'cltbld', 12:16:12 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1482522865.672488-1587695568', 12:16:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:16:12 INFO - '_': '/tools/buildbot/bin/python'} 12:16:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:16:12 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:16:12 INFO - Cleaning up... 12:16:12 INFO - Return code: 0 12:16:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:16:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 12:16:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:16:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 12:16:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:16:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16:12 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x15da1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17a6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x194bc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19493f0>, '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 0x1949cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x194a1a0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1482522865.672488-1587695568', '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 12:16:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 12:16:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:16:12 INFO - Using env: {'DISPLAY': ':0', 12:16:12 INFO - 'HOME': '/home/cltbld', 12:16:12 INFO - 'LANG': 'en_US.UTF-8', 12:16:12 INFO - 'LANGUAGE': 'en_US:en', 12:16:12 INFO - 'LOGNAME': 'cltbld', 12:16:12 INFO - 'MAIL': '/var/mail/cltbld', 12:16:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:16:12 INFO - 'MOZ_NO_REMOTE': '1', 12:16:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:16:12 INFO - 'NO_EM_RESTART': '1', 12:16:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:16:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:16:12 INFO - 'PWD': '/builds/slave/test', 12:16:12 INFO - 'SHELL': '/bin/bash', 12:16:12 INFO - 'SHLVL': '1', 12:16:12 INFO - 'TERM': 'linux', 12:16:12 INFO - 'TMOUT': '86400', 12:16:12 INFO - 'USER': 'cltbld', 12:16:12 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1482522865.672488-1587695568', 12:16:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:16:12 INFO - '_': '/tools/buildbot/bin/python'} 12:16:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:16:12 INFO - Downloading/unpacking blobuploader==1.2.4 12:16:12 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 12:16:12 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 12:16:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:16:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:16:12 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 12:16:12 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 12:16:14 INFO - Downloading blobuploader-1.2.4.tar.gz 12:16:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:16:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:16:15 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 12:16:15 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 12:16:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:16:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:16:15 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 12:16:15 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 12:16:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:16:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:16:15 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 12:16:15 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 12:16:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:16:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:16:15 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 12:16:15 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 12:16:15 INFO - Downloading docopt-0.6.1.tar.gz 12:16:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:16:15 INFO - Installing collected packages: blobuploader, requests, docopt 12:16:15 INFO - Running setup.py install for blobuploader 12:16:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:16:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:16:15 INFO - Running setup.py install for requests 12:16:16 INFO - Running setup.py install for docopt 12:16:16 INFO - Successfully installed blobuploader requests docopt 12:16:16 INFO - Cleaning up... 12:16:16 INFO - Return code: 0 12:16:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:16:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 12:16:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:16:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 12:16:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:16:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16:16 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x15da1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17a6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x194bc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19493f0>, '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 0x1949cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x194a1a0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1482522865.672488-1587695568', '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 12:16:16 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 12:16:16 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:16:16 INFO - Using env: {'DISPLAY': ':0', 12:16:16 INFO - 'HOME': '/home/cltbld', 12:16:16 INFO - 'LANG': 'en_US.UTF-8', 12:16:16 INFO - 'LANGUAGE': 'en_US:en', 12:16:16 INFO - 'LOGNAME': 'cltbld', 12:16:16 INFO - 'MAIL': '/var/mail/cltbld', 12:16:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:16:16 INFO - 'MOZ_NO_REMOTE': '1', 12:16:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:16:16 INFO - 'NO_EM_RESTART': '1', 12:16:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:16:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:16:16 INFO - 'PWD': '/builds/slave/test', 12:16:16 INFO - 'SHELL': '/bin/bash', 12:16:16 INFO - 'SHLVL': '1', 12:16:16 INFO - 'TERM': 'linux', 12:16:16 INFO - 'TMOUT': '86400', 12:16:16 INFO - 'USER': 'cltbld', 12:16:16 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1482522865.672488-1587695568', 12:16:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:16:16 INFO - '_': '/tools/buildbot/bin/python'} 12:16:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:16:16 INFO - Running setup.py (path:/tmp/pip-oK4iPn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:16:16 INFO - Running setup.py (path:/tmp/pip-0_JPe2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:16:16 INFO - Running setup.py (path:/tmp/pip-szKGBD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:16:16 INFO - Running setup.py (path:/tmp/pip-Xz8jpS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:16:16 INFO - Running setup.py (path:/tmp/pip-RC0CyZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:16:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:16:16 INFO - Running setup.py (path:/tmp/pip-7IML6N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:16:17 INFO - Running setup.py (path:/tmp/pip-7Zzx6j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:16:17 INFO - Running setup.py (path:/tmp/pip-u6DpH6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:16:17 INFO - Running setup.py (path:/tmp/pip-CHc3co-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:16:17 INFO - Running setup.py (path:/tmp/pip-lgL8VN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:16:17 INFO - Running setup.py (path:/tmp/pip-7e9hyx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:16:17 INFO - Running setup.py (path:/tmp/pip-erU53b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:16:17 INFO - Running setup.py (path:/tmp/pip-Ld1U4x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:16:17 INFO - Running setup.py (path:/tmp/pip-CCGqvA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:16:17 INFO - Running setup.py (path:/tmp/pip-MmzOd4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:16:17 INFO - Running setup.py (path:/tmp/pip-F_OYQi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:16:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:16:17 INFO - Running setup.py (path:/tmp/pip-CFH9lO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:16:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:16:17 INFO - Running setup.py install for manifestparser 12:16:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:16:17 INFO - Running setup.py install for mozcrash 12:16:18 INFO - Running setup.py install for mozdebug 12:16:18 INFO - Running setup.py install for mozdevice 12:16:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:16:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:16:18 INFO - Running setup.py install for mozfile 12:16:18 INFO - Running setup.py install for mozhttpd 12:16:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:16:18 INFO - Running setup.py install for mozinfo 12:16:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:16:18 INFO - Running setup.py install for mozInstall 12:16:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:16:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:16:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:16:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:16:18 INFO - Running setup.py install for mozleak 12:16:18 INFO - Running setup.py install for mozlog 12:16:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:16:18 INFO - Running setup.py install for moznetwork 12:16:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:16:18 INFO - Running setup.py install for mozprocess 12:16:19 INFO - Running setup.py install for mozprofile 12:16:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:16:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:16:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:16:19 INFO - Running setup.py install for mozrunner 12:16:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:16:19 INFO - Running setup.py install for mozscreenshot 12:16:19 INFO - Running setup.py install for moztest 12:16:19 INFO - Running setup.py install for mozversion 12:16:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:16:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:16:19 INFO - Cleaning up... 12:16:19 INFO - Return code: 0 12:16:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:16:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 12:16:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:16:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 12:16:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:16:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16:19 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x15da1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17a6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x194bc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19493f0>, '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 0x1949cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x194a1a0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1482522865.672488-1587695568', '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 12:16:19 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 12:16:19 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:16:19 INFO - Using env: {'DISPLAY': ':0', 12:16:19 INFO - 'HOME': '/home/cltbld', 12:16:19 INFO - 'LANG': 'en_US.UTF-8', 12:16:19 INFO - 'LANGUAGE': 'en_US:en', 12:16:19 INFO - 'LOGNAME': 'cltbld', 12:16:19 INFO - 'MAIL': '/var/mail/cltbld', 12:16:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:16:19 INFO - 'MOZ_NO_REMOTE': '1', 12:16:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:16:19 INFO - 'NO_EM_RESTART': '1', 12:16:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:16:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:16:19 INFO - 'PWD': '/builds/slave/test', 12:16:19 INFO - 'SHELL': '/bin/bash', 12:16:19 INFO - 'SHLVL': '1', 12:16:19 INFO - 'TERM': 'linux', 12:16:19 INFO - 'TMOUT': '86400', 12:16:19 INFO - 'USER': 'cltbld', 12:16:19 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1482522865.672488-1587695568', 12:16:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:16:19 INFO - '_': '/tools/buildbot/bin/python'} 12:16:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:16:19 INFO - Running setup.py (path:/tmp/pip-nUb38A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:16:19 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)) 12:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:16:19 INFO - Running setup.py (path:/tmp/pip-WXWrpL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:16:20 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)) 12:16:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:16:20 INFO - Running setup.py (path:/tmp/pip-irGg8s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:16:20 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)) 12:16:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:16:20 INFO - Running setup.py (path:/tmp/pip-SAx_Zo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:16:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 12:16:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:16:20 INFO - Running setup.py (path:/tmp/pip-3aS26f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:16:20 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)) 12:16:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:16:20 INFO - Running setup.py (path:/tmp/pip-qo5E3y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:16:20 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)) 12:16:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:16:20 INFO - Running setup.py (path:/tmp/pip-zQvbPO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:16:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 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)) 12:16:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:16:20 INFO - Running setup.py (path:/tmp/pip-rdHtuF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:16:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 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)) 12:16:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:16:20 INFO - Running setup.py (path:/tmp/pip-VQRSeh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:16:20 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)) 12:16:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:16:20 INFO - Running setup.py (path:/tmp/pip-Wgu6T_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:16:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 12:16:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:16:20 INFO - Running setup.py (path:/tmp/pip-w5U8xF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:16:20 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)) 12:16:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:16:20 INFO - Running setup.py (path:/tmp/pip-n_uGKJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:16:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 12:16:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:16:20 INFO - Running setup.py (path:/tmp/pip-BoN7mg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:16:20 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)) 12:16:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:16:20 INFO - Running setup.py (path:/tmp/pip-wGjAMb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:16:20 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)) 12:16:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:16:20 INFO - Running setup.py (path:/tmp/pip-LX9M5p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:16:20 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)) 12:16:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:16:20 INFO - Running setup.py (path:/tmp/pip-rgMgXP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:16:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 12:16:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:16:21 INFO - Running setup.py (path:/tmp/pip-pLasY_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:16:21 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)) 12:16:21 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)) 12:16:21 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)) 12:16:21 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)) 12:16:21 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:16:21 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:16:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:16:21 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 12:16:21 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 12:16:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:16:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:16:21 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 12:16:21 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 12:16:23 INFO - Downloading blessings-1.6.tar.gz 12:16:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:16:23 INFO - Installing collected packages: blessings 12:16:23 INFO - Running setup.py install for blessings 12:16:23 INFO - Successfully installed blessings 12:16:23 INFO - Cleaning up... 12:16:23 INFO - Return code: 0 12:16:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:16:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:16:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:16:23 INFO - Reading from file tmpfile_stdout 12:16:23 INFO - Current package versions: 12:16:23 INFO - argparse == 1.2.1 12:16:23 INFO - blessings == 1.6 12:16:23 INFO - blobuploader == 1.2.4 12:16:23 INFO - docopt == 0.6.1 12:16:23 INFO - functools32 == 3.2.3-2 12:16:23 INFO - jsonschema == 2.5.1 12:16:23 INFO - manifestparser == 1.1 12:16:23 INFO - mozInstall == 1.12 12:16:23 INFO - mozcrash == 1.0 12:16:23 INFO - mozdebug == 0.1 12:16:23 INFO - mozdevice == 0.49 12:16:23 INFO - mozfile == 1.2 12:16:23 INFO - mozhttpd == 0.7 12:16:23 INFO - mozinfo == 0.9 12:16:23 INFO - mozleak == 0.1 12:16:23 INFO - mozlog == 3.4 12:16:23 INFO - moznetwork == 0.27 12:16:23 INFO - mozprocess == 0.23 12:16:23 INFO - mozprofile == 0.28 12:16:23 INFO - mozrunner == 6.13 12:16:23 INFO - mozscreenshot == 0.1 12:16:23 INFO - mozsystemmonitor == 0.3 12:16:23 INFO - moztest == 0.7 12:16:23 INFO - mozversion == 1.4 12:16:23 INFO - psutil == 3.1.1 12:16:23 INFO - requests == 1.2.3 12:16:23 INFO - wsgiref == 0.1.2 12:16:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:16:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 12:16:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:16:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 12:16:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:16:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16:23 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x15da1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17a6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x194bc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19493f0>, '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 0x1949cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x194a1a0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1482522865.672488-1587695568', '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 12:16:23 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 12:16:23 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:16:23 INFO - Using env: {'DISPLAY': ':0', 12:16:23 INFO - 'HOME': '/home/cltbld', 12:16:23 INFO - 'LANG': 'en_US.UTF-8', 12:16:23 INFO - 'LANGUAGE': 'en_US:en', 12:16:23 INFO - 'LOGNAME': 'cltbld', 12:16:23 INFO - 'MAIL': '/var/mail/cltbld', 12:16:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:16:23 INFO - 'MOZ_NO_REMOTE': '1', 12:16:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:16:23 INFO - 'NO_EM_RESTART': '1', 12:16:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:16:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:16:23 INFO - 'PWD': '/builds/slave/test', 12:16:23 INFO - 'SHELL': '/bin/bash', 12:16:23 INFO - 'SHLVL': '1', 12:16:23 INFO - 'TERM': 'linux', 12:16:23 INFO - 'TMOUT': '86400', 12:16:23 INFO - 'USER': 'cltbld', 12:16:23 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1482522865.672488-1587695568', 12:16:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:16:23 INFO - '_': '/tools/buildbot/bin/python'} 12:16:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:16:23 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)) 12:16:23 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)) 12:16:23 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)) 12:16:23 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)) 12:16:23 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)) 12:16:23 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)) 12:16:23 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)) 12:16:23 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)) 12:16:23 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)) 12:16:23 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)) 12:16:23 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)) 12:16:23 INFO - Cleaning up... 12:16:23 INFO - Return code: 0 12:16:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:16:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 12:16:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:16:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 12:16:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:16:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16:23 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x15da1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17a6a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x194bc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19493f0>, '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 0x1949cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x194a1a0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1482522865.672488-1587695568', '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 12:16:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:16:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:16:23 INFO - Using env: {'DISPLAY': ':0', 12:16:23 INFO - 'HOME': '/home/cltbld', 12:16:23 INFO - 'LANG': 'en_US.UTF-8', 12:16:23 INFO - 'LANGUAGE': 'en_US:en', 12:16:23 INFO - 'LOGNAME': 'cltbld', 12:16:23 INFO - 'MAIL': '/var/mail/cltbld', 12:16:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:16:23 INFO - 'MOZ_NO_REMOTE': '1', 12:16:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:16:23 INFO - 'NO_EM_RESTART': '1', 12:16:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:16:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:16:23 INFO - 'PWD': '/builds/slave/test', 12:16:23 INFO - 'SHELL': '/bin/bash', 12:16:23 INFO - 'SHLVL': '1', 12:16:23 INFO - 'TERM': 'linux', 12:16:23 INFO - 'TMOUT': '86400', 12:16:23 INFO - 'USER': 'cltbld', 12:16:23 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1482522865.672488-1587695568', 12:16:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:16:23 INFO - '_': '/tools/buildbot/bin/python'} 12:16:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:16:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:16:24 INFO - Cleaning up... 12:16:24 INFO - Return code: 0 12:16:24 INFO - Running post-action listener: _resource_record_post_action 12:16:24 INFO - Running post-action listener: _start_resource_monitoring 12:16:24 INFO - Starting resource monitoring. 12:16:24 INFO - [mozharness: 2016-12-23 20:16:24.116319Z] Finished create-virtualenv step (success) 12:16:24 INFO - [mozharness: 2016-12-23 20:16:24.116792Z] Running install step. 12:16:24 INFO - Running pre-action listener: _resource_record_pre_action 12:16:24 INFO - Running main action method: install 12:16:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:16:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:16:24 INFO - Reading from file tmpfile_stdout 12:16:24 INFO - Detecting whether we're running mozinstall >=1.0... 12:16:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:16:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:16:24 INFO - Reading from file tmpfile_stdout 12:16:24 INFO - Output received: 12:16:24 INFO - Usage: mozinstall [options] installer 12:16:24 INFO - Options: 12:16:24 INFO - -h, --help show this help message and exit 12:16:24 INFO - -d DEST, --destination=DEST 12:16:24 INFO - Directory to install application into. [default: 12:16:24 INFO - "/builds/slave/test"] 12:16:24 INFO - --app=APP Application being installed. [default: firefox] 12:16:24 INFO - mkdir: /builds/slave/test/build/application 12:16:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 12:16:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 12:16:40 INFO - Reading from file tmpfile_stdout 12:16:40 INFO - Output received: 12:16:40 INFO - /builds/slave/test/build/application/firefox/firefox 12:16:40 INFO - Running post-action listener: _resource_record_post_action 12:16:40 INFO - [mozharness: 2016-12-23 20:16:40.537721Z] Finished install step (success) 12:16:40 INFO - [mozharness: 2016-12-23 20:16:40.537965Z] Running run-tests step. 12:16:40 INFO - Running pre-action listener: _resource_record_pre_action 12:16:40 INFO - Running main action method: run_tests 12:16:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:16:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:16:40 INFO - Python 2.7.3 12:16:40 INFO - Return code: 0 12:16:40 INFO - grabbing minidump binary from tooltool 12:16:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 12:16:40 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 0x19493f0>, '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 0x1949cf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x194a1a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:16:40 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 12:16:40 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 12:16:40 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 12:16:40 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:16:40 INFO - Return code: 0 12:16:40 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:16:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:16:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:16:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:16:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:16:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PhhrtpKkQ9yTnzc8Hs_mGA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-005', '--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 12:16:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PhhrtpKkQ9yTnzc8Hs_mGA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-005 --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 12:16:40 INFO - Using env: {'DISPLAY': ':0', 12:16:40 INFO - 'HOME': '/home/cltbld', 12:16:40 INFO - 'LANG': 'en_US.UTF-8', 12:16:40 INFO - 'LANGUAGE': 'en_US:en', 12:16:40 INFO - 'LOGNAME': 'cltbld', 12:16:40 INFO - 'MAIL': '/var/mail/cltbld', 12:16:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:16:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:16:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:16:40 INFO - 'MOZ_NO_REMOTE': '1', 12:16:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:16:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:16:40 INFO - 'NO_EM_RESTART': '1', 12:16:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:16:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:16:40 INFO - 'PWD': '/builds/slave/test', 12:16:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:16:40 INFO - 'SHELL': '/bin/bash', 12:16:40 INFO - 'SHLVL': '1', 12:16:40 INFO - 'TERM': 'linux', 12:16:40 INFO - 'TMOUT': '86400', 12:16:40 INFO - 'USER': 'cltbld', 12:16:40 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1482522865.672488-1587695568', 12:16:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:16:40 INFO - '_': '/tools/buildbot/bin/python'} 12:16:40 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PhhrtpKkQ9yTnzc8Hs_mGA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-005', '--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 12:16:40 INFO - mozversion application_buildid: 20161223102637 12:16:40 INFO - mozversion application_changeset: a731b493e8c74008b3a63abaaf7da822122de793 12:16:40 INFO - mozversion application_display_name: Nightly 12:16:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:16:40 INFO - mozversion application_name: Firefox 12:16:40 INFO - mozversion application_remotingname: firefox 12:16:40 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 12:16:40 INFO - mozversion application_vendor: Mozilla 12:16:40 INFO - mozversion application_version: 53.0a1 12:16:40 INFO - mozversion platform_buildid: 20161223102637 12:16:40 INFO - mozversion platform_changeset: a731b493e8c74008b3a63abaaf7da822122de793 12:16:40 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 12:16:40 INFO - mozversion platform_version: 53.0a1 12:16:40 INFO - using testdate: 1482524200 12:16:40 INFO - actual date: 1482524200 12:16:40 INFO - starting webserver on 'localhost:42320' 12:16:40 INFO - SUITE-START | Running 2 tests 12:16:40 INFO - TEST-START | dromaeo_css 12:16:40 INFO - operating with platform_type : linux_ 12:16:40 INFO - Initialising browser for dromaeo_css test... 12:16:40 INFO - TEST-INFO | started process 6854 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSF_20F/profile http://localhost:42320/getInfo.html) 12:16:41 INFO - PROCESS | 6854 | 1482524201311 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 12:16:43 INFO - PROCESS | 6854 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 12:16:44 INFO - PROCESS | 6854 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 12:16:45 INFO - PROCESS | 6854 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 12:16:45 INFO - PROCESS | 6854 | __metrics Screen width/height:1600/1200 12:16:45 INFO - PROCESS | 6854 | colorDepth:24 12:16:45 INFO - PROCESS | 6854 | Browser inner width/height: 1024/697 12:16:45 INFO - PROCESS | 6854 | __metrics 12:16:45 ERROR - PROCESS | 6854 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3247: TypeError: this.tabs is undefined 12:16:46 INFO - TEST-INFO | 6854: exit 0 12:16:46 INFO - Browser initialized. 12:16:46 INFO - Running cycle 1/1 for dromaeo_css test... 12:16:46 INFO - TEST-INFO | started process 6973 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpSF_20F/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:16:58 INFO - PROCESS | 6973 | RSS: Main: 158388224 12:16:58 INFO - PROCESS | 6973 | 12:17:48 INFO - PROCESS | 6973 | 1482524268304 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 12:20:02 INFO - PROCESS | 6973 | Cycle 1(1): loaded http://localhost:42320/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42320/tests/dromaeo/cssquery-ext.html) 12:20:03 INFO - PROCESS | 6973 | RSS: Main: 165036032 12:20:03 INFO - PROCESS | 6973 | 12:21:47 INFO - PROCESS | 6973 | ************************* 12:21:47 INFO - PROCESS | 6973 | A coding exception was thrown and uncaught in a Task. 12:21:47 INFO - PROCESS | 6973 | 12:21:47 INFO - PROCESS | 6973 | Full message: ReferenceError: fetch is not defined 12:21:47 INFO - PROCESS | 6973 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 12:21:47 INFO - PROCESS | 6973 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 12:21:47 INFO - PROCESS | 6973 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 12:21:47 INFO - PROCESS | 6973 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 12:21:47 INFO - PROCESS | 6973 | TaskImpl@resource://gre/modules/Task.jsm:277:3 12:21:47 INFO - PROCESS | 6973 | asyncFunction@resource://gre/modules/Task.jsm:252:14 12:21:47 INFO - PROCESS | 6973 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 12:21:47 INFO - PROCESS | 6973 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 12:21:47 INFO - PROCESS | 6973 | TaskImpl@resource://gre/modules/Task.jsm:277:3 12:21:47 INFO - PROCESS | 6973 | asyncFunction@resource://gre/modules/Task.jsm:252:14 12:21:47 INFO - PROCESS | 6973 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 12:21:47 INFO - PROCESS | 6973 | 12:21:47 INFO - PROCESS | 6973 | ************************* 12:23:06 INFO - PROCESS | 6973 | Cycle 1(1): loaded http://localhost:42320/tests/dromaeo/cssquery-ext.html (next: http://localhost:42320/tests/dromaeo/cssquery-jquery.html) 12:23:06 INFO - PROCESS | 6973 | RSS: Main: 162328576 12:23:06 INFO - PROCESS | 6973 | 12:26:08 INFO - PROCESS | 6973 | Cycle 1(1): loaded http://localhost:42320/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42320/tests/dromaeo/cssquery-mootools.html) 12:26:09 INFO - PROCESS | 6973 | RSS: Main: 168185856 12:26:09 INFO - PROCESS | 6973 | 12:29:11 INFO - PROCESS | 6973 | Cycle 1(1): loaded http://localhost:42320/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42320/tests/dromaeo/cssquery-prototype.html) 12:29:12 INFO - PROCESS | 6973 | RSS: Main: 162222080 12:29:12 INFO - PROCESS | 6973 | 12:32:15 INFO - PROCESS | 6973 | Cycle 1(1): loaded http://localhost:42320/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42320/tests/dromaeo/cssquery-yui.html) 12:32:15 INFO - PROCESS | 6973 | RSS: Main: 165994496 12:32:15 INFO - PROCESS | 6973 | 12:35:18 INFO - PROCESS | 6973 | Cycle 1(1): loaded http://localhost:42320/tests/dromaeo/cssquery-yui.html (next: http://localhost:42320/tests/dromaeo/cssquery-dojo.html) 12:35:19 INFO - PROCESS | 6973 | RSS: Main: 166215680 12:35:19 INFO - PROCESS | 6973 | 12:35:19 INFO - PROCESS | 6973 | __start_tp_report 12:35:19 INFO - PROCESS | 6973 | _x_x_mozilla_page_load 12:35:19 INFO - PROCESS | 6973 | _x_x_mozilla_page_load_details 12:35:19 INFO - PROCESS | 6973 | |i|pagename|runs| 12:35:19 INFO - PROCESS | 6973 | |0;dojo.html;2600.375909781653;2607.0353969031457;2615.7907367410608;2630.881610327535;2646.6956300025504;4691.788869500872;4800.7839647215915;4811.663183577151;4839.128956787777;4846.345743324655;4729.148753224418;4818.132736107508;4871.829485967992;4895.730734809584;4906.509349065101;4377.737335759856;4399.560043995607;4436.755978421185;4500.5499450054895;4534.13851368239;4055.695822813286;4510.458744950611;4537.523560026199;4558.954410455903;4560.429946256718;4335.284678028122;4474.709143905636;4489.416375871132;4489.9775501122285;4564.7717614119165;4342.2401079811225;4403.163398954189;4407.250767369523;4420.3369494575745;4518.051209246062;4390.297552391634;4402.097569998143;4414.911701765979;4421.60205581496;4425.491068527129;4782.736949467778;4787.449443314029;4794.736289504076;4835.443923948756;4844.15227335215;4974.204127339644;4989.975050124726;4995.175795993657;5000.274960130797;5002.424721157077;5013.573846223076;5045.117104506733;5059.721715305656;5088.745562721886;5113.59091272701;4752.073345697556;4756.16767065769;4759.48711822567;4761.6904901181315;4791.281307803857;5084.6440749147205;5139.640225184201;5143.691378517226;5219.817306394258;5238;4991.875203119878;4994.9750251248515;5003.899922001612;5008.373953255843;5014.2979982802435;5135.512126347991;5140.742962851842;5154.14956532168;5185.662931909414;5198.454162312978;4908.263760435963;4945.9258111128365;4988.675736077216;5014.07239660664;5076.898462030738;5063.949360506348;5068.366454193226;5068.518490743373;5084.618653600995;5121.820736274212;4257.042165512797;4282.957170428256;4304.139172165579;4316.827326906889;4366.126774645084;3712.88861334155;3766.6233376662663;3807.4859893913967;3812.694984401241;3821.2548553031893;4460.661801459549;4465.285554311295;4468.3074123510905;4470.664700147502;4535.501094879561;4995.975020124949;5058.190689489664;5142.408623008327;5159.690418574897;5189.766460509286;4470.485894122217;4471.642268618502;4486.842960496432;4522.638188944877;4532.410786597721;4646.953530464652;4652.906941861143;4654.4414670239785;4661.463931647904;4718.905621887612;4698.92951605733;4745.311929770233;4821.228603423436;4833.202521583969;4841.15279826022;5083.237514372874;5122.129238029618;5148.58811295103;5178.042062218502;5214.869628259172;4755.548222918956;4763.309320148628;4764.475907650087;4772.212584923449;4818.68678535894;4958.479359667183;5050.217216331474;5051.292819005269;5060.443351231435;5132.486751324837;5067.49325067505;5157.896842063213;5174.223866420067;5187.714675692722;5283.181106928432;5072.9746351268;5083.38999320084;5089.6691715038405;5089.705435167299;5093.23601459784;4893.510648935078;4922.606191504743;5022.3219865317715;5098.1842905135;5131.974340128276;4873.122837889072;4896.2410826321975;4919.409670839379;4969.900601987868;5032.84901452957;5050.217216331474;5066.08810414129;5071.340725705634;5084.847454576368;5130.51260130287;4764.809407623657;4909.459959404391;4975.203967365204;4976.2038073908;5022.698638081727 12:35:19 INFO - PROCESS | 6973 | |1;ext.html;500.82220811611876;506.3215291509377;507.40886866800156;514.907316682997;520.5757307794147;41809.37285940709;43369.78315108424;43397.78301108494;43502.56497435024;43517.34723979135;40138.7993060035;40648.39027414591;40673.59326406735;40704;40733.592664073425;7606.467547271707;7753.612319384037;7757.418193635472;9540.475273859935;9717.125458708715;9652.372595781288;9808.31341806074;9809.803803923916;9814;9825.65610203643;9832.360896541702;9890.752731181707;9910.108090271875;9912.702618921408;9923.206143508502;9760.511974401286;9781.51092445382;9783.657571984946;9798.559064842028;9822.361546499455;9737.853932191023;9800.117989380919;9801.068898454636;9814.263930205192;9819.06718861707;31970;32342.35315293681;32347;32374.83812580922;32434.351312973846;3022.6221722284713;3107.564940908275;3287.7369810415107;3559.9644003559893;3684.410494320896;26542.6018609721;27421.725782742116;29169.70830291691;29486.26284342887;29566.85216573925;3507.9298414031578;3573.87491437801;3691.7046636269033;3747.850085996557;3760.341940160461;253686;257992;259285;259962;260040;56227.71886140543;57228;57312.713436432554;57377.42622573804;57393.71303143458;60348.69825650931;60382;60605.39394606086;60687.69656151691;60747.392526075055;14526.346314415876;14949.701005980038;15051.096934183985;15231.314590843438;15272.465463708717;9795.412275263507;9996.50017499122;10683.09193718527;10813.567457301777;10928.344299342065;18712.812871871378;18760.81239187618;18822.81177188238;18935.147918343708;19242;7271.418286537064;7476.401887848959;7564.205758395399;7640.440452448381;7681.923180768121;778.8014056415691;809.6842231529708;893.0533634347612;936.6206686292063;939.1688355805162;23704.762952370602;25844.483110338064;26051.73948260493;26077.73922260753;26130.216093517225;947.928905332089;973.5229737428609;974.1719538392451;980.161961522902;990.1286867556648;13728.93135534316;14061.156330619991;14199.78700319496;14256.572302830931;14312.11909281634;368923;369709;372301;372961;373811;32612;32700;32710.383169346394;32772.508412373834;32861.67138328586;1161.506359797083;1193.5225909636215;1203.921745086584;1210.3101232297681;1223.822545730864;23466;23977.52044959126;24636.753632464162;26195;27259.318517037234;47444.7627761859;53006.20490692565;53217;53276.200856987954;53350;316391;320945;321491;321867;322950;34465.65534344625;35097.8245108783;35151.29697406089;37537.624623753414;38934;42094.36858447064;42818;43214.351784722625;43554;43595.34606980834;37045.25909481741;37115.257694845415;37576;37852;38725.80637096796;35511.64488355187;36001.27997439984;36309.27381452409;36428;36860.63139368572;32062;32380.35239295154;33278.5008224872;33532.32935341328;33573.83213083919;31779.841100794347;33542.83228583841;34539;34686.918784872985;34980.65019349774;25193.87403062973;25363.33684963126;25408.491830162922;25497.745022549538;25801.483970320114 12:35:19 INFO - PROCESS | 6973 | |2;jquery.html;366.19071851208827;376.80029584320306;383.4957031503574;389.0390734884837;402.46070265843764;16420.917895410523;16913.746293805587;17431.825681743212;17443.389481368155;17618.82381176188;15749.291281892314;16927.66144677109;16989.405370812023;17071;17748.64502709945;5585.1063829787345;5821.53427725781;5889.175515427822;5890.617109887865;5891.469767720904;5022.472640372763;5847.181394604738;6831.146106736658;6861.176658800926;6956.234814170437;6475;6913.861722765541;6920.75777347793;6950.339717726833;6964.79105626832;6629.138212032429;6673.666316684146;6697.330266973288;6703.564268322543;6704.396604305612;6440.806775796734;6582.703778329987;6831.077804496429;6870.31296870314;6874.346937040973;22414;22752.772472275396;22804.31587052391;23033.193838215582;23177.53644927109;2088.801563851447;2518.609615509599;2568.2552059902605;2604.270804174834;2612.6211699303494;13234.602961911158;14164.29178541079;14362.281885905768;14688.265586720729;14872.107673539515;1775.4762345108095;1797.8292062254066;1833.7616109905628;1875.3623767918973;1879.2013394307364;73259.63370183168;74324.99999999946;75514.86727699089;76169.23830761733;76201.23798761997;16035.759463608056;16149.273282702306;16475.34098636041;16517.83482165187;16652.66694666124;27799;27800;27827.721722782917;27889.302767430978;28039.719602804118;8873.157050080232;8900.777480562909;8910.153535414125;9275.72172834816;9290.62837486493;7324.121105467378;7375.483716139819;7480.326770590564;7637.770866874002;7657.655405506765;7845.529268243924;8150.877736833952;8202.712905048294;8211.935092129315;8221.835563288701;3305.652906444837;3374.1901943533867;3427.2974040321674;3450.6031806342594;3492.231709024011;378.2623883427328;379.646928356631;394.52065740125886;399.9780012099362;732.4470025130992;9906.801863962824;10437.739056523647;10508.527116279633;10624.734381640521;10674.946625266824;767.6545554500497;769.4037121230979;787.2835719495232;799.3245707377257;827.3960009193203;8774.916368099091;8907.73276801697;8994.86507702385;9052.683156089506;9307.906920930704;342232;357973;360758;362423;363383;71652;75395.62302188454;76938.23061769422;77308.61345693236;78150.60924695454;893.2853717026483;899.4288626721948;931.6273490603812;932.379967321737;935.8783358163395;14406.783898241325;14542.345594448487;14682.265886705409;14774.556763297118;15029.624259393166;31644.208894777814;35645;36548.81725591355;37568.81215593904;37912.43131352977;43893;47074;47638;49642.25536617026;50156.74921625369;7386.667599957907;7414.888776668462;7423.777286681192;7542.208068152765;7645.694172233271;11371.090312774793;11617.825732613848;11657.475413606579;11752.294862308288;11958.880411196125;9074.500902450429;9655;10036;10037.69886903394;10374.792504150026;9291.535423228946;10030.348027378197;10050.899491004997;10147.137493313152;10213.387196768217;9192.540372981457;9329.72010839676;9758.512074396394;10162.847557286499;10527.420991845522;7352.301531354513;7474.738384156529;7513;7681.8463630725955;7692.076950765944;9439.480828554495;9812.290100751821;9888.555015024198;10075.34510256831;10437.425941573287 12:35:19 INFO - PROCESS | 6973 | |3;mootools.html;1289.1942535915052;1302.029987659194;1366.5763613279885;1690.6111594333295;1707.5731067233185;6652.401283884449;6873.621950792704;7102;7504.924950750491;7589.241075892408;7107.751228706997;7302.233265507125;7311.720448921444;7534.660940257688;7552.81117972051;2132.541503576727;2178.237616834111;2178.4227179797367;2181.2801775414155;2185.0495034659893;2166.945826354346;2185.44271210841;2199.5490924360547;2205.38249290199;2206.360155554887;2129.5634394949075;2152.440365504964;2152.6448136057534;2156.4716644422138;2185.661222510513;2060.6908963655424;2082.312836763864;2085.780992995729;2087.2068613926763;2087.551176497045;2076.5016396064834;2126.383348828838;2131.0836340373635;2133.1040962795664;2162.859414138076;7461.7388391406585;7494.362979146779;7500.924990750077;7515.398768098539;7540.75577529707;4381.6494680425485;4558.726476411426;4569.657275704302;4575.382323386333;4577.130345234395;2982.6271716035494;3092.70619291167;3106.7048630380077;3121.3757248550382;3135.278885856266;876.2989608313324;922.5802259971697;957.727047791383;959.7264779537799;974.3617930255623;9825.312228144032;9825.312228144032;9848.90151098487;9865.506724663737;9886.555105020292;4058.370952502367;4157.563455837154;4214.093969796447;4218.6625069994325;4306.633936115402;8506;8512.829743405096;8517.531535765593;8533;8536.189062039097;4636.072785442925;4647.814087436464;4648.83729069481;4654.162250794821;4655.162070827284;4031.8185681644395;4155.314373128461;4164.3961625564725;4243.236217480821;4243.427137336482;3236.6277878043857;3312.138843900555;3319;3373.5108409280515;3376.40912840252;1065.8081545321916;1069.2622090757352;1072.8498010278568;1073.6510634043966;1081.8214994525972;681.9829504262433;721.0544923985577;730.4156674660356;731.8536292741474;767.2480968650642;4400.559944005574;4473.150101480708;4597.149527337453;4624.838130665411;4806.327114204014;381.74232393134525;384.8672208088205;388.7706253310504;389.3381251871829;392.8330459554678;2930.6776254611977;2952.011076289467;3018.2756138527034;3031.6968303169506;3037.3014206732573;8532.488050716976;8756.824863502694;8814.955925220333;8922.01857795642;9018.323625728097;8890.244329231959;8904.510251936206;8904.55477226149;8906.688265910661;8912.55437228149;6787.321267873173;6864.553804002724;6929.3070692931315;6977.755778547828;6992.440604751608;4509.706869053501;4619.422572178478;4634.582887540138;4644.628429725546;4726.841923728571;8230.794230144054;8252.381071419548;8276.08963014056;8347.874781878398;8350.331973442231;8010.3992200584025;8323.833523329378;8333.416660833684;8348.749537513884;8349;5970.880582388241;6108.541859360477;6314.463270622048;6424.13274207976;6433.517486188648;6599.472042236705;6701.765438209639;6822.1131252938785;6977.686004129928;6982.301769822977;6324.525660575568;6336.176297081534;6398.87202255943;6541.8691626168165;6590.934090659224;6355.52333574993;6437.485001199799;6440.25937017249;6467.676616169267;6524.5759025665075;5637.238972738628;5728.3985181557;5730.885382292414;5731.226284451545;5735.4551317624755;4082.4692789937117;4086.468759061303;4108.91782164361;4217.388478670514;4257.212415703105;3978.4629075075654;4044.5550989390554;4073.287174744467;4084.346504559256;4180.4147419361925 12:35:19 INFO - PROCESS | 6973 | |4;prototype.html;349.1863956980236;356.8715262505496;358.6431500656846;361.89143257022886;365.69281803285253;4916.115099282128;4925.753712314379;5035.546800787928;5069.569086627641;5072.163093089641;4785.976070119653;4874.829380971667;4926.5566099051075;4934.235193544997;4935.876603084925;3967.940480892789;3985.4221137934983;3993.7204395692315;4001.7198796084285;4005.6795456363566;3873.7453330615654;3991.7604943703327;4009.273693888155;4012.959870401288;4026;3949.9605003950023;3953.169834334779;3962.881113566598;3969.8213580388806;3974.721769476138;3940.822662980173;3943.442340275604;3946.565877753445;3957.4063890416523;3962.841486340543;3559.875404360835;3577.964220357789;3578.9284214315567;3582.6059133495296;3590.1383667919513;4732;4880.365552478155;4882.658213925027;4898.730569818658;4973.477784832613;4510.007798284372;4513.848968513041;4526.049529598756;4534.523874993111;4541.296099104644;5217.660852044605;5234.26720259164;5234.842954711365;5245.291885595434;5257.474252574751;3501.7608711941352;3683.0608194910396;3891.8832435026993;3896.649301562845;3903.394973779069;5420.24116623673;5425;5427.793460068478;5434.375046869621;5436.646617969819;3686.870959516404;3715.436348919237;3749.2314075614436;3758.5113935188797;3760.8871733848027;3760.9059773505332;3785.280796648628;3790.507234059555;3790.8104594770152;3810.447485114643;4242.342436922282;4314.201872653569;4360.49854266757;4361.454818147731;4362.650987920959;5198.376194856566;5204.713740744295;5207.609429292818;5221.624161006733;5232.973835130876;1255.2970336611527;1258.3645259144073;1261.0731112632209;1281.077624110639;1285.9099863009499;860.8407444622765;877.9561021948876;890.5235698901057;891.8617614269799;902.3548163063441;4494.325851122358;4502.57225563571;4520.2315606347;4534.863954081383;4536.364908912755;5227.477252274742;5230.796916709102;5267.288915996368;5295;5306.28365170697;4467.977660111678;4470.865874023784;4492.955070449254;4500.1199232491235;4502.887427814266;2676.7055623881356;2820.1116648255734;2847.672517660454;2860.5995160677107;2861.353248245406;4974.8756281093265;4983.358819825498;4994.8251811187165;5001.124803159432;5007.823161557031;5244.895102097937;5373.382061063028;5417.945820541744;5422.728863556807;5461.863453413696;4873.780679869344;4890.364252647133;4897.338859254026;4901.681390709592;4906.852794416173;5298.44366341544;5302.337207849019;5306.97346513265;5324.440933701905;5352.4915133062295;4961.776720047535;5086.516780905808;5203.528324046048;5234.319538459976;5236.319278493771;4875.975620122019;4880.365552478297;4893.75209321635;4905.190643543775;4918.1393256180745;3639.872604458831;3650.7079433645777;3659.5425571803526;3670.50448189491;3685.8341374638744;4997.675151115166;5241.292425522505;5309.256704061358;5315.4153043166;5390.784368625211;5268.81559145428;5268.9736551317;5269.287481568582;5277.366715994105;5285.524302812765;5220.112580861187;5269.314989051399;5276.630635855453;5279.52484276417;5283.5773138149625;4996.800127994985;5161.354830646169;5164.896702066013;5201.531862132426;5243.292155558951;5324.512444348958;5331.973340133274;5346.1713434417725;5369.140937449989;5370.248165256945;5212.462323614467;5226.170840205898;5228.37259528859;5240.423553409199;5261.631685781958 12:35:19 INFO - PROCESS | 6973 | |5;yui.html;667.266007391869;691.8754624167651;737.2885165814987;753.4349238071446;777.1257335497563;12392.876071239285;13473.528426505074;13483.258420786853;13487.3256337183;13539.05226634136;13042.869571304283;13199;13261.336933153329;13292.335383230848;13299.734005319911;668.7659319238252;687.5256073309425;775.647080578337;791.3945831438954;811.4117264982899;822.8436597046572;825.6821123867294;826;826.499967519651;827.6399766101734;880.009988762642;881.0220655072864;882.4484697064335;884.4516399832113;885.3935054487664;877.4165180155189;879.973600791974;880.0451510111473;882.2853488674162;884.0673089890163;643.7489379142138;644.3073695777058;648.0506058624138;648.5524987758431;648.9805305840832;13260.53588124411;13310.933445332712;13316.400761965735;13436.798448023288;13880.58358249244;12914.418851151626;13624.727505449835;13829.006673421605;13861.37623806921;14051.297435128305;10579.576816927314;10674.573017079385;10726.195535334804;10786.191035672357;10964.835527467094;3318.0875259303643;3337.2324365396094;3358.2779702363864;3389.559357283562;3476.3916314644835;61480.69259653673;61834.381656183315;62363;62483.06275405918;63649.68175159141;10044.397336159855;10057.195424366033;10316.43259620728;10495.685129446128;10817.621383251706;19785.307514236934;19800;19838.801611983985;19852.305169319003;19865.801341986684;6257.436830685169;6591.242007169141;6648.833779155463;6807.217170025411;6828.897566536506;3520.8415621297104;3589.892303230855;3655.8354874030733;3735.327641024587;3739.08392443853;2853.543433050702;2905.5932169496286;2918.416316736661;2925.6635486919276;2940.470715271229;500.6620531141465;502.4975024975025;508.23256882108285;511.1081163369869;519.8544407565888;537.5377175628956;584.7085361510096;591.6745789815584;591.6982339007051;599.5713065158345;8080.636371363303;8096.595170241582;8209.917900821034;8239.629216685264;8525.786855328666;502.11627535536985;563.5688698145922;575.2119596153213;593.2288025566833;596.263614436171;3852.1332700142243;3961.3265744823448;3995.141044675409;4024.9195016099516;4089.5501494835544;66595;66973.66513167403;68470;68602.65698671475;70620.29379706137;58143.802090884565;58736;58887;58977;59143.11285330723;42490.57509424928;43482.34776478355;47274.763626182335;53236.46763532315;53602.7319863406;7552.509086909334;8005.39959503028;8027.2775450209765;8072.87890681652;8109.472884262504;38062;38137;38451.42322865103;39009.414858776574;39874.401883971186;45122.323165153204;49236;50731;51140.48859511357;52687.736561316946;10437.112845408225;10553.155747540332;10661.147108231478;10683.732906677395;10718.785624287626;10227.795444091225;10411.375317480975;10655.30740501865;10696.358218506915;10813.945930270298;10496.947515262375;11595.622897305864;11729.882701172879;12371.443285052013;12501.43743531525;11498.195126341076;11890.821637675615;12054.51781928713;12215.633530994084;12577.559785407468;12021.879781202426;12305.384730763248;12613.243205407705;12664.240145591293;12897.806532901826;10119.237200414034;10311.976445629545;10750;10775.730606734895;11040;8718.738437846874;9450.763730906783;9775.902240977499;10263.332883362558;10367.170626350025 12:35:19 INFO - PROCESS | 6973 | __end_tp_report 12:35:19 INFO - PROCESS | 6973 | __start_cc_report 12:35:19 INFO - PROCESS | 6973 | _x_x_mozilla_cycle_collect,331 12:35:19 INFO - PROCESS | 6973 | __end_cc_report 12:35:19 INFO - PROCESS | 6973 | __startTimestamp1482525319026__endTimestamp 12:35:19 INFO - PROCESS | 6973 | 12:35:19 INFO - PROCESS | 6973 | ------- Summary: start ------- 12:35:19 INFO - PROCESS | 6973 | Number of tests: 6 12:35:19 INFO - PROCESS | 6973 | 12:35:19 INFO - PROCESS | 6973 | [#0] dojo.html Cycles:165 Average:4736.75 Median:4846.35 stddev:495.99 (10.2%) stddev-sans-first:468.34 12:35:19 INFO - PROCESS | 6973 | Values: 2600.4 2607.0 2615.8 2630.9 2646.7 4691.8 4800.8 4811.7 4839.1 4846.3 4729.1 4818.1 4871.8 4895.7 4906.5 4377.7 4399.6 4436.8 4500.5 4534.1 4055.7 4510.5 4537.5 4559.0 4560.4 4335.3 4474.7 4489.4 4490.0 4564.8 4342.2 4403.2 4407.3 4420.3 4518.1 4390.3 4402.1 4414.9 4421.6 4425.5 4782.7 4787.4 4794.7 4835.4 4844.2 4974.2 4990.0 4995.2 5000.3 5002.4 5013.6 5045.1 5059.7 5088.7 5113.6 4752.1 4756.2 4759.5 4761.7 4791.3 5084.6 5139.6 5143.7 5219.8 5238.0 4991.9 4995.0 5003.9 5008.4 5014.3 5135.5 5140.7 5154.1 5185.7 5198.5 4908.3 4945.9 4988.7 5014.1 5076.9 5063.9 5068.4 5068.5 5084.6 5121.8 4257.0 4283.0 4304.1 4316.8 4366.1 3712.9 3766.6 3807.5 3812.7 3821.3 4460.7 4465.3 4468.3 4470.7 4535.5 4996.0 5058.2 5142.4 5159.7 5189.8 4470.5 4471.6 4486.8 4522.6 4532.4 4647.0 4652.9 4654.4 4661.5 4718.9 4698.9 4745.3 4821.2 4833.2 4841.2 5083.2 5122.1 5148.6 5178.0 5214.9 4755.5 4763.3 4764.5 4772.2 4818.7 4958.5 5050.2 5051.3 5060.4 5132.5 5067.5 5157.9 5174.2 5187.7 5283.2 5073.0 5083.4 5089.7 5089.7 5093.2 4893.5 4922.6 5022.3 5098.2 5132.0 4873.1 4896.2 4919.4 4969.9 5032.8 5050.2 5066.1 5071.3 5084.8 5130.5 4764.8 4909.5 4975.2 4976.2 5022.7 12:35:19 INFO - PROCESS | 6973 | 12:35:19 INFO - PROCESS | 6973 | [#1] ext.html Cycles:180 Average:47715.98 Median:203377.13 stddev:84099.62 (41.4%) stddev-sans-first:84260.83 12:35:19 INFO - PROCESS | 6973 | Values: 500.8 506.3 507.4 514.9 520.6 41809.4 43369.8 43397.8 43502.6 43517.3 40138.8 40648.4 40673.6 40704.0 40733.6 7606.5 7753.6 7757.4 9540.5 9717.1 9652.4 9808.3 9809.8 9814.0 9825.7 9832.4 9890.8 9910.1 9912.7 9923.2 9760.5 9781.5 9783.7 9798.6 9822.4 9737.9 9800.1 9801.1 9814.3 9819.1 31970.0 32342.4 32347.0 32374.8 32434.4 3022.6 3107.6 3287.7 3560.0 3684.4 26542.6 27421.7 29169.7 29486.3 29566.9 3507.9 3573.9 3691.7 3747.9 3760.3 253686.0 257992.0 259285.0 259962.0 260040.0 56227.7 57228.0 57312.7 57377.4 57393.7 60348.7 60382.0 60605.4 60687.7 60747.4 14526.3 14949.7 15051.1 15231.3 15272.5 9795.4 9996.5 10683.1 10813.6 10928.3 18712.8 18760.8 18822.8 18935.1 19242.0 7271.4 7476.4 7564.2 7640.4 7681.9 778.8 809.7 893.1 936.6 939.2 23704.8 25844.5 26051.7 26077.7 26130.2 947.9 973.5 974.2 980.2 990.1 13728.9 14061.2 14199.8 14256.6 14312.1 368923.0 369709.0 372301.0 372961.0 373811.0 32612.0 32700.0 32710.4 32772.5 32861.7 1161.5 1193.5 1203.9 1210.3 1223.8 23466.0 23977.5 24636.8 26195.0 27259.3 47444.8 53006.2 53217.0 53276.2 53350.0 316391.0 320945.0 321491.0 321867.0 322950.0 34465.7 35097.8 35151.3 37537.6 38934.0 42094.4 42818.0 43214.4 43554.0 43595.3 37045.3 37115.3 37576.0 37852.0 38725.8 35511.6 36001.3 36309.3 36428.0 36860.6 32062.0 32380.4 33278.5 33532.3 33573.8 31779.8 33542.8 34539.0 34686.9 34980.7 25193.9 25363.3 25408.5 25497.7 25801.5 12:35:19 INFO - PROCESS | 6973 | 12:35:19 INFO - PROCESS | 6973 | [#2] jquery.html Cycles:180 Average:24303.31 Median:47356.00 stddev:59162.73 (124.9%) stddev-sans-first:59301.40 12:35:19 INFO - PROCESS | 6973 | Values: 366.2 376.8 383.5 389.0 402.5 16420.9 16913.7 17431.8 17443.4 17618.8 15749.3 16927.7 16989.4 17071.0 17748.6 5585.1 5821.5 5889.2 5890.6 5891.5 5022.5 5847.2 6831.1 6861.2 6956.2 6475.0 6913.9 6920.8 6950.3 6964.8 6629.1 6673.7 6697.3 6703.6 6704.4 6440.8 6582.7 6831.1 6870.3 6874.3 22414.0 22752.8 22804.3 23033.2 23177.5 2088.8 2518.6 2568.3 2604.3 2612.6 13234.6 14164.3 14362.3 14688.3 14872.1 1775.5 1797.8 1833.8 1875.4 1879.2 73259.6 74325.0 75514.9 76169.2 76201.2 16035.8 16149.3 16475.3 16517.8 16652.7 27799.0 27800.0 27827.7 27889.3 28039.7 8873.2 8900.8 8910.2 9275.7 9290.6 7324.1 7375.5 7480.3 7637.8 7657.7 7845.5 8150.9 8202.7 8211.9 8221.8 3305.7 3374.2 3427.3 3450.6 3492.2 378.3 379.6 394.5 400.0 732.4 9906.8 10437.7 10508.5 10624.7 10674.9 767.7 769.4 787.3 799.3 827.4 8774.9 8907.7 8994.9 9052.7 9307.9 342232.0 357973.0 360758.0 362423.0 363383.0 71652.0 75395.6 76938.2 77308.6 78150.6 893.3 899.4 931.6 932.4 935.9 14406.8 14542.3 14682.3 14774.6 15029.6 31644.2 35645.0 36548.8 37568.8 37912.4 43893.0 47074.0 47638.0 49642.3 50156.7 7386.7 7414.9 7423.8 7542.2 7645.7 11371.1 11617.8 11657.5 11752.3 11958.9 9074.5 9655.0 10036.0 10037.7 10374.8 9291.5 10030.3 10050.9 10147.1 10213.4 9192.5 9329.7 9758.5 10162.8 10527.4 7352.3 7474.7 7513.0 7681.8 7692.1 9439.5 9812.3 9888.6 10075.3 10437.4 12:35:19 INFO - PROCESS | 6973 | 12:35:19 INFO - PROCESS | 6973 | [#3] mootools.html Cycles:180 Average:4803.56 Median:4651.50 stddev:2666.66 (57.3%) stddev-sans-first:2661.06 12:35:19 INFO - PROCESS | 6973 | Values: 1289.2 1302.0 1366.6 1690.6 1707.6 6652.4 6873.6 7102.0 7504.9 7589.2 7107.8 7302.2 7311.7 7534.7 7552.8 2132.5 2178.2 2178.4 2181.3 2185.0 2166.9 2185.4 2199.5 2205.4 2206.4 2129.6 2152.4 2152.6 2156.5 2185.7 2060.7 2082.3 2085.8 2087.2 2087.6 2076.5 2126.4 2131.1 2133.1 2162.9 7461.7 7494.4 7500.9 7515.4 7540.8 4381.6 4558.7 4569.7 4575.4 4577.1 2982.6 3092.7 3106.7 3121.4 3135.3 876.3 922.6 957.7 959.7 974.4 9825.3 9825.3 9848.9 9865.5 9886.6 4058.4 4157.6 4214.1 4218.7 4306.6 8506.0 8512.8 8517.5 8533.0 8536.2 4636.1 4647.8 4648.8 4654.2 4655.2 4031.8 4155.3 4164.4 4243.2 4243.4 3236.6 3312.1 3319.0 3373.5 3376.4 1065.8 1069.3 1072.8 1073.7 1081.8 682.0 721.1 730.4 731.9 767.2 4400.6 4473.2 4597.1 4624.8 4806.3 381.7 384.9 388.8 389.3 392.8 2930.7 2952.0 3018.3 3031.7 3037.3 8532.5 8756.8 8815.0 8922.0 9018.3 8890.2 8904.5 8904.6 8906.7 8912.6 6787.3 6864.6 6929.3 6977.8 6992.4 4509.7 4619.4 4634.6 4644.6 4726.8 8230.8 8252.4 8276.1 8347.9 8350.3 8010.4 8323.8 8333.4 8348.7 8349.0 5970.9 6108.5 6314.5 6424.1 6433.5 6599.5 6701.8 6822.1 6977.7 6982.3 6324.5 6336.2 6398.9 6541.9 6590.9 6355.5 6437.5 6440.3 6467.7 6524.6 5637.2 5728.4 5730.9 5731.2 5735.5 4082.5 4086.5 4108.9 4217.4 4257.2 3978.5 4044.6 4073.3 4084.3 4180.4 12:35:19 INFO - PROCESS | 6973 | 12:35:19 INFO - PROCESS | 6973 | [#4] prototype.html Cycles:180 Average:4324.23 Median:4898.03 stddev:1247.57 (25.5%) stddev-sans-first:1214.87 12:35:19 INFO - PROCESS | 6973 | Values: 349.2 356.9 358.6 361.9 365.7 4916.1 4925.8 5035.5 5069.6 5072.2 4786.0 4874.8 4926.6 4934.2 4935.9 3967.9 3985.4 3993.7 4001.7 4005.7 3873.7 3991.8 4009.3 4013.0 4026.0 3950.0 3953.2 3962.9 3969.8 3974.7 3940.8 3943.4 3946.6 3957.4 3962.8 3559.9 3578.0 3578.9 3582.6 3590.1 4732.0 4880.4 4882.7 4898.7 4973.5 4510.0 4513.8 4526.0 4534.5 4541.3 5217.7 5234.3 5234.8 5245.3 5257.5 3501.8 3683.1 3891.9 3896.6 3903.4 5420.2 5425.0 5427.8 5434.4 5436.6 3686.9 3715.4 3749.2 3758.5 3760.9 3760.9 3785.3 3790.5 3790.8 3810.4 4242.3 4314.2 4360.5 4361.5 4362.7 5198.4 5204.7 5207.6 5221.6 5233.0 1255.3 1258.4 1261.1 1281.1 1285.9 860.8 878.0 890.5 891.9 902.4 4494.3 4502.6 4520.2 4534.9 4536.4 5227.5 5230.8 5267.3 5295.0 5306.3 4468.0 4470.9 4493.0 4500.1 4502.9 2676.7 2820.1 2847.7 2860.6 2861.4 4974.9 4983.4 4994.8 5001.1 5007.8 5244.9 5373.4 5417.9 5422.7 5461.9 4873.8 4890.4 4897.3 4901.7 4906.9 5298.4 5302.3 5307.0 5324.4 5352.5 4961.8 5086.5 5203.5 5234.3 5236.3 4876.0 4880.4 4893.8 4905.2 4918.1 3639.9 3650.7 3659.5 3670.5 3685.8 4997.7 5241.3 5309.3 5315.4 5390.8 5268.8 5269.0 5269.3 5277.4 5285.5 5220.1 5269.3 5276.6 5279.5 5283.6 4996.8 5161.4 5164.9 5201.5 5243.3 5324.5 5332.0 5346.2 5369.1 5370.2 5212.5 5226.2 5228.4 5240.4 5261.6 12:35:19 INFO - PROCESS | 6973 | 12:35:19 INFO - PROCESS | 6973 | [#5] yui.html Cycles:180 Average:15035.48 Median:4057.23 stddev:18769.65 (462.6%) stddev-sans-first:18791.29 12:35:19 INFO - PROCESS | 6973 | Values: 667.3 691.9 737.3 753.4 777.1 12392.9 13473.5 13483.3 13487.3 13539.1 13042.9 13199.0 13261.3 13292.3 13299.7 668.8 687.5 775.6 791.4 811.4 822.8 825.7 826.0 826.5 827.6 880.0 881.0 882.4 884.5 885.4 877.4 880.0 880.0 882.3 884.1 643.7 644.3 648.1 648.6 649.0 13260.5 13310.9 13316.4 13436.8 13880.6 12914.4 13624.7 13829.0 13861.4 14051.3 10579.6 10674.6 10726.2 10786.2 10964.8 3318.1 3337.2 3358.3 3389.6 3476.4 61480.7 61834.4 62363.0 62483.1 63649.7 10044.4 10057.2 10316.4 10495.7 10817.6 19785.3 19800.0 19838.8 19852.3 19865.8 6257.4 6591.2 6648.8 6807.2 6828.9 3520.8 3589.9 3655.8 3735.3 3739.1 2853.5 2905.6 2918.4 2925.7 2940.5 500.7 502.5 508.2 511.1 519.9 537.5 584.7 591.7 591.7 599.6 8080.6 8096.6 8209.9 8239.6 8525.8 502.1 563.6 575.2 593.2 596.3 3852.1 3961.3 3995.1 4024.9 4089.6 66595.0 66973.7 68470.0 68602.7 70620.3 58143.8 58736.0 58887.0 58977.0 59143.1 42490.6 43482.3 47274.8 53236.5 53602.7 7552.5 8005.4 8027.3 8072.9 8109.5 38062.0 38137.0 38451.4 39009.4 39874.4 45122.3 49236.0 50731.0 51140.5 52687.7 10437.1 10553.2 10661.1 10683.7 10718.8 10227.8 10411.4 10655.3 10696.4 10813.9 10496.9 11595.6 11729.9 12371.4 12501.4 11498.2 11890.8 12054.5 12215.6 12577.6 12021.9 12305.4 12613.2 12664.2 12897.8 10119.2 10312.0 10750.0 10775.7 11040.0 8718.7 9450.8 9775.9 10263.3 10367.2 12:35:19 INFO - PROCESS | 6973 | -------- Summary: end -------- 12:35:19 INFO - PROCESS | 6973 | 12:35:19 INFO - TEST-INFO | 6973: exit 0 12:35:19 INFO - TEST-OK | dromaeo_css | took 1119035ms 12:35:19 INFO - TEST-START | kraken 12:35:19 INFO - operating with platform_type : linux_ 12:35:19 INFO - Initialising browser for kraken test... 12:35:19 INFO - TEST-INFO | started process 7337 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpd9jis5/profile http://localhost:42320/getInfo.html) 12:35:20 INFO - PROCESS | 7337 | 1482525320338 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 12:35:22 INFO - PROCESS | 7337 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 12:35:23 INFO - PROCESS | 7337 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 12:35:24 INFO - PROCESS | 7337 | JavaScript error: chrome://global/content/browser-content.js, line 1772: ReferenceError: event is not defined 12:35:24 INFO - PROCESS | 7337 | __metrics Screen width/height:1600/1200 12:35:24 INFO - PROCESS | 7337 | colorDepth:24 12:35:24 INFO - PROCESS | 7337 | Browser inner width/height: 1024/697 12:35:24 INFO - PROCESS | 7337 | __metrics 12:35:24 ERROR - PROCESS | 7337 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3247: TypeError: this.tabs is undefined 12:35:25 INFO - TEST-INFO | 7337: exit 0 12:35:25 INFO - Browser initialized. 12:35:25 INFO - Running cycle 1/1 for kraken test... 12:35:25 INFO - TEST-INFO | started process 7458 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpd9jis5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:35:37 INFO - PROCESS | 7458 | RSS: Main: 158498816 12:35:37 INFO - PROCESS | 7458 | 12:35:40 INFO - PROCESS | 7458 | Cycle 1(1): loaded http://localhost:42320/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42320/tests/kraken/driver.html?testName=audio-beat-detection) 12:35:41 INFO - PROCESS | 7458 | RSS: Main: 165122048 12:35:41 INFO - PROCESS | 7458 | 12:35:44 INFO - PROCESS | 7458 | Cycle 1(1): loaded http://localhost:42320/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42320/tests/kraken/driver.html?testName=audio-dft) 12:35:44 INFO - PROCESS | 7458 | RSS: Main: 163004416 12:35:44 INFO - PROCESS | 7458 | 12:35:47 INFO - PROCESS | 7458 | Cycle 1(1): loaded http://localhost:42320/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42320/tests/kraken/driver.html?testName=audio-fft) 12:35:48 INFO - PROCESS | 7458 | RSS: Main: 159862784 12:35:48 INFO - PROCESS | 7458 | 12:35:50 INFO - PROCESS | 7458 | Cycle 1(1): loaded http://localhost:42320/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42320/tests/kraken/driver.html?testName=audio-oscillator) 12:35:51 INFO - PROCESS | 7458 | RSS: Main: 160325632 12:35:51 INFO - PROCESS | 7458 | 12:35:53 INFO - PROCESS | 7458 | Cycle 1(1): loaded http://localhost:42320/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42320/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:35:54 INFO - PROCESS | 7458 | RSS: Main: 160600064 12:35:54 INFO - PROCESS | 7458 | 12:35:57 INFO - PROCESS | 7458 | Cycle 1(1): loaded http://localhost:42320/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42320/tests/kraken/driver.html?testName=imaging-darkroom) 12:35:57 INFO - PROCESS | 7458 | RSS: Main: 164970496 12:35:57 INFO - PROCESS | 7458 | 12:36:01 INFO - PROCESS | 7458 | Cycle 1(1): loaded http://localhost:42320/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42320/tests/kraken/driver.html?testName=imaging-desaturate) 12:36:02 INFO - PROCESS | 7458 | RSS: Main: 162349056 12:36:02 INFO - PROCESS | 7458 | 12:36:04 INFO - PROCESS | 7458 | Cycle 1(1): loaded http://localhost:42320/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42320/tests/kraken/driver.html?testName=json-parse-financial) 12:36:05 INFO - PROCESS | 7458 | RSS: Main: 163033088 12:36:05 INFO - PROCESS | 7458 | 12:36:07 INFO - PROCESS | 7458 | Cycle 1(1): loaded http://localhost:42320/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42320/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:36:07 INFO - PROCESS | 7458 | RSS: Main: 163377152 12:36:07 INFO - PROCESS | 7458 | 12:36:09 INFO - PROCESS | 7458 | Cycle 1(1): loaded http://localhost:42320/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42320/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:36:10 INFO - PROCESS | 7458 | RSS: Main: 163643392 12:36:10 INFO - PROCESS | 7458 | 12:36:12 INFO - PROCESS | 7458 | Cycle 1(1): loaded http://localhost:42320/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42320/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:36:13 INFO - PROCESS | 7458 | RSS: Main: 163618816 12:36:13 INFO - PROCESS | 7458 | 12:36:16 INFO - PROCESS | 7458 | Cycle 1(1): loaded http://localhost:42320/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42320/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:36:17 INFO - PROCESS | 7458 | RSS: Main: 164417536 12:36:17 INFO - PROCESS | 7458 | 12:36:20 INFO - PROCESS | 7458 | Cycle 1(1): loaded http://localhost:42320/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42320/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:36:20 INFO - PROCESS | 7458 | RSS: Main: 165773312 12:36:20 INFO - PROCESS | 7458 | 12:36:22 INFO - PROCESS | 7458 | Cycle 1(1): loaded http://localhost:42320/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42320/tests/kraken/driver.html?testName=ai-astar) 12:36:22 INFO - PROCESS | 7458 | RSS: Main: 165732352 12:36:22 INFO - PROCESS | 7458 | 12:36:22 INFO - PROCESS | 7458 | __start_tp_report 12:36:22 INFO - PROCESS | 7458 | _x_x_mozilla_page_load 12:36:22 INFO - PROCESS | 7458 | _x_x_mozilla_page_load_details 12:36:22 INFO - PROCESS | 7458 | |i|pagename|runs| 12:36:22 INFO - PROCESS | 7458 | |0;ai-astar;107;115;114;117;115;113;114;113;115;113 12:36:22 INFO - PROCESS | 7458 | |1;audio-beat-detection;156;133;130;183;127;132;127;128;147;131 12:36:22 INFO - PROCESS | 7458 | |2;audio-dft;164;165;166;169;164;164;162;163;163;163 12:36:22 INFO - PROCESS | 7458 | |3;audio-fft;82;76;77;113;77;77;76;78;99;78 12:36:22 INFO - PROCESS | 7458 | |4;audio-oscillator;90;85;113;87;88;87;105;85;85;107 12:36:22 INFO - PROCESS | 7458 | |5;imaging-gaussian-blur;118;118;118;117;118;122;119;118;119;118 12:36:22 INFO - PROCESS | 7458 | |6;imaging-darkroom;151;151;149;152;151;150;150;151;151;154 12:36:22 INFO - PROCESS | 7458 | |7;imaging-desaturate;97;97;96;96;95;96;96;95;96;96 12:36:22 INFO - PROCESS | 7458 | |8;json-parse-financial;64;67;65;64;65;66;66;65;65;65 12:36:22 INFO - PROCESS | 7458 | |9;json-stringify-tinderbox;68;68;67;67;67;67;67;67;67;67 12:36:22 INFO - PROCESS | 7458 | |10;stanford-crypto-aes;85;89;91;85;86;85;86;92;86;86 12:36:22 INFO - PROCESS | 7458 | |11;stanford-crypto-ccm;139;158;134;136;152;138;145;143;188;146 12:36:22 INFO - PROCESS | 7458 | |12;stanford-crypto-pbkdf2;192;176;178;177;181;184;177;179;183;186 12:36:22 INFO - PROCESS | 7458 | |13;stanford-crypto-sha256-iterative;69;74;69;68;68;70;68;67;66;68 12:36:22 INFO - PROCESS | 7458 | __end_tp_report 12:36:22 INFO - PROCESS | 7458 | __start_cc_report 12:36:22 INFO - PROCESS | 7458 | _x_x_mozilla_cycle_collect,790 12:36:22 INFO - PROCESS | 7458 | __end_cc_report 12:36:22 INFO - PROCESS | 7458 | __startTimestamp1482525382709__endTimestamp 12:36:22 INFO - PROCESS | 7458 | 12:36:22 INFO - PROCESS | 7458 | ------- Summary: start ------- 12:36:22 INFO - PROCESS | 7458 | Number of tests: 14 12:36:22 INFO - PROCESS | 7458 | 12:36:22 INFO - PROCESS | 7458 | [#0] ai-astar Cycles:10 Average:113.60 Median:114.50 stddev:2.63 (2.3%) stddev-sans-first:1.32 12:36:22 INFO - PROCESS | 7458 | Values: 107.0 115.0 114.0 117.0 115.0 113.0 114.0 113.0 115.0 113.0 12:36:22 INFO - PROCESS | 7458 | 12:36:22 INFO - PROCESS | 7458 | [#1] audio-beat-detection Cycles:10 Average:139.40 Median:132.50 stddev:18.03 (13.6%) stddev-sans-first:18.10 12:36:22 INFO - PROCESS | 7458 | Values: 156.0 133.0 130.0 183.0 127.0 132.0 127.0 128.0 147.0 131.0 12:36:22 INFO - PROCESS | 7458 | 12:36:22 INFO - PROCESS | 7458 | [#2] audio-dft Cycles:10 Average:164.30 Median:164.00 stddev:2.00 (1.2%) stddev-sans-first:2.12 12:36:22 INFO - PROCESS | 7458 | Values: 164.0 165.0 166.0 169.0 164.0 164.0 162.0 163.0 163.0 163.0 12:36:22 INFO - PROCESS | 7458 | 12:36:22 INFO - PROCESS | 7458 | [#3] audio-fft Cycles:10 Average:83.30 Median:77.50 stddev:12.53 (16.2%) stddev-sans-first:13.28 12:36:22 INFO - PROCESS | 7458 | Values: 82.0 76.0 77.0 113.0 77.0 77.0 76.0 78.0 99.0 78.0 12:36:22 INFO - PROCESS | 7458 | 12:36:22 INFO - PROCESS | 7458 | [#4] audio-oscillator Cycles:10 Average:93.20 Median:86.00 stddev:10.74 (12.5%) stddev-sans-first:11.33 12:36:22 INFO - PROCESS | 7458 | Values: 90.0 85.0 113.0 87.0 88.0 87.0 105.0 85.0 85.0 107.0 12:36:22 INFO - PROCESS | 7458 | 12:36:22 INFO - PROCESS | 7458 | [#5] imaging-gaussian-blur Cycles:10 Average:118.50 Median:118.00 stddev:1.35 (1.1%) stddev-sans-first:1.42 12:36:22 INFO - PROCESS | 7458 | Values: 118.0 118.0 118.0 117.0 118.0 122.0 119.0 118.0 119.0 118.0 12:36:22 INFO - PROCESS | 7458 | 12:36:22 INFO - PROCESS | 7458 | [#6] imaging-darkroom Cycles:10 Average:151.00 Median:151.00 stddev:1.33 (0.9%) stddev-sans-first:1.41 12:36:22 INFO - PROCESS | 7458 | Values: 151.0 151.0 149.0 152.0 151.0 150.0 150.0 151.0 151.0 154.0 12:36:22 INFO - PROCESS | 7458 | 12:36:22 INFO - PROCESS | 7458 | [#7] imaging-desaturate Cycles:10 Average:96.00 Median:96.00 stddev:0.67 (0.7%) stddev-sans-first:0.60 12:36:22 INFO - PROCESS | 7458 | Values: 97.0 97.0 96.0 96.0 95.0 96.0 96.0 95.0 96.0 96.0 12:36:22 INFO - PROCESS | 7458 | 12:36:22 INFO - PROCESS | 7458 | [#8] json-parse-financial Cycles:10 Average:65.20 Median:65.00 stddev:0.92 (1.4%) stddev-sans-first:0.87 12:36:22 INFO - PROCESS | 7458 | Values: 64.0 67.0 65.0 64.0 65.0 66.0 66.0 65.0 65.0 65.0 12:36:22 INFO - PROCESS | 7458 | 12:36:22 INFO - PROCESS | 7458 | [#9] json-stringify-tinderbox Cycles:10 Average:67.20 Median:67.00 stddev:0.42 (0.6%) stddev-sans-first:0.33 12:36:22 INFO - PROCESS | 7458 | Values: 68.0 68.0 67.0 67.0 67.0 67.0 67.0 67.0 67.0 67.0 12:36:22 INFO - PROCESS | 7458 | 12:36:22 INFO - PROCESS | 7458 | [#10] stanford-crypto-aes Cycles:10 Average:87.10 Median:86.00 stddev:2.60 (3.0%) stddev-sans-first:2.65 12:36:22 INFO - PROCESS | 7458 | Values: 85.0 89.0 91.0 85.0 86.0 85.0 86.0 92.0 86.0 86.0 12:36:22 INFO - PROCESS | 7458 | 12:36:22 INFO - PROCESS | 7458 | [#11] stanford-crypto-ccm Cycles:10 Average:147.90 Median:145.50 stddev:15.90 (10.9%) stddev-sans-first:16.53 12:36:22 INFO - PROCESS | 7458 | Values: 139.0 158.0 134.0 136.0 152.0 138.0 145.0 143.0 188.0 146.0 12:36:22 INFO - PROCESS | 7458 | 12:36:22 INFO - PROCESS | 7458 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.30 Median:182.00 stddev:5.03 (2.8%) stddev-sans-first:3.55 12:36:22 INFO - PROCESS | 7458 | Values: 192.0 176.0 178.0 177.0 181.0 184.0 177.0 179.0 183.0 186.0 12:36:22 INFO - PROCESS | 7458 | 12:36:22 INFO - PROCESS | 7458 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.70 Median:68.50 stddev:2.16 (3.2%) stddev-sans-first:2.29 12:36:22 INFO - PROCESS | 7458 | Values: 69.0 74.0 69.0 68.0 68.0 70.0 68.0 67.0 66.0 68.0 12:36:22 INFO - PROCESS | 7458 | -------- Summary: end -------- 12:36:22 INFO - PROCESS | 7458 | 12:36:23 INFO - TEST-INFO | 7458: exit 0 12:36:23 INFO - TEST-OK | kraken | took 64042ms 12:36:23 INFO - SUITE-END | took 1183s 12:36:24 INFO - Completed test suite (00:19:43) 12:36:24 INFO - javascript benchmark 12:36:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6457.713463560111, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2600.375909781653, 2607.0353969031457, 2615.7907367410608, 2630.881610327535, 2646.6956300025504, 4691.788869500872, 4800.7839647215915, 4811.663183577151, 4839.128956787777, 4846.345743324655, 4729.148753224418, 4818.132736107508, 4871.829485967992, 4895.730734809584, 4906.509349065101, 4377.737335759856, 4399.560043995607, 4436.755978421185, 4500.5499450054895, 4534.13851368239, 4055.695822813286, 4510.458744950611, 4537.523560026199, 4558.954410455903, 4560.429946256718, 4335.284678028122, 4474.709143905636, 4489.416375871132, 4489.9775501122285, 4564.7717614119165, 4342.2401079811225, 4403.163398954189, 4407.250767369523, 4420.3369494575745, 4518.051209246062, 4390.297552391634, 4402.097569998143, 4414.911701765979, 4421.60205581496, 4425.491068527129, 4782.736949467778, 4787.449443314029, 4794.736289504076, 4835.443923948756, 4844.15227335215, 4974.204127339644, 4989.975050124726, 4995.175795993657, 5000.274960130797, 5002.424721157077, 5013.573846223076, 5045.117104506733, 5059.721715305656, 5088.745562721886, 5113.59091272701, 4752.073345697556, 4756.16767065769, 4759.48711822567, 4761.6904901181315, 4791.281307803857, 5084.6440749147205, 5139.640225184201, 5143.691378517226, 5219.817306394258, 5238.0, 4991.875203119878, 4994.9750251248515, 5003.899922001612, 5008.373953255843, 5014.2979982802435, 5135.512126347991, 5140.742962851842, 5154.14956532168, 5185.662931909414, 5198.454162312978, 4908.263760435963, 4945.9258111128365, 4988.675736077216, 5014.07239660664, 5076.898462030738, 5063.949360506348, 5068.366454193226, 5068.518490743373, 5084.618653600995, 5121.820736274212, 4257.042165512797, 4282.957170428256, 4304.139172165579, 4316.827326906889, 4366.126774645084, 3712.88861334155, 3766.6233376662663, 3807.4859893913967, 3812.694984401241, 3821.2548553031893, 4460.661801459549, 4465.285554311295, 4468.3074123510905, 4470.664700147502, 4535.501094879561, 4995.975020124949, 5058.190689489664, 5142.408623008327, 5159.690418574897, 5189.766460509286, 4470.485894122217, 4471.642268618502, 4486.842960496432, 4522.638188944877, 4532.410786597721, 4646.953530464652, 4652.906941861143, 4654.4414670239785, 4661.463931647904, 4718.905621887612, 4698.92951605733, 4745.311929770233, 4821.228603423436, 4833.202521583969, 4841.15279826022, 5083.237514372874, 5122.129238029618, 5148.58811295103, 5178.042062218502, 5214.869628259172, 4755.548222918956, 4763.309320148628, 4764.475907650087, 4772.212584923449, 4818.68678535894, 4958.479359667183, 5050.217216331474, 5051.292819005269, 5060.443351231435, 5132.486751324837, 5067.49325067505, 5157.896842063213, 5174.223866420067, 5187.714675692722, 5283.181106928432, 5072.9746351268, 5083.38999320084, 5089.6691715038405, 5089.705435167299, 5093.23601459784, 4893.510648935078, 4922.606191504743, 5022.3219865317715, 5098.1842905135, 5131.974340128276, 4873.122837889072, 4896.2410826321975, 4919.409670839379, 4969.900601987868, 5032.84901452957, 5050.217216331474, 5066.08810414129, 5071.340725705634, 5084.847454576368, 5130.51260130287, 4764.809407623657, 4909.459959404391, 4975.203967365204, 4976.2038073908, 5022.698638081727], "value": 4704.5961121353685, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [500.82220811611876, 506.3215291509377, 507.40886866800156, 514.907316682997, 520.5757307794147, 41809.37285940709, 43369.78315108424, 43397.78301108494, 43502.56497435024, 43517.34723979135, 40138.7993060035, 40648.39027414591, 40673.59326406735, 40704.0, 40733.592664073425, 7606.467547271707, 7753.612319384037, 7757.418193635472, 9540.475273859935, 9717.125458708715, 9652.372595781288, 9808.31341806074, 9809.803803923916, 9814.0, 9825.65610203643, 9832.360896541702, 9890.752731181707, 9910.108090271875, 9912.702618921408, 9923.206143508502, 9760.511974401286, 9781.51092445382, 9783.657571984946, 9798.559064842028, 9822.361546499455, 9737.853932191023, 9800.117989380919, 9801.068898454636, 9814.263930205192, 9819.06718861707, 31970.0, 32342.35315293681, 32347.0, 32374.83812580922, 32434.351312973846, 3022.6221722284713, 3107.564940908275, 3287.7369810415107, 3559.9644003559893, 3684.410494320896, 26542.6018609721, 27421.725782742116, 29169.70830291691, 29486.26284342887, 29566.85216573925, 3507.9298414031578, 3573.87491437801, 3691.7046636269033, 3747.850085996557, 3760.341940160461, 253686.0, 257992.0, 259285.0, 259962.0, 260040.0, 56227.71886140543, 57228.0, 57312.713436432554, 57377.42622573804, 57393.71303143458, 60348.69825650931, 60382.0, 60605.39394606086, 60687.69656151691, 60747.392526075055, 14526.346314415876, 14949.701005980038, 15051.096934183985, 15231.314590843438, 15272.465463708717, 9795.412275263507, 9996.50017499122, 10683.09193718527, 10813.567457301777, 10928.344299342065, 18712.812871871378, 18760.81239187618, 18822.81177188238, 18935.147918343708, 19242.0, 7271.418286537064, 7476.401887848959, 7564.205758395399, 7640.440452448381, 7681.923180768121, 778.8014056415691, 809.6842231529708, 893.0533634347612, 936.6206686292063, 939.1688355805162, 23704.762952370602, 25844.483110338064, 26051.73948260493, 26077.73922260753, 26130.216093517225, 947.928905332089, 973.5229737428609, 974.1719538392451, 980.161961522902, 990.1286867556648, 13728.93135534316, 14061.156330619991, 14199.78700319496, 14256.572302830931, 14312.11909281634, 368923.0, 369709.0, 372301.0, 372961.0, 373811.0, 32612.0, 32700.0, 32710.383169346394, 32772.508412373834, 32861.67138328586, 1161.506359797083, 1193.5225909636215, 1203.921745086584, 1210.3101232297681, 1223.822545730864, 23466.0, 23977.52044959126, 24636.753632464162, 26195.0, 27259.318517037234, 47444.7627761859, 53006.20490692565, 53217.0, 53276.200856987954, 53350.0, 316391.0, 320945.0, 321491.0, 321867.0, 322950.0, 34465.65534344625, 35097.8245108783, 35151.29697406089, 37537.624623753414, 38934.0, 42094.36858447064, 42818.0, 43214.351784722625, 43554.0, 43595.34606980834, 37045.25909481741, 37115.257694845415, 37576.0, 37852.0, 38725.80637096796, 35511.64488355187, 36001.27997439984, 36309.27381452409, 36428.0, 36860.63139368572, 32062.0, 32380.35239295154, 33278.5008224872, 33532.32935341328, 33573.83213083919, 31779.841100794347, 33542.83228583841, 34539.0, 34686.918784872985, 34980.65019349774, 25193.87403062973, 25363.33684963126, 25408.491830162922, 25497.745022549538, 25801.483970320114], "value": 18069.30669840718, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [366.19071851208827, 376.80029584320306, 383.4957031503574, 389.0390734884837, 402.46070265843764, 16420.917895410523, 16913.746293805587, 17431.825681743212, 17443.389481368155, 17618.82381176188, 15749.291281892314, 16927.66144677109, 16989.405370812023, 17071.0, 17748.64502709945, 5585.1063829787345, 5821.53427725781, 5889.175515427822, 5890.617109887865, 5891.469767720904, 5022.472640372763, 5847.181394604738, 6831.146106736658, 6861.176658800926, 6956.234814170437, 6475.0, 6913.861722765541, 6920.75777347793, 6950.339717726833, 6964.79105626832, 6629.138212032429, 6673.666316684146, 6697.330266973288, 6703.564268322543, 6704.396604305612, 6440.806775796734, 6582.703778329987, 6831.077804496429, 6870.31296870314, 6874.346937040973, 22414.0, 22752.772472275396, 22804.31587052391, 23033.193838215582, 23177.53644927109, 2088.801563851447, 2518.609615509599, 2568.2552059902605, 2604.270804174834, 2612.6211699303494, 13234.602961911158, 14164.29178541079, 14362.281885905768, 14688.265586720729, 14872.107673539515, 1775.4762345108095, 1797.8292062254066, 1833.7616109905628, 1875.3623767918973, 1879.2013394307364, 73259.63370183168, 74324.99999999946, 75514.86727699089, 76169.23830761733, 76201.23798761997, 16035.759463608056, 16149.273282702306, 16475.34098636041, 16517.83482165187, 16652.66694666124, 27799.0, 27800.0, 27827.721722782917, 27889.302767430978, 28039.719602804118, 8873.157050080232, 8900.777480562909, 8910.153535414125, 9275.72172834816, 9290.62837486493, 7324.121105467378, 7375.483716139819, 7480.326770590564, 7637.770866874002, 7657.655405506765, 7845.529268243924, 8150.877736833952, 8202.712905048294, 8211.935092129315, 8221.835563288701, 3305.652906444837, 3374.1901943533867, 3427.2974040321674, 3450.6031806342594, 3492.231709024011, 378.2623883427328, 379.646928356631, 394.52065740125886, 399.9780012099362, 732.4470025130992, 9906.801863962824, 10437.739056523647, 10508.527116279633, 10624.734381640521, 10674.946625266824, 767.6545554500497, 769.4037121230979, 787.2835719495232, 799.3245707377257, 827.3960009193203, 8774.916368099091, 8907.73276801697, 8994.86507702385, 9052.683156089506, 9307.906920930704, 342232.0, 357973.0, 360758.0, 362423.0, 363383.0, 71652.0, 75395.62302188454, 76938.23061769422, 77308.61345693236, 78150.60924695454, 893.2853717026483, 899.4288626721948, 931.6273490603812, 932.379967321737, 935.8783358163395, 14406.783898241325, 14542.345594448487, 14682.265886705409, 14774.556763297118, 15029.624259393166, 31644.208894777814, 35645.0, 36548.81725591355, 37568.81215593904, 37912.43131352977, 43893.0, 47074.0, 47638.0, 49642.25536617026, 50156.74921625369, 7386.667599957907, 7414.888776668462, 7423.777286681192, 7542.208068152765, 7645.694172233271, 11371.090312774793, 11617.825732613848, 11657.475413606579, 11752.294862308288, 11958.880411196125, 9074.500902450429, 9655.0, 10036.0, 10037.69886903394, 10374.792504150026, 9291.535423228946, 10030.348027378197, 10050.899491004997, 10147.137493313152, 10213.387196768217, 9192.540372981457, 9329.72010839676, 9758.512074396394, 10162.847557286499, 10527.420991845522, 7352.301531354513, 7474.738384156529, 7513.0, 7681.8463630725955, 7692.076950765944, 9439.480828554495, 9812.290100751821, 9888.555015024198, 10075.34510256831, 10437.425941573287], "value": 8914.799705252064, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1289.1942535915052, 1302.029987659194, 1366.5763613279885, 1690.6111594333295, 1707.5731067233185, 6652.401283884449, 6873.621950792704, 7102.0, 7504.924950750491, 7589.241075892408, 7107.751228706997, 7302.233265507125, 7311.720448921444, 7534.660940257688, 7552.81117972051, 2132.541503576727, 2178.237616834111, 2178.4227179797367, 2181.2801775414155, 2185.0495034659893, 2166.945826354346, 2185.44271210841, 2199.5490924360547, 2205.38249290199, 2206.360155554887, 2129.5634394949075, 2152.440365504964, 2152.6448136057534, 2156.4716644422138, 2185.661222510513, 2060.6908963655424, 2082.312836763864, 2085.780992995729, 2087.2068613926763, 2087.551176497045, 2076.5016396064834, 2126.383348828838, 2131.0836340373635, 2133.1040962795664, 2162.859414138076, 7461.7388391406585, 7494.362979146779, 7500.924990750077, 7515.398768098539, 7540.75577529707, 4381.6494680425485, 4558.726476411426, 4569.657275704302, 4575.382323386333, 4577.130345234395, 2982.6271716035494, 3092.70619291167, 3106.7048630380077, 3121.3757248550382, 3135.278885856266, 876.2989608313324, 922.5802259971697, 957.727047791383, 959.7264779537799, 974.3617930255623, 9825.312228144032, 9825.312228144032, 9848.90151098487, 9865.506724663737, 9886.555105020292, 4058.370952502367, 4157.563455837154, 4214.093969796447, 4218.6625069994325, 4306.633936115402, 8506.0, 8512.829743405096, 8517.531535765593, 8533.0, 8536.189062039097, 4636.072785442925, 4647.814087436464, 4648.83729069481, 4654.162250794821, 4655.162070827284, 4031.8185681644395, 4155.314373128461, 4164.3961625564725, 4243.236217480821, 4243.427137336482, 3236.6277878043857, 3312.138843900555, 3319.0, 3373.5108409280515, 3376.40912840252, 1065.8081545321916, 1069.2622090757352, 1072.8498010278568, 1073.6510634043966, 1081.8214994525972, 681.9829504262433, 721.0544923985577, 730.4156674660356, 731.8536292741474, 767.2480968650642, 4400.559944005574, 4473.150101480708, 4597.149527337453, 4624.838130665411, 4806.327114204014, 381.74232393134525, 384.8672208088205, 388.7706253310504, 389.3381251871829, 392.8330459554678, 2930.6776254611977, 2952.011076289467, 3018.2756138527034, 3031.6968303169506, 3037.3014206732573, 8532.488050716976, 8756.824863502694, 8814.955925220333, 8922.01857795642, 9018.323625728097, 8890.244329231959, 8904.510251936206, 8904.55477226149, 8906.688265910661, 8912.55437228149, 6787.321267873173, 6864.553804002724, 6929.3070692931315, 6977.755778547828, 6992.440604751608, 4509.706869053501, 4619.422572178478, 4634.582887540138, 4644.628429725546, 4726.841923728571, 8230.794230144054, 8252.381071419548, 8276.08963014056, 8347.874781878398, 8350.331973442231, 8010.3992200584025, 8323.833523329378, 8333.416660833684, 8348.749537513884, 8349.0, 5970.880582388241, 6108.541859360477, 6314.463270622048, 6424.13274207976, 6433.517486188648, 6599.472042236705, 6701.765438209639, 6822.1131252938785, 6977.686004129928, 6982.301769822977, 6324.525660575568, 6336.176297081534, 6398.87202255943, 6541.8691626168165, 6590.934090659224, 6355.52333574993, 6437.485001199799, 6440.25937017249, 6467.676616169267, 6524.5759025665075, 5637.238972738628, 5728.3985181557, 5730.885382292414, 5731.226284451545, 5735.4551317624755, 4082.4692789937117, 4086.468759061303, 4108.91782164361, 4217.388478670514, 4257.212415703105, 3978.4629075075654, 4044.5550989390554, 4073.287174744467, 4084.346504559256, 4180.4147419361925], "value": 3826.5353705288117, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.1863956980236, 356.8715262505496, 358.6431500656846, 361.89143257022886, 365.69281803285253, 4916.115099282128, 4925.753712314379, 5035.546800787928, 5069.569086627641, 5072.163093089641, 4785.976070119653, 4874.829380971667, 4926.5566099051075, 4934.235193544997, 4935.876603084925, 3967.940480892789, 3985.4221137934983, 3993.7204395692315, 4001.7198796084285, 4005.6795456363566, 3873.7453330615654, 3991.7604943703327, 4009.273693888155, 4012.959870401288, 4026.0, 3949.9605003950023, 3953.169834334779, 3962.881113566598, 3969.8213580388806, 3974.721769476138, 3940.822662980173, 3943.442340275604, 3946.565877753445, 3957.4063890416523, 3962.841486340543, 3559.875404360835, 3577.964220357789, 3578.9284214315567, 3582.6059133495296, 3590.1383667919513, 4732.0, 4880.365552478155, 4882.658213925027, 4898.730569818658, 4973.477784832613, 4510.007798284372, 4513.848968513041, 4526.049529598756, 4534.523874993111, 4541.296099104644, 5217.660852044605, 5234.26720259164, 5234.842954711365, 5245.291885595434, 5257.474252574751, 3501.7608711941352, 3683.0608194910396, 3891.8832435026993, 3896.649301562845, 3903.394973779069, 5420.24116623673, 5425.0, 5427.793460068478, 5434.375046869621, 5436.646617969819, 3686.870959516404, 3715.436348919237, 3749.2314075614436, 3758.5113935188797, 3760.8871733848027, 3760.9059773505332, 3785.280796648628, 3790.507234059555, 3790.8104594770152, 3810.447485114643, 4242.342436922282, 4314.201872653569, 4360.49854266757, 4361.454818147731, 4362.650987920959, 5198.376194856566, 5204.713740744295, 5207.609429292818, 5221.624161006733, 5232.973835130876, 1255.2970336611527, 1258.3645259144073, 1261.0731112632209, 1281.077624110639, 1285.9099863009499, 860.8407444622765, 877.9561021948876, 890.5235698901057, 891.8617614269799, 902.3548163063441, 4494.325851122358, 4502.57225563571, 4520.2315606347, 4534.863954081383, 4536.364908912755, 5227.477252274742, 5230.796916709102, 5267.288915996368, 5295.0, 5306.28365170697, 4467.977660111678, 4470.865874023784, 4492.955070449254, 4500.1199232491235, 4502.887427814266, 2676.7055623881356, 2820.1116648255734, 2847.672517660454, 2860.5995160677107, 2861.353248245406, 4974.8756281093265, 4983.358819825498, 4994.8251811187165, 5001.124803159432, 5007.823161557031, 5244.895102097937, 5373.382061063028, 5417.945820541744, 5422.728863556807, 5461.863453413696, 4873.780679869344, 4890.364252647133, 4897.338859254026, 4901.681390709592, 4906.852794416173, 5298.44366341544, 5302.337207849019, 5306.97346513265, 5324.440933701905, 5352.4915133062295, 4961.776720047535, 5086.516780905808, 5203.528324046048, 5234.319538459976, 5236.319278493771, 4875.975620122019, 4880.365552478297, 4893.75209321635, 4905.190643543775, 4918.1393256180745, 3639.872604458831, 3650.7079433645777, 3659.5425571803526, 3670.50448189491, 3685.8341374638744, 4997.675151115166, 5241.292425522505, 5309.256704061358, 5315.4153043166, 5390.784368625211, 5268.81559145428, 5268.9736551317, 5269.287481568582, 5277.366715994105, 5285.524302812765, 5220.112580861187, 5269.314989051399, 5276.630635855453, 5279.52484276417, 5283.5773138149625, 4996.800127994985, 5161.354830646169, 5164.896702066013, 5201.531862132426, 5243.292155558951, 5324.512444348958, 5331.973340133274, 5346.1713434417725, 5369.140937449989, 5370.248165256945, 5212.462323614467, 5226.170840205898, 5228.37259528859, 5240.423553409199, 5261.631685781958], "value": 3938.546342522879, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [667.266007391869, 691.8754624167651, 737.2885165814987, 753.4349238071446, 777.1257335497563, 12392.876071239285, 13473.528426505074, 13483.258420786853, 13487.3256337183, 13539.05226634136, 13042.869571304283, 13199.0, 13261.336933153329, 13292.335383230848, 13299.734005319911, 668.7659319238252, 687.5256073309425, 775.647080578337, 791.3945831438954, 811.4117264982899, 822.8436597046572, 825.6821123867294, 826.0, 826.499967519651, 827.6399766101734, 880.009988762642, 881.0220655072864, 882.4484697064335, 884.4516399832113, 885.3935054487664, 877.4165180155189, 879.973600791974, 880.0451510111473, 882.2853488674162, 884.0673089890163, 643.7489379142138, 644.3073695777058, 648.0506058624138, 648.5524987758431, 648.9805305840832, 13260.53588124411, 13310.933445332712, 13316.400761965735, 13436.798448023288, 13880.58358249244, 12914.418851151626, 13624.727505449835, 13829.006673421605, 13861.37623806921, 14051.297435128305, 10579.576816927314, 10674.573017079385, 10726.195535334804, 10786.191035672357, 10964.835527467094, 3318.0875259303643, 3337.2324365396094, 3358.2779702363864, 3389.559357283562, 3476.3916314644835, 61480.69259653673, 61834.381656183315, 62363.0, 62483.06275405918, 63649.68175159141, 10044.397336159855, 10057.195424366033, 10316.43259620728, 10495.685129446128, 10817.621383251706, 19785.307514236934, 19800.0, 19838.801611983985, 19852.305169319003, 19865.801341986684, 6257.436830685169, 6591.242007169141, 6648.833779155463, 6807.217170025411, 6828.897566536506, 3520.8415621297104, 3589.892303230855, 3655.8354874030733, 3735.327641024587, 3739.08392443853, 2853.543433050702, 2905.5932169496286, 2918.416316736661, 2925.6635486919276, 2940.470715271229, 500.6620531141465, 502.4975024975025, 508.23256882108285, 511.1081163369869, 519.8544407565888, 537.5377175628956, 584.7085361510096, 591.6745789815584, 591.6982339007051, 599.5713065158345, 8080.636371363303, 8096.595170241582, 8209.917900821034, 8239.629216685264, 8525.786855328666, 502.11627535536985, 563.5688698145922, 575.2119596153213, 593.2288025566833, 596.263614436171, 3852.1332700142243, 3961.3265744823448, 3995.141044675409, 4024.9195016099516, 4089.5501494835544, 66595.0, 66973.66513167403, 68470.0, 68602.65698671475, 70620.29379706137, 58143.802090884565, 58736.0, 58887.0, 58977.0, 59143.11285330723, 42490.57509424928, 43482.34776478355, 47274.763626182335, 53236.46763532315, 53602.7319863406, 7552.509086909334, 8005.39959503028, 8027.2775450209765, 8072.87890681652, 8109.472884262504, 38062.0, 38137.0, 38451.42322865103, 39009.414858776574, 39874.401883971186, 45122.323165153204, 49236.0, 50731.0, 51140.48859511357, 52687.736561316946, 10437.112845408225, 10553.155747540332, 10661.147108231478, 10683.732906677395, 10718.785624287626, 10227.795444091225, 10411.375317480975, 10655.30740501865, 10696.358218506915, 10813.945930270298, 10496.947515262375, 11595.622897305864, 11729.882701172879, 12371.443285052013, 12501.43743531525, 11498.195126341076, 11890.821637675615, 12054.51781928713, 12215.633530994084, 12577.559785407468, 12021.879781202426, 12305.384730763248, 12613.243205407705, 12664.240145591293, 12897.806532901826, 10119.237200414034, 10311.976445629545, 10750.0, 10775.730606734895, 11040.0, 8718.738437846874, 9450.763730906783, 9775.902240977499, 10263.332883362558, 10367.170626350025], "value": 6348.9485273595, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1576.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 115.0, 114.0, 117.0, 115.0, 113.0, 114.0, 113.0, 115.0, 113.0], "value": 113.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 133.0, 130.0, 183.0, 127.0, 132.0, 127.0, 128.0, 147.0, 131.0], "value": 139.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 165.0, 166.0, 169.0, 164.0, 164.0, 162.0, 163.0, 163.0, 163.0], "value": 164.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 76.0, 77.0, 113.0, 77.0, 77.0, 76.0, 78.0, 99.0, 78.0], "value": 83.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 85.0, 113.0, 87.0, 88.0, 87.0, 105.0, 85.0, 85.0, 107.0], "value": 93.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 118.0, 117.0, 118.0, 122.0, 119.0, 118.0, 119.0, 118.0], "value": 118.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 151.0, 149.0, 152.0, 151.0, 150.0, 150.0, 151.0, 151.0, 154.0], "value": 151.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 97.0, 96.0, 96.0, 95.0, 96.0, 96.0, 95.0, 96.0, 96.0], "value": 96.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 67.0, 65.0, 64.0, 65.0, 66.0, 66.0, 65.0, 65.0, 65.0], "value": 65.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 67.0, 67.0, 67.0, 67.0, 67.0, 67.0, 67.0, 67.0], "value": 67.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 89.0, 91.0, 85.0, 86.0, 85.0, 86.0, 92.0, 86.0, 86.0], "value": 87.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 158.0, 134.0, 136.0, 152.0, 138.0, 145.0, 143.0, 188.0, 146.0], "value": 147.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [192.0, 176.0, 178.0, 177.0, 181.0, 184.0, 177.0, 179.0, 183.0, 186.0], "value": 181.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 74.0, 69.0, 68.0, 68.0, 70.0, 68.0, 67.0, 66.0, 68.0], "value": 68.7, "unit": "score"}]}]} 12:36:24 INFO - Return code: 0 12:36:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:36:24 ERROR - # TBPL SUCCESS # 12:36:24 INFO - Running post-action listener: _resource_record_post_action 12:36:24 INFO - [mozharness: 2016-12-23 20:36:24.528914Z] Finished run-tests step (success) 12:36:24 INFO - Running post-run listener: _resource_record_post_run 12:36:24 INFO - Total resource usage - Wall time: 1200s; CPU: 14.0%; Read bytes: 4096; Write bytes: 412418048; Read time: 12; Write time: 227872 12:36:24 INFO - TinderboxPrint: CPU usage
13.5% 12:36:24 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 12:36:24 INFO - TinderboxPrint: I/O write bytes / time
412,418,048 / 227,872 12:36:24 INFO - TinderboxPrint: CPU idle
8,279.3 (86.5%) 12:36:24 INFO - TinderboxPrint: CPU user
1,235.3 (12.9%) 12:36:24 INFO - TinderboxPrint: Swap in / out
0 / 0 12:36:24 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 115990528; Read time: 0; Write time: 59448 12:36:24 INFO - run-tests - Wall time: 1184s; CPU: 14.0%; Read bytes: 0; Write bytes: 295505920; Read time: 0; Write time: 168392 12:36:25 INFO - Running post-run listener: _upload_blobber_files 12:36:25 INFO - Blob upload gear active. 12:36:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:36:25 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:36:25 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', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:36:25 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 Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:36:25 INFO - (blobuploader) - INFO - Open directory for files ... 12:36:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:36:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:36:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:36:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:36:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:36:26 INFO - (blobuploader) - INFO - Done attempting. 12:36:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:36:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:36:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:36:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:36:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:36:27 INFO - (blobuploader) - INFO - Done attempting. 12:36:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:36:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:36:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:36:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:36:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:36:31 INFO - (blobuploader) - INFO - Done attempting. 12:36:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:36:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:36:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:36:31 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:36:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:36:31 INFO - (blobuploader) - INFO - Done attempting. 12:36:31 INFO - (blobuploader) - INFO - Iteration through files over. 12:36:31 INFO - Return code: 0 12:36:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:36:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:36:31 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/2e35ad4b9c07064f0bca54532d4c79244a1ac08d4e14fac1d5684057946bf6899a385481974ad7d7709b890e48119175ea775f1a10cd83c40f53e6e033e1b59f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/9ac21d5b94ab3bb0cdd043c7bb61a8a7fb57d5723ecfd6391cb05b68e8f7153f9c250a3f643c20d2b9495d9a364b14744ebee55d0c8b82a53febaf7a59626a39", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/22130703ea12124bd0faf7343d7c6360a30eb2442ca8f1312b2bcb26325705703af54e8ffcd4d822dbbf5a7b220e69b4cf0baf8d07f3586ec01645111d115b18", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/a8879fe0b221df2d835640c174ab4766a89c9d312ac854e3b7ceae2b4db59c1a7f219a5eb1c1237bca0be4a6493929274ae6fee6365fc4c24ecd9cae4f053f40"} 12:36:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:36:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:36:31 INFO - Contents: 12:36:31 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/2e35ad4b9c07064f0bca54532d4c79244a1ac08d4e14fac1d5684057946bf6899a385481974ad7d7709b890e48119175ea775f1a10cd83c40f53e6e033e1b59f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/9ac21d5b94ab3bb0cdd043c7bb61a8a7fb57d5723ecfd6391cb05b68e8f7153f9c250a3f643c20d2b9495d9a364b14744ebee55d0c8b82a53febaf7a59626a39", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/22130703ea12124bd0faf7343d7c6360a30eb2442ca8f1312b2bcb26325705703af54e8ffcd4d822dbbf5a7b220e69b4cf0baf8d07f3586ec01645111d115b18", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/a8879fe0b221df2d835640c174ab4766a89c9d312ac854e3b7ceae2b4db59c1a7f219a5eb1c1237bca0be4a6493929274ae6fee6365fc4c24ecd9cae4f053f40"} 12:36:31 INFO - Running post-run listener: copy_logs_to_upload_dir 12:36:31 INFO - Copying logs to upload dir... 12:36:31 INFO - mkdir: /builds/slave/test/build/upload/logs 12:36:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.540176 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-12-23 12:36:31.540601) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-23 12:36:31.544261) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1482522865.672488-1587695568 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/2e35ad4b9c07064f0bca54532d4c79244a1ac08d4e14fac1d5684057946bf6899a385481974ad7d7709b890e48119175ea775f1a10cd83c40f53e6e033e1b59f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/9ac21d5b94ab3bb0cdd043c7bb61a8a7fb57d5723ecfd6391cb05b68e8f7153f9c250a3f643c20d2b9495d9a364b14744ebee55d0c8b82a53febaf7a59626a39", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/22130703ea12124bd0faf7343d7c6360a30eb2442ca8f1312b2bcb26325705703af54e8ffcd4d822dbbf5a7b220e69b4cf0baf8d07f3586ec01645111d115b18", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/a8879fe0b221df2d835640c174ab4766a89c9d312ac854e3b7ceae2b4db59c1a7f219a5eb1c1237bca0be4a6493929274ae6fee6365fc4c24ecd9cae4f053f40"} build_url:https://queue.taskcluster.net/v1/task/PhhrtpKkQ9yTnzc8Hs_mGA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011239 build_url: 'https://queue.taskcluster.net/v1/task/PhhrtpKkQ9yTnzc8Hs_mGA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/2e35ad4b9c07064f0bca54532d4c79244a1ac08d4e14fac1d5684057946bf6899a385481974ad7d7709b890e48119175ea775f1a10cd83c40f53e6e033e1b59f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/9ac21d5b94ab3bb0cdd043c7bb61a8a7fb57d5723ecfd6391cb05b68e8f7153f9c250a3f643c20d2b9495d9a364b14744ebee55d0c8b82a53febaf7a59626a39", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/22130703ea12124bd0faf7343d7c6360a30eb2442ca8f1312b2bcb26325705703af54e8ffcd4d822dbbf5a7b220e69b4cf0baf8d07f3586ec01645111d115b18", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/a8879fe0b221df2d835640c174ab4766a89c9d312ac854e3b7ceae2b4db59c1a7f219a5eb1c1237bca0be4a6493929274ae6fee6365fc4c24ecd9cae4f053f40"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-23 12:36:31.570239) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 12:36:31.570589) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1482522865.672488-1587695568 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004522 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-23 12:36:31.655700) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-23 12:36:31.656005) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-23 12:36:31.656351) ========= ========= Total master_lag: 0.19 =========