builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-033 starttime: 1471609270.96 results: success (0) buildid: 20160819023002 builduid: 7b3c542b86a04654b5c217a911bc81fd revision: eaff4fe1c6194cc73476d14b4faff2f355bb64b0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-19 05:21:10.956929) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-19 05:21:10.957365) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-19 05:21:10.957657) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-19 05:21:10.983850) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 05:21:10.984338) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1471608777.456172-963003291 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-19 05:21:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 43.2M=0s 2016-08-19 05:21:11 (43.2 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.094281 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 05:21:11.104601) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 05:21:11.105068) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1471608777.456172-963003291 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017694 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 05:21:11.155176) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-19 05:21:11.155507) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev eaff4fe1c6194cc73476d14b4faff2f355bb64b0 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev eaff4fe1c6194cc73476d14b4faff2f355bb64b0 --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=edae0fb5fee9fb758e8af6d3000002a4-1471608777.456172-963003291 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-19 05:21:11,210 truncating revision to first 12 chars 2016-08-19 05:21:11,210 Setting DEBUG logging. 2016-08-19 05:21:11,210 attempt 1/10 2016-08-19 05:21:11,210 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/eaff4fe1c619?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-19 05:21:12,694 unpacking tar archive at: fx-team-eaff4fe1c619/testing/mozharness/ program finished with exit code 0 elapsedTime=1.794802 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-19 05:21:12.964184) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-19 05:21:12.964506) ========= script_repo_revision: eaff4fe1c6194cc73476d14b4faff2f355bb64b0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-19 05:21:12.964910) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-19 05:21:12.965180) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-19 05:21:12.974707) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-08-19 05:21:12.975014) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=edae0fb5fee9fb758e8af6d3000002a4-1471608777.456172-963003291 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:21:13 INFO - MultiFileLogger online at 20160819 05:21:13 in /builds/slave/test 05:21:13 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 05:21:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:21:13 INFO - {'append_to_log': False, 05:21:13 INFO - 'base_work_dir': '/builds/slave/test', 05:21:13 INFO - 'blob_upload_branch': 'Fx-Team', 05:21:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:21:13 INFO - 'branch': 'Fx-Team', 05:21:13 INFO - 'buildbot_json_path': 'buildprops.json', 05:21:13 INFO - 'config_files': ('talos/linux_config.py',), 05:21:13 INFO - 'default_actions': ('clobber', 05:21:13 INFO - 'read-buildbot-config', 05:21:13 INFO - 'download-and-extract', 05:21:13 INFO - 'populate-webroot', 05:21:13 INFO - 'create-virtualenv', 05:21:13 INFO - 'install', 05:21:13 INFO - 'run-tests'), 05:21:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:21:13 INFO - 'download_minidump_stackwalk': True, 05:21:13 INFO - 'download_symbols': 'ondemand', 05:21:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:21:13 INFO - 'tooltool.py': '/tools/tooltool.py', 05:21:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:21:13 INFO - '/tools/misc-python/virtualenv.py')}, 05:21:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:21:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:21:13 INFO - 'installer_path': 'installer.exe', 05:21:13 INFO - 'log_level': 'info', 05:21:13 INFO - 'log_name': 'talos', 05:21:13 INFO - 'log_to_console': True, 05:21:13 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:21:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:21:13 INFO - 'opt_config_files': (), 05:21:13 INFO - 'pip_index': False, 05:21:13 INFO - 'sps_profile': False, 05:21:13 INFO - 'sps_profile_interval': 0, 05:21:13 INFO - 'suite': 'dromaeojs', 05:21:13 INFO - 'system_bits': '32', 05:21:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:21:13 INFO - 'title': 'talos-linux64-ix-033', 05:21:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:21:13 INFO - 'use_talos_json': True, 05:21:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:21:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:21:13 INFO - 'work_dir': 'build'} 05:21:13 INFO - [mozharness: 2016-08-19 12:21:13.130453Z] Running clobber step. 05:21:13 INFO - Running pre-action listener: _resource_record_pre_action 05:21:13 INFO - Running main action method: clobber 05:21:13 INFO - rmtree: /builds/slave/test/build 05:21:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:21:13 INFO - Running post-action listener: _resource_record_post_action 05:21:13 INFO - [mozharness: 2016-08-19 12:21:13.578360Z] Finished clobber step (success) 05:21:13 INFO - [mozharness: 2016-08-19 12:21:13.578461Z] Running read-buildbot-config step. 05:21:13 INFO - Running pre-action listener: _resource_record_pre_action 05:21:13 INFO - Running main action method: read_buildbot_config 05:21:13 INFO - Using buildbot properties: 05:21:13 INFO - { 05:21:13 INFO - "project": "", 05:21:13 INFO - "product": "firefox", 05:21:13 INFO - "script_repo_revision": "production", 05:21:13 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 05:21:13 INFO - "repository": "", 05:21:13 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 05:21:13 INFO - "buildid": "20160819023002", 05:21:13 INFO - "pgo_build": "True", 05:21:13 INFO - "basedir": "/builds/slave/test", 05:21:13 INFO - "buildnumber": 1479, 05:21:13 INFO - "slavename": "talos-linux64-ix-033", 05:21:13 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:21:13 INFO - "platform": "ubuntu64_hw", 05:21:13 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 05:21:13 INFO - "branch": "fx-team", 05:21:13 INFO - "repo_path": "integration/fx-team", 05:21:13 INFO - "revision": "eaff4fe1c6194cc73476d14b4faff2f355bb64b0", 05:21:13 INFO - "stage_platform": "linux64-pgo", 05:21:13 INFO - "builduid": "7b3c542b86a04654b5c217a911bc81fd", 05:21:13 INFO - "slavebuilddir": "test" 05:21:13 INFO - } 05:21:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/XnbO4E_qTAy46EVv17OKXg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 05:21:13 INFO - Running post-action listener: _resource_record_post_action 05:21:13 INFO - [mozharness: 2016-08-19 12:21:13.580939Z] Finished read-buildbot-config step (success) 05:21:13 INFO - [mozharness: 2016-08-19 12:21:13.581027Z] Running download-and-extract step. 05:21:13 INFO - Running pre-action listener: _resource_record_pre_action 05:21:13 INFO - Running main action method: download_and_extract 05:21:13 INFO - mkdir: /builds/slave/test/build/tests 05:21:13 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')]} 05:21:13 INFO - trying https://queue.taskcluster.net/v1/task/XnbO4E_qTAy46EVv17OKXg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 05:21:13 INFO - Downloading https://queue.taskcluster.net/v1/task/XnbO4E_qTAy46EVv17OKXg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 05:21:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XnbO4E_qTAy46EVv17OKXg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 05:21:14 INFO - Downloaded 1361 bytes. 05:21:14 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 05:21:14 INFO - Using the following test package requirements: 05:21:14 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 05:21:14 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:21:14 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:21:14 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:21:14 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 05:21:14 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:21:14 INFO - u'jsshell-linux-x86_64.zip'], 05:21:14 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:21:14 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:21:14 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 05:21:14 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:21:14 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:21:14 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:21:14 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:21:14 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:21:14 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:21:14 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 05:21:14 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:21:14 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:21:14 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')]} 05:21:14 INFO - trying https://queue.taskcluster.net/v1/task/XnbO4E_qTAy46EVv17OKXg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 05:21:14 INFO - Downloading https://queue.taskcluster.net/v1/task/XnbO4E_qTAy46EVv17OKXg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 05:21:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XnbO4E_qTAy46EVv17OKXg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:21:15 INFO - Downloaded 21907509 bytes. 05:21:15 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 05:21:19 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 05:21: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')]} 05:21:19 INFO - trying https://queue.taskcluster.net/v1/task/XnbO4E_qTAy46EVv17OKXg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 05:21:19 INFO - Downloading https://queue.taskcluster.net/v1/task/XnbO4E_qTAy46EVv17OKXg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 05:21:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XnbO4E_qTAy46EVv17OKXg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:21:20 INFO - Downloaded 21907509 bytes. 05:21:20 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/tests 05:21:25 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')]} 05:21:25 INFO - trying https://queue.taskcluster.net/v1/task/XnbO4E_qTAy46EVv17OKXg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 05:21:25 INFO - Downloading https://queue.taskcluster.net/v1/task/XnbO4E_qTAy46EVv17OKXg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 05:21:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XnbO4E_qTAy46EVv17OKXg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 05:21:25 INFO - Downloaded 14413981 bytes. 05:21:25 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/tests 05:21:26 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')]} 05:21:26 INFO - trying https://queue.taskcluster.net/v1/task/XnbO4E_qTAy46EVv17OKXg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 05:21:26 INFO - Downloading https://queue.taskcluster.net/v1/task/XnbO4E_qTAy46EVv17OKXg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 05:21:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XnbO4E_qTAy46EVv17OKXg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 05:21:27 INFO - Downloaded 59617999 bytes. 05:21:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XnbO4E_qTAy46EVv17OKXg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 05:21:27 INFO - mkdir: /builds/slave/test/properties 05:21:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:21:27 INFO - Writing to file /builds/slave/test/properties/build_url 05:21:27 INFO - Contents: 05:21:27 INFO - build_url:https://queue.taskcluster.net/v1/task/XnbO4E_qTAy46EVv17OKXg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 05:21:28 INFO - Running post-action listener: _resource_record_post_action 05:21:28 INFO - Running post-action listener: set_extra_try_arguments 05:21:28 INFO - [mozharness: 2016-08-19 12:21:28.048390Z] Finished download-and-extract step (success) 05:21:28 INFO - [mozharness: 2016-08-19 12:21:28.048613Z] Running populate-webroot step. 05:21:28 INFO - Running pre-action listener: _resource_record_pre_action 05:21:28 INFO - Running main action method: populate_webroot 05:21:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:21:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:21:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:21:28 INFO - '--tppagecycles', 05:21:28 INFO - '7'], 05:21:28 INFO - 'tests': ['tsvgm']}}, 05:21:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:21:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:21:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:21:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:21:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:21:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:21:28 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:21:28 INFO - 'talos_options': ['--disable-e10s'], 05:21:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:21:28 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:21:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:21:28 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:21:28 INFO - 'talos_options': ['--disable-e10s'], 05:21:28 INFO - 'tests': ['damp', 'tps']}, 05:21:28 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:21:28 INFO - 'tests': ['damp', 'tps']}, 05:21:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:21:28 INFO - 'tests': ['dromaeo_dom']}, 05:21:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:21:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:21:28 INFO - 'tests': ['basic_compositor_video']}, 05:21:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:21:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:21:28 INFO - 'tests': ['a11yr', 05:21:28 INFO - 'ts_paint', 05:21:28 INFO - 'tpaint', 05:21:28 INFO - 'sessionrestore', 05:21:28 INFO - 'sessionrestore_no_auto_restore', 05:21:28 INFO - 'tabpaint']}, 05:21:28 INFO - 'other-e10s': {'tests': ['a11yr', 05:21:28 INFO - 'ts_paint', 05:21:28 INFO - 'tpaint', 05:21:28 INFO - 'sessionrestore', 05:21:28 INFO - 'sessionrestore_no_auto_restore', 05:21:28 INFO - 'tabpaint']}, 05:21:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:21:28 INFO - 'ts_paint', 05:21:28 INFO - 'tpaint', 05:21:28 INFO - 'sessionrestore', 05:21:28 INFO - 'sessionrestore_no_auto_restore', 05:21:28 INFO - 'tabpaint']}, 05:21:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:21:28 INFO - 'ts_paint', 05:21:28 INFO - 'tpaint', 05:21:28 INFO - 'sessionrestore', 05:21:28 INFO - 'sessionrestore_no_auto_restore', 05:21:28 INFO - 'tabpaint']}, 05:21:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:21:28 INFO - 'tests': ['a11yr', 05:21:28 INFO - 'ts_paint', 05:21:28 INFO - 'tpaint', 05:21:28 INFO - 'sessionrestore', 05:21:28 INFO - 'sessionrestore_no_auto_restore', 05:21:28 INFO - 'tabpaint']}, 05:21:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:21:28 INFO - 'tests': ['a11yr', 05:21:28 INFO - 'ts_paint', 05:21:28 INFO - 'tpaint', 05:21:28 INFO - 'sessionrestore', 05:21:28 INFO - 'sessionrestore_no_auto_restore', 05:21:28 INFO - 'tabpaint']}, 05:21:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:21:28 INFO - 'tests': ['tsvgx', 05:21:28 INFO - 'tsvgr_opacity', 05:21:28 INFO - 'tart', 05:21:28 INFO - 'tscrollx', 05:21:28 INFO - 'cart']}, 05:21:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:21:28 INFO - 'tsvgr_opacity', 05:21:28 INFO - 'tart', 05:21:28 INFO - 'tscrollx', 05:21:28 INFO - 'cart']}, 05:21:28 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:21:28 INFO - 'talos_options': ['--disable-e10s'], 05:21:28 INFO - 'tests': ['tp5o']}, 05:21:28 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:21:28 INFO - 'tests': ['tp5o']}, 05:21:28 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:21:28 INFO - 'talos_options': ['--disable-e10s', 05:21:28 INFO - '--xperf_path', 05:21:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:21:28 INFO - 'tests': ['tp5n']}, 05:21:28 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:21:28 INFO - 'talos_options': ['--xperf_path', 05:21:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:21:28 INFO - 'tests': ['tp5n']}}, 05:21:28 INFO - 'talos.zip': {'path': '', 05:21:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:21:28 INFO - Running post-action listener: _resource_record_post_action 05:21:28 INFO - [mozharness: 2016-08-19 12:21:28.067544Z] Finished populate-webroot step (success) 05:21:28 INFO - [mozharness: 2016-08-19 12:21:28.067632Z] Running create-virtualenv step. 05:21:28 INFO - Running pre-action listener: _resource_record_pre_action 05:21:28 INFO - Running main action method: create_virtualenv 05:21:28 INFO - Creating virtualenv /builds/slave/test/build/venv 05:21:28 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 05:21:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:21:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:21:28 INFO - Using real prefix '/usr' 05:21:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:21:28 INFO - Installing distribute.............................................................................................................................................................................................done. 05:21:30 INFO - Installing pip.................done. 05:21:30 INFO - Return code: 0 05:21:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:21:30 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')]} 05:21:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:21:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:30 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')]} 05:21:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:21:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:30 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 0x7f035541cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x144c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1519850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1514370>, '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 0x15178f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1517d80>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1471608777.456172-963003291', '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 05:21:30 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 05:21:30 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 05:21:30 INFO - Using env: {'DISPLAY': ':0', 05:21:30 INFO - 'HOME': '/home/cltbld', 05:21:30 INFO - 'LANG': 'en_US.UTF-8', 05:21:30 INFO - 'LANGUAGE': 'en_US:en', 05:21:30 INFO - 'LOGNAME': 'cltbld', 05:21:30 INFO - 'MAIL': '/var/mail/cltbld', 05:21:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:21:30 INFO - 'MOZ_NO_REMOTE': '1', 05:21:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:21:30 INFO - 'NO_EM_RESTART': '1', 05:21:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:21:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:21:30 INFO - 'PWD': '/builds/slave/test', 05:21:30 INFO - 'SHELL': '/bin/bash', 05:21:30 INFO - 'SHLVL': '1', 05:21:30 INFO - 'TERM': 'linux', 05:21:30 INFO - 'TMOUT': '86400', 05:21:30 INFO - 'USER': 'cltbld', 05:21:30 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1471608777.456172-963003291', 05:21:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:21:30 INFO - '_': '/tools/buildbot/bin/python'} 05:21:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:21:30 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 05:21:30 INFO - Cleaning up... 05:21:30 INFO - Return code: 0 05:21:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:21:30 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')]} 05:21:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:21:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:30 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')]} 05:21:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:21:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:30 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 0x7f035541cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x144c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1519850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1514370>, '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 0x15178f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1517d80>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1471608777.456172-963003291', '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 05:21:30 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 05:21:30 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 05:21:30 INFO - Using env: {'DISPLAY': ':0', 05:21:30 INFO - 'HOME': '/home/cltbld', 05:21:30 INFO - 'LANG': 'en_US.UTF-8', 05:21:30 INFO - 'LANGUAGE': 'en_US:en', 05:21:30 INFO - 'LOGNAME': 'cltbld', 05:21:30 INFO - 'MAIL': '/var/mail/cltbld', 05:21:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:21:30 INFO - 'MOZ_NO_REMOTE': '1', 05:21:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:21:30 INFO - 'NO_EM_RESTART': '1', 05:21:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:21:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:21:30 INFO - 'PWD': '/builds/slave/test', 05:21:30 INFO - 'SHELL': '/bin/bash', 05:21:30 INFO - 'SHLVL': '1', 05:21:30 INFO - 'TERM': 'linux', 05:21:30 INFO - 'TMOUT': '86400', 05:21:30 INFO - 'USER': 'cltbld', 05:21:30 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1471608777.456172-963003291', 05:21:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:21:30 INFO - '_': '/tools/buildbot/bin/python'} 05:21:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:21:31 INFO - Downloading/unpacking psutil>=3.1.1 05:21:31 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 05:21:31 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 05:21:31 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 05:21:31 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 05:21:31 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 05:21:31 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 05:21:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:21:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:21:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:21:33 INFO - Installing collected packages: psutil 05:21:33 INFO - Running setup.py install for psutil 05:21:33 INFO - building 'psutil._psutil_linux' extension 05:21:33 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 05:21:33 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 05:21:33 INFO - building 'psutil._psutil_posix' extension 05:21:33 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 05:21:33 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 05:21:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:21:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:21:33 INFO - Successfully installed psutil 05:21:33 INFO - Cleaning up... 05:21:33 INFO - Return code: 0 05:21:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:21:33 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')]} 05:21:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:21:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:33 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')]} 05:21:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:21:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:33 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 0x7f035541cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x144c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1519850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1514370>, '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 0x15178f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1517d80>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1471608777.456172-963003291', '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 05:21:33 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 05:21:33 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 05:21:33 INFO - Using env: {'DISPLAY': ':0', 05:21:33 INFO - 'HOME': '/home/cltbld', 05:21:33 INFO - 'LANG': 'en_US.UTF-8', 05:21:33 INFO - 'LANGUAGE': 'en_US:en', 05:21:33 INFO - 'LOGNAME': 'cltbld', 05:21:33 INFO - 'MAIL': '/var/mail/cltbld', 05:21:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:21:33 INFO - 'MOZ_NO_REMOTE': '1', 05:21:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:21:33 INFO - 'NO_EM_RESTART': '1', 05:21:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:21:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:21:33 INFO - 'PWD': '/builds/slave/test', 05:21:33 INFO - 'SHELL': '/bin/bash', 05:21:33 INFO - 'SHLVL': '1', 05:21:33 INFO - 'TERM': 'linux', 05:21:33 INFO - 'TMOUT': '86400', 05:21:33 INFO - 'USER': 'cltbld', 05:21:33 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1471608777.456172-963003291', 05:21:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:21:33 INFO - '_': '/tools/buildbot/bin/python'} 05:21:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:21:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:21:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:21:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:21:33 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 05:21:33 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 05:21:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:21:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:21:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:21:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:21:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:21:35 INFO - Installing collected packages: mozsystemmonitor 05:21:35 INFO - Running setup.py install for mozsystemmonitor 05:21:35 INFO - Successfully installed mozsystemmonitor 05:21:35 INFO - Cleaning up... 05:21:35 INFO - Return code: 0 05:21:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:21:35 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')]} 05:21:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:21:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:35 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')]} 05:21:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:21:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:35 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 0x7f035541cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x144c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1519850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1514370>, '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 0x15178f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1517d80>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1471608777.456172-963003291', '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 05:21:35 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 05:21:35 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 05:21:35 INFO - Using env: {'DISPLAY': ':0', 05:21:35 INFO - 'HOME': '/home/cltbld', 05:21:35 INFO - 'LANG': 'en_US.UTF-8', 05:21:35 INFO - 'LANGUAGE': 'en_US:en', 05:21:35 INFO - 'LOGNAME': 'cltbld', 05:21:35 INFO - 'MAIL': '/var/mail/cltbld', 05:21:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:21:35 INFO - 'MOZ_NO_REMOTE': '1', 05:21:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:21:35 INFO - 'NO_EM_RESTART': '1', 05:21:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:21:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:21:35 INFO - 'PWD': '/builds/slave/test', 05:21:35 INFO - 'SHELL': '/bin/bash', 05:21:35 INFO - 'SHLVL': '1', 05:21:35 INFO - 'TERM': 'linux', 05:21:35 INFO - 'TMOUT': '86400', 05:21:35 INFO - 'USER': 'cltbld', 05:21:35 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1471608777.456172-963003291', 05:21:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:21:35 INFO - '_': '/tools/buildbot/bin/python'} 05:21:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:21:36 INFO - Downloading/unpacking jsonschema==2.5.1 05:21:36 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 05:21:36 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 05:21:36 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 05:21:36 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 05:21:36 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 05:21:36 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 05:21:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:21:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:21:38 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 05:21:38 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 05:21:38 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 05:21:38 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 05:21:38 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 05:21:38 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 05:21:38 INFO - Downloading functools32-3.2.3-2.tar.gz 05:21:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:21:38 INFO - warning: no files found matching '*.txt' 05:21:38 INFO - no previously-included directories found matching 'build' 05:21:38 INFO - no previously-included directories found matching 'dist' 05:21:38 INFO - no previously-included directories found matching '.git*' 05:21:38 INFO - Installing collected packages: jsonschema, functools32 05:21:38 INFO - Running setup.py install for functools32 05:21:38 INFO - warning: no files found matching '*.txt' 05:21:38 INFO - no previously-included directories found matching 'build' 05:21:38 INFO - no previously-included directories found matching 'dist' 05:21:38 INFO - no previously-included directories found matching '.git*' 05:21:38 INFO - Successfully installed jsonschema functools32 05:21:38 INFO - Cleaning up... 05:21:38 INFO - Return code: 0 05:21:38 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:21:38 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')]} 05:21:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:21:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:38 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')]} 05:21:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:21:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:38 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 0x7f035541cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x144c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1519850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1514370>, '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 0x15178f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1517d80>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1471608777.456172-963003291', '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 05:21:38 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 05:21:38 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 05:21:38 INFO - Using env: {'DISPLAY': ':0', 05:21:38 INFO - 'HOME': '/home/cltbld', 05:21:38 INFO - 'LANG': 'en_US.UTF-8', 05:21:38 INFO - 'LANGUAGE': 'en_US:en', 05:21:38 INFO - 'LOGNAME': 'cltbld', 05:21:38 INFO - 'MAIL': '/var/mail/cltbld', 05:21:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:21:38 INFO - 'MOZ_NO_REMOTE': '1', 05:21:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:21:38 INFO - 'NO_EM_RESTART': '1', 05:21:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:21:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:21:38 INFO - 'PWD': '/builds/slave/test', 05:21:38 INFO - 'SHELL': '/bin/bash', 05:21:38 INFO - 'SHLVL': '1', 05:21:38 INFO - 'TERM': 'linux', 05:21:38 INFO - 'TMOUT': '86400', 05:21:38 INFO - 'USER': 'cltbld', 05:21:38 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1471608777.456172-963003291', 05:21:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:21:38 INFO - '_': '/tools/buildbot/bin/python'} 05:21:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:21:38 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:21:38 INFO - Cleaning up... 05:21:38 INFO - Return code: 0 05:21:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:21:38 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')]} 05:21:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:21:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:38 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')]} 05:21:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:21:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:38 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 0x7f035541cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x144c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1519850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1514370>, '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 0x15178f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1517d80>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1471608777.456172-963003291', '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 05:21:38 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 05:21:38 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 05:21:38 INFO - Using env: {'DISPLAY': ':0', 05:21:38 INFO - 'HOME': '/home/cltbld', 05:21:38 INFO - 'LANG': 'en_US.UTF-8', 05:21:38 INFO - 'LANGUAGE': 'en_US:en', 05:21:38 INFO - 'LOGNAME': 'cltbld', 05:21:38 INFO - 'MAIL': '/var/mail/cltbld', 05:21:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:21:38 INFO - 'MOZ_NO_REMOTE': '1', 05:21:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:21:38 INFO - 'NO_EM_RESTART': '1', 05:21:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:21:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:21:38 INFO - 'PWD': '/builds/slave/test', 05:21:38 INFO - 'SHELL': '/bin/bash', 05:21:38 INFO - 'SHLVL': '1', 05:21:38 INFO - 'TERM': 'linux', 05:21:38 INFO - 'TMOUT': '86400', 05:21:38 INFO - 'USER': 'cltbld', 05:21:38 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1471608777.456172-963003291', 05:21:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:21:38 INFO - '_': '/tools/buildbot/bin/python'} 05:21:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:21:38 INFO - Downloading/unpacking blobuploader==1.2.4 05:21:38 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 05:21:38 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 05:21:38 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 05:21:38 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 05:21:38 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 05:21:38 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 05:21:40 INFO - Downloading blobuploader-1.2.4.tar.gz 05:21:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:21:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:21:40 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 05:21:40 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 05:21:40 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 05:21:40 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 05:21:40 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 05:21:40 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 05:21:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:21:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:21:41 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 05:21:41 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 05:21:41 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 05:21:41 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 05:21:41 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 05:21:41 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 05:21:41 INFO - Downloading docopt-0.6.1.tar.gz 05:21:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:21:41 INFO - Installing collected packages: blobuploader, requests, docopt 05:21:41 INFO - Running setup.py install for blobuploader 05:21:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:21:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:21:41 INFO - Running setup.py install for requests 05:21:41 INFO - Running setup.py install for docopt 05:21:42 INFO - Successfully installed blobuploader requests docopt 05:21:42 INFO - Cleaning up... 05:21:42 INFO - Return code: 0 05:21:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:21:42 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')]} 05:21:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:21:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:42 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')]} 05:21:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:21:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:42 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 0x7f035541cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x144c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1519850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1514370>, '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 0x15178f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1517d80>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1471608777.456172-963003291', '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 05:21:42 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 05:21:42 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 05:21:42 INFO - Using env: {'DISPLAY': ':0', 05:21:42 INFO - 'HOME': '/home/cltbld', 05:21:42 INFO - 'LANG': 'en_US.UTF-8', 05:21:42 INFO - 'LANGUAGE': 'en_US:en', 05:21:42 INFO - 'LOGNAME': 'cltbld', 05:21:42 INFO - 'MAIL': '/var/mail/cltbld', 05:21:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:21:42 INFO - 'MOZ_NO_REMOTE': '1', 05:21:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:21:42 INFO - 'NO_EM_RESTART': '1', 05:21:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:21:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:21:42 INFO - 'PWD': '/builds/slave/test', 05:21:42 INFO - 'SHELL': '/bin/bash', 05:21:42 INFO - 'SHLVL': '1', 05:21:42 INFO - 'TERM': 'linux', 05:21:42 INFO - 'TMOUT': '86400', 05:21:42 INFO - 'USER': 'cltbld', 05:21:42 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1471608777.456172-963003291', 05:21:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:21:42 INFO - '_': '/tools/buildbot/bin/python'} 05:21:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:21:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:21:42 INFO - Running setup.py (path:/tmp/pip-Ziv0VJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:21:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:21:42 INFO - Running setup.py (path:/tmp/pip-tfs2pu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:21:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:21:42 INFO - Running setup.py (path:/tmp/pip-xPI23D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:21:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:21:42 INFO - Running setup.py (path:/tmp/pip-icstZo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:21:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:21:42 INFO - Running setup.py (path:/tmp/pip-m_skMN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:21:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:21:42 INFO - Running setup.py (path:/tmp/pip-15aXQM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:21:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:21:42 INFO - Running setup.py (path:/tmp/pip-a_BRfu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:21:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:21:42 INFO - Running setup.py (path:/tmp/pip-hYusow-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:21:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:21:42 INFO - Running setup.py (path:/tmp/pip-uKY35T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:21:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:21:42 INFO - Running setup.py (path:/tmp/pip-W9wKTA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:21:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:21:43 INFO - Running setup.py (path:/tmp/pip-oChBba-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:21:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:21:43 INFO - Running setup.py (path:/tmp/pip-6xJIQm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:21:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:21:43 INFO - Running setup.py (path:/tmp/pip-qvBRkt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:21:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:21:43 INFO - Running setup.py (path:/tmp/pip-89Xx5Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:21:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:21:43 INFO - Running setup.py (path:/tmp/pip-gymrJj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:21:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:21:43 INFO - Running setup.py (path:/tmp/pip-UgibIR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:21:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:21:43 INFO - Running setup.py (path:/tmp/pip-hXkGW0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:21:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:21:43 INFO - Running setup.py install for manifestparser 05:21:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:21:43 INFO - Running setup.py install for mozcrash 05:21:43 INFO - Running setup.py install for mozdebug 05:21:43 INFO - Running setup.py install for mozdevice 05:21:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:21:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:21:43 INFO - Running setup.py install for mozfile 05:21:44 INFO - Running setup.py install for mozhttpd 05:21:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:21:44 INFO - Running setup.py install for mozinfo 05:21:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:21:44 INFO - Running setup.py install for mozInstall 05:21:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:21:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:21:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:21:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:21:44 INFO - Running setup.py install for mozleak 05:21:44 INFO - Running setup.py install for mozlog 05:21:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:21:44 INFO - Running setup.py install for moznetwork 05:21:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:21:44 INFO - Running setup.py install for mozprocess 05:21:44 INFO - Running setup.py install for mozprofile 05:21:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:21:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:21:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:21:44 INFO - Running setup.py install for mozrunner 05:21:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:21:45 INFO - Running setup.py install for mozscreenshot 05:21:45 INFO - Running setup.py install for moztest 05:21:45 INFO - Running setup.py install for mozversion 05:21:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:21:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:21:45 INFO - Cleaning up... 05:21:45 INFO - Return code: 0 05:21:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:21:45 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')]} 05:21:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:21:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:45 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')]} 05:21:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:21:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:45 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 0x7f035541cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x144c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1519850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1514370>, '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 0x15178f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1517d80>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1471608777.456172-963003291', '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 05:21:45 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 05:21:45 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 05:21:45 INFO - Using env: {'DISPLAY': ':0', 05:21:45 INFO - 'HOME': '/home/cltbld', 05:21:45 INFO - 'LANG': 'en_US.UTF-8', 05:21:45 INFO - 'LANGUAGE': 'en_US:en', 05:21:45 INFO - 'LOGNAME': 'cltbld', 05:21:45 INFO - 'MAIL': '/var/mail/cltbld', 05:21:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:21:45 INFO - 'MOZ_NO_REMOTE': '1', 05:21:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:21:45 INFO - 'NO_EM_RESTART': '1', 05:21:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:21:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:21:45 INFO - 'PWD': '/builds/slave/test', 05:21:45 INFO - 'SHELL': '/bin/bash', 05:21:45 INFO - 'SHLVL': '1', 05:21:45 INFO - 'TERM': 'linux', 05:21:45 INFO - 'TMOUT': '86400', 05:21:45 INFO - 'USER': 'cltbld', 05:21:45 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1471608777.456172-963003291', 05:21:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:21:45 INFO - '_': '/tools/buildbot/bin/python'} 05:21:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:21:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:21:45 INFO - Running setup.py (path:/tmp/pip-kNWliz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:21:45 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)) 05:21:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:21:45 INFO - Running setup.py (path:/tmp/pip-HXc4d5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:21:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 05:21:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:21:45 INFO - Running setup.py (path:/tmp/pip-RpX5cF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:21:45 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)) 05:21:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:21:45 INFO - Running setup.py (path:/tmp/pip-BcHT34-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:21:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 05:21:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:21:45 INFO - Running setup.py (path:/tmp/pip-x_ebgv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:21:45 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)) 05:21:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:21:45 INFO - Running setup.py (path:/tmp/pip-WojINc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:21:46 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)) 05:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:21:46 INFO - Running setup.py (path:/tmp/pip-uyRv70-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:21:46 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)) 05:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:21:46 INFO - Running setup.py (path:/tmp/pip-NfkTPy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:21:46 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)) 05:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:21:46 INFO - Running setup.py (path:/tmp/pip-CrFLzz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:21:46 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)) 05:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:21:46 INFO - Running setup.py (path:/tmp/pip-KJpJP1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:21:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 05:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:21:46 INFO - Running setup.py (path:/tmp/pip-GIujoI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:21:46 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)) 05:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:21:46 INFO - Running setup.py (path:/tmp/pip-CMFdUo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:21:46 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)) 05:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:21:46 INFO - Running setup.py (path:/tmp/pip-hiuBsY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:21:46 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)) 05:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:21:46 INFO - Running setup.py (path:/tmp/pip-olNyM_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:21:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 05:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:21:46 INFO - Running setup.py (path:/tmp/pip-EgpgKn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:21:46 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)) 05:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:21:46 INFO - Running setup.py (path:/tmp/pip-IZPE0d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:21:46 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)) 05:21:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:21:46 INFO - Running setup.py (path:/tmp/pip-vCBlaA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:21:46 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)) 05:21:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:21:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:21:46 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)) 05:21:46 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:21:46 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:21:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:21:46 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 05:21:46 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 05:21:46 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 05:21:46 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 05:21:46 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 05:21:46 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 05:21:48 INFO - Downloading blessings-1.6.tar.gz 05:21:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:21:48 INFO - Installing collected packages: blessings 05:21:48 INFO - Running setup.py install for blessings 05:21:48 INFO - Successfully installed blessings 05:21:48 INFO - Cleaning up... 05:21:48 INFO - Return code: 0 05:21:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:21:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:21:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:21:49 INFO - Reading from file tmpfile_stdout 05:21:49 INFO - Current package versions: 05:21:49 INFO - argparse == 1.2.1 05:21:49 INFO - blessings == 1.6 05:21:49 INFO - blobuploader == 1.2.4 05:21:49 INFO - docopt == 0.6.1 05:21:49 INFO - functools32 == 3.2.3-2 05:21:49 INFO - jsonschema == 2.5.1 05:21:49 INFO - manifestparser == 1.1 05:21:49 INFO - mozInstall == 1.12 05:21:49 INFO - mozcrash == 0.17 05:21:49 INFO - mozdebug == 0.1 05:21:49 INFO - mozdevice == 0.48 05:21:49 INFO - mozfile == 1.2 05:21:49 INFO - mozhttpd == 0.7 05:21:49 INFO - mozinfo == 0.9 05:21:49 INFO - mozleak == 0.1 05:21:49 INFO - mozlog == 3.3 05:21:49 INFO - moznetwork == 0.27 05:21:49 INFO - mozprocess == 0.23 05:21:49 INFO - mozprofile == 0.28 05:21:49 INFO - mozrunner == 6.12 05:21:49 INFO - mozscreenshot == 0.1 05:21:49 INFO - mozsystemmonitor == 0.3 05:21:49 INFO - moztest == 0.7 05:21:49 INFO - mozversion == 1.4 05:21:49 INFO - psutil == 3.1.1 05:21:49 INFO - requests == 1.2.3 05:21:49 INFO - wsgiref == 0.1.2 05:21:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:21:49 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')]} 05:21:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:21:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:49 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')]} 05:21:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:21:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:49 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 0x7f035541cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x144c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1519850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1514370>, '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 0x15178f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1517d80>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1471608777.456172-963003291', '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 05:21:49 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 05:21:49 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 05:21:49 INFO - Using env: {'DISPLAY': ':0', 05:21:49 INFO - 'HOME': '/home/cltbld', 05:21:49 INFO - 'LANG': 'en_US.UTF-8', 05:21:49 INFO - 'LANGUAGE': 'en_US:en', 05:21:49 INFO - 'LOGNAME': 'cltbld', 05:21:49 INFO - 'MAIL': '/var/mail/cltbld', 05:21:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:21:49 INFO - 'MOZ_NO_REMOTE': '1', 05:21:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:21:49 INFO - 'NO_EM_RESTART': '1', 05:21:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:21:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:21:49 INFO - 'PWD': '/builds/slave/test', 05:21:49 INFO - 'SHELL': '/bin/bash', 05:21:49 INFO - 'SHLVL': '1', 05:21:49 INFO - 'TERM': 'linux', 05:21:49 INFO - 'TMOUT': '86400', 05:21:49 INFO - 'USER': 'cltbld', 05:21:49 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1471608777.456172-963003291', 05:21:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:21:49 INFO - '_': '/tools/buildbot/bin/python'} 05:21:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:21:49 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)) 05:21:49 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)) 05:21:49 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)) 05:21:49 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)) 05:21:49 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)) 05:21:49 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)) 05:21:49 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)) 05:21:49 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)) 05:21:49 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)) 05:21:49 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)) 05:21:49 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)) 05:21:49 INFO - Cleaning up... 05:21:49 INFO - Return code: 0 05:21:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:21:49 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')]} 05:21:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:21:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:49 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')]} 05:21:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:21:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:21:49 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 0x7f035541cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x144c8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1519850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1514370>, '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 0x15178f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1517d80>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1471608777.456172-963003291', '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 05:21:49 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 05:21:49 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 05:21:49 INFO - Using env: {'DISPLAY': ':0', 05:21:49 INFO - 'HOME': '/home/cltbld', 05:21:49 INFO - 'LANG': 'en_US.UTF-8', 05:21:49 INFO - 'LANGUAGE': 'en_US:en', 05:21:49 INFO - 'LOGNAME': 'cltbld', 05:21:49 INFO - 'MAIL': '/var/mail/cltbld', 05:21:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:21:49 INFO - 'MOZ_NO_REMOTE': '1', 05:21:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:21:49 INFO - 'NO_EM_RESTART': '1', 05:21:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:21:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:21:49 INFO - 'PWD': '/builds/slave/test', 05:21:49 INFO - 'SHELL': '/bin/bash', 05:21:49 INFO - 'SHLVL': '1', 05:21:49 INFO - 'TERM': 'linux', 05:21:49 INFO - 'TMOUT': '86400', 05:21:49 INFO - 'USER': 'cltbld', 05:21:49 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1471608777.456172-963003291', 05:21:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:21:49 INFO - '_': '/tools/buildbot/bin/python'} 05:21:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:21:49 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:21:49 INFO - Cleaning up... 05:21:49 INFO - Return code: 0 05:21:49 INFO - Running post-action listener: _resource_record_post_action 05:21:49 INFO - Running post-action listener: _start_resource_monitoring 05:21:49 INFO - Starting resource monitoring. 05:21:49 INFO - [mozharness: 2016-08-19 12:21:49.665348Z] Finished create-virtualenv step (success) 05:21:49 INFO - [mozharness: 2016-08-19 12:21:49.665863Z] Running install step. 05:21:49 INFO - Running pre-action listener: _resource_record_pre_action 05:21:49 INFO - Running main action method: install 05:21:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:21:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:21:49 INFO - Reading from file tmpfile_stdout 05:21:49 INFO - Detecting whether we're running mozinstall >=1.0... 05:21:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:21:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:21:49 INFO - Reading from file tmpfile_stdout 05:21:49 INFO - Output received: 05:21:49 INFO - Usage: mozinstall [options] installer 05:21:49 INFO - Options: 05:21:49 INFO - -h, --help show this help message and exit 05:21:49 INFO - -d DEST, --destination=DEST 05:21:49 INFO - Directory to install application into. [default: 05:21:49 INFO - "/builds/slave/test"] 05:21:49 INFO - --app=APP Application being installed. [default: firefox] 05:21:49 INFO - mkdir: /builds/slave/test/build/application 05:21:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 05:21:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 05:22:06 INFO - Reading from file tmpfile_stdout 05:22:06 INFO - Output received: 05:22:06 INFO - /builds/slave/test/build/application/firefox/firefox 05:22:06 INFO - Running post-action listener: _resource_record_post_action 05:22:06 INFO - [mozharness: 2016-08-19 12:22:06.996325Z] Finished install step (success) 05:22:06 INFO - [mozharness: 2016-08-19 12:22:06.996572Z] Running run-tests step. 05:22:06 INFO - Running pre-action listener: _resource_record_pre_action 05:22:06 INFO - Running main action method: run_tests 05:22:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:22:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:22:07 INFO - Python 2.7.3 05:22:07 INFO - Return code: 0 05:22:07 INFO - grabbing minidump binary from tooltool 05:22: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')]} 05:22:07 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 0x1514370>, '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 0x15178f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1517d80>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:22:07 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 05:22:07 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 05:22:07 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 05:22:07 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:22:07 INFO - Return code: 0 05:22:07 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:22:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:22:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:22:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:22:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:22:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XnbO4E_qTAy46EVv17OKXg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-033', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:22:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/XnbO4E_qTAy46EVv17OKXg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-033 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:22:07 INFO - Using env: {'DISPLAY': ':0', 05:22:07 INFO - 'HOME': '/home/cltbld', 05:22:07 INFO - 'LANG': 'en_US.UTF-8', 05:22:07 INFO - 'LANGUAGE': 'en_US:en', 05:22:07 INFO - 'LOGNAME': 'cltbld', 05:22:07 INFO - 'MAIL': '/var/mail/cltbld', 05:22:07 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:22:07 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:22:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:22:07 INFO - 'MOZ_NO_REMOTE': '1', 05:22:07 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:22:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:22:07 INFO - 'NO_EM_RESTART': '1', 05:22:07 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:22:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:22:07 INFO - 'PWD': '/builds/slave/test', 05:22:07 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:22:07 INFO - 'SHELL': '/bin/bash', 05:22:07 INFO - 'SHLVL': '1', 05:22:07 INFO - 'TERM': 'linux', 05:22:07 INFO - 'TMOUT': '86400', 05:22:07 INFO - 'USER': 'cltbld', 05:22:07 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1471608777.456172-963003291', 05:22:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:22:07 INFO - '_': '/tools/buildbot/bin/python'} 05:22:07 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XnbO4E_qTAy46EVv17OKXg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-033', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:22:07 INFO - mozversion application_buildid: 20160819023002 05:22:07 INFO - mozversion application_changeset: eaff4fe1c6194cc73476d14b4faff2f355bb64b0 05:22:07 INFO - mozversion application_display_name: Nightly 05:22:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:22:07 INFO - mozversion application_name: Firefox 05:22:07 INFO - mozversion application_remotingname: firefox 05:22:07 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:22:07 INFO - mozversion application_vendor: Mozilla 05:22:07 INFO - mozversion application_version: 51.0a1 05:22:07 INFO - mozversion platform_buildid: 20160819023002 05:22:07 INFO - mozversion platform_changeset: eaff4fe1c6194cc73476d14b4faff2f355bb64b0 05:22:07 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:22:07 INFO - mozversion platform_version: 51.0a1 05:22:07 INFO - using testdate: 1471609327 05:22:07 INFO - actual date: 1471609327 05:22:07 INFO - starting webserver on 'localhost:38341' 05:22:07 INFO - SUITE-START | Running 2 tests 05:22:07 INFO - TEST-START | dromaeo_css 05:22:07 INFO - operating with platform_type : linux_ 05:22:07 INFO - Initialising browser for dromaeo_css test... 05:22:07 INFO - TEST-INFO | started process 12423 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFtr5eY/profile http://localhost:38341/getInfo.html) 05:22:12 INFO - PROCESS | 12423 | __metrics Screen width/height:1600/1200 05:22:12 INFO - PROCESS | 12423 | colorDepth:24 05:22:12 INFO - PROCESS | 12423 | Browser inner width/height: 1024/697 05:22:12 INFO - PROCESS | 12423 | __metrics 05:22:12 INFO - PROCESS | 12423 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:22:12 INFO - PROCESS | 12423 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:22:13 INFO - PROCESS | 12423 | console.error: PushService: 05:22:13 INFO - PROCESS | 12423 | stateChangeProcessEnqueue: Error transitioning state 05:22:13 INFO - PROCESS | 12423 | UnknownError 05:22:13 INFO - TEST-INFO | 12423: exit 0 05:22:13 INFO - Browser initialized. 05:22:13 INFO - Running cycle 1/1 for dromaeo_css test... 05:22:13 INFO - TEST-INFO | started process 12503 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFtr5eY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:22:25 INFO - PROCESS | 12503 | RSS: Main: 160780288 05:22:25 INFO - PROCESS | 12503 | 05:23:17 INFO - PROCESS | 12503 | 1471609397197 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] 05:25:26 INFO - PROCESS | 12503 | Cycle 1(1): loaded http://localhost:38341/tests/dromaeo/cssquery-dojo.html (next: http://localhost:38341/tests/dromaeo/cssquery-ext.html) 05:25:27 INFO - PROCESS | 12503 | RSS: Main: 251478016 05:25:27 INFO - PROCESS | 12503 | 05:28:30 INFO - PROCESS | 12503 | Cycle 1(1): loaded http://localhost:38341/tests/dromaeo/cssquery-ext.html (next: http://localhost:38341/tests/dromaeo/cssquery-jquery.html) 05:28:31 INFO - PROCESS | 12503 | RSS: Main: 250073088 05:28:31 INFO - PROCESS | 12503 | 05:31:33 INFO - PROCESS | 12503 | Cycle 1(1): loaded http://localhost:38341/tests/dromaeo/cssquery-jquery.html (next: http://localhost:38341/tests/dromaeo/cssquery-mootools.html) 05:31:33 INFO - PROCESS | 12503 | RSS: Main: 186580992 05:31:33 INFO - PROCESS | 12503 | 05:34:35 INFO - PROCESS | 12503 | Cycle 1(1): loaded http://localhost:38341/tests/dromaeo/cssquery-mootools.html (next: http://localhost:38341/tests/dromaeo/cssquery-prototype.html) 05:34:36 INFO - PROCESS | 12503 | RSS: Main: 206401536 05:34:36 INFO - PROCESS | 12503 | 05:37:38 INFO - PROCESS | 12503 | Cycle 1(1): loaded http://localhost:38341/tests/dromaeo/cssquery-prototype.html (next: http://localhost:38341/tests/dromaeo/cssquery-yui.html) 05:37:39 INFO - PROCESS | 12503 | RSS: Main: 190279680 05:37:39 INFO - PROCESS | 12503 | 05:40:42 INFO - PROCESS | 12503 | Cycle 1(1): loaded http://localhost:38341/tests/dromaeo/cssquery-yui.html (next: http://localhost:38341/tests/dromaeo/cssquery-dojo.html) 05:40:42 INFO - PROCESS | 12503 | RSS: Main: 278904832 05:40:42 INFO - PROCESS | 12503 | 05:40:42 INFO - PROCESS | 12503 | __start_tp_report 05:40:42 INFO - PROCESS | 12503 | _x_x_mozilla_page_load 05:40:42 INFO - PROCESS | 12503 | _x_x_mozilla_page_load_details 05:40:42 INFO - PROCESS | 12503 | |i|pagename|runs| 05:40:42 INFO - PROCESS | 12503 | |0;dojo.html;3040.1335619348483;3044.406340763552;3073.10879844845;3081.707237812407;3082.9691703082976;5240.34495688039;5281.973590132044;5282.392524859638;5368.758405871736;5391.91912121318;5255.185446255827;5302.840914772553;5307.973460132694;5357.330333708286;5412.07994640911;4697.835575754849;4763.737994410306;4789.544993225655;4800.735959522225;4951.306817045616;4909.828156014541;4914.164592019365;4920.335754673127;4937.0125974805005;4948.604111671058;4721.173794585934;4764.523547645243;4878.512148785128;4923.261510773377;4923.9753801230945;4763.833265835679;4777.331173635694;4790.856274311776;4798.136335459616;4826.806927722887;4750.904981900378;4760.833370832039;4777.116233496814;4853.563179313879;4857.514248575149;5176.197689358156;5320.6275560710765;5360.3299587551555;5363.168708850135;5365.624406291561;5751;5760.135979603051;5808.88382232353;5821.67980761058;5826.533877289807;5783.479486846204;5809.88380232393;5817.447342502455;5837.474627283523;5839.64962102271;5172.974135129375;5246.31797866275;5447.891042179134;5450.3732070811575;5456.045192091368;5786.07422812348;5822.359540450547;5853.590248682636;5853.64878107315;5856.1508581255475;5606.187102870061;5613.073842815971;5616.747246373923;5655.575831812631;5658.943410565924;5570.805021824217;5631.099024156116;5691.743871525791;5729.341125770589;5732.168835518826;5640.125780504029;5689.374168841424;5701.372848986608;5736.053551164095;5742.0238559445;5644.463775941279;5678.375378708339;5683.772649094072;5689.687067211343;5691.288588926384;4262.573742625713;4351.26028575143;4373.387725718402;4389.385486031958;4430.9587246996925;3864.903377415531;3894.7078969077434;3916.2167566486814;3925.430812532167;4010.1177740896956;5440.537554307849;5446.1558458439;5449.6730196188355;5468.863278417992;5500.944990550123;5519.254900588449;5750.597458177971;5760.711964401763;5764.596478246483;5765.740541675551;5254.211868219798;5287.154055351125;5323.866903327448;5386.165144402624;5391.784328626811;4838.080764654705;4851.272309153655;4852.539008794159;4853.611711063107;4912.828051018198;5839.357670656224;5871.236739223874;5884.2350494436305;5889.352171261072;5890.911636325459;5424.755885985219;5458.426865179099;5646.265985421869;5700.344460386943;5782.190493330853;5947.286325640951;5953.553483488669;5958.672273025025;5959.314678811992;5960.970195148997;5589.049861523469;5634.718264086698;5637.9718101409235;5643.238162848127;5643.689597072201;5502.80740174092;5657.377688454185;5680.971595141998;5705.543556515552;5804.21643078179;5591.524720398812;5626.240457538343;5633.436656334334;5636.070048441961;5642.633228840113;5600.187972744011;5607;5609.214709940693;5609.719514024201;5617.662940223599;5388.137897936312;5484.8354549363585;5496.340439147168;5514.889702205854;5626.465485778845;5612.214289999321;5613.859653508695;5617.185508101221;5654.123610840326;5669.7165141741925;5484.945150548603;5492.231087647812;5493.7802487900035;5497.972510137423;5502.614816962934 05:40:42 INFO - PROCESS | 12503 | |1;ext.html;594.6432140715572;604.4257954942804;617.4751461257931;618.9195404597401;634.0552576660776;43999.34000989984;44977.325340119925;45086;45193.774031129884;48078;46330.76834615831;46896.062078758405;46944;46980.29529557059;46985.76507117468;8199.098099209083;8960.372773905829;9091.227245684126;9096.090390960884;10600.78798424031;10650.520726567283;10782.676519704382;10788.676339709822;10793.622223222188;10795.190360722938;10729;10873.184511161655;10881.455927203648;10881.727956801104;10885.18361122915;10799.4060326682;10802.67591972242;10811.405372704497;10830.350178989285;10842.186835987333;10754.408507532082;10816.837747433794;10819.67540973772;10832.025117739362;10837.403942783143;44043;44422.111557768985;44451.77741112931;44482.777586111864;44511.777441112914;3394.4908263760385;3404.4042292598697;3523.3834079036324;3588.8743893963847;3616.168281295317;33789;33979.320413591835;34148.14629634279;34913.82543087268;35736.46395304072;3948.605139486057;4056.7971601420104;4063.4108054331955;4185.142045880587;4211.852585159535;353946;355343.02869112854;356763;357181;357956;35720.46419303712;60531.69734151301;60762.39237607656;60797.696011519656;60840;36656.45015324772;37920.24159516794;38063.42904856429;38199.23601527954;38271;14614.123152610877;14934.10395376281;14938;14951.626209344637;14951.700965980837;10958.890411095947;10996.175286853519;11779.528818847322;12120.81818772719;12121.878781212075;8725.825483490295;8758.605862736193;8820.647174113092;8870;8878.245349145394;3350.2461946062426;3397.456406974872;3432.3650124727005;3453.1539772755837;3491;919.6873063158558;971.4414211828228;1003.7892042670975;1029.4749677664295;1097.7605684403886;32364.190895227337;32366.514502282484;32485.837570811993;32630.021099367055;33152.1711957203;1089.5369467976236;1095.2606990281688;1141.275290190735;1216.403962058585;1227.245244174826;17722.91138544299;18657.533561660795;19068.713969290195;19193.136308866135;19204.71192932107;636328;638399;638963;639927;642629;61774.69112654408;62159;62532.37467625357;62550.37449625445;62623;1233.5744168261936;1327.4889167670517;1352.6280272925023;1360.7074478987263;1387.7987691784635;32143.839280803448;33157.83421082879;34165.316693666486;34361.656383435846;34644.307113858085;52502.737486313854;52926.4707352937;52938;52945.8813649138;53187.46812531825;446280;451019;451836;451870;451910;28807.279818004718;29077.7092229075;29132;29228.56157157687;30820.22949426282;48884;49094.50905491042;49773.253401199734;49784;53320;42497.78751106225;42970.35544466773;43512;43534.129317412844;45439.31841022321;42801;43768.78115609402;44368.60068259396;44898.551014489436;44935.55064449314;42264.36603451012;43219;43579.34630980601;43826.78086609547;43837;35631.82184089063;38930.80534597309;39291.80354098211;39668.66129766002;39689;28732.856335719025;29079.56380654334;29183.270418239714;29285;29400.411991760473 05:40:42 INFO - PROCESS | 12503 | |2;jquery.html;366.86792754608354;388.57451091055304;396.4806104003756;399.9700022498313;418.31814142947013;14423.78364324536;17398.565035874108;18903;19899;19918.10368533414;18970.905145474288;18983.33558325459;18991.62016759664;19182.424527264204;19229;5658.858528536789;5701.147127183661;5860.355360910296;5863.032599621058;5868.295804503444;5846.298444186704;6030.577859549833;6042.516598672096;6043.3956604339655;6045.365236650155;5940.940590594082;6048.0625503047095;6054.364291749369;6497.155369801919;6565.244996825355;6549.050387693782;6573.967130164342;6578.506612004119;6584.242812076601;6588.703508342088;5158.40678321994;5382.8923421531745;5417.647852889589;5497.890042199174;5508.641938274;13825.10136841102;13941.790873136912;14162.71674566503;14175.716485670331;14308.92845535776;2212.579609874119;2236.71388951352;2321.7097862767155;2544.732803055671;2693.851838148901;10203;10565.841512377321;10750.784984300348;10952.507137178845;11576.710582235428;1819.654265689528;1888.9244430222911;1896.8292853643238;1918.7697476302933;1935.8548108891748;79114;84657;86515.99999999937;86607.13392866054;88208;11051.17116216287;11104.167187460811;11384.487698053608;11391.430428478543;11687.88965048319;15850.76223856643;15857.207139642971;15859.841401586067;15861.444849430214;15876.92061539685;7658.425618078555;7819.84360312802;7940.722074727357;8015.038195416588;8133.0646975598565;7377.852442951111;7423.331900129015;7430.777076687708;7568.772936811904;7650.502717323357;4766.828100342817;4831.154547954094;4835.223011426306;4961.618423156905;4980.676256043345;1957.285590759391;2017.2032047341218;2024.5545979884403;2077.781832907553;2093.769685334612;856.9828603427896;905.9954700226589;975.3124047546463;1007.4005966449951;1017.6336518853207;18000.08964188077;19071.427857164308;19447.3193438229;19667.803321966883;19675.40973770789;943.5942544705842;961.8701475300745;983.9458829764288;988.4415305352453;1017.7964407118606;10009.849852252222;10812.189085818438;10843.891561084445;10879.184061195441;10894.346339219672;375730;471162;474673;474972;477525;91013.54493227492;93780;96265;96742.51628741811;97766;984.2568860510269;1016.9593216271414;1038.127972503101;1047.2564479219686;1058.5501162006117;16508.507608125135;16699.833001669827;17167.656646866744;17380.73928891093;17916.193771280585;35503.64496355003;38201.61798381981;39473.40789888211;40093.59906400899;41726.32378819496;35860.282794344486;36612.450813238356;52713.20930185974;58376;59924.40075599363;7628.42786791004;7815.570143641927;8391.32869370461;8436.873446898178;8671.869921951302;12613;13028.283444410648;13161.73676526445;13308.667283317607;13375.464981400635;10764.192685548458;11751.941240293743;11862.169648124549;12180.57367992116;12304.507819687113;11134.164937629872;11244.212905096892;11272.098232141572;11294.378809165575;11372.886271137182;11593.710157246136;11761.647150585497;11823.46794394237;11897.107716921439;12072.517099315928;8048.798780030546;8077.63650635711;8264.421490495608;8267.338612910833;8420.915790842013;11738.76522469563;11854.466549005489;11883.702907427038;11907.52369905194;11969.281843089442 05:40:42 INFO - PROCESS | 12503 | |3;mootools.html;1324.4371142264536;1498.6553092788654;1503.827059888113;1647.9176041197945;1723.112597012538;8241.217084376982;8571.314294856413;8628.439151455159;8784.64861405543;8789.604467798948;8672.47965122093;8762.912370876289;8779.34154938379;8789.7802554936;8794.95602521988;2391.9521609567755;2421.285720712394;2423.212455951814;2426.2236084452984;2427.5509030829267;2344.6248600223967;2365.432296248905;2412.348665860217;2412.6139817629182;2417.0815090265764;2357.316378250305;2357.610994185966;2369.3128992592215;2391.270662447953;2396.95206095878;2303.1017903017837;2333.2067097187037;2334.8482348647285;2345.6012477878803;2345.8475199112004;2239.4513344230604;2283.155232563962;2287.039443433762;2296.368498662881;2298.816094712419;8675.609597568124;8700.99938507067;8723.781905452352;8769.166929141786;8780.341474389383;5126.410462796789;5358.5981051421295;5365.839024829262;5366.543843773284;5367.946320536823;3465.8267086645565;3485.4597537381746;3525.4006818840858;3565.554305711786;3574.9285014299567;1216.7809794237035;1217.8721234270363;1227.030645789825;1235.1045492018304;1262.3751243134707;12166.878331216663;12234;12251.754964900652;12264;12273.140880138304;4270.935935960963;4415.116976604628;4557.316402539579;4562.794674239667;4577.679562430598;9344.579493922789;9353.90646093544;9362.90637093634;9366.344355895159;9370.718878433521;5212.061828870839;5220.399654039758;5230.215467679878;5233.633645644768;5239.345081864767;4243.215005224043;4680.274557443602;4820.734859582686;4855.223164293696;4921.458639549646;3617.149969757105;3671.81640917953;3812.390017597171;3816.542014958223;3825.062859599416;1141.868685101224;1149.5114576305036;1150.097173718627;1152.9711757206137;1164.458526785032;940.7742141886035;967.6129548180643;978.6330126202674;986.3539381704994;1000.9890011088853;5871.765129394862;5916.467517923407;6012.729427175787;6057.485113765291;6073.210482637229;472.18076637034716;481.7543053042973;484.8085006422514;484.849696594057;489.52760586034645;4113.753174809521;4153.91692166161;4169.603887630731;4184.079502509443;4210.536840947493;10671.679849604525;10875.129989600813;10887.129029677606;10970.725731856608;10993.285436446606;10286.845697314546;10905.127589792797;10905.255159174494;10906.34561926287;10922.890771092187;7603.46775725705;7838.098618658927;7848.686052557834;7853.253940875494;7858.096318923397;5865.621578928863;5981.461668449861;6011.489023433057;6020.096985452042;6162.44537991565;9967.451790151612;10109.090181883666;10170.08469237772;10188.134008609351;10198.133158681596;9927.900720992986;9935.304528682922;10347.534360953925;10353.378797272188;10377.16982641402;6763.526553141233;7735;7748.92251077482;7840;7963.362930965624;8566.700165494178;8683.739487815375;8690.217880390795;8691.391602587759;8703.47779133254;7706.267904549059;7727.227277272228;7883.763487095396;7984.520928744294;8019.679212831421;7544;7609.124950630749;7623.199564045917;7626.885596716164;7670.463067585216;7084.220735718964;7188.640567971685;7188.928110718826;7196.928030719626;7198.49610527279;5028.924566131438;5052.873678158076;5100.69395836251;5122.129238029469;5393.865153371197;5023.522765337287;5029.5221953914315;5083.745812709276;5126.487351264844;5171.818986335611 05:40:42 INFO - PROCESS | 12503 | |4;prototype.html;306.2008158705776;310.7172473049525;320.54162547556984;322.4148171069509;338.125944433639;5115.462876397981;5180.093483640358;5263.9736801315985;5304.708241046741;5318.0693378658725;5214.296070030545;5231.189165679316;5233;5263.552598029162;5279.604029697778;4169.437125987998;4180.1430706705205;4183.16336732654;4186.874393768177;4205.474315710536;4036.616521430474;4161.791910404483;4200.390943313233;4204.789760511962;4208.13733184698;4148.439960605325;4160.916781664365;4166.145940082276;4166.937495937548;4193.43388642534;4152.730072545305;4171.937420938689;4179.2895207814745;4196.475440569929;4207.453031105937;3739.607341229159;3745.250949810049;3751.3247615429214;3754.0427191066105;3755.962440375589;4951.455339912607;5067.797288108472;5089.829339251993;5094.872628184288;5104.2088476286235;4780.760961951889;4796.496367881392;4808.278758186265;4810;4818.445878723956;5403.758049930825;5424.690938527495;5456.91814622781;5459.580509067631;5491.395946445888;3978.6419222269847;4066.2680717470844;4080.3879418087226;4086.8569600063856;4102.118044620391;5624.55003599711;5651.9717401413145;5675.120356344773;5675.489205971524;5678.687672178028;4164.146349998243;4191.140816132685;4211.241976444208;4225.9577404225565;4235.894102647397;4089.284375234322;4105.322621767375;4126.979365103155;4135.937960930589;4212.947878716065;4653.883652908705;4664.277037059262;4679.368285281444;4681.291328665013;4686.554777296152;5494.835154945358;5501.202325662836;5502.166384312082;5505.614606977553;5552.361478430033;1300.876416740408;1343.9731205375838;1445.7108578284385;1466.8753155981647;1472.8232612086617;1015.7308313296983;1023.7696518283476;1035.177034257767;1041.1046500009986;1043.9478026098664;5200.167973124358;5200.6619569727845;5215.426303106655;5240.973795131076;5251.264822924793;5557.861053473695;5573.832785016456;5609.411011843779;5610.158476228598;5627.690477023803;5110.412302585176;5248.317718696545;5265.447128051576;5291.735413229323;5394.838154855361;3009.2627306310096;3011.472992226352;3012.7288544031144;3040.452718510645;3042.300270937697;5900.262467191601;5910.172575839386;5922.674252916131;5949.613275137189;5957.944509144339;5587.944120558824;5810.745982249953;5845.503132233723;5856.180134781134;5857.20927674767;5910.581460449461;5934.139549765347;5946.762129514857;5977.551683623746;5982.521398288213;5418.83743487696;5669.688167150846;5703.942960570341;5730.684812335195;5754.971225143848;5627.437256274504;5631.746571404378;5637.605367624227;5641.351244606923;5644.294463192101;5888.4700376966275;5908.763649453974;5986.371430999682;5991.460768530853;5991.910121348271;3988.7806170660892;4032.516098068251;4060.3097473428998;4063.6633935487803;4072.8981775454668;5700.25896633435;5702.714864256687;5708.885822283614;5710.887566276472;5736.799212027559;5501.367342755634;5655.349634792052;5658.472212502684;5660.490555849993;5663.603547751618;5530.695811730394;5532.1425179097305;5537.833864984057;5544.695041772741;5566.91649625264;5534.031544479781;5545.085060964896;5558.471945165203;5560.193771903132;5631.774729010794;5370.2213179089595;5572.35917869434;5608.831735047955;5652.519535839499;5681.801136960186;5420.457954204548;5431.293931788842;5464.371597266365;5482.479164479368;5492.835214943558 05:40:42 INFO - PROCESS | 12503 | |5;yui.html;663.3134705579726;681.3833480699968;703.6024646074968;720.188082176833;746.5520687587448;15227.314770835312;16450.75323870143;16868.49394518163;16969.915150424247;17077.914610426946;14955.626109347271;15089.094654320748;15107.84892151081;15114.848851511482;15118.924405377986;655.2268323378412;786.9724559640399;793.2464159048898;804.2600807257337;804.4891493901386;794.1938931984041;801.6152246921481;803.8874557561946;804.3605333759646;806.2300503019602;853.8975322961253;855.6278019061729;857.1514200941086;857.7598272483715;857.8841856349406;683.4088513435892;796.6693822063836;842.2966822702989;850.8936382952131;851.3997631669689;620.695859029077;620.7672122953892;621.0404924391826;621.1676353686082;621.3910367839543;14949.925250373788;14985.32566034531;14995.95028348016;14996.47512337074;15007.924960375238;13709.725805483835;14027.579172624837;14299.928500357535;14464.566063018126;14500.854991450162;12501.874981250163;12831.679208019874;13059.216447013114;13165.53920612774;13189.406476708571;4439.7780110994645;4482.193205223059;4489.393931819195;4532.93200601988;4558.407407037097;81568.18431815665;82162.65942713764;82795;82892.1975461718;83707;11369.772604547863;11386.259893106922;11468.13988950832;11493.310401375944;11612.94193529027;22212.77787222107;22221.333359998902;22307;22330.77669223287;22424.215152469584;7664.616769161519;7686.577238251838;8000.399970002273;8013.278804907586;8048.798780030546;4319.784010799447;4373.234683930299;4387.714798538085;4470.932936005963;4574.804094034882;3305.25631732863;3372.629010808809;3404.4893266010295;3417.1115509967585;3521.1549228185563;629.4209327418786;630.130420020369;638.5211091681239;640.6092283706935;657.135866335767;625.4371066040509;654.898490733937;656.1961597043679;664.7905909638545;668.1080757189206;8532.317414606816;9100.681476148435;9618.663346782969;9843.981983693326;9893.159081478156;526.3263023330144;673.5285300289719;683.3030309084805;707.706301884712;710.7583421636668;4771.212749896297;4883.291922671193;4888.462269150391;4952.62855285855;4964.627652926045;99198;100952;103839.48080259701;115389.4230528842;131689.34155329163;97010.51494742482;97747.51126244324;98117.50941245248;98374;99527;86098;86989.5650521756;87514.99999999872;87788;87850.12149878548;9827.11555959967;9879.555420005972;9909.207263419054;9937.950310248403;9958.302918795614;49280;49948.50051499438;51177.488225117275;51503;51856;69645.30354696591;71002.6449867768;77297.22702772901;77881;78542.6072869632;13123.803142953055;13185.274809885548;13726.382312796148;14081.859181408056;14306.71092625175;13461.86538134606;13485.932570337478;13641.931790340985;14490.85509144895;14536.200508972108;14098.647533811736;14376.424943002581;14966.32651530662;15071.54785356441;15410.15244161537;13898.72202555923;14478.565643030726;14551.708965820413;14813.185274809988;15058.698826023638;13289.601311960656;14282.143071415749;14343.772651153407;14499.202543860189;14944.850551494346;11509.597164099216;11510.827337589775;11642.53429862796;12038.578649747216;12140.514379425078;10305.690829275134;11776;12092.334921579397;12140.75718485643;12181.469878075435 05:40:42 INFO - PROCESS | 12503 | __end_tp_report 05:40:42 INFO - PROCESS | 12503 | __start_cc_report 05:40:42 INFO - PROCESS | 12503 | _x_x_mozilla_cycle_collect,983 05:40:42 INFO - PROCESS | 12503 | __end_cc_report 05:40:42 INFO - PROCESS | 12503 | __startTimestamp1471610442777__endTimestamp 05:40:42 INFO - PROCESS | 12503 | 05:40:42 INFO - PROCESS | 12503 | ------- Summary: start ------- 05:40:42 INFO - PROCESS | 12503 | Number of tests: 6 05:40:42 INFO - PROCESS | 12503 | 05:40:42 INFO - PROCESS | 12503 | [#0] dojo.html Cycles:165 Average:5300.25 Median:5497.97 stddev:610.39 (11.1%) stddev-sans-first:585.95 05:40:42 INFO - PROCESS | 12503 | Values: 3040.1 3044.4 3073.1 3081.7 3083.0 5240.3 5282.0 5282.4 5368.8 5391.9 5255.2 5302.8 5308.0 5357.3 5412.1 4697.8 4763.7 4789.5 4800.7 4951.3 4909.8 4914.2 4920.3 4937.0 4948.6 4721.2 4764.5 4878.5 4923.3 4924.0 4763.8 4777.3 4790.9 4798.1 4826.8 4750.9 4760.8 4777.1 4853.6 4857.5 5176.2 5320.6 5360.3 5363.2 5365.6 5751.0 5760.1 5808.9 5821.7 5826.5 5783.5 5809.9 5817.4 5837.5 5839.6 5173.0 5246.3 5447.9 5450.4 5456.0 5786.1 5822.4 5853.6 5853.6 5856.2 5606.2 5613.1 5616.7 5655.6 5658.9 5570.8 5631.1 5691.7 5729.3 5732.2 5640.1 5689.4 5701.4 5736.1 5742.0 5644.5 5678.4 5683.8 5689.7 5691.3 4262.6 4351.3 4373.4 4389.4 4431.0 3864.9 3894.7 3916.2 3925.4 4010.1 5440.5 5446.2 5449.7 5468.9 5500.9 5519.3 5750.6 5760.7 5764.6 5765.7 5254.2 5287.2 5323.9 5386.2 5391.8 4838.1 4851.3 4852.5 4853.6 4912.8 5839.4 5871.2 5884.2 5889.4 5890.9 5424.8 5458.4 5646.3 5700.3 5782.2 5947.3 5953.6 5958.7 5959.3 5961.0 5589.0 5634.7 5638.0 5643.2 5643.7 5502.8 5657.4 5681.0 5705.5 5804.2 5591.5 5626.2 5633.4 5636.1 5642.6 5600.2 5607.0 5609.2 5609.7 5617.7 5388.1 5484.8 5496.3 5514.9 5626.5 5612.2 5613.9 5617.2 5654.1 5669.7 5484.9 5492.2 5493.8 5498.0 5502.6 05:40:42 INFO - PROCESS | 12503 | 05:40:42 INFO - PROCESS | 12503 | [#1] ext.html Cycles:180 Average:63677.80 Median:20136.31 stddev:132276.30 (656.9%) stddev-sans-first:132562.57 05:40:42 INFO - PROCESS | 12503 | Values: 594.6 604.4 617.5 618.9 634.1 43999.3 44977.3 45086.0 45193.8 48078.0 46330.8 46896.1 46944.0 46980.3 46985.8 8199.1 8960.4 9091.2 9096.1 10600.8 10650.5 10782.7 10788.7 10793.6 10795.2 10729.0 10873.2 10881.5 10881.7 10885.2 10799.4 10802.7 10811.4 10830.4 10842.2 10754.4 10816.8 10819.7 10832.0 10837.4 44043.0 44422.1 44451.8 44482.8 44511.8 3394.5 3404.4 3523.4 3588.9 3616.2 33789.0 33979.3 34148.1 34913.8 35736.5 3948.6 4056.8 4063.4 4185.1 4211.9 353946.0 355343.0 356763.0 357181.0 357956.0 35720.5 60531.7 60762.4 60797.7 60840.0 36656.5 37920.2 38063.4 38199.2 38271.0 14614.1 14934.1 14938.0 14951.6 14951.7 10958.9 10996.2 11779.5 12120.8 12121.9 8725.8 8758.6 8820.6 8870.0 8878.2 3350.2 3397.5 3432.4 3453.2 3491.0 919.7 971.4 1003.8 1029.5 1097.8 32364.2 32366.5 32485.8 32630.0 33152.2 1089.5 1095.3 1141.3 1216.4 1227.2 17722.9 18657.5 19068.7 19193.1 19204.7 636328.0 638399.0 638963.0 639927.0 642629.0 61774.7 62159.0 62532.4 62550.4 62623.0 1233.6 1327.5 1352.6 1360.7 1387.8 32143.8 33157.8 34165.3 34361.7 34644.3 52502.7 52926.5 52938.0 52945.9 53187.5 446280.0 451019.0 451836.0 451870.0 451910.0 28807.3 29077.7 29132.0 29228.6 30820.2 48884.0 49094.5 49773.3 49784.0 53320.0 42497.8 42970.4 43512.0 43534.1 45439.3 42801.0 43768.8 44368.6 44898.6 44935.6 42264.4 43219.0 43579.3 43826.8 43837.0 35631.8 38930.8 39291.8 39668.7 39689.0 28732.9 29079.6 29183.3 29285.0 29400.4 05:40:42 INFO - PROCESS | 12503 | 05:40:42 INFO - PROCESS | 12503 | [#2] jquery.html Cycles:180 Average:27641.04 Median:2619.29 stddev:75593.39 (2886.0%) stddev-sans-first:75777.71 05:40:42 INFO - PROCESS | 12503 | Values: 366.9 388.6 396.5 400.0 418.3 14423.8 17398.6 18903.0 19899.0 19918.1 18970.9 18983.3 18991.6 19182.4 19229.0 5658.9 5701.1 5860.4 5863.0 5868.3 5846.3 6030.6 6042.5 6043.4 6045.4 5940.9 6048.1 6054.4 6497.2 6565.2 6549.1 6574.0 6578.5 6584.2 6588.7 5158.4 5382.9 5417.6 5497.9 5508.6 13825.1 13941.8 14162.7 14175.7 14308.9 2212.6 2236.7 2321.7 2544.7 2693.9 10203.0 10565.8 10750.8 10952.5 11576.7 1819.7 1888.9 1896.8 1918.8 1935.9 79114.0 84657.0 86516.0 86607.1 88208.0 11051.2 11104.2 11384.5 11391.4 11687.9 15850.8 15857.2 15859.8 15861.4 15876.9 7658.4 7819.8 7940.7 8015.0 8133.1 7377.9 7423.3 7430.8 7568.8 7650.5 4766.8 4831.2 4835.2 4961.6 4980.7 1957.3 2017.2 2024.6 2077.8 2093.8 857.0 906.0 975.3 1007.4 1017.6 18000.1 19071.4 19447.3 19667.8 19675.4 943.6 961.9 983.9 988.4 1017.8 10009.8 10812.2 10843.9 10879.2 10894.3 375730.0 471162.0 474673.0 474972.0 477525.0 91013.5 93780.0 96265.0 96742.5 97766.0 984.3 1017.0 1038.1 1047.3 1058.6 16508.5 16699.8 17167.7 17380.7 17916.2 35503.6 38201.6 39473.4 40093.6 41726.3 35860.3 36612.5 52713.2 58376.0 59924.4 7628.4 7815.6 8391.3 8436.9 8671.9 12613.0 13028.3 13161.7 13308.7 13375.5 10764.2 11751.9 11862.2 12180.6 12304.5 11134.2 11244.2 11272.1 11294.4 11372.9 11593.7 11761.6 11823.5 11897.1 12072.5 8048.8 8077.6 8264.4 8267.3 8420.9 11738.8 11854.5 11883.7 11907.5 11969.3 05:40:42 INFO - PROCESS | 12503 | 05:40:42 INFO - PROCESS | 12503 | [#3] mootools.html Cycles:180 Average:5748.90 Median:2672.38 stddev:3263.62 (122.1%) stddev-sans-first:3255.84 05:40:42 INFO - PROCESS | 12503 | Values: 1324.4 1498.7 1503.8 1647.9 1723.1 8241.2 8571.3 8628.4 8784.6 8789.6 8672.5 8762.9 8779.3 8789.8 8795.0 2392.0 2421.3 2423.2 2426.2 2427.6 2344.6 2365.4 2412.3 2412.6 2417.1 2357.3 2357.6 2369.3 2391.3 2397.0 2303.1 2333.2 2334.8 2345.6 2345.8 2239.5 2283.2 2287.0 2296.4 2298.8 8675.6 8701.0 8723.8 8769.2 8780.3 5126.4 5358.6 5365.8 5366.5 5367.9 3465.8 3485.5 3525.4 3565.6 3574.9 1216.8 1217.9 1227.0 1235.1 1262.4 12166.9 12234.0 12251.8 12264.0 12273.1 4270.9 4415.1 4557.3 4562.8 4577.7 9344.6 9353.9 9362.9 9366.3 9370.7 5212.1 5220.4 5230.2 5233.6 5239.3 4243.2 4680.3 4820.7 4855.2 4921.5 3617.1 3671.8 3812.4 3816.5 3825.1 1141.9 1149.5 1150.1 1153.0 1164.5 940.8 967.6 978.6 986.4 1001.0 5871.8 5916.5 6012.7 6057.5 6073.2 472.2 481.8 484.8 484.8 489.5 4113.8 4153.9 4169.6 4184.1 4210.5 10671.7 10875.1 10887.1 10970.7 10993.3 10286.8 10905.1 10905.3 10906.3 10922.9 7603.5 7838.1 7848.7 7853.3 7858.1 5865.6 5981.5 6011.5 6020.1 6162.4 9967.5 10109.1 10170.1 10188.1 10198.1 9927.9 9935.3 10347.5 10353.4 10377.2 6763.5 7735.0 7748.9 7840.0 7963.4 8566.7 8683.7 8690.2 8691.4 8703.5 7706.3 7727.2 7883.8 7984.5 8019.7 7544.0 7609.1 7623.2 7626.9 7670.5 7084.2 7188.6 7188.9 7196.9 7198.5 5028.9 5052.9 5100.7 5122.1 5393.9 5023.5 5029.5 5083.7 5126.5 5171.8 05:40:42 INFO - PROCESS | 12503 | 05:40:42 INFO - PROCESS | 12503 | [#4] prototype.html Cycles:180 Average:4688.59 Median:5249.79 stddev:1361.54 (25.9%) stddev-sans-first:1325.03 05:40:42 INFO - PROCESS | 12503 | Values: 306.2 310.7 320.5 322.4 338.1 5115.5 5180.1 5264.0 5304.7 5318.1 5214.3 5231.2 5233.0 5263.6 5279.6 4169.4 4180.1 4183.2 4186.9 4205.5 4036.6 4161.8 4200.4 4204.8 4208.1 4148.4 4160.9 4166.1 4166.9 4193.4 4152.7 4171.9 4179.3 4196.5 4207.5 3739.6 3745.3 3751.3 3754.0 3756.0 4951.5 5067.8 5089.8 5094.9 5104.2 4780.8 4796.5 4808.3 4810.0 4818.4 5403.8 5424.7 5456.9 5459.6 5491.4 3978.6 4066.3 4080.4 4086.9 4102.1 5624.6 5652.0 5675.1 5675.5 5678.7 4164.1 4191.1 4211.2 4226.0 4235.9 4089.3 4105.3 4127.0 4135.9 4212.9 4653.9 4664.3 4679.4 4681.3 4686.6 5494.8 5501.2 5502.2 5505.6 5552.4 1300.9 1344.0 1445.7 1466.9 1472.8 1015.7 1023.8 1035.2 1041.1 1043.9 5200.2 5200.7 5215.4 5241.0 5251.3 5557.9 5573.8 5609.4 5610.2 5627.7 5110.4 5248.3 5265.4 5291.7 5394.8 3009.3 3011.5 3012.7 3040.5 3042.3 5900.3 5910.2 5922.7 5949.6 5957.9 5587.9 5810.7 5845.5 5856.2 5857.2 5910.6 5934.1 5946.8 5977.6 5982.5 5418.8 5669.7 5703.9 5730.7 5755.0 5627.4 5631.7 5637.6 5641.4 5644.3 5888.5 5908.8 5986.4 5991.5 5991.9 3988.8 4032.5 4060.3 4063.7 4072.9 5700.3 5702.7 5708.9 5710.9 5736.8 5501.4 5655.3 5658.5 5660.5 5663.6 5530.7 5532.1 5537.8 5544.7 5566.9 5534.0 5545.1 5558.5 5560.2 5631.8 5370.2 5572.4 5608.8 5652.5 5681.8 5420.5 5431.3 5464.4 5482.5 5492.8 05:40:42 INFO - PROCESS | 12503 | 05:40:42 INFO - PROCESS | 12503 | [#5] yui.html Cycles:180 Average:21027.07 Median:4885.88 stddev:29894.82 (611.9%) stddev-sans-first:29939.58 05:40:42 INFO - PROCESS | 12503 | Values: 663.3 681.4 703.6 720.2 746.6 15227.3 16450.8 16868.5 16969.9 17077.9 14955.6 15089.1 15107.8 15114.8 15118.9 655.2 787.0 793.2 804.3 804.5 794.2 801.6 803.9 804.4 806.2 853.9 855.6 857.2 857.8 857.9 683.4 796.7 842.3 850.9 851.4 620.7 620.8 621.0 621.2 621.4 14949.9 14985.3 14996.0 14996.5 15007.9 13709.7 14027.6 14299.9 14464.6 14500.9 12501.9 12831.7 13059.2 13165.5 13189.4 4439.8 4482.2 4489.4 4532.9 4558.4 81568.2 82162.7 82795.0 82892.2 83707.0 11369.8 11386.3 11468.1 11493.3 11612.9 22212.8 22221.3 22307.0 22330.8 22424.2 7664.6 7686.6 8000.4 8013.3 8048.8 4319.8 4373.2 4387.7 4470.9 4574.8 3305.3 3372.6 3404.5 3417.1 3521.2 629.4 630.1 638.5 640.6 657.1 625.4 654.9 656.2 664.8 668.1 8532.3 9100.7 9618.7 9844.0 9893.2 526.3 673.5 683.3 707.7 710.8 4771.2 4883.3 4888.5 4952.6 4964.6 99198.0 100952.0 103839.5 115389.4 131689.3 97010.5 97747.5 98117.5 98374.0 99527.0 86098.0 86989.6 87515.0 87788.0 87850.1 9827.1 9879.6 9909.2 9938.0 9958.3 49280.0 49948.5 51177.5 51503.0 51856.0 69645.3 71002.6 77297.2 77881.0 78542.6 13123.8 13185.3 13726.4 14081.9 14306.7 13461.9 13485.9 13641.9 14490.9 14536.2 14098.6 14376.4 14966.3 15071.5 15410.2 13898.7 14478.6 14551.7 14813.2 15058.7 13289.6 14282.1 14343.8 14499.2 14944.9 11509.6 11510.8 11642.5 12038.6 12140.5 10305.7 11776.0 12092.3 12140.8 12181.5 05:40:42 INFO - PROCESS | 12503 | -------- Summary: end -------- 05:40:42 INFO - PROCESS | 12503 | 05:40:43 INFO - TEST-INFO | 12503: exit 0 05:40:43 INFO - TEST-OK | dromaeo_css | took 1116290ms 05:40:43 INFO - TEST-START | kraken 05:40:43 INFO - operating with platform_type : linux_ 05:40:43 INFO - Initialising browser for kraken test... 05:40:43 INFO - TEST-INFO | started process 12807 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptG0eNs/profile http://localhost:38341/getInfo.html) 05:40:48 INFO - PROCESS | 12807 | __metrics Screen width/height:1600/1200 05:40:48 INFO - PROCESS | 12807 | colorDepth:24 05:40:48 INFO - PROCESS | 12807 | Browser inner width/height: 1024/697 05:40:48 INFO - PROCESS | 12807 | __metrics 05:40:48 INFO - PROCESS | 12807 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:40:48 INFO - PROCESS | 12807 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:40:49 INFO - PROCESS | 12807 | console.error: PushService: 05:40:49 INFO - PROCESS | 12807 | stateChangeProcessEnqueue: Error transitioning state 05:40:49 INFO - PROCESS | 12807 | UnknownError 05:40:50 INFO - TEST-INFO | 12807: exit 0 05:40:50 INFO - Browser initialized. 05:40:50 INFO - Running cycle 1/1 for kraken test... 05:40:50 INFO - TEST-INFO | started process 12885 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmptG0eNs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:41:01 INFO - PROCESS | 12885 | RSS: Main: 156061696 05:41:01 INFO - PROCESS | 12885 | 05:41:04 INFO - PROCESS | 12885 | Cycle 1(1): loaded http://localhost:38341/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:38341/tests/kraken/driver.html?testName=audio-beat-detection) 05:41:05 INFO - PROCESS | 12885 | RSS: Main: 204357632 05:41:05 INFO - PROCESS | 12885 | 05:41:07 INFO - PROCESS | 12885 | Cycle 1(1): loaded http://localhost:38341/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:38341/tests/kraken/driver.html?testName=audio-dft) 05:41:08 INFO - PROCESS | 12885 | RSS: Main: 192081920 05:41:08 INFO - PROCESS | 12885 | 05:41:11 INFO - PROCESS | 12885 | Cycle 1(1): loaded http://localhost:38341/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:38341/tests/kraken/driver.html?testName=audio-fft) 05:41:12 INFO - PROCESS | 12885 | RSS: Main: 276283392 05:41:12 INFO - PROCESS | 12885 | 05:41:14 INFO - PROCESS | 12885 | Cycle 1(1): loaded http://localhost:38341/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:38341/tests/kraken/driver.html?testName=audio-oscillator) 05:41:14 INFO - PROCESS | 12885 | RSS: Main: 186359808 05:41:14 INFO - PROCESS | 12885 | 05:41:17 INFO - PROCESS | 12885 | Cycle 1(1): loaded http://localhost:38341/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:38341/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:41:17 INFO - PROCESS | 12885 | RSS: Main: 190763008 05:41:17 INFO - PROCESS | 12885 | 05:41:20 INFO - PROCESS | 12885 | Cycle 1(1): loaded http://localhost:38341/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:38341/tests/kraken/driver.html?testName=imaging-darkroom) 05:41:21 INFO - PROCESS | 12885 | RSS: Main: 225927168 05:41:21 INFO - PROCESS | 12885 | 05:41:24 INFO - PROCESS | 12885 | Cycle 1(1): loaded http://localhost:38341/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:38341/tests/kraken/driver.html?testName=imaging-desaturate) 05:41:25 INFO - PROCESS | 12885 | RSS: Main: 226557952 05:41:25 INFO - PROCESS | 12885 | 05:41:28 INFO - PROCESS | 12885 | Cycle 1(1): loaded http://localhost:38341/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:38341/tests/kraken/driver.html?testName=json-parse-financial) 05:41:28 INFO - PROCESS | 12885 | RSS: Main: 226263040 05:41:28 INFO - PROCESS | 12885 | 05:41:30 INFO - PROCESS | 12885 | Cycle 1(1): loaded http://localhost:38341/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:38341/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:41:30 INFO - PROCESS | 12885 | RSS: Main: 188760064 05:41:30 INFO - PROCESS | 12885 | 05:41:32 INFO - PROCESS | 12885 | Cycle 1(1): loaded http://localhost:38341/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:38341/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:41:33 INFO - PROCESS | 12885 | RSS: Main: 197169152 05:41:33 INFO - PROCESS | 12885 | 05:41:35 INFO - PROCESS | 12885 | Cycle 1(1): loaded http://localhost:38341/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:38341/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:41:36 INFO - PROCESS | 12885 | RSS: Main: 193409024 05:41:36 INFO - PROCESS | 12885 | 05:41:38 INFO - PROCESS | 12885 | Cycle 1(1): loaded http://localhost:38341/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:38341/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:41:39 INFO - PROCESS | 12885 | RSS: Main: 187654144 05:41:39 INFO - PROCESS | 12885 | 05:41:41 INFO - PROCESS | 12885 | Cycle 1(1): loaded http://localhost:38341/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:38341/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:41:42 INFO - PROCESS | 12885 | RSS: Main: 192593920 05:41:42 INFO - PROCESS | 12885 | 05:41:44 INFO - PROCESS | 12885 | Cycle 1(1): loaded http://localhost:38341/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:38341/tests/kraken/driver.html?testName=ai-astar) 05:41:44 INFO - PROCESS | 12885 | RSS: Main: 242507776 05:41:44 INFO - PROCESS | 12885 | 05:41:44 INFO - PROCESS | 12885 | __start_tp_report 05:41:44 INFO - PROCESS | 12885 | _x_x_mozilla_page_load 05:41:44 INFO - PROCESS | 12885 | _x_x_mozilla_page_load_details 05:41:44 INFO - PROCESS | 12885 | |i|pagename|runs| 05:41:44 INFO - PROCESS | 12885 | |0;ai-astar;104;113;112;111;107;111;110;110;110;110 05:41:44 INFO - PROCESS | 12885 | |1;audio-beat-detection;144;118;120;183;118;123;151;117;158;119 05:41:44 INFO - PROCESS | 12885 | |2;audio-dft;160;160;161;161;160;159;161;162;161;161 05:41:44 INFO - PROCESS | 12885 | |3;audio-fft;75;75;77;113;74;76;74;74;101;75 05:41:44 INFO - PROCESS | 12885 | |4;audio-oscillator;85;85;135;85;86;86;121;84;85;134 05:41:44 INFO - PROCESS | 12885 | |5;imaging-gaussian-blur;118;116;117;117;117;117;117;117;117;117 05:41:44 INFO - PROCESS | 12885 | |6;imaging-darkroom;148;147;145;145;145;145;146;146;145;146 05:41:44 INFO - PROCESS | 12885 | |7;imaging-desaturate;95;95;95;96;97;97;97;96;96;95 05:41:44 INFO - PROCESS | 12885 | |8;json-parse-financial;50;49;52;51;50;53;50;50;52;50 05:41:44 INFO - PROCESS | 12885 | |9;json-stringify-tinderbox;61;62;62;61;61;60;61;61;61;62 05:41:44 INFO - PROCESS | 12885 | |10;stanford-crypto-aes;79;79;88;79;82;79;79;81;77;78 05:41:44 INFO - PROCESS | 12885 | |11;stanford-crypto-ccm;124;123;123;127;124;122;133;124;141;132 05:41:44 INFO - PROCESS | 12885 | |12;stanford-crypto-pbkdf2;169;163;162;163;162;163;163;166;161;163 05:41:44 INFO - PROCESS | 12885 | |13;stanford-crypto-sha256-iterative;64;64;66;64;64;66;64;63;64;63 05:41:44 INFO - PROCESS | 12885 | __end_tp_report 05:41:44 INFO - PROCESS | 12885 | __start_cc_report 05:41:44 INFO - PROCESS | 12885 | _x_x_mozilla_cycle_collect,1676 05:41:44 INFO - PROCESS | 12885 | __end_cc_report 05:41:44 INFO - PROCESS | 12885 | __startTimestamp1471610504396__endTimestamp 05:41:44 INFO - PROCESS | 12885 | 05:41:44 INFO - PROCESS | 12885 | ------- Summary: start ------- 05:41:44 INFO - PROCESS | 12885 | Number of tests: 14 05:41:44 INFO - PROCESS | 12885 | 05:41:44 INFO - PROCESS | 12885 | [#0] ai-astar Cycles:10 Average:109.80 Median:110.50 stddev:2.57 (2.3%) stddev-sans-first:1.67 05:41:44 INFO - PROCESS | 12885 | Values: 104.0 113.0 112.0 111.0 107.0 111.0 110.0 110.0 110.0 110.0 05:41:44 INFO - PROCESS | 12885 | 05:41:44 INFO - PROCESS | 12885 | [#1] audio-beat-detection Cycles:10 Average:135.10 Median:133.50 stddev:22.84 (17.1%) stddev-sans-first:24.00 05:41:44 INFO - PROCESS | 12885 | Values: 144.0 118.0 120.0 183.0 118.0 123.0 151.0 117.0 158.0 119.0 05:41:44 INFO - PROCESS | 12885 | 05:41:44 INFO - PROCESS | 12885 | [#2] audio-dft Cycles:10 Average:160.60 Median:161.00 stddev:0.84 (0.5%) stddev-sans-first:0.87 05:41:44 INFO - PROCESS | 12885 | Values: 160.0 160.0 161.0 161.0 160.0 159.0 161.0 162.0 161.0 161.0 05:41:44 INFO - PROCESS | 12885 | 05:41:44 INFO - PROCESS | 12885 | [#3] audio-fft Cycles:10 Average:81.40 Median:75.00 stddev:13.82 (18.4%) stddev-sans-first:14.46 05:41:44 INFO - PROCESS | 12885 | Values: 75.0 75.0 77.0 113.0 74.0 76.0 74.0 74.0 101.0 75.0 05:41:44 INFO - PROCESS | 12885 | 05:41:44 INFO - PROCESS | 12885 | [#4] audio-oscillator Cycles:10 Average:98.60 Median:85.00 stddev:21.99 (25.9%) stddev-sans-first:22.76 05:41:44 INFO - PROCESS | 12885 | Values: 85.0 85.0 135.0 85.0 86.0 86.0 121.0 84.0 85.0 134.0 05:41:44 INFO - PROCESS | 12885 | 05:41:44 INFO - PROCESS | 12885 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.47 (0.4%) stddev-sans-first:0.33 05:41:44 INFO - PROCESS | 12885 | Values: 118.0 116.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 05:41:44 INFO - PROCESS | 12885 | 05:41:44 INFO - PROCESS | 12885 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:1.03 (0.7%) stddev-sans-first:0.73 05:41:44 INFO - PROCESS | 12885 | Values: 148.0 147.0 145.0 145.0 145.0 145.0 146.0 146.0 145.0 146.0 05:41:44 INFO - PROCESS | 12885 | 05:41:44 INFO - PROCESS | 12885 | [#7] imaging-desaturate Cycles:10 Average:95.90 Median:96.00 stddev:0.88 (0.9%) stddev-sans-first:0.87 05:41:44 INFO - PROCESS | 12885 | Values: 95.0 95.0 95.0 96.0 97.0 97.0 97.0 96.0 96.0 95.0 05:41:44 INFO - PROCESS | 12885 | 05:41:44 INFO - PROCESS | 12885 | [#8] json-parse-financial Cycles:10 Average:50.70 Median:50.50 stddev:1.25 (2.5%) stddev-sans-first:1.30 05:41:44 INFO - PROCESS | 12885 | Values: 50.0 49.0 52.0 51.0 50.0 53.0 50.0 50.0 52.0 50.0 05:41:44 INFO - PROCESS | 12885 | 05:41:44 INFO - PROCESS | 12885 | [#9] json-stringify-tinderbox Cycles:10 Average:61.20 Median:61.00 stddev:0.63 (1.0%) stddev-sans-first:0.67 05:41:44 INFO - PROCESS | 12885 | Values: 61.0 62.0 62.0 61.0 61.0 60.0 61.0 61.0 61.0 62.0 05:41:44 INFO - PROCESS | 12885 | 05:41:44 INFO - PROCESS | 12885 | [#10] stanford-crypto-aes Cycles:10 Average:80.10 Median:79.00 stddev:3.11 (3.9%) stddev-sans-first:3.27 05:41:44 INFO - PROCESS | 12885 | Values: 79.0 79.0 88.0 79.0 82.0 79.0 79.0 81.0 77.0 78.0 05:41:44 INFO - PROCESS | 12885 | 05:41:44 INFO - PROCESS | 12885 | [#11] stanford-crypto-ccm Cycles:10 Average:127.30 Median:125.50 stddev:6.15 (4.9%) stddev-sans-first:6.40 05:41:44 INFO - PROCESS | 12885 | Values: 124.0 123.0 123.0 127.0 124.0 122.0 133.0 124.0 141.0 132.0 05:41:44 INFO - PROCESS | 12885 | 05:41:44 INFO - PROCESS | 12885 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.50 Median:163.00 stddev:2.32 (1.4%) stddev-sans-first:1.36 05:41:44 INFO - PROCESS | 12885 | Values: 169.0 163.0 162.0 163.0 162.0 163.0 163.0 166.0 161.0 163.0 05:41:44 INFO - PROCESS | 12885 | 05:41:44 INFO - PROCESS | 12885 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.20 Median:64.00 stddev:1.03 (1.6%) stddev-sans-first:1.09 05:41:44 INFO - PROCESS | 12885 | Values: 64.0 64.0 66.0 64.0 64.0 66.0 64.0 63.0 64.0 63.0 05:41:44 INFO - PROCESS | 12885 | -------- Summary: end -------- 05:41:44 INFO - PROCESS | 12885 | 05:41:45 INFO - TEST-INFO | 12885: exit 0 05:41:45 INFO - TEST-OK | kraken | took 61825ms 05:41:45 INFO - SUITE-END | took 1178s 05:41:45 INFO - Completed test suite (00:19:38) 05:41:45 INFO - javascript benchmark 05:41:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3040.1335619348483, 3044.406340763552, 3073.10879844845, 3081.707237812407, 3082.9691703082976, 5240.34495688039, 5281.973590132044, 5282.392524859638, 5368.758405871736, 5391.91912121318, 5255.185446255827, 5302.840914772553, 5307.973460132694, 5357.330333708286, 5412.07994640911, 4697.835575754849, 4763.737994410306, 4789.544993225655, 4800.735959522225, 4951.306817045616, 4909.828156014541, 4914.164592019365, 4920.335754673127, 4937.0125974805005, 4948.604111671058, 4721.173794585934, 4764.523547645243, 4878.512148785128, 4923.261510773377, 4923.9753801230945, 4763.833265835679, 4777.331173635694, 4790.856274311776, 4798.136335459616, 4826.806927722887, 4750.904981900378, 4760.833370832039, 4777.116233496814, 4853.563179313879, 4857.514248575149, 5176.197689358156, 5320.6275560710765, 5360.3299587551555, 5363.168708850135, 5365.624406291561, 5751.0, 5760.135979603051, 5808.88382232353, 5821.67980761058, 5826.533877289807, 5783.479486846204, 5809.88380232393, 5817.447342502455, 5837.474627283523, 5839.64962102271, 5172.974135129375, 5246.31797866275, 5447.891042179134, 5450.3732070811575, 5456.045192091368, 5786.07422812348, 5822.359540450547, 5853.590248682636, 5853.64878107315, 5856.1508581255475, 5606.187102870061, 5613.073842815971, 5616.747246373923, 5655.575831812631, 5658.943410565924, 5570.805021824217, 5631.099024156116, 5691.743871525791, 5729.341125770589, 5732.168835518826, 5640.125780504029, 5689.374168841424, 5701.372848986608, 5736.053551164095, 5742.0238559445, 5644.463775941279, 5678.375378708339, 5683.772649094072, 5689.687067211343, 5691.288588926384, 4262.573742625713, 4351.26028575143, 4373.387725718402, 4389.385486031958, 4430.9587246996925, 3864.903377415531, 3894.7078969077434, 3916.2167566486814, 3925.430812532167, 4010.1177740896956, 5440.537554307849, 5446.1558458439, 5449.6730196188355, 5468.863278417992, 5500.944990550123, 5519.254900588449, 5750.597458177971, 5760.711964401763, 5764.596478246483, 5765.740541675551, 5254.211868219798, 5287.154055351125, 5323.866903327448, 5386.165144402624, 5391.784328626811, 4838.080764654705, 4851.272309153655, 4852.539008794159, 4853.611711063107, 4912.828051018198, 5839.357670656224, 5871.236739223874, 5884.2350494436305, 5889.352171261072, 5890.911636325459, 5424.755885985219, 5458.426865179099, 5646.265985421869, 5700.344460386943, 5782.190493330853, 5947.286325640951, 5953.553483488669, 5958.672273025025, 5959.314678811992, 5960.970195148997, 5589.049861523469, 5634.718264086698, 5637.9718101409235, 5643.238162848127, 5643.689597072201, 5502.80740174092, 5657.377688454185, 5680.971595141998, 5705.543556515552, 5804.21643078179, 5591.524720398812, 5626.240457538343, 5633.436656334334, 5636.070048441961, 5642.633228840113, 5600.187972744011, 5607.0, 5609.214709940693, 5609.719514024201, 5617.662940223599, 5388.137897936312, 5484.8354549363585, 5496.340439147168, 5514.889702205854, 5626.465485778845, 5612.214289999321, 5613.859653508695, 5617.185508101221, 5654.123610840326, 5669.7165141741925, 5484.945150548603, 5492.231087647812, 5493.7802487900035, 5497.972510137423, 5502.614816962934], "value": 5258.553316100785, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [594.6432140715572, 604.4257954942804, 617.4751461257931, 618.9195404597401, 634.0552576660776, 43999.34000989984, 44977.325340119925, 45086.0, 45193.774031129884, 48078.0, 46330.76834615831, 46896.062078758405, 46944.0, 46980.29529557059, 46985.76507117468, 8199.098099209083, 8960.372773905829, 9091.227245684126, 9096.090390960884, 10600.78798424031, 10650.520726567283, 10782.676519704382, 10788.676339709822, 10793.622223222188, 10795.190360722938, 10729.0, 10873.184511161655, 10881.455927203648, 10881.727956801104, 10885.18361122915, 10799.4060326682, 10802.67591972242, 10811.405372704497, 10830.350178989285, 10842.186835987333, 10754.408507532082, 10816.837747433794, 10819.67540973772, 10832.025117739362, 10837.403942783143, 44043.0, 44422.111557768985, 44451.77741112931, 44482.777586111864, 44511.777441112914, 3394.4908263760385, 3404.4042292598697, 3523.3834079036324, 3588.8743893963847, 3616.168281295317, 33789.0, 33979.320413591835, 34148.14629634279, 34913.82543087268, 35736.46395304072, 3948.605139486057, 4056.7971601420104, 4063.4108054331955, 4185.142045880587, 4211.852585159535, 353946.0, 355343.02869112854, 356763.0, 357181.0, 357956.0, 35720.46419303712, 60531.69734151301, 60762.39237607656, 60797.696011519656, 60840.0, 36656.45015324772, 37920.24159516794, 38063.42904856429, 38199.23601527954, 38271.0, 14614.123152610877, 14934.10395376281, 14938.0, 14951.626209344637, 14951.700965980837, 10958.890411095947, 10996.175286853519, 11779.528818847322, 12120.81818772719, 12121.878781212075, 8725.825483490295, 8758.605862736193, 8820.647174113092, 8870.0, 8878.245349145394, 3350.2461946062426, 3397.456406974872, 3432.3650124727005, 3453.1539772755837, 3491.0, 919.6873063158558, 971.4414211828228, 1003.7892042670975, 1029.4749677664295, 1097.7605684403886, 32364.190895227337, 32366.514502282484, 32485.837570811993, 32630.021099367055, 33152.1711957203, 1089.5369467976236, 1095.2606990281688, 1141.275290190735, 1216.403962058585, 1227.245244174826, 17722.91138544299, 18657.533561660795, 19068.713969290195, 19193.136308866135, 19204.71192932107, 636328.0, 638399.0, 638963.0, 639927.0, 642629.0, 61774.69112654408, 62159.0, 62532.37467625357, 62550.37449625445, 62623.0, 1233.5744168261936, 1327.4889167670517, 1352.6280272925023, 1360.7074478987263, 1387.7987691784635, 32143.839280803448, 33157.83421082879, 34165.316693666486, 34361.656383435846, 34644.307113858085, 52502.737486313854, 52926.4707352937, 52938.0, 52945.8813649138, 53187.46812531825, 446280.0, 451019.0, 451836.0, 451870.0, 451910.0, 28807.279818004718, 29077.7092229075, 29132.0, 29228.56157157687, 30820.22949426282, 48884.0, 49094.50905491042, 49773.253401199734, 49784.0, 53320.0, 42497.78751106225, 42970.35544466773, 43512.0, 43534.129317412844, 45439.31841022321, 42801.0, 43768.78115609402, 44368.60068259396, 44898.551014489436, 44935.55064449314, 42264.36603451012, 43219.0, 43579.34630980601, 43826.78086609547, 43837.0, 35631.82184089063, 38930.80534597309, 39291.80354098211, 39668.66129766002, 39689.0, 28732.856335719025, 29079.56380654334, 29183.270418239714, 29285.0, 29400.411991760473], "value": 19918.59400524547, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [366.86792754608354, 388.57451091055304, 396.4806104003756, 399.9700022498313, 418.31814142947013, 14423.78364324536, 17398.565035874108, 18903.0, 19899.0, 19918.10368533414, 18970.905145474288, 18983.33558325459, 18991.62016759664, 19182.424527264204, 19229.0, 5658.858528536789, 5701.147127183661, 5860.355360910296, 5863.032599621058, 5868.295804503444, 5846.298444186704, 6030.577859549833, 6042.516598672096, 6043.3956604339655, 6045.365236650155, 5940.940590594082, 6048.0625503047095, 6054.364291749369, 6497.155369801919, 6565.244996825355, 6549.050387693782, 6573.967130164342, 6578.506612004119, 6584.242812076601, 6588.703508342088, 5158.40678321994, 5382.8923421531745, 5417.647852889589, 5497.890042199174, 5508.641938274, 13825.10136841102, 13941.790873136912, 14162.71674566503, 14175.716485670331, 14308.92845535776, 2212.579609874119, 2236.71388951352, 2321.7097862767155, 2544.732803055671, 2693.851838148901, 10203.0, 10565.841512377321, 10750.784984300348, 10952.507137178845, 11576.710582235428, 1819.654265689528, 1888.9244430222911, 1896.8292853643238, 1918.7697476302933, 1935.8548108891748, 79114.0, 84657.0, 86515.99999999937, 86607.13392866054, 88208.0, 11051.17116216287, 11104.167187460811, 11384.487698053608, 11391.430428478543, 11687.88965048319, 15850.76223856643, 15857.207139642971, 15859.841401586067, 15861.444849430214, 15876.92061539685, 7658.425618078555, 7819.84360312802, 7940.722074727357, 8015.038195416588, 8133.0646975598565, 7377.852442951111, 7423.331900129015, 7430.777076687708, 7568.772936811904, 7650.502717323357, 4766.828100342817, 4831.154547954094, 4835.223011426306, 4961.618423156905, 4980.676256043345, 1957.285590759391, 2017.2032047341218, 2024.5545979884403, 2077.781832907553, 2093.769685334612, 856.9828603427896, 905.9954700226589, 975.3124047546463, 1007.4005966449951, 1017.6336518853207, 18000.08964188077, 19071.427857164308, 19447.3193438229, 19667.803321966883, 19675.40973770789, 943.5942544705842, 961.8701475300745, 983.9458829764288, 988.4415305352453, 1017.7964407118606, 10009.849852252222, 10812.189085818438, 10843.891561084445, 10879.184061195441, 10894.346339219672, 375730.0, 471162.0, 474673.0, 474972.0, 477525.0, 91013.54493227492, 93780.0, 96265.0, 96742.51628741811, 97766.0, 984.2568860510269, 1016.9593216271414, 1038.127972503101, 1047.2564479219686, 1058.5501162006117, 16508.507608125135, 16699.833001669827, 17167.656646866744, 17380.73928891093, 17916.193771280585, 35503.64496355003, 38201.61798381981, 39473.40789888211, 40093.59906400899, 41726.32378819496, 35860.282794344486, 36612.450813238356, 52713.20930185974, 58376.0, 59924.40075599363, 7628.42786791004, 7815.570143641927, 8391.32869370461, 8436.873446898178, 8671.869921951302, 12613.0, 13028.283444410648, 13161.73676526445, 13308.667283317607, 13375.464981400635, 10764.192685548458, 11751.941240293743, 11862.169648124549, 12180.57367992116, 12304.507819687113, 11134.164937629872, 11244.212905096892, 11272.098232141572, 11294.378809165575, 11372.886271137182, 11593.710157246136, 11761.647150585497, 11823.46794394237, 11897.107716921439, 12072.517099315928, 8048.798780030546, 8077.63650635711, 8264.421490495608, 8267.338612910833, 8420.915790842013, 11738.76522469563, 11854.466549005489, 11883.702907427038, 11907.52369905194, 11969.281843089442], "value": 9068.496277087823, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1324.4371142264536, 1498.6553092788654, 1503.827059888113, 1647.9176041197945, 1723.112597012538, 8241.217084376982, 8571.314294856413, 8628.439151455159, 8784.64861405543, 8789.604467798948, 8672.47965122093, 8762.912370876289, 8779.34154938379, 8789.7802554936, 8794.95602521988, 2391.9521609567755, 2421.285720712394, 2423.212455951814, 2426.2236084452984, 2427.5509030829267, 2344.6248600223967, 2365.432296248905, 2412.348665860217, 2412.6139817629182, 2417.0815090265764, 2357.316378250305, 2357.610994185966, 2369.3128992592215, 2391.270662447953, 2396.95206095878, 2303.1017903017837, 2333.2067097187037, 2334.8482348647285, 2345.6012477878803, 2345.8475199112004, 2239.4513344230604, 2283.155232563962, 2287.039443433762, 2296.368498662881, 2298.816094712419, 8675.609597568124, 8700.99938507067, 8723.781905452352, 8769.166929141786, 8780.341474389383, 5126.410462796789, 5358.5981051421295, 5365.839024829262, 5366.543843773284, 5367.946320536823, 3465.8267086645565, 3485.4597537381746, 3525.4006818840858, 3565.554305711786, 3574.9285014299567, 1216.7809794237035, 1217.8721234270363, 1227.030645789825, 1235.1045492018304, 1262.3751243134707, 12166.878331216663, 12234.0, 12251.754964900652, 12264.0, 12273.140880138304, 4270.935935960963, 4415.116976604628, 4557.316402539579, 4562.794674239667, 4577.679562430598, 9344.579493922789, 9353.90646093544, 9362.90637093634, 9366.344355895159, 9370.718878433521, 5212.061828870839, 5220.399654039758, 5230.215467679878, 5233.633645644768, 5239.345081864767, 4243.215005224043, 4680.274557443602, 4820.734859582686, 4855.223164293696, 4921.458639549646, 3617.149969757105, 3671.81640917953, 3812.390017597171, 3816.542014958223, 3825.062859599416, 1141.868685101224, 1149.5114576305036, 1150.097173718627, 1152.9711757206137, 1164.458526785032, 940.7742141886035, 967.6129548180643, 978.6330126202674, 986.3539381704994, 1000.9890011088853, 5871.765129394862, 5916.467517923407, 6012.729427175787, 6057.485113765291, 6073.210482637229, 472.18076637034716, 481.7543053042973, 484.8085006422514, 484.849696594057, 489.52760586034645, 4113.753174809521, 4153.91692166161, 4169.603887630731, 4184.079502509443, 4210.536840947493, 10671.679849604525, 10875.129989600813, 10887.129029677606, 10970.725731856608, 10993.285436446606, 10286.845697314546, 10905.127589792797, 10905.255159174494, 10906.34561926287, 10922.890771092187, 7603.46775725705, 7838.098618658927, 7848.686052557834, 7853.253940875494, 7858.096318923397, 5865.621578928863, 5981.461668449861, 6011.489023433057, 6020.096985452042, 6162.44537991565, 9967.451790151612, 10109.090181883666, 10170.08469237772, 10188.134008609351, 10198.133158681596, 9927.900720992986, 9935.304528682922, 10347.534360953925, 10353.378797272188, 10377.16982641402, 6763.526553141233, 7735.0, 7748.92251077482, 7840.0, 7963.362930965624, 8566.700165494178, 8683.739487815375, 8690.217880390795, 8691.391602587759, 8703.47779133254, 7706.267904549059, 7727.227277272228, 7883.763487095396, 7984.520928744294, 8019.679212831421, 7544.0, 7609.124950630749, 7623.199564045917, 7626.885596716164, 7670.463067585216, 7084.220735718964, 7188.640567971685, 7188.928110718826, 7196.928030719626, 7198.49610527279, 5028.924566131438, 5052.873678158076, 5100.69395836251, 5122.129238029469, 5393.865153371197, 5023.522765337287, 5029.5221953914315, 5083.745812709276, 5126.487351264844, 5171.818986335611], "value": 4542.50808784881, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.2008158705776, 310.7172473049525, 320.54162547556984, 322.4148171069509, 338.125944433639, 5115.462876397981, 5180.093483640358, 5263.9736801315985, 5304.708241046741, 5318.0693378658725, 5214.296070030545, 5231.189165679316, 5233.0, 5263.552598029162, 5279.604029697778, 4169.437125987998, 4180.1430706705205, 4183.16336732654, 4186.874393768177, 4205.474315710536, 4036.616521430474, 4161.791910404483, 4200.390943313233, 4204.789760511962, 4208.13733184698, 4148.439960605325, 4160.916781664365, 4166.145940082276, 4166.937495937548, 4193.43388642534, 4152.730072545305, 4171.937420938689, 4179.2895207814745, 4196.475440569929, 4207.453031105937, 3739.607341229159, 3745.250949810049, 3751.3247615429214, 3754.0427191066105, 3755.962440375589, 4951.455339912607, 5067.797288108472, 5089.829339251993, 5094.872628184288, 5104.2088476286235, 4780.760961951889, 4796.496367881392, 4808.278758186265, 4810.0, 4818.445878723956, 5403.758049930825, 5424.690938527495, 5456.91814622781, 5459.580509067631, 5491.395946445888, 3978.6419222269847, 4066.2680717470844, 4080.3879418087226, 4086.8569600063856, 4102.118044620391, 5624.55003599711, 5651.9717401413145, 5675.120356344773, 5675.489205971524, 5678.687672178028, 4164.146349998243, 4191.140816132685, 4211.241976444208, 4225.9577404225565, 4235.894102647397, 4089.284375234322, 4105.322621767375, 4126.979365103155, 4135.937960930589, 4212.947878716065, 4653.883652908705, 4664.277037059262, 4679.368285281444, 4681.291328665013, 4686.554777296152, 5494.835154945358, 5501.202325662836, 5502.166384312082, 5505.614606977553, 5552.361478430033, 1300.876416740408, 1343.9731205375838, 1445.7108578284385, 1466.8753155981647, 1472.8232612086617, 1015.7308313296983, 1023.7696518283476, 1035.177034257767, 1041.1046500009986, 1043.9478026098664, 5200.167973124358, 5200.6619569727845, 5215.426303106655, 5240.973795131076, 5251.264822924793, 5557.861053473695, 5573.832785016456, 5609.411011843779, 5610.158476228598, 5627.690477023803, 5110.412302585176, 5248.317718696545, 5265.447128051576, 5291.735413229323, 5394.838154855361, 3009.2627306310096, 3011.472992226352, 3012.7288544031144, 3040.452718510645, 3042.300270937697, 5900.262467191601, 5910.172575839386, 5922.674252916131, 5949.613275137189, 5957.944509144339, 5587.944120558824, 5810.745982249953, 5845.503132233723, 5856.180134781134, 5857.20927674767, 5910.581460449461, 5934.139549765347, 5946.762129514857, 5977.551683623746, 5982.521398288213, 5418.83743487696, 5669.688167150846, 5703.942960570341, 5730.684812335195, 5754.971225143848, 5627.437256274504, 5631.746571404378, 5637.605367624227, 5641.351244606923, 5644.294463192101, 5888.4700376966275, 5908.763649453974, 5986.371430999682, 5991.460768530853, 5991.910121348271, 3988.7806170660892, 4032.516098068251, 4060.3097473428998, 4063.6633935487803, 4072.8981775454668, 5700.25896633435, 5702.714864256687, 5708.885822283614, 5710.887566276472, 5736.799212027559, 5501.367342755634, 5655.349634792052, 5658.472212502684, 5660.490555849993, 5663.603547751618, 5530.695811730394, 5532.1425179097305, 5537.833864984057, 5544.695041772741, 5566.91649625264, 5534.031544479781, 5545.085060964896, 5558.471945165203, 5560.193771903132, 5631.774729010794, 5370.2213179089595, 5572.35917869434, 5608.831735047955, 5652.519535839499, 5681.801136960186, 5420.457954204548, 5431.293931788842, 5464.371597266365, 5482.479164479368, 5492.835214943558], "value": 4254.807503568699, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [663.3134705579726, 681.3833480699968, 703.6024646074968, 720.188082176833, 746.5520687587448, 15227.314770835312, 16450.75323870143, 16868.49394518163, 16969.915150424247, 17077.914610426946, 14955.626109347271, 15089.094654320748, 15107.84892151081, 15114.848851511482, 15118.924405377986, 655.2268323378412, 786.9724559640399, 793.2464159048898, 804.2600807257337, 804.4891493901386, 794.1938931984041, 801.6152246921481, 803.8874557561946, 804.3605333759646, 806.2300503019602, 853.8975322961253, 855.6278019061729, 857.1514200941086, 857.7598272483715, 857.8841856349406, 683.4088513435892, 796.6693822063836, 842.2966822702989, 850.8936382952131, 851.3997631669689, 620.695859029077, 620.7672122953892, 621.0404924391826, 621.1676353686082, 621.3910367839543, 14949.925250373788, 14985.32566034531, 14995.95028348016, 14996.47512337074, 15007.924960375238, 13709.725805483835, 14027.579172624837, 14299.928500357535, 14464.566063018126, 14500.854991450162, 12501.874981250163, 12831.679208019874, 13059.216447013114, 13165.53920612774, 13189.406476708571, 4439.7780110994645, 4482.193205223059, 4489.393931819195, 4532.93200601988, 4558.407407037097, 81568.18431815665, 82162.65942713764, 82795.0, 82892.1975461718, 83707.0, 11369.772604547863, 11386.259893106922, 11468.13988950832, 11493.310401375944, 11612.94193529027, 22212.77787222107, 22221.333359998902, 22307.0, 22330.77669223287, 22424.215152469584, 7664.616769161519, 7686.577238251838, 8000.399970002273, 8013.278804907586, 8048.798780030546, 4319.784010799447, 4373.234683930299, 4387.714798538085, 4470.932936005963, 4574.804094034882, 3305.25631732863, 3372.629010808809, 3404.4893266010295, 3417.1115509967585, 3521.1549228185563, 629.4209327418786, 630.130420020369, 638.5211091681239, 640.6092283706935, 657.135866335767, 625.4371066040509, 654.898490733937, 656.1961597043679, 664.7905909638545, 668.1080757189206, 8532.317414606816, 9100.681476148435, 9618.663346782969, 9843.981983693326, 9893.159081478156, 526.3263023330144, 673.5285300289719, 683.3030309084805, 707.706301884712, 710.7583421636668, 4771.212749896297, 4883.291922671193, 4888.462269150391, 4952.62855285855, 4964.627652926045, 99198.0, 100952.0, 103839.48080259701, 115389.4230528842, 131689.34155329163, 97010.51494742482, 97747.51126244324, 98117.50941245248, 98374.0, 99527.0, 86098.0, 86989.5650521756, 87514.99999999872, 87788.0, 87850.12149878548, 9827.11555959967, 9879.555420005972, 9909.207263419054, 9937.950310248403, 9958.302918795614, 49280.0, 49948.50051499438, 51177.488225117275, 51503.0, 51856.0, 69645.30354696591, 71002.6449867768, 77297.22702772901, 77881.0, 78542.6072869632, 13123.803142953055, 13185.274809885548, 13726.382312796148, 14081.859181408056, 14306.71092625175, 13461.86538134606, 13485.932570337478, 13641.931790340985, 14490.85509144895, 14536.200508972108, 14098.647533811736, 14376.424943002581, 14966.32651530662, 15071.54785356441, 15410.15244161537, 13898.72202555923, 14478.565643030726, 14551.708965820413, 14813.185274809988, 15058.698826023638, 13289.601311960656, 14282.143071415749, 14343.772651153407, 14499.202543860189, 14944.850551494346, 11509.597164099216, 11510.827337589775, 11642.53429862796, 12038.578649747216, 12140.514379425078, 10305.690829275134, 11776.0, 12092.334921579397, 12140.75718485643, 12181.469878075435], "value": 7606.3916045867345, "unit": "score"}], "name": "dromaeo_css", "value": 7202.942885008379, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 113.0, 112.0, 111.0, 107.0, 111.0, 110.0, 110.0, 110.0, 110.0], "value": 109.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 118.0, 120.0, 183.0, 118.0, 123.0, 151.0, 117.0, 158.0, 119.0], "value": 135.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 161.0, 161.0, 160.0, 159.0, 161.0, 162.0, 161.0, 161.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 77.0, 113.0, 74.0, 76.0, 74.0, 74.0, 101.0, 75.0], "value": 81.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 135.0, 85.0, 86.0, 86.0, 121.0, 84.0, 85.0, 134.0], "value": 98.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 147.0, 145.0, 145.0, 145.0, 145.0, 146.0, 146.0, 145.0, 146.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 97.0, 97.0, 97.0, 96.0, 96.0, 95.0], "value": 95.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 49.0, 52.0, 51.0, 50.0, 53.0, 50.0, 50.0, 52.0, 50.0], "value": 50.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 62.0, 61.0, 61.0, 60.0, 61.0, 61.0, 61.0, 62.0], "value": 61.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 88.0, 79.0, 82.0, 79.0, 79.0, 81.0, 77.0, 78.0], "value": 80.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 123.0, 123.0, 127.0, 124.0, 122.0, 133.0, 124.0, 141.0, 132.0], "value": 127.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 163.0, 162.0, 163.0, 162.0, 163.0, 163.0, 166.0, 161.0, 163.0], "value": 163.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 66.0, 64.0, 64.0, 66.0, 64.0, 63.0, 64.0, 63.0], "value": 64.2, "unit": "score"}], "name": "kraken", "value": 1491.1999999999998, "alertThreshold": 2.0}]} 05:41:45 INFO - Return code: 0 05:41:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:41:45 INFO - # TBPL SUCCESS # 05:41:45 INFO - Running post-action listener: _resource_record_post_action 05:41:45 INFO - [mozharness: 2016-08-19 12:41:45.684994Z] Finished run-tests step (success) 05:41:45 INFO - Running post-run listener: _resource_record_post_run 05:41:45 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 4096; Write bytes: 417271808; Read time: 12; Write time: 421308 05:41:45 INFO - TinderboxPrint: CPU usage
13.0% 05:41:45 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 05:41:45 INFO - TinderboxPrint: I/O write bytes / time
417,271,808 / 421,308 05:41:45 INFO - TinderboxPrint: CPU idle
8,296.9 (87.0%) 05:41:45 INFO - TinderboxPrint: CPU user
1,193.9 (12.5%) 05:41:45 INFO - TinderboxPrint: Swap in / out
0 / 0 05:41:45 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 129589248; Read time: 0; Write time: 128620 05:41:45 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 0; Write bytes: 287682560; Read time: 0; Write time: 292688 05:41:46 INFO - Running post-run listener: _upload_blobber_files 05:41:46 INFO - Blob upload gear active. 05:41:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:41:46 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 05:41:46 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:41:46 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:41:46 INFO - (blobuploader) - INFO - Open directory for files ... 05:41:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:41:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:41:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:41:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:41:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:41:49 INFO - (blobuploader) - INFO - Done attempting. 05:41:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:41:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:41:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:41:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:41:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:41:50 INFO - (blobuploader) - INFO - Done attempting. 05:41:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:41:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:41:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:41:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:41:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:41:51 INFO - (blobuploader) - INFO - Done attempting. 05:41:51 INFO - (blobuploader) - INFO - Iteration through files over. 05:41:51 INFO - Return code: 0 05:41:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:41:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:41:51 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2e0ee3f2cc1deb5a64d3ccd68b6f8509751c605b02074ae4cbc610ab691644b549da6c7f399b93e71e6287e06733eeba5c652848a8d9b3c6dfac1d481bf397a7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8e1e5cc0d2cb0aeba0f13cbb89ed5b21d8128309eabfd04f8796f386fbdfb46436f66d19a0003cbaeea491a47da3bff1462fdda2e22a3a439f8a63d4cec3747a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/645a5f79a14008413caf809191b4fb9b565ca0ce0f68758d538cae7cbf3d58090f59e9c289a42a06c24fe5f29ce0feb498af92d325e9efe9c8623369a8014551"} 05:41:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:41:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:41:51 INFO - Contents: 05:41:51 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2e0ee3f2cc1deb5a64d3ccd68b6f8509751c605b02074ae4cbc610ab691644b549da6c7f399b93e71e6287e06733eeba5c652848a8d9b3c6dfac1d481bf397a7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8e1e5cc0d2cb0aeba0f13cbb89ed5b21d8128309eabfd04f8796f386fbdfb46436f66d19a0003cbaeea491a47da3bff1462fdda2e22a3a439f8a63d4cec3747a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/645a5f79a14008413caf809191b4fb9b565ca0ce0f68758d538cae7cbf3d58090f59e9c289a42a06c24fe5f29ce0feb498af92d325e9efe9c8623369a8014551"} 05:41:51 INFO - Running post-run listener: copy_logs_to_upload_dir 05:41:51 INFO - Copying logs to upload dir... 05:41:51 INFO - mkdir: /builds/slave/test/build/upload/logs 05:41:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.607465 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-08-19 05:41:51.604954) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-19 05:41:51.608069) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1471608777.456172-963003291 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2e0ee3f2cc1deb5a64d3ccd68b6f8509751c605b02074ae4cbc610ab691644b549da6c7f399b93e71e6287e06733eeba5c652848a8d9b3c6dfac1d481bf397a7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8e1e5cc0d2cb0aeba0f13cbb89ed5b21d8128309eabfd04f8796f386fbdfb46436f66d19a0003cbaeea491a47da3bff1462fdda2e22a3a439f8a63d4cec3747a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/645a5f79a14008413caf809191b4fb9b565ca0ce0f68758d538cae7cbf3d58090f59e9c289a42a06c24fe5f29ce0feb498af92d325e9efe9c8623369a8014551"} build_url:https://queue.taskcluster.net/v1/task/XnbO4E_qTAy46EVv17OKXg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011349 build_url: 'https://queue.taskcluster.net/v1/task/XnbO4E_qTAy46EVv17OKXg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2e0ee3f2cc1deb5a64d3ccd68b6f8509751c605b02074ae4cbc610ab691644b549da6c7f399b93e71e6287e06733eeba5c652848a8d9b3c6dfac1d481bf397a7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/8e1e5cc0d2cb0aeba0f13cbb89ed5b21d8128309eabfd04f8796f386fbdfb46436f66d19a0003cbaeea491a47da3bff1462fdda2e22a3a439f8a63d4cec3747a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/645a5f79a14008413caf809191b4fb9b565ca0ce0f68758d538cae7cbf3d58090f59e9c289a42a06c24fe5f29ce0feb498af92d325e9efe9c8623369a8014551"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-19 05:41:51.635049) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 05:41:51.635529) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1471608777.456172-963003291 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004472 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-19 05:41:51.720153) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-19 05:41:51.720469) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-19 05:41:51.720799) ========= ========= Total master_lag: 0.19 =========