builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-060 starttime: 1472614059.28 results: success (0) buildid: 20160830173002 builduid: 1b33880b493147b8a27cfcc4af26309d revision: 0f4d2ef453c82d2499564402c0c3666ac834b5d8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-30 20:27:39.279459) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-30 20:27:39.279939) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-30 20:27:39.280319) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-30 20:27:39.307183) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 20:27:39.307490) ========= 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=337fe3f4b1febe37b85fccc40000019e-1472613041.570383-482468053 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-30 20:27:39-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 45.9M=0s 2016-08-30 20:27:39 (45.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.131584 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 20:27:39.453548) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 20:27:39.453874) ========= 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=337fe3f4b1febe37b85fccc40000019e-1472613041.570383-482468053 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014654 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 20:27:39.503907) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 20:27:39.504336) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 0f4d2ef453c82d2499564402c0c3666ac834b5d8 --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 0f4d2ef453c82d2499564402c0c3666ac834b5d8 --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=337fe3f4b1febe37b85fccc40000019e-1472613041.570383-482468053 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-30 20:27:39,903 truncating revision to first 12 chars 2016-08-30 20:27:39,903 Setting DEBUG logging. 2016-08-30 20:27:39,903 attempt 1/10 2016-08-30 20:27:39,903 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/0f4d2ef453c8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-30 20:27:40,178 unpacking tar archive at: fx-team-0f4d2ef453c8/testing/mozharness/ program finished with exit code 0 elapsedTime=0.611909 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 20:27:40.129776) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-30 20:27:40.130078) ========= script_repo_revision: 0f4d2ef453c82d2499564402c0c3666ac834b5d8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-30 20:27:40.130499) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-30 20:27:40.130777) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-30 20:27:40.140238) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-08-30 20:27:40.140539) ========= /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=337fe3f4b1febe37b85fccc40000019e-1472613041.570383-482468053 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 20:27:40 INFO - MultiFileLogger online at 20160830 20:27:40 in /builds/slave/test 20:27:40 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 20:27:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:27:40 INFO - {'append_to_log': False, 20:27:40 INFO - 'base_work_dir': '/builds/slave/test', 20:27:40 INFO - 'blob_upload_branch': 'Fx-Team', 20:27:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:27:40 INFO - 'branch': 'Fx-Team', 20:27:40 INFO - 'buildbot_json_path': 'buildprops.json', 20:27:40 INFO - 'config_files': ('talos/linux_config.py',), 20:27:40 INFO - 'default_actions': ('clobber', 20:27:40 INFO - 'read-buildbot-config', 20:27:40 INFO - 'download-and-extract', 20:27:40 INFO - 'populate-webroot', 20:27:40 INFO - 'create-virtualenv', 20:27:40 INFO - 'install', 20:27:40 INFO - 'run-tests'), 20:27:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:27:40 INFO - 'download_minidump_stackwalk': True, 20:27:40 INFO - 'download_symbols': 'ondemand', 20:27:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:27:40 INFO - 'tooltool.py': '/tools/tooltool.py', 20:27:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:27:40 INFO - '/tools/misc-python/virtualenv.py')}, 20:27:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:27:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:27:40 INFO - 'installer_path': 'installer.exe', 20:27:40 INFO - 'log_level': 'info', 20:27:40 INFO - 'log_name': 'talos', 20:27:40 INFO - 'log_to_console': True, 20:27:40 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 20:27:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 20:27:40 INFO - 'opt_config_files': (), 20:27:40 INFO - 'pip_index': False, 20:27:40 INFO - 'sps_profile': False, 20:27:40 INFO - 'sps_profile_interval': 0, 20:27:40 INFO - 'suite': 'dromaeojs', 20:27:40 INFO - 'system_bits': '32', 20:27:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:27:40 INFO - 'title': 'talos-linux64-ix-060', 20:27:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:27:40 INFO - 'use_talos_json': True, 20:27:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:27:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:27:40 INFO - 'work_dir': 'build'} 20:27:40 INFO - [mozharness: 2016-08-31 03:27:40.638338Z] Running clobber step. 20:27:40 INFO - Running pre-action listener: _resource_record_pre_action 20:27:40 INFO - Running main action method: clobber 20:27:40 INFO - rmtree: /builds/slave/test/build 20:27:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:27:41 INFO - Running post-action listener: _resource_record_post_action 20:27:41 INFO - [mozharness: 2016-08-31 03:27:41.095663Z] Finished clobber step (success) 20:27:41 INFO - [mozharness: 2016-08-31 03:27:41.095767Z] Running read-buildbot-config step. 20:27:41 INFO - Running pre-action listener: _resource_record_pre_action 20:27:41 INFO - Running main action method: read_buildbot_config 20:27:41 INFO - Using buildbot properties: 20:27:41 INFO - { 20:27:41 INFO - "project": "", 20:27:41 INFO - "product": "firefox", 20:27:41 INFO - "script_repo_revision": "production", 20:27:41 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 20:27:41 INFO - "repository": "", 20:27:41 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 20:27:41 INFO - "buildid": "20160830173002", 20:27:41 INFO - "pgo_build": "True", 20:27:41 INFO - "basedir": "/builds/slave/test", 20:27:41 INFO - "buildnumber": 1021, 20:27:41 INFO - "slavename": "talos-linux64-ix-060", 20:27:41 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 20:27:41 INFO - "platform": "ubuntu64_hw", 20:27:41 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 20:27:41 INFO - "branch": "fx-team", 20:27:41 INFO - "repo_path": "integration/fx-team", 20:27:41 INFO - "revision": "0f4d2ef453c82d2499564402c0c3666ac834b5d8", 20:27:41 INFO - "stage_platform": "linux64-pgo", 20:27:41 INFO - "builduid": "1b33880b493147b8a27cfcc4af26309d", 20:27:41 INFO - "slavebuilddir": "test" 20:27:41 INFO - } 20:27:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/VXTKi3uCQpeMo1ggJWB8eg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 20:27:41 INFO - Running post-action listener: _resource_record_post_action 20:27:41 INFO - [mozharness: 2016-08-31 03:27:41.098244Z] Finished read-buildbot-config step (success) 20:27:41 INFO - [mozharness: 2016-08-31 03:27:41.098335Z] Running download-and-extract step. 20:27:41 INFO - Running pre-action listener: _resource_record_pre_action 20:27:41 INFO - Running main action method: download_and_extract 20:27:41 INFO - mkdir: /builds/slave/test/build/tests 20:27:41 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')]} 20:27:41 INFO - trying https://queue.taskcluster.net/v1/task/VXTKi3uCQpeMo1ggJWB8eg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 20:27:41 INFO - Downloading https://queue.taskcluster.net/v1/task/VXTKi3uCQpeMo1ggJWB8eg/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 20:27:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VXTKi3uCQpeMo1ggJWB8eg/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 20:27:41 INFO - Downloaded 1361 bytes. 20:27:41 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 20:27:41 INFO - Using the following test package requirements: 20:27:41 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 20:27:41 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:27:41 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 20:27:41 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:27:41 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 20:27:41 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:27:41 INFO - u'jsshell-linux-x86_64.zip'], 20:27:41 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:27:41 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 20:27:41 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 20:27:41 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:27:41 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 20:27:41 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:27:41 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 20:27:41 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:27:41 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 20:27:41 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 20:27:41 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 20:27:41 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 20:27:41 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')]} 20:27:41 INFO - trying https://queue.taskcluster.net/v1/task/VXTKi3uCQpeMo1ggJWB8eg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 20:27:41 INFO - Downloading https://queue.taskcluster.net/v1/task/VXTKi3uCQpeMo1ggJWB8eg/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 20:27:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VXTKi3uCQpeMo1ggJWB8eg/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 20:27:42 INFO - Downloaded 21886098 bytes. 20:27:42 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 20:27:46 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 20:27:46 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')]} 20:27:46 INFO - trying https://queue.taskcluster.net/v1/task/VXTKi3uCQpeMo1ggJWB8eg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 20:27:46 INFO - Downloading https://queue.taskcluster.net/v1/task/VXTKi3uCQpeMo1ggJWB8eg/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 20:27:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VXTKi3uCQpeMo1ggJWB8eg/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 20:27:47 INFO - Downloaded 21886098 bytes. 20:27:47 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 20:27:52 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')]} 20:27:52 INFO - trying https://queue.taskcluster.net/v1/task/VXTKi3uCQpeMo1ggJWB8eg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 20:27:52 INFO - Downloading https://queue.taskcluster.net/v1/task/VXTKi3uCQpeMo1ggJWB8eg/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 20:27:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VXTKi3uCQpeMo1ggJWB8eg/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 20:27:52 INFO - Downloaded 14413998 bytes. 20:27:52 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 20:27:53 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')]} 20:27:53 INFO - trying https://queue.taskcluster.net/v1/task/VXTKi3uCQpeMo1ggJWB8eg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 20:27:53 INFO - Downloading https://queue.taskcluster.net/v1/task/VXTKi3uCQpeMo1ggJWB8eg/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 20:27:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VXTKi3uCQpeMo1ggJWB8eg/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 20:27:54 INFO - Downloaded 59721407 bytes. 20:27:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VXTKi3uCQpeMo1ggJWB8eg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 20:27:54 INFO - mkdir: /builds/slave/test/properties 20:27:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:27:54 INFO - Writing to file /builds/slave/test/properties/build_url 20:27:54 INFO - Contents: 20:27:54 INFO - build_url:https://queue.taskcluster.net/v1/task/VXTKi3uCQpeMo1ggJWB8eg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 20:27:55 INFO - Running post-action listener: _resource_record_post_action 20:27:55 INFO - Running post-action listener: set_extra_try_arguments 20:27:55 INFO - [mozharness: 2016-08-31 03:27:55.622320Z] Finished download-and-extract step (success) 20:27:55 INFO - [mozharness: 2016-08-31 03:27:55.622548Z] Running populate-webroot step. 20:27:55 INFO - Running pre-action listener: _resource_record_pre_action 20:27:55 INFO - Running main action method: populate_webroot 20:27:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:27:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:27:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:27:55 INFO - '--tppagecycles', 20:27:55 INFO - '7'], 20:27:55 INFO - 'tests': ['tsvgm']}}, 20:27:55 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:27:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:27:55 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:27:55 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:27:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:27:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:27:55 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:27:55 INFO - 'talos_options': ['--disable-e10s'], 20:27:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:27:55 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:27:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:27:55 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:27:55 INFO - 'talos_options': ['--disable-e10s'], 20:27:55 INFO - 'tests': ['damp', 'tps']}, 20:27:55 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:27:55 INFO - 'tests': ['damp', 'tps']}, 20:27:55 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:27:55 INFO - 'tests': ['dromaeo_dom']}, 20:27:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:27:55 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:27:55 INFO - 'tests': ['basic_compositor_video']}, 20:27:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:27:55 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:27:55 INFO - 'tests': ['a11yr', 20:27:55 INFO - 'ts_paint', 20:27:55 INFO - 'tpaint', 20:27:55 INFO - 'sessionrestore', 20:27:55 INFO - 'sessionrestore_no_auto_restore', 20:27:55 INFO - 'tabpaint']}, 20:27:55 INFO - 'other-e10s': {'tests': ['a11yr', 20:27:55 INFO - 'ts_paint', 20:27:55 INFO - 'tpaint', 20:27:55 INFO - 'sessionrestore', 20:27:55 INFO - 'sessionrestore_no_auto_restore', 20:27:55 INFO - 'tabpaint']}, 20:27:55 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:27:55 INFO - 'ts_paint', 20:27:55 INFO - 'tpaint', 20:27:55 INFO - 'sessionrestore', 20:27:55 INFO - 'sessionrestore_no_auto_restore', 20:27:55 INFO - 'tabpaint']}, 20:27:55 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:27:55 INFO - 'ts_paint', 20:27:55 INFO - 'tpaint', 20:27:55 INFO - 'sessionrestore', 20:27:55 INFO - 'sessionrestore_no_auto_restore', 20:27:55 INFO - 'tabpaint']}, 20:27:55 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:27:55 INFO - 'tests': ['a11yr', 20:27:55 INFO - 'ts_paint', 20:27:55 INFO - 'tpaint', 20:27:55 INFO - 'sessionrestore', 20:27:55 INFO - 'sessionrestore_no_auto_restore', 20:27:55 INFO - 'tabpaint']}, 20:27:55 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:27:55 INFO - 'tests': ['a11yr', 20:27:55 INFO - 'ts_paint', 20:27:55 INFO - 'tpaint', 20:27:55 INFO - 'sessionrestore', 20:27:55 INFO - 'sessionrestore_no_auto_restore', 20:27:55 INFO - 'tabpaint']}, 20:27:55 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:27:55 INFO - 'tests': ['tsvgx', 20:27:55 INFO - 'tsvgr_opacity', 20:27:55 INFO - 'tart', 20:27:55 INFO - 'tscrollx', 20:27:55 INFO - 'cart']}, 20:27:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:27:55 INFO - 'tsvgr_opacity', 20:27:55 INFO - 'tart', 20:27:55 INFO - 'tscrollx', 20:27:55 INFO - 'cart']}, 20:27:55 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:27:55 INFO - 'talos_options': ['--disable-e10s'], 20:27:55 INFO - 'tests': ['tp5o']}, 20:27:55 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:27:55 INFO - 'tests': ['tp5o']}, 20:27:55 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:27:55 INFO - 'talos_options': ['--disable-e10s', 20:27:55 INFO - '--xperf_path', 20:27:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:27:55 INFO - 'tests': ['tp5n']}, 20:27:55 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:27:55 INFO - 'talos_options': ['--xperf_path', 20:27:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:27:55 INFO - 'tests': ['tp5n']}}, 20:27:55 INFO - 'talos.zip': {'path': '', 20:27:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:27:55 INFO - Running post-action listener: _resource_record_post_action 20:27:55 INFO - [mozharness: 2016-08-31 03:27:55.637826Z] Finished populate-webroot step (success) 20:27:55 INFO - [mozharness: 2016-08-31 03:27:55.637916Z] Running create-virtualenv step. 20:27:55 INFO - Running pre-action listener: _resource_record_pre_action 20:27:55 INFO - Running main action method: create_virtualenv 20:27:55 INFO - Creating virtualenv /builds/slave/test/build/venv 20:27:55 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 20:27:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:27:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:27:55 INFO - Using real prefix '/usr' 20:27:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:27:56 INFO - Installing distribute.............................................................................................................................................................................................done. 20:27:58 INFO - Installing pip.................done. 20:27:58 INFO - Return code: 0 20:27:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:27:58 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')]} 20:27:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:27:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:27:58 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')]} 20:27:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:27:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:27:58 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 0x7f0689a38f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c388a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d05850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d00370>, '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 0x1d038f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d03d80>, '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': '337fe3f4b1febe37b85fccc40000019e-1472613041.570383-482468053', '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 20:27:58 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 20:27:58 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 20:27:58 INFO - Using env: {'DISPLAY': ':0', 20:27:58 INFO - 'HOME': '/home/cltbld', 20:27:58 INFO - 'LANG': 'en_US.UTF-8', 20:27:58 INFO - 'LANGUAGE': 'en_US:en', 20:27:58 INFO - 'LOGNAME': 'cltbld', 20:27:58 INFO - 'MAIL': '/var/mail/cltbld', 20:27:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:27:58 INFO - 'MOZ_NO_REMOTE': '1', 20:27:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:27:58 INFO - 'NO_EM_RESTART': '1', 20:27:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:27:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:27:58 INFO - 'PWD': '/builds/slave/test', 20:27:58 INFO - 'SHELL': '/bin/bash', 20:27:58 INFO - 'SHLVL': '1', 20:27:58 INFO - 'TERM': 'linux', 20:27:58 INFO - 'TMOUT': '86400', 20:27:58 INFO - 'USER': 'cltbld', 20:27:58 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1472613041.570383-482468053', 20:27:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:27:58 INFO - '_': '/tools/buildbot/bin/python'} 20:27:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:27:58 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 20:27:58 INFO - Cleaning up... 20:27:58 INFO - Return code: 0 20:27:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:27:58 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')]} 20:27:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:27:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:27:58 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')]} 20:27:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:27:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:27:58 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 0x7f0689a38f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c388a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d05850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d00370>, '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 0x1d038f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d03d80>, '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': '337fe3f4b1febe37b85fccc40000019e-1472613041.570383-482468053', '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 20:27:58 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 20:27:58 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 20:27:58 INFO - Using env: {'DISPLAY': ':0', 20:27:58 INFO - 'HOME': '/home/cltbld', 20:27:58 INFO - 'LANG': 'en_US.UTF-8', 20:27:58 INFO - 'LANGUAGE': 'en_US:en', 20:27:58 INFO - 'LOGNAME': 'cltbld', 20:27:58 INFO - 'MAIL': '/var/mail/cltbld', 20:27:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:27:58 INFO - 'MOZ_NO_REMOTE': '1', 20:27:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:27:58 INFO - 'NO_EM_RESTART': '1', 20:27:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:27:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:27:58 INFO - 'PWD': '/builds/slave/test', 20:27:58 INFO - 'SHELL': '/bin/bash', 20:27:58 INFO - 'SHLVL': '1', 20:27:58 INFO - 'TERM': 'linux', 20:27:58 INFO - 'TMOUT': '86400', 20:27:58 INFO - 'USER': 'cltbld', 20:27:58 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1472613041.570383-482468053', 20:27:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:27:58 INFO - '_': '/tools/buildbot/bin/python'} 20:27:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:27:58 INFO - Downloading/unpacking psutil>=3.1.1 20:27:58 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 20:27:58 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 20:27:58 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 20:27:58 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 20:27:58 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 20:27:58 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 20:28:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:28:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:28:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:28:00 INFO - Installing collected packages: psutil 20:28:00 INFO - Running setup.py install for psutil 20:28:00 INFO - building 'psutil._psutil_linux' extension 20:28:00 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 20:28:00 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 20:28:00 INFO - building 'psutil._psutil_posix' extension 20:28:00 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 20:28:01 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 20:28:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:28:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:28:01 INFO - Successfully installed psutil 20:28:01 INFO - Cleaning up... 20:28:01 INFO - Return code: 0 20:28:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:28:01 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')]} 20:28:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:28:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:28:01 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')]} 20:28:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:28:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:28:01 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 0x7f0689a38f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c388a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d05850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d00370>, '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 0x1d038f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d03d80>, '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': '337fe3f4b1febe37b85fccc40000019e-1472613041.570383-482468053', '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 20:28:01 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 20:28:01 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 20:28:01 INFO - Using env: {'DISPLAY': ':0', 20:28:01 INFO - 'HOME': '/home/cltbld', 20:28:01 INFO - 'LANG': 'en_US.UTF-8', 20:28:01 INFO - 'LANGUAGE': 'en_US:en', 20:28:01 INFO - 'LOGNAME': 'cltbld', 20:28:01 INFO - 'MAIL': '/var/mail/cltbld', 20:28:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:28:01 INFO - 'MOZ_NO_REMOTE': '1', 20:28:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:28:01 INFO - 'NO_EM_RESTART': '1', 20:28:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:28:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:28:01 INFO - 'PWD': '/builds/slave/test', 20:28:01 INFO - 'SHELL': '/bin/bash', 20:28:01 INFO - 'SHLVL': '1', 20:28:01 INFO - 'TERM': 'linux', 20:28:01 INFO - 'TMOUT': '86400', 20:28:01 INFO - 'USER': 'cltbld', 20:28:01 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1472613041.570383-482468053', 20:28:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:28:01 INFO - '_': '/tools/buildbot/bin/python'} 20:28:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:28:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:28:01 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 20:28:01 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 20:28:01 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 20:28:01 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 20:28:01 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 20:28:01 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 20:28:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:28:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:28:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:28:03 INFO - Installing collected packages: mozsystemmonitor 20:28:03 INFO - Running setup.py install for mozsystemmonitor 20:28:03 INFO - Successfully installed mozsystemmonitor 20:28:03 INFO - Cleaning up... 20:28:03 INFO - Return code: 0 20:28:03 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:28:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:28:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:28:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:28:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:28:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:28:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:28:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f0689a38f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c388a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d05850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d00370>, '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 0x1d038f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d03d80>, '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': '337fe3f4b1febe37b85fccc40000019e-1472613041.570383-482468053', '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 20:28:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 20:28:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 20:28:03 INFO - Using env: {'DISPLAY': ':0', 20:28:03 INFO - 'HOME': '/home/cltbld', 20:28:03 INFO - 'LANG': 'en_US.UTF-8', 20:28:03 INFO - 'LANGUAGE': 'en_US:en', 20:28:03 INFO - 'LOGNAME': 'cltbld', 20:28:03 INFO - 'MAIL': '/var/mail/cltbld', 20:28:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:28:03 INFO - 'MOZ_NO_REMOTE': '1', 20:28:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:28:03 INFO - 'NO_EM_RESTART': '1', 20:28:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:28:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:28:03 INFO - 'PWD': '/builds/slave/test', 20:28:03 INFO - 'SHELL': '/bin/bash', 20:28:03 INFO - 'SHLVL': '1', 20:28:03 INFO - 'TERM': 'linux', 20:28:03 INFO - 'TMOUT': '86400', 20:28:03 INFO - 'USER': 'cltbld', 20:28:03 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1472613041.570383-482468053', 20:28:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:28:03 INFO - '_': '/tools/buildbot/bin/python'} 20:28:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:28:03 INFO - Downloading/unpacking jsonschema==2.5.1 20:28:03 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 20:28:03 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 20:28:03 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 20:28:03 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 20:28:03 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 20:28:03 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 20:28:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:28:05 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:28:05 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 20:28:05 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 20:28:05 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 20:28:05 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 20:28:05 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 20:28:05 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 20:28:06 INFO - Downloading functools32-3.2.3-2.tar.gz 20:28:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:28:06 INFO - warning: no files found matching '*.txt' 20:28:06 INFO - no previously-included directories found matching 'build' 20:28:06 INFO - no previously-included directories found matching 'dist' 20:28:06 INFO - no previously-included directories found matching '.git*' 20:28:06 INFO - Installing collected packages: jsonschema, functools32 20:28:06 INFO - Running setup.py install for functools32 20:28:06 INFO - warning: no files found matching '*.txt' 20:28:06 INFO - no previously-included directories found matching 'build' 20:28:06 INFO - no previously-included directories found matching 'dist' 20:28:06 INFO - no previously-included directories found matching '.git*' 20:28:06 INFO - Successfully installed jsonschema functools32 20:28:06 INFO - Cleaning up... 20:28:06 INFO - Return code: 0 20:28:06 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:28:06 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')]} 20:28:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:28:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:28:06 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')]} 20:28:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:28:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:28:06 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 0x7f0689a38f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c388a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d05850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d00370>, '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 0x1d038f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d03d80>, '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': '337fe3f4b1febe37b85fccc40000019e-1472613041.570383-482468053', '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 20:28:06 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 20:28:06 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 20:28:06 INFO - Using env: {'DISPLAY': ':0', 20:28:06 INFO - 'HOME': '/home/cltbld', 20:28:06 INFO - 'LANG': 'en_US.UTF-8', 20:28:06 INFO - 'LANGUAGE': 'en_US:en', 20:28:06 INFO - 'LOGNAME': 'cltbld', 20:28:06 INFO - 'MAIL': '/var/mail/cltbld', 20:28:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:28:06 INFO - 'MOZ_NO_REMOTE': '1', 20:28:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:28:06 INFO - 'NO_EM_RESTART': '1', 20:28:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:28:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:28:06 INFO - 'PWD': '/builds/slave/test', 20:28:06 INFO - 'SHELL': '/bin/bash', 20:28:06 INFO - 'SHLVL': '1', 20:28:06 INFO - 'TERM': 'linux', 20:28:06 INFO - 'TMOUT': '86400', 20:28:06 INFO - 'USER': 'cltbld', 20:28:06 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1472613041.570383-482468053', 20:28:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:28:06 INFO - '_': '/tools/buildbot/bin/python'} 20:28:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:28:06 INFO - Cleaning up... 20:28:06 INFO - Return code: 0 20:28:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:28:06 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')]} 20:28:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:28:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:28:06 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')]} 20:28:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:28:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:28:06 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 0x7f0689a38f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c388a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d05850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d00370>, '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 0x1d038f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d03d80>, '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': '337fe3f4b1febe37b85fccc40000019e-1472613041.570383-482468053', '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 20:28:06 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 20:28:06 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 20:28:06 INFO - Using env: {'DISPLAY': ':0', 20:28:06 INFO - 'HOME': '/home/cltbld', 20:28:06 INFO - 'LANG': 'en_US.UTF-8', 20:28:06 INFO - 'LANGUAGE': 'en_US:en', 20:28:06 INFO - 'LOGNAME': 'cltbld', 20:28:06 INFO - 'MAIL': '/var/mail/cltbld', 20:28:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:28:06 INFO - 'MOZ_NO_REMOTE': '1', 20:28:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:28:06 INFO - 'NO_EM_RESTART': '1', 20:28:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:28:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:28:06 INFO - 'PWD': '/builds/slave/test', 20:28:06 INFO - 'SHELL': '/bin/bash', 20:28:06 INFO - 'SHLVL': '1', 20:28:06 INFO - 'TERM': 'linux', 20:28:06 INFO - 'TMOUT': '86400', 20:28:06 INFO - 'USER': 'cltbld', 20:28:06 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1472613041.570383-482468053', 20:28:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:28:06 INFO - '_': '/tools/buildbot/bin/python'} 20:28:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:28:06 INFO - Downloading/unpacking blobuploader==1.2.4 20:28:06 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 20:28:06 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 20:28:06 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 20:28:06 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 20:28:06 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 20:28:06 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 20:28:08 INFO - Downloading blobuploader-1.2.4.tar.gz 20:28:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:28:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:28:08 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 20:28:08 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 20:28:08 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 20:28:08 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 20:28:08 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 20:28:08 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 20:28:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:28:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:28:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:28:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:28:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:28:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:28:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:28:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:28:09 INFO - Downloading docopt-0.6.1.tar.gz 20:28:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:28:09 INFO - Installing collected packages: blobuploader, requests, docopt 20:28:09 INFO - Running setup.py install for blobuploader 20:28:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:28:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:28:09 INFO - Running setup.py install for requests 20:28:09 INFO - Running setup.py install for docopt 20:28:09 INFO - Successfully installed blobuploader requests docopt 20:28:09 INFO - Cleaning up... 20:28:09 INFO - Return code: 0 20:28:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:28:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:28:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:28:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:28:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:28:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:28:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:28:09 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 0x7f0689a38f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c388a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d05850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d00370>, '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 0x1d038f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d03d80>, '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': '337fe3f4b1febe37b85fccc40000019e-1472613041.570383-482468053', '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 20:28:09 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 20:28:09 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 20:28:09 INFO - Using env: {'DISPLAY': ':0', 20:28:09 INFO - 'HOME': '/home/cltbld', 20:28:09 INFO - 'LANG': 'en_US.UTF-8', 20:28:09 INFO - 'LANGUAGE': 'en_US:en', 20:28:09 INFO - 'LOGNAME': 'cltbld', 20:28:09 INFO - 'MAIL': '/var/mail/cltbld', 20:28:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:28:09 INFO - 'MOZ_NO_REMOTE': '1', 20:28:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:28:09 INFO - 'NO_EM_RESTART': '1', 20:28:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:28:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:28:09 INFO - 'PWD': '/builds/slave/test', 20:28:09 INFO - 'SHELL': '/bin/bash', 20:28:09 INFO - 'SHLVL': '1', 20:28:09 INFO - 'TERM': 'linux', 20:28:09 INFO - 'TMOUT': '86400', 20:28:09 INFO - 'USER': 'cltbld', 20:28:09 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1472613041.570383-482468053', 20:28:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:28:09 INFO - '_': '/tools/buildbot/bin/python'} 20:28:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:28:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:28:10 INFO - Running setup.py (path:/tmp/pip-XM0nrG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:28:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:28:10 INFO - Running setup.py (path:/tmp/pip-Y5Ba14-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:28:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:28:10 INFO - Running setup.py (path:/tmp/pip-k2IgzE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:28:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:28:10 INFO - Running setup.py (path:/tmp/pip-VlTSVk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:28:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:28:10 INFO - Running setup.py (path:/tmp/pip-BXgcfF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:28:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:28:10 INFO - Running setup.py (path:/tmp/pip-V9i3KJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:28:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:28:10 INFO - Running setup.py (path:/tmp/pip-zvh3Wd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:28:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:28:10 INFO - Running setup.py (path:/tmp/pip-iF8PLR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:28:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:28:10 INFO - Running setup.py (path:/tmp/pip-ZGSpUt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:28:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:28:10 INFO - Running setup.py (path:/tmp/pip-t1ivz0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:28:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:28:10 INFO - Running setup.py (path:/tmp/pip-Z9V0Uz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:28:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:28:10 INFO - Running setup.py (path:/tmp/pip-Mc8VPU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:28:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:28:11 INFO - Running setup.py (path:/tmp/pip-7NXP72-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:28:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:28:11 INFO - Running setup.py (path:/tmp/pip-3eo9_E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:28:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:28:11 INFO - Running setup.py (path:/tmp/pip-dzjVKj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:28:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:28:11 INFO - Running setup.py (path:/tmp/pip-FXYJFY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:28:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:28:11 INFO - Running setup.py (path:/tmp/pip-3LdiW3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:28:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:28:11 INFO - Running setup.py install for manifestparser 20:28:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:28:11 INFO - Running setup.py install for mozcrash 20:28:11 INFO - Running setup.py install for mozdebug 20:28:11 INFO - Running setup.py install for mozdevice 20:28:11 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:28:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:28:11 INFO - Running setup.py install for mozfile 20:28:11 INFO - Running setup.py install for mozhttpd 20:28:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:28:12 INFO - Running setup.py install for mozinfo 20:28:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:28:12 INFO - Running setup.py install for mozInstall 20:28:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:28:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:28:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:28:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:28:12 INFO - Running setup.py install for mozleak 20:28:12 INFO - Running setup.py install for mozlog 20:28:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:28:12 INFO - Running setup.py install for moznetwork 20:28:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:28:12 INFO - Running setup.py install for mozprocess 20:28:12 INFO - Running setup.py install for mozprofile 20:28:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:28:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:28:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:28:12 INFO - Running setup.py install for mozrunner 20:28:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:28:12 INFO - Running setup.py install for mozscreenshot 20:28:12 INFO - Running setup.py install for moztest 20:28:13 INFO - Running setup.py install for mozversion 20:28:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:28:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:28:13 INFO - Cleaning up... 20:28:13 INFO - Return code: 0 20:28:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:28: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')]} 20:28:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:28:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:28: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')]} 20:28:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:28:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:28:13 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 0x7f0689a38f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c388a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d05850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d00370>, '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 0x1d038f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d03d80>, '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': '337fe3f4b1febe37b85fccc40000019e-1472613041.570383-482468053', '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 20:28:13 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 20:28:13 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 20:28:13 INFO - Using env: {'DISPLAY': ':0', 20:28:13 INFO - 'HOME': '/home/cltbld', 20:28:13 INFO - 'LANG': 'en_US.UTF-8', 20:28:13 INFO - 'LANGUAGE': 'en_US:en', 20:28:13 INFO - 'LOGNAME': 'cltbld', 20:28:13 INFO - 'MAIL': '/var/mail/cltbld', 20:28:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:28:13 INFO - 'MOZ_NO_REMOTE': '1', 20:28:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:28:13 INFO - 'NO_EM_RESTART': '1', 20:28:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:28:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:28:13 INFO - 'PWD': '/builds/slave/test', 20:28:13 INFO - 'SHELL': '/bin/bash', 20:28:13 INFO - 'SHLVL': '1', 20:28:13 INFO - 'TERM': 'linux', 20:28:13 INFO - 'TMOUT': '86400', 20:28:13 INFO - 'USER': 'cltbld', 20:28:13 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1472613041.570383-482468053', 20:28:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:28:13 INFO - '_': '/tools/buildbot/bin/python'} 20:28:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:28:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:28:13 INFO - Running setup.py (path:/tmp/pip-iMwYsO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:28:13 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)) 20:28:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:28:13 INFO - Running setup.py (path:/tmp/pip-DUdUS1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:28:13 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)) 20:28:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:28:13 INFO - Running setup.py (path:/tmp/pip-qfsphq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:28:13 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)) 20:28:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:28:13 INFO - Running setup.py (path:/tmp/pip-aC5swR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:28:13 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)) 20:28:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:28:13 INFO - Running setup.py (path:/tmp/pip-hoalVX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:28:13 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)) 20:28:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:28:13 INFO - Running setup.py (path:/tmp/pip-0nx4UB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:28:13 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)) 20:28:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:28:13 INFO - Running setup.py (path:/tmp/pip-_twgOj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:28:13 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)) 20:28:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:28:13 INFO - Running setup.py (path:/tmp/pip-YFgmwS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:28:14 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)) 20:28:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:28:14 INFO - Running setup.py (path:/tmp/pip-pcre8e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:28:14 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)) 20:28:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:28:14 INFO - Running setup.py (path:/tmp/pip-eZSEog-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:28:14 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)) 20:28:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:28:14 INFO - Running setup.py (path:/tmp/pip-vvqNMo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:28:14 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)) 20:28:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:28:14 INFO - Running setup.py (path:/tmp/pip-N36y1d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:28:14 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)) 20:28:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:28:14 INFO - Running setup.py (path:/tmp/pip-obIPXU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:28:14 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)) 20:28:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:28:14 INFO - Running setup.py (path:/tmp/pip-HEAbTi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:28:14 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)) 20:28:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:28:14 INFO - Running setup.py (path:/tmp/pip-VwSfk0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:28:14 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)) 20:28:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:28:14 INFO - Running setup.py (path:/tmp/pip-kdT14d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:28:14 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)) 20:28:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:28:14 INFO - Running setup.py (path:/tmp/pip-1RuGHn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:28:14 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)) 20:28:14 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)) 20:28:14 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)) 20:28:14 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)) 20:28:14 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)) 20:28:14 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)) 20:28:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:28:14 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 20:28:14 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 20:28:14 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 20:28:14 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 20:28:14 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 20:28:14 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 20:28:16 INFO - Downloading blessings-1.6.tar.gz 20:28:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:28:16 INFO - Installing collected packages: blessings 20:28:16 INFO - Running setup.py install for blessings 20:28:16 INFO - Successfully installed blessings 20:28:16 INFO - Cleaning up... 20:28:16 INFO - Return code: 0 20:28:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:28:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:28:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:28:17 INFO - Reading from file tmpfile_stdout 20:28:17 INFO - Current package versions: 20:28:17 INFO - argparse == 1.2.1 20:28:17 INFO - blessings == 1.6 20:28:17 INFO - blobuploader == 1.2.4 20:28:17 INFO - docopt == 0.6.1 20:28:17 INFO - functools32 == 3.2.3-2 20:28:17 INFO - jsonschema == 2.5.1 20:28:17 INFO - manifestparser == 1.1 20:28:17 INFO - mozInstall == 1.12 20:28:17 INFO - mozcrash == 0.17 20:28:17 INFO - mozdebug == 0.1 20:28:17 INFO - mozdevice == 0.48 20:28:17 INFO - mozfile == 1.2 20:28:17 INFO - mozhttpd == 0.7 20:28:17 INFO - mozinfo == 0.9 20:28:17 INFO - mozleak == 0.1 20:28:17 INFO - mozlog == 3.3 20:28:17 INFO - moznetwork == 0.27 20:28:17 INFO - mozprocess == 0.23 20:28:17 INFO - mozprofile == 0.28 20:28:17 INFO - mozrunner == 6.12 20:28:17 INFO - mozscreenshot == 0.1 20:28:17 INFO - mozsystemmonitor == 0.3 20:28:17 INFO - moztest == 0.7 20:28:17 INFO - mozversion == 1.4 20:28:17 INFO - psutil == 3.1.1 20:28:17 INFO - requests == 1.2.3 20:28:17 INFO - wsgiref == 0.1.2 20:28:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:28:17 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')]} 20:28:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:28:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:28:17 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')]} 20:28:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:28:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:28:17 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 0x7f0689a38f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c388a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d05850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d00370>, '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 0x1d038f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d03d80>, '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': '337fe3f4b1febe37b85fccc40000019e-1472613041.570383-482468053', '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 20:28:17 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 20:28:17 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 20:28:17 INFO - Using env: {'DISPLAY': ':0', 20:28:17 INFO - 'HOME': '/home/cltbld', 20:28:17 INFO - 'LANG': 'en_US.UTF-8', 20:28:17 INFO - 'LANGUAGE': 'en_US:en', 20:28:17 INFO - 'LOGNAME': 'cltbld', 20:28:17 INFO - 'MAIL': '/var/mail/cltbld', 20:28:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:28:17 INFO - 'MOZ_NO_REMOTE': '1', 20:28:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:28:17 INFO - 'NO_EM_RESTART': '1', 20:28:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:28:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:28:17 INFO - 'PWD': '/builds/slave/test', 20:28:17 INFO - 'SHELL': '/bin/bash', 20:28:17 INFO - 'SHLVL': '1', 20:28:17 INFO - 'TERM': 'linux', 20:28:17 INFO - 'TMOUT': '86400', 20:28:17 INFO - 'USER': 'cltbld', 20:28:17 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1472613041.570383-482468053', 20:28:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:28:17 INFO - '_': '/tools/buildbot/bin/python'} 20:28:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:28:17 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)) 20:28:17 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)) 20:28:17 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)) 20:28:17 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)) 20:28:17 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)) 20:28:17 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)) 20:28:17 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)) 20:28:17 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)) 20:28:17 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)) 20:28:17 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)) 20:28:17 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)) 20:28:17 INFO - Cleaning up... 20:28:17 INFO - Return code: 0 20:28:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:28:17 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')]} 20:28:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:28:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:28:17 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')]} 20:28:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:28:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:28:17 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 0x7f0689a38f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c388a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d05850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d00370>, '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 0x1d038f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d03d80>, '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': '337fe3f4b1febe37b85fccc40000019e-1472613041.570383-482468053', '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 20:28:17 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 20:28:17 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 20:28:17 INFO - Using env: {'DISPLAY': ':0', 20:28:17 INFO - 'HOME': '/home/cltbld', 20:28:17 INFO - 'LANG': 'en_US.UTF-8', 20:28:17 INFO - 'LANGUAGE': 'en_US:en', 20:28:17 INFO - 'LOGNAME': 'cltbld', 20:28:17 INFO - 'MAIL': '/var/mail/cltbld', 20:28:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:28:17 INFO - 'MOZ_NO_REMOTE': '1', 20:28:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:28:17 INFO - 'NO_EM_RESTART': '1', 20:28:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:28:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:28:17 INFO - 'PWD': '/builds/slave/test', 20:28:17 INFO - 'SHELL': '/bin/bash', 20:28:17 INFO - 'SHLVL': '1', 20:28:17 INFO - 'TERM': 'linux', 20:28:17 INFO - 'TMOUT': '86400', 20:28:17 INFO - 'USER': 'cltbld', 20:28:17 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1472613041.570383-482468053', 20:28:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:28:17 INFO - '_': '/tools/buildbot/bin/python'} 20:28:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:28:17 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:28:17 INFO - Cleaning up... 20:28:17 INFO - Return code: 0 20:28:17 INFO - Running post-action listener: _resource_record_post_action 20:28:17 INFO - Running post-action listener: _start_resource_monitoring 20:28:17 INFO - Starting resource monitoring. 20:28:17 INFO - [mozharness: 2016-08-31 03:28:17.505616Z] Finished create-virtualenv step (success) 20:28:17 INFO - [mozharness: 2016-08-31 03:28:17.506131Z] Running install step. 20:28:17 INFO - Running pre-action listener: _resource_record_pre_action 20:28:17 INFO - Running main action method: install 20:28:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:28:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:28:17 INFO - Reading from file tmpfile_stdout 20:28:17 INFO - Detecting whether we're running mozinstall >=1.0... 20:28:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:28:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:28:17 INFO - Reading from file tmpfile_stdout 20:28:17 INFO - Output received: 20:28:17 INFO - Usage: mozinstall [options] installer 20:28:17 INFO - Options: 20:28:17 INFO - -h, --help show this help message and exit 20:28:17 INFO - -d DEST, --destination=DEST 20:28:17 INFO - Directory to install application into. [default: 20:28:17 INFO - "/builds/slave/test"] 20:28:17 INFO - --app=APP Application being installed. [default: firefox] 20:28:17 INFO - mkdir: /builds/slave/test/build/application 20:28:17 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'] 20:28:17 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 20:28:34 INFO - Reading from file tmpfile_stdout 20:28:34 INFO - Output received: 20:28:34 INFO - /builds/slave/test/build/application/firefox/firefox 20:28:34 INFO - Running post-action listener: _resource_record_post_action 20:28:34 INFO - [mozharness: 2016-08-31 03:28:34.827497Z] Finished install step (success) 20:28:34 INFO - [mozharness: 2016-08-31 03:28:34.827778Z] Running run-tests step. 20:28:34 INFO - Running pre-action listener: _resource_record_pre_action 20:28:34 INFO - Running main action method: run_tests 20:28:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:28:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:28:34 INFO - Python 2.7.3 20:28:34 INFO - Return code: 0 20:28:34 INFO - grabbing minidump binary from tooltool 20:28:34 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')]} 20:28:34 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 0x1d00370>, '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 0x1d038f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1d03d80>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:28:34 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 20:28:34 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 20:28:34 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 20:28:34 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:28:34 INFO - Return code: 0 20:28:34 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 20:28:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:28:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:28:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 20:28:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:28:34 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/VXTKi3uCQpeMo1ggJWB8eg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-060', '--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 20:28:34 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/VXTKi3uCQpeMo1ggJWB8eg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-060 --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 20:28:34 INFO - Using env: {'DISPLAY': ':0', 20:28:34 INFO - 'HOME': '/home/cltbld', 20:28:34 INFO - 'LANG': 'en_US.UTF-8', 20:28:34 INFO - 'LANGUAGE': 'en_US:en', 20:28:34 INFO - 'LOGNAME': 'cltbld', 20:28:34 INFO - 'MAIL': '/var/mail/cltbld', 20:28:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:28:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 20:28:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:28:34 INFO - 'MOZ_NO_REMOTE': '1', 20:28:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:28:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 20:28:34 INFO - 'NO_EM_RESTART': '1', 20:28:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 20:28:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:28:34 INFO - 'PWD': '/builds/slave/test', 20:28:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:28:34 INFO - 'SHELL': '/bin/bash', 20:28:34 INFO - 'SHLVL': '1', 20:28:34 INFO - 'TERM': 'linux', 20:28:34 INFO - 'TMOUT': '86400', 20:28:34 INFO - 'USER': 'cltbld', 20:28:34 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1472613041.570383-482468053', 20:28:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:28:34 INFO - '_': '/tools/buildbot/bin/python'} 20:28:34 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/VXTKi3uCQpeMo1ggJWB8eg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-060', '--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 20:28:35 INFO - mozversion application_buildid: 20160830173002 20:28:35 INFO - mozversion application_changeset: 0f4d2ef453c82d2499564402c0c3666ac834b5d8 20:28:35 INFO - mozversion application_display_name: Nightly 20:28:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:28:35 INFO - mozversion application_name: Firefox 20:28:35 INFO - mozversion application_remotingname: firefox 20:28:35 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 20:28:35 INFO - mozversion application_vendor: Mozilla 20:28:35 INFO - mozversion application_version: 51.0a1 20:28:35 INFO - mozversion platform_buildid: 20160830173002 20:28:35 INFO - mozversion platform_changeset: 0f4d2ef453c82d2499564402c0c3666ac834b5d8 20:28:35 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 20:28:35 INFO - mozversion platform_version: 51.0a1 20:28:35 INFO - using testdate: 1472614115 20:28:35 INFO - actual date: 1472614115 20:28:35 INFO - starting webserver on 'localhost:44484' 20:28:35 INFO - SUITE-START | Running 2 tests 20:28:35 INFO - TEST-START | dromaeo_css 20:28:35 INFO - operating with platform_type : linux_ 20:28:35 INFO - Initialising browser for dromaeo_css test... 20:28:35 INFO - TEST-INFO | started process 18383 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpch6rJv/profile http://localhost:44484/getInfo.html) 20:28:39 INFO - PROCESS | 18383 | __metrics Screen width/height:1600/1200 20:28:39 INFO - PROCESS | 18383 | colorDepth:24 20:28:39 INFO - PROCESS | 18383 | Browser inner width/height: 1024/697 20:28:39 INFO - PROCESS | 18383 | __metrics 20:28:40 INFO - PROCESS | 18383 | 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" 20:28:40 INFO - PROCESS | 18383 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:28:40 INFO - PROCESS | 18383 | console.error: PushService: 20:28:40 INFO - PROCESS | 18383 | stateChangeProcessEnqueue: Error transitioning state 20:28:40 INFO - PROCESS | 18383 | UnknownError 20:28:41 INFO - TEST-INFO | 18383: exit 0 20:28:41 INFO - Browser initialized. 20:28:41 INFO - Running cycle 1/1 for dromaeo_css test... 20:28:41 INFO - TEST-INFO | started process 18470 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpch6rJv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:28:53 INFO - PROCESS | 18470 | RSS: Main: 180506624 20:28:53 INFO - PROCESS | 18470 | 20:29:44 INFO - PROCESS | 18470 | 1472614184785 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] 20:31:54 INFO - PROCESS | 18470 | Cycle 1(1): loaded http://localhost:44484/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44484/tests/dromaeo/cssquery-ext.html) 20:31:55 INFO - PROCESS | 18470 | RSS: Main: 282677248 20:31:55 INFO - PROCESS | 18470 | 20:34:57 INFO - PROCESS | 18470 | Cycle 1(1): loaded http://localhost:44484/tests/dromaeo/cssquery-ext.html (next: http://localhost:44484/tests/dromaeo/cssquery-jquery.html) 20:34:58 INFO - PROCESS | 18470 | RSS: Main: 272240640 20:34:58 INFO - PROCESS | 18470 | 20:37:59 INFO - PROCESS | 18470 | Cycle 1(1): loaded http://localhost:44484/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44484/tests/dromaeo/cssquery-mootools.html) 20:38:00 INFO - PROCESS | 18470 | RSS: Main: 275267584 20:38:00 INFO - PROCESS | 18470 | 20:41:02 INFO - PROCESS | 18470 | Cycle 1(1): loaded http://localhost:44484/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44484/tests/dromaeo/cssquery-prototype.html) 20:41:03 INFO - PROCESS | 18470 | RSS: Main: 254332928 20:41:03 INFO - PROCESS | 18470 | 20:44:05 INFO - PROCESS | 18470 | Cycle 1(1): loaded http://localhost:44484/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44484/tests/dromaeo/cssquery-yui.html) 20:44:06 INFO - PROCESS | 18470 | RSS: Main: 216842240 20:44:06 INFO - PROCESS | 18470 | 20:47:09 INFO - PROCESS | 18470 | Cycle 1(1): loaded http://localhost:44484/tests/dromaeo/cssquery-yui.html (next: http://localhost:44484/tests/dromaeo/cssquery-dojo.html) 20:47:09 INFO - PROCESS | 18470 | RSS: Main: 307073024 20:47:09 INFO - PROCESS | 18470 | 20:47:09 INFO - PROCESS | 18470 | __start_tp_report 20:47:09 INFO - PROCESS | 18470 | _x_x_mozilla_page_load 20:47:09 INFO - PROCESS | 18470 | _x_x_mozilla_page_load_details 20:47:09 INFO - PROCESS | 18470 | |i|pagename|runs| 20:47:09 INFO - PROCESS | 18470 | |0;dojo.html;2999.4750918589243;3013.065949555638;3013.4123845850067;3034.7572194224463;3074.908407515332;5124.590032797377;5153.871153221171;5198.350206224222;5208.088584497712;5258.3427071616;5080.060188865063;5086.033653605812;5159.819406320779;5191.974040129803;5193.350831146106;4741.122892264925;4756.595689366408;4758.333833263346;4766.356541866839;4898;4843.878903027435;4879.341288926003;4894.951050489503;4920.7785649645675;4943.530364615356;4646.303054541812;4676.18166820805;4687.156311863863;4729.527047295277;4731.455882573514;4744.16977029022;4792.2332426811545;4817.831375901862;4867;4885.120678277908;4666.6500012498855;4679.298105284201;4697.060587882403;4729.196036673774;4841.080194762983;5268.078086334915;5288.206768984644;5290.0477913975465;5298.894022119574;5311.28297679816;5487.75305111271;5503.422140675211;5547.140193270009;5623.606347555673;5636.49271565557;5585.301837270341;5619.129034999582;5634.915476267818;5639.041362968305;5690.971545142248;5258.973705131488;5260.526552610283;5269.10425227716;5269.1832765921345;5275.788968441258;5652.491275785199;5669.773209071591;5703.17303990919;5707.258056452634;5744.195812586241;5489.780408783684;5492.176173573996;5493.176023596492;5494.532964698045;5552.222688823568;5565.387807341189;5573.386927437979;5574.860628484239;5581.302337207849;5586.413426590231;5374.677519348774;5548.889022219614;5554.638948468337;5601.383847776741;5608.158776183605;5593.07714227148;5594.1608758686525;5601.663900166003;5604.635698679573;5658;4310.870673879788;4355.869323920288;4358.912821743611;4359.367891655692;4407.27279998803;3794.962050379516;3813.427985802152;3829.4064420014943;3846.1922996170556;3924.0189952511873;5212.296339994053;5214.739263036833;5220.477952204749;5222.060029194706;5272.024675435056;5472.069748142825;5476.151196564539;5485.149801780731;5492.066348720727;5547.223388725582;5140.408852981955;5167.069927413102;5311.760970756325;5352.9732351338;5363.356397232279;4837.975810120927;4843.927341089887;4846.5638092571835;4855.223164293696;4919.877003074952;5849.181114643953;5852.853678658068;5853.209816674695;5858.53131749459;5867.119932010147;5431.6740995540395;5566.415526369835;5625.578081643975;5631.380548139619;5651.237082993744;5792.739326730391;5794;5799.391063938226;5806.912896306643;5858.648481091148;5576.9163462547285;5578.86052848682;5579.442055794388;5581.609287349846;5587.7485513152815;5438;5491.478309560585;5546.084895992116;5580.190872323409;5663.235463212413;5448.618596698193;5486.231927530229;5495.477929596682;5547.140193270049;5557.499825015768;5418.864528386822;5474.233607294902;5478.643888147258;5479.150731636602;5483.945160548503;5222.764975576184;5414.160805075219;5429.891402172014;5437.510624043855;5479.75341109659;5560.332760068818;5563;5563.527100196528;5592.245046918614;5608.906263278622;5435.239066530768;5441.319835020622;5444.455554444524;5451.863703407446;5455.263539422127 20:47:09 INFO - PROCESS | 18470 | |1;ext.html;562.8626134144113;573.0487390931054;601.8314871835887;608.7260732670296;609.8749756299958;32009.35981280376;35013;35141.12147196321;35141.297174056504;43207.35188972168;41332.38001429981;45041;45084.323735144;45140.774296128555;45153.774231128795;8835.690750823707;9004.414713043663;9009.414388064788;9009.729708108767;9014.729558113235;8720.389572729911;8761.080086590911;8765.649374025032;8776.341774366916;8785.868211976825;8821.911780882205;8878.689245876396;8884.466931984101;8885.111488851127;8891.95554022229;8787;8813.294936405073;8817.559122043873;8821.911780882174;8824.558772061371;8736.650533978633;8853.11468853116;8873.290136789106;8880.289576833839;8883.777905552348;42191;42588.57411425908;42707;42708.57291427077;42791.35812962808;3402.0644322811177;3467.4798780183173;3471.8542880849513;3666.981665091684;3879.9030024249337;32752;32793.50809737856;32837.83581082103;32854.83572582146;32939.50590741141;4251.319788833772;4366.956330436687;4407.933880991788;4490.820367185309;4535.206338890714;327987;328280;329245;329811.9999999976;330741;56929.71535142298;57125.14312285319;57154.14268785971;57224.42775572191;57240.713796430755;60614.090788638205;60646;60746.392536074956;60748;60829;16458.09480478561;16706;16709.749353759704;16715.247813848408;16723.49829505117;12381.566645167375;12487.188332758342;12501.874981250254;12551.811722824164;12561.811572826415;14397.64005899861;16509.257083431276;16621.257492513843;16639.6672066558;16929.746053809202;5696.20253164549;5810.1865738796605;5952.196453478725;6004.819855404345;6014.6391216527145;691.5307977204069;993.5742209928775;1041.598984391015;1088.4176965323672;1157.0916830288184;31180.220494487818;31504.527432088536;31673.683263167073;32062.19844503906;32210.19474513109;1080.670395529371;1107.291333546531;1146.5299227316927;1163.4822503985645;1168.5092261250295;16218.351265949466;17052.48842534726;17118.743218851727;17124.315027398763;17228.4011077246;518395;521651;522002;522395;524611;57068.429315706315;57378.13932791012;57465.71267143638;57504.71247643735;57541.71229143828;1213.8846809553079;1307.130758045908;1334.0661536924192;1352.2224720785587;1358.5109360630165;30689.38621227518;32040.83979580087;32363.51454728228;32652;33234.83382583072;48076.5192348072;48527.272090917955;48584.271235930784;48656.2701559484;48675.269870952674;295787;295871;297406;297449;299256;27406.45187096287;28551.571726423706;28639.560868065484;29114;29486;46579.301310481045;47699.284510731675;48011.279830803265;48125.51874481211;49206;39487.80256098701;39794;40472.3929141069;40550.59449405586;41566.79216603898;39894;39922.800385997885;40254.59108931173;40832.3875141879;41795;38768.80615596904;39975.80012099921;40971.1805763889;41095.38356924708;41393.37909931293;34940.82529587336;36503.26993460169;36971.44542831806;37203.813980929925;37970;27074.458510829278;27386.368744100597;27436.314092147855;27651.308717281423;27784.72215277902 20:47:09 INFO - PROCESS | 18470 | |2;jquery.html;374.3168717091309;379.6924491162157;385.498851493059;388.8600103962573;410.30862995851993;17289.827101728995;19621.705674414876;19677.50806229845;19851;20786.272480463187;19448.222071117172;19711.211551537923;20327.28854490093;20531.692024619642;20722.481937951557;5251.711155886424;5724.62789918655;5894.263217097863;5934.703264836741;5942.584019118642;5820.156077368779;5834.358220595731;5897.793577224798;5899.351071382144;5900.967330717107;5602.1596760486045;5776.133579962997;5798.971005144968;5804.303483581976;5820.534357251431;5599.188117722907;5771.711414429262;5787.50806181475;5812.680302583356;5824.0390335594575;5181.844544663666;5227.294315267428;5235.502627250406;5236.9738151309;5239.397469291004;24027.7597224029;24280.514389712283;24314;24724.13465528698;24860;2351.776581224781;2385.9045638174453;2404.5912194926905;2471.5551200783852;2548.987255063713;14087.985183119881;14641.121532708072;15029.323680434407;15057.247137643075;15505;1882.2282864025683;1921.28912302449;1924.3264857299832;1941.1361943934955;1964.204497178645;63498;83545.58227208885;86705.99999999937;88233.55883220606;89083;10718.195040760575;10754;10996.23026388145;11160.553577856796;11182.608608698656;15344;15345.156016419203;15389.461368852037;15389.846101538842;15392.076475411512;8119.634616442274;8239.134890836496;8308.750737477885;8348.248657620843;8555.82888342242;7909.6836126555445;7941.64262608184;8122.147174546706;8148.796280093045;8181.509109453333;6368.395002474757;6523.836904077436;6549.803505894831;6569.408753212234;6706.195256569243;1885.688861337864;1888.4523488188272;1895.9052047397574;1930.8841469511594;1971.7042443633284;874.9387542872065;921.447131720962;1002.3334482569155;1047.4814966591587;1097.7969075721016;20219.29232476856;20596.485087872923;20765;20765.169393224103;20768;983.041534503853;983.4019717958317;996.461910568285;1032.76762728387;1074.4842475611595;10461.26771126014;11661.18371713989;11683.18217724751;11711.765764684658;11771.529138834521;488919;496360;512095;514318;515842;44820.77589612031;74121;81745;82462;84481;1012.0790081026378;1049.6221360310283;1076.0853274716428;1082.1018554599664;1082.3332068614402;17388;17564.73652895182;18035.368762093265;18067.54831129232;18349.54126146857;39875.40186897141;40669.38995915123;40933.38599920944;41260.1747965045;42184.56490600294;54866.451335486134;55687.92525083921;56013.71993140008;56515.196208322755;58023;8557;8624.698135565224;8630.82738345242;8687.826243475221;9031.864522032307;13314.800277996032;13378.598642040753;13576.796348054984;13586.048976571545;13589.116707413987;10910.61812836547;11295.830562541732;11360.431978401211;11591.130665200313;11890.34603096838;5246.3704355477585;8886.622573501118;9943.651972180938;10526.10528105089;11157.609483668357;11438.942805286253;11653.359065251154;11683.415829208676;11705.707357316136;12393.504259829866;8457;8650.524221167896;8675.869861952202;8749.212570868653;8866.11338866134;10991.395473249047;11045.851932199284;11503.827442588536;11577.305361678325;11626.651200463999 20:47:09 INFO - PROCESS | 18470 | |3;mootools.html;1347.5283650722242;1458.7155504676587;1484.2578710644677;1589.8728101751854;1789.6152327249647;8436.91563084369;8779.824403511926;8871.1572400622;9075.81848363034;9095.863562046574;8805.95597022014;8872.467651940871;8911.15344042315;8935.821283574325;8936.8659470108;2369.395804069964;2371.7983971362455;2383.249276477904;2383.4875501767115;2390.486045500217;2376.4415362389823;2390.8326417150806;2449.889754961022;2452.619843924195;2455.067074511683;2432;2433.428144386077;2436.6710494083336;2460.4095017195923;2461.507698460306;2375.7980571651256;2395.3053614451787;2398.20859116492;2414.4326083370393;2419.9153029643967;2343.367290831466;2360.209329874494;2362.5038741864228;2364.2198074635503;2366.7988221001237;8811.162939520735;8828.911710882938;8897.510636914967;8899.822003559893;8959.776005599848;4943.604511639061;5111.028904508132;5129.281900533891;5130.204818253177;5138.075146473633;3313.1385839681857;3478.89563313101;3513.9985104245166;3537.8584856605703;3545.716342692604;1127.881572434899;1160.8781077986773;1164.9068074554016;1205.4876677412065;1207.7041124924363;12412.131150819447;12502.937485312515;12536;12544.184627999151;12553.874461255453;4467.5532446755715;4489.147062058199;4661.230896901973;4668.089722504079;4776.331313616097;9682.75793105178;9684.176844968115;9699.17557007648;9714.465704386326;9724.805503890024;5284.920726189111;5291.894162116736;5299.5230429261555;5301.363836339664;5305.36335639718;4390.319500477431;4754.453237877619;4896;4935.185694360391;4991.276264941635;3748.5314335708035;3757.5490941087246;3911.5892831252877;3913.3347330953807;3947.743396679207;1192.3382522699812;1194.9522019119138;1210.0863847794858;1211.0977321895068;1218.3725381428571;946.3564775952418;962.7833737409027;964.8118616869643;974.4906864823109;982.931194816356;5880.647161170258;5892.646441213541;5933.169356290123;6031.788887388921;6128.571000029955;472.24676640757934;481.7157876852674;482.4668741041169;482.90583336249097;489.7649128418308;4224.683148763855;4235.385869048971;4261.74429534229;4305.203537345601;4325;10765.83851242232;11167.162462815322;11198.608048718255;11219.382933938712;11237.94381028106;10595;11002.174836887265;11035.779284414266;11041.116710663287;11081.667549973514;8190.754277371689;8391.706290279812;8409.32725381968;8411.70559030431;8412.284955778827;6005.699715014319;6137.478314343331;6166.784162554289;6219.720112595034;6340;9880.061394167542;9895.55470003866;9989.950050249703;10250.846237306298;10429.947850261004;9883.30816842814;9938.354006989523;10206.693799186036;10574.471276435994;10656.946715266375;6955.200151982587;7506.887396688945;7622.923770762443;7708.537487750753;7879.448438609243;8326.167383261625;8369.246767790928;8373.539455330016;8396.664133434595;8417.579121044046;7366.889496657662;7491.175970643334;7503.062117235346;7518.097828260644;7703.152653208031;7043.190033146254;7091.468139889425;7278.67245973943;7295.8540829184185;7853.371730261451;6816.693248803716;6905;6914.170299564084;6923.8961415579815;6925.480588955949;5018.347614810052;5034.345535080416;5058.215976523645;5148.562372198405;5172;5000.974995125001;5082;5142.588592912483;5323.441038691037;5448.264484294569 20:47:09 INFO - PROCESS | 18470 | |4;prototype.html;324.35777161220796;335.64421712984233;338.88308533555914;341.7812599936041;343.5585272924293;5181.430042695305;5226.8693282667955;5261.316028916241;5269.761606022586;5270.209468579706;4871.173310008747;4989.825356112537;5063.202563090105;5112.642115051938;5174.016936782018;4108.897277568055;4113.71204015719;4116.938245926313;4120.25835349637;4140.171965606875;4092.242935057009;4099.487564054493;4114.526829414611;4114.855980040699;4116.855910043149;3894.824732887027;3927.7643341399466;4071.3282308419075;4089.7750623715683;4090.284200264957;4084.9591504084874;4086.5300490443383;4095.99999999997;4104.589541045902;4150.3359462486005;3658.2866341063504;3662.974367177195;3668.229671768905;3676.283124790667;3684.6499582539614;5023.648344615878;5099.643024988252;5133.307003554547;5158.509941555546;5178.171492561172;4680.602148817321;4688.179568575486;4689.358724446451;4708.364370809965;4730.2498075711865;5411.404745477994;5413.377461591928;5423.322084739408;5425.267588875491;5435.157550579627;3708;3908.3160446921675;4004.6796256299426;4016.614268077538;4027.516697996259;5623.325200975909;5625.71871406428;5626.071698169797;5628.577856660767;5633.521150702195;4007.539132999684;4015.457913181683;4047.6964227683043;4057.208844275338;4063.065494936181;3851.441540976599;4110.77390743512;4115.5678653741525;4119.526254480773;4121.216968775923;4605.309203619484;4613.400257966443;4626.699264547794;4659.627229821606;4693.6949098308505;5420.864478388072;5430.158325459481;5435.510804027656;5447.237386765856;5452.863678408072;1353.3447514577956;1384.4116250593459;1396.2250950722446;1397.5737400092867;1403.4736973634888;911.8677791720163;912.981740365189;916.4455504419864;922.1976880114344;932.5477143585356;4709.882252943704;4724.834630787906;4726.858194254178;4729.196036673774;4788.592969597623;4934.901301974012;4937.8271760488215;4984.90030199394;5017.222330538772;5040.899182016339;4555.749433781174;4568.908621827545;4676.438827340741;4712.835050773275;4831.541003604652;2629.644997925294;2632.328756167165;2635.7759590434644;2641.7754490868106;2662.467506498708;4971.28490670718;4976.676516026447;5044.351715349471;5101.387833459934;5146.716930568779;4643.72297618151;4956.083124621957;4968.22992436173;4986.700797952134;4998.700077995332;5014.247862820534;5039.647224694236;5047.343845300087;5053.0798296647035;5066.873328166826;4846.691393323712;4887.84733846674;4892.755362231803;4899.510048995072;4923;4856.757162141808;5092.185250359925;5542.3903370628395;5554.416786237387;5558.166275058774;5748;5763.077907534775;5776.480116789509;5786.218860453785;5794.739236734273;4010.218007488512;4032.2137183248983;4034.374671925856;4041.81811818463;4061.781465560261;5285.9471405286995;5442.891142177214;5468.4531546845;5596;5611.551075913999;5452.536534394462;5453.754581043783;5460.481254280837;5471.425500322568;5474.9468949144075;5438.673679579238;5439.374471935778;5441.075017247156;5457.563394928476;5472.26124473207;5192.792288308426;5358.678479291255;5376.59675524345;5379.865503362447;5383.192521121863;5452.700101494456;5472.370677371994;5488.533474654699;5496.725163741877;5531.391546929754;5248.553872920845;5369.3556773187465;5380.273663055437;5401.135818269216;5474.260974768515 20:47:09 INFO - PROCESS | 18470 | |5;yui.html;734.6069852628841;760.3612965109309;782.401462880896;794.4280118314812;860.9139086091395;15472.845271547281;16968.06675632841;17000.48998530046;17016.4044258451;17022.148892555368;14121.223332716696;14288.71422571548;14341.784873226883;14346.856531434683;14392.568222953301;807.4872455990444;820;822.1367564057715;822.6585966823761;823.0206054794805;663.8871391863395;801.390942883407;832.5961908474385;833.8874251975966;838.5304229631425;859.8409294280547;865.4807115730574;870.9695160669378;872.3457406944791;873.3449912565576;860.6729442811692;861.4874149880811;862.2024627219797;862.633380813155;862.7325529085972;507.2011581758708;615.1050221927086;628.9937100628981;632.5793347424003;633.6926590603554;15660.216989150496;15672.921635391864;15717.13555754433;15717.37130514778;15720.528384148494;14072.155670659691;14503.41986320546;14718.87192284078;15056.698866022727;15333.156676382794;12966.157199782077;13130.803037954438;13135.211887286698;13401.932990334986;13967.79048314276;4476.149531588988;4515.706479078849;4550.408446901914;4712.458067322267;4717.221658426355;77069;77089.61455192688;78007.6099619504;83265.16734832634;84147.57926210391;11590.536378544764;11615.593454229062;11648.126390520745;11711.297322160697;12093.218559731135;21717.67423488649;21737.565248694937;21754;21758.891205544187;21882.343529694135;8595;8799.164079412445;8818;9072.092790720875;9120.270378369714;4693.601043911306;4773.355596994431;4833.879153021203;4944.1100601891285;5018.272350509155;3425.1950791563963;3553.591336996227;3558.8576456941432;3568.839402226906;3570.125319296789;606.130203158469;608.5496732417979;614.6496496996758;616.3990109643062;629.3580547841176;628.2837565175612;657.1785268414482;679.7858674517511;684.9726010959506;694.7464175575878;9530.95234523823;9684.806303874024;9908.900910990798;9944.602215911427;10095.24285678577;592.602956019468;621.8631900981777;634.7156692326794;644.1175589442494;661.2825084782968;4535.727856328631;4823.855284341475;4877.829275975324;4898.461169271377;4908.631852611068;123909;125944.37027814802;129735.35132324467;130378.34810825886;133360.33319833534;64130.6793466039;89546.55226723825;110728;111267;111573;94386;95971;96110;96536.14081394824;97207.51396243116;8779.429337093068;8905.955470222607;8922.68770593026;9196.218321442751;9233.676821311223;53520;54255.72872135772;54672;55936;57195;47581.52418475771;72575;80190.59904700672;80948.7857682147;84285.57857210675;11119.72200694989;11447.141464390037;11473.885261147616;11720.472578733807;11809.291442513477;13092.607221783363;13366.799498007344;14490.637734056732;14896.702065958836;15111.23995941275;13342.733145337234;13932.442702291795;13934.19270351727;14261.857381426467;14444.350004249623;14228.146311220946;14334.49829255971;14440.350184241524;14767.79449366685;14786.408543658134;14092.22492762867;14148;14149.717005660035;14184.716305674036;15115;11500.769984600429;11524.064171122795;11773.823392648947;12097.011979070416;12455.813162802384;11525;12067.818982715442;12338;12364.381780911099;12759.48962041508 20:47:09 INFO - PROCESS | 18470 | __end_tp_report 20:47:09 INFO - PROCESS | 18470 | __start_cc_report 20:47:09 INFO - PROCESS | 18470 | _x_x_mozilla_cycle_collect,859 20:47:09 INFO - PROCESS | 18470 | __end_cc_report 20:47:09 INFO - PROCESS | 18470 | __startTimestamp1472615229554__endTimestamp 20:47:09 INFO - PROCESS | 18470 | 20:47:09 INFO - PROCESS | 18470 | ------- Summary: start ------- 20:47:09 INFO - PROCESS | 18470 | Number of tests: 6 20:47:09 INFO - PROCESS | 18470 | 20:47:09 INFO - PROCESS | 18470 | [#0] dojo.html Cycles:165 Average:5205.13 Median:5437.51 stddev:583.24 (10.7%) stddev-sans-first:558.78 20:47:09 INFO - PROCESS | 18470 | Values: 2999.5 3013.1 3013.4 3034.8 3074.9 5124.6 5153.9 5198.4 5208.1 5258.3 5080.1 5086.0 5159.8 5192.0 5193.4 4741.1 4756.6 4758.3 4766.4 4898.0 4843.9 4879.3 4895.0 4920.8 4943.5 4646.3 4676.2 4687.2 4729.5 4731.5 4744.2 4792.2 4817.8 4867.0 4885.1 4666.7 4679.3 4697.1 4729.2 4841.1 5268.1 5288.2 5290.0 5298.9 5311.3 5487.8 5503.4 5547.1 5623.6 5636.5 5585.3 5619.1 5634.9 5639.0 5691.0 5259.0 5260.5 5269.1 5269.2 5275.8 5652.5 5669.8 5703.2 5707.3 5744.2 5489.8 5492.2 5493.2 5494.5 5552.2 5565.4 5573.4 5574.9 5581.3 5586.4 5374.7 5548.9 5554.6 5601.4 5608.2 5593.1 5594.2 5601.7 5604.6 5658.0 4310.9 4355.9 4358.9 4359.4 4407.3 3795.0 3813.4 3829.4 3846.2 3924.0 5212.3 5214.7 5220.5 5222.1 5272.0 5472.1 5476.2 5485.1 5492.1 5547.2 5140.4 5167.1 5311.8 5353.0 5363.4 4838.0 4843.9 4846.6 4855.2 4919.9 5849.2 5852.9 5853.2 5858.5 5867.1 5431.7 5566.4 5625.6 5631.4 5651.2 5792.7 5794.0 5799.4 5806.9 5858.6 5576.9 5578.9 5579.4 5581.6 5587.7 5438.0 5491.5 5546.1 5580.2 5663.2 5448.6 5486.2 5495.5 5547.1 5557.5 5418.9 5474.2 5478.6 5479.2 5483.9 5222.8 5414.2 5429.9 5437.5 5479.8 5560.3 5563.0 5563.5 5592.2 5608.9 5435.2 5441.3 5444.5 5451.9 5455.3 20:47:09 INFO - PROCESS | 18470 | 20:47:09 INFO - PROCESS | 18470 | [#1] ext.html Cycles:180 Average:54954.07 Median:40691.49 stddev:104728.80 (257.4%) stddev-sans-first:104942.97 20:47:09 INFO - PROCESS | 18470 | Values: 562.9 573.0 601.8 608.7 609.9 32009.4 35013.0 35141.1 35141.3 43207.4 41332.4 45041.0 45084.3 45140.8 45153.8 8835.7 9004.4 9009.4 9009.7 9014.7 8720.4 8761.1 8765.6 8776.3 8785.9 8821.9 8878.7 8884.5 8885.1 8892.0 8787.0 8813.3 8817.6 8821.9 8824.6 8736.7 8853.1 8873.3 8880.3 8883.8 42191.0 42588.6 42707.0 42708.6 42791.4 3402.1 3467.5 3471.9 3667.0 3879.9 32752.0 32793.5 32837.8 32854.8 32939.5 4251.3 4367.0 4407.9 4490.8 4535.2 327987.0 328280.0 329245.0 329812.0 330741.0 56929.7 57125.1 57154.1 57224.4 57240.7 60614.1 60646.0 60746.4 60748.0 60829.0 16458.1 16706.0 16709.7 16715.2 16723.5 12381.6 12487.2 12501.9 12551.8 12561.8 14397.6 16509.3 16621.3 16639.7 16929.7 5696.2 5810.2 5952.2 6004.8 6014.6 691.5 993.6 1041.6 1088.4 1157.1 31180.2 31504.5 31673.7 32062.2 32210.2 1080.7 1107.3 1146.5 1163.5 1168.5 16218.4 17052.5 17118.7 17124.3 17228.4 518395.0 521651.0 522002.0 522395.0 524611.0 57068.4 57378.1 57465.7 57504.7 57541.7 1213.9 1307.1 1334.1 1352.2 1358.5 30689.4 32040.8 32363.5 32652.0 33234.8 48076.5 48527.3 48584.3 48656.3 48675.3 295787.0 295871.0 297406.0 297449.0 299256.0 27406.5 28551.6 28639.6 29114.0 29486.0 46579.3 47699.3 48011.3 48125.5 49206.0 39487.8 39794.0 40472.4 40550.6 41566.8 39894.0 39922.8 40254.6 40832.4 41795.0 38768.8 39975.8 40971.2 41095.4 41393.4 34940.8 36503.3 36971.4 37203.8 37970.0 27074.5 27386.4 27436.3 27651.3 27784.7 20:47:09 INFO - PROCESS | 18470 | 20:47:09 INFO - PROCESS | 18470 | [#2] jquery.html Cycles:180 Average:29144.86 Median:13704.49 stddev:82962.53 (605.4%) stddev-sans-first:83167.14 20:47:09 INFO - PROCESS | 18470 | Values: 374.3 379.7 385.5 388.9 410.3 17289.8 19621.7 19677.5 19851.0 20786.3 19448.2 19711.2 20327.3 20531.7 20722.5 5251.7 5724.6 5894.3 5934.7 5942.6 5820.2 5834.4 5897.8 5899.4 5901.0 5602.2 5776.1 5799.0 5804.3 5820.5 5599.2 5771.7 5787.5 5812.7 5824.0 5181.8 5227.3 5235.5 5237.0 5239.4 24027.8 24280.5 24314.0 24724.1 24860.0 2351.8 2385.9 2404.6 2471.6 2549.0 14088.0 14641.1 15029.3 15057.2 15505.0 1882.2 1921.3 1924.3 1941.1 1964.2 63498.0 83545.6 86706.0 88233.6 89083.0 10718.2 10754.0 10996.2 11160.6 11182.6 15344.0 15345.2 15389.5 15389.8 15392.1 8119.6 8239.1 8308.8 8348.2 8555.8 7909.7 7941.6 8122.1 8148.8 8181.5 6368.4 6523.8 6549.8 6569.4 6706.2 1885.7 1888.5 1895.9 1930.9 1971.7 874.9 921.4 1002.3 1047.5 1097.8 20219.3 20596.5 20765.0 20765.2 20768.0 983.0 983.4 996.5 1032.8 1074.5 10461.3 11661.2 11683.2 11711.8 11771.5 488919.0 496360.0 512095.0 514318.0 515842.0 44820.8 74121.0 81745.0 82462.0 84481.0 1012.1 1049.6 1076.1 1082.1 1082.3 17388.0 17564.7 18035.4 18067.5 18349.5 39875.4 40669.4 40933.4 41260.2 42184.6 54866.5 55687.9 56013.7 56515.2 58023.0 8557.0 8624.7 8630.8 8687.8 9031.9 13314.8 13378.6 13576.8 13586.0 13589.1 10910.6 11295.8 11360.4 11591.1 11890.3 5246.4 8886.6 9943.7 10526.1 11157.6 11438.9 11653.4 11683.4 11705.7 12393.5 8457.0 8650.5 8675.9 8749.2 8866.1 10991.4 11045.9 11503.8 11577.3 11626.7 20:47:09 INFO - PROCESS | 18470 | 20:47:09 INFO - PROCESS | 18470 | [#3] mootools.html Cycles:180 Average:5790.16 Median:4996.13 stddev:3303.78 (66.1%) stddev-sans-first:3296.18 20:47:09 INFO - PROCESS | 18470 | Values: 1347.5 1458.7 1484.3 1589.9 1789.6 8436.9 8779.8 8871.2 9075.8 9095.9 8806.0 8872.5 8911.2 8935.8 8936.9 2369.4 2371.8 2383.2 2383.5 2390.5 2376.4 2390.8 2449.9 2452.6 2455.1 2432.0 2433.4 2436.7 2460.4 2461.5 2375.8 2395.3 2398.2 2414.4 2419.9 2343.4 2360.2 2362.5 2364.2 2366.8 8811.2 8828.9 8897.5 8899.8 8959.8 4943.6 5111.0 5129.3 5130.2 5138.1 3313.1 3478.9 3514.0 3537.9 3545.7 1127.9 1160.9 1164.9 1205.5 1207.7 12412.1 12502.9 12536.0 12544.2 12553.9 4467.6 4489.1 4661.2 4668.1 4776.3 9682.8 9684.2 9699.2 9714.5 9724.8 5284.9 5291.9 5299.5 5301.4 5305.4 4390.3 4754.5 4896.0 4935.2 4991.3 3748.5 3757.5 3911.6 3913.3 3947.7 1192.3 1195.0 1210.1 1211.1 1218.4 946.4 962.8 964.8 974.5 982.9 5880.6 5892.6 5933.2 6031.8 6128.6 472.2 481.7 482.5 482.9 489.8 4224.7 4235.4 4261.7 4305.2 4325.0 10765.8 11167.2 11198.6 11219.4 11237.9 10595.0 11002.2 11035.8 11041.1 11081.7 8190.8 8391.7 8409.3 8411.7 8412.3 6005.7 6137.5 6166.8 6219.7 6340.0 9880.1 9895.6 9990.0 10250.8 10429.9 9883.3 9938.4 10206.7 10574.5 10656.9 6955.2 7506.9 7622.9 7708.5 7879.4 8326.2 8369.2 8373.5 8396.7 8417.6 7366.9 7491.2 7503.1 7518.1 7703.2 7043.2 7091.5 7278.7 7295.9 7853.4 6816.7 6905.0 6914.2 6923.9 6925.5 5018.3 5034.3 5058.2 5148.6 5172.0 5001.0 5082.0 5142.6 5323.4 5448.3 20:47:09 INFO - PROCESS | 18470 | 20:47:09 INFO - PROCESS | 18470 | [#4] prototype.html Cycles:180 Average:4460.28 Median:4962.16 stddev:1281.77 (25.8%) stddev-sans-first:1247.21 20:47:09 INFO - PROCESS | 18470 | Values: 324.4 335.6 338.9 341.8 343.6 5181.4 5226.9 5261.3 5269.8 5270.2 4871.2 4989.8 5063.2 5112.6 5174.0 4108.9 4113.7 4116.9 4120.3 4140.2 4092.2 4099.5 4114.5 4114.9 4116.9 3894.8 3927.8 4071.3 4089.8 4090.3 4085.0 4086.5 4096.0 4104.6 4150.3 3658.3 3663.0 3668.2 3676.3 3684.6 5023.6 5099.6 5133.3 5158.5 5178.2 4680.6 4688.2 4689.4 4708.4 4730.2 5411.4 5413.4 5423.3 5425.3 5435.2 3708.0 3908.3 4004.7 4016.6 4027.5 5623.3 5625.7 5626.1 5628.6 5633.5 4007.5 4015.5 4047.7 4057.2 4063.1 3851.4 4110.8 4115.6 4119.5 4121.2 4605.3 4613.4 4626.7 4659.6 4693.7 5420.9 5430.2 5435.5 5447.2 5452.9 1353.3 1384.4 1396.2 1397.6 1403.5 911.9 913.0 916.4 922.2 932.5 4709.9 4724.8 4726.9 4729.2 4788.6 4934.9 4937.8 4984.9 5017.2 5040.9 4555.7 4568.9 4676.4 4712.8 4831.5 2629.6 2632.3 2635.8 2641.8 2662.5 4971.3 4976.7 5044.4 5101.4 5146.7 4643.7 4956.1 4968.2 4986.7 4998.7 5014.2 5039.6 5047.3 5053.1 5066.9 4846.7 4887.8 4892.8 4899.5 4923.0 4856.8 5092.2 5542.4 5554.4 5558.2 5748.0 5763.1 5776.5 5786.2 5794.7 4010.2 4032.2 4034.4 4041.8 4061.8 5285.9 5442.9 5468.5 5596.0 5611.6 5452.5 5453.8 5460.5 5471.4 5474.9 5438.7 5439.4 5441.1 5457.6 5472.3 5192.8 5358.7 5376.6 5379.9 5383.2 5452.7 5472.4 5488.5 5496.7 5531.4 5248.6 5369.4 5380.3 5401.1 5474.3 20:47:09 INFO - PROCESS | 18470 | 20:47:09 INFO - PROCESS | 18470 | [#5] yui.html Cycles:180 Average:21759.33 Median:26149.37 stddev:32089.89 (122.7%) stddev-sans-first:32141.08 20:47:09 INFO - PROCESS | 18470 | Values: 734.6 760.4 782.4 794.4 860.9 15472.8 16968.1 17000.5 17016.4 17022.1 14121.2 14288.7 14341.8 14346.9 14392.6 807.5 820.0 822.1 822.7 823.0 663.9 801.4 832.6 833.9 838.5 859.8 865.5 871.0 872.3 873.3 860.7 861.5 862.2 862.6 862.7 507.2 615.1 629.0 632.6 633.7 15660.2 15672.9 15717.1 15717.4 15720.5 14072.2 14503.4 14718.9 15056.7 15333.2 12966.2 13130.8 13135.2 13401.9 13967.8 4476.1 4515.7 4550.4 4712.5 4717.2 77069.0 77089.6 78007.6 83265.2 84147.6 11590.5 11615.6 11648.1 11711.3 12093.2 21717.7 21737.6 21754.0 21758.9 21882.3 8595.0 8799.2 8818.0 9072.1 9120.3 4693.6 4773.4 4833.9 4944.1 5018.3 3425.2 3553.6 3558.9 3568.8 3570.1 606.1 608.5 614.6 616.4 629.4 628.3 657.2 679.8 685.0 694.7 9531.0 9684.8 9908.9 9944.6 10095.2 592.6 621.9 634.7 644.1 661.3 4535.7 4823.9 4877.8 4898.5 4908.6 123909.0 125944.4 129735.4 130378.3 133360.3 64130.7 89546.6 110728.0 111267.0 111573.0 94386.0 95971.0 96110.0 96536.1 97207.5 8779.4 8906.0 8922.7 9196.2 9233.7 53520.0 54255.7 54672.0 55936.0 57195.0 47581.5 72575.0 80190.6 80948.8 84285.6 11119.7 11447.1 11473.9 11720.5 11809.3 13092.6 13366.8 14490.6 14896.7 15111.2 13342.7 13932.4 13934.2 14261.9 14444.4 14228.1 14334.5 14440.4 14767.8 14786.4 14092.2 14148.0 14149.7 14184.7 15115.0 11500.8 11524.1 11773.8 12097.0 12455.8 11525.0 12067.8 12338.0 12364.4 12759.5 20:47:09 INFO - PROCESS | 18470 | -------- Summary: end -------- 20:47:09 INFO - PROCESS | 18470 | 20:47:10 INFO - TEST-INFO | 18470: exit 0 20:47:10 INFO - TEST-OK | dromaeo_css | took 1115455ms 20:47:10 INFO - TEST-START | kraken 20:47:10 INFO - operating with platform_type : linux_ 20:47:10 INFO - Initialising browser for kraken test... 20:47:10 INFO - TEST-INFO | started process 18776 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpotrqkH/profile http://localhost:44484/getInfo.html) 20:47:14 INFO - PROCESS | 18776 | __metrics Screen width/height:1600/1200 20:47:14 INFO - PROCESS | 18776 | colorDepth:24 20:47:14 INFO - PROCESS | 18776 | Browser inner width/height: 1024/697 20:47:14 INFO - PROCESS | 18776 | __metrics 20:47:15 INFO - PROCESS | 18776 | 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" 20:47:15 INFO - PROCESS | 18776 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:47:15 INFO - PROCESS | 18776 | console.error: PushService: 20:47:15 INFO - PROCESS | 18776 | stateChangeProcessEnqueue: Error transitioning state 20:47:15 INFO - PROCESS | 18776 | UnknownError 20:47:16 INFO - TEST-INFO | 18776: exit 0 20:47:16 INFO - Browser initialized. 20:47:16 INFO - Running cycle 1/1 for kraken test... 20:47:16 INFO - TEST-INFO | started process 18858 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpotrqkH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:47:28 INFO - PROCESS | 18858 | RSS: Main: 180314112 20:47:28 INFO - PROCESS | 18858 | 20:47:31 INFO - PROCESS | 18858 | Cycle 1(1): loaded http://localhost:44484/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44484/tests/kraken/driver.html?testName=audio-beat-detection) 20:47:32 INFO - PROCESS | 18858 | RSS: Main: 238206976 20:47:32 INFO - PROCESS | 18858 | 20:47:34 INFO - PROCESS | 18858 | Cycle 1(1): loaded http://localhost:44484/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44484/tests/kraken/driver.html?testName=audio-dft) 20:47:35 INFO - PROCESS | 18858 | RSS: Main: 225144832 20:47:35 INFO - PROCESS | 18858 | 20:47:38 INFO - PROCESS | 18858 | Cycle 1(1): loaded http://localhost:44484/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44484/tests/kraken/driver.html?testName=audio-fft) 20:47:38 INFO - PROCESS | 18858 | RSS: Main: 302120960 20:47:38 INFO - PROCESS | 18858 | 20:47:41 INFO - PROCESS | 18858 | Cycle 1(1): loaded http://localhost:44484/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44484/tests/kraken/driver.html?testName=audio-oscillator) 20:47:41 INFO - PROCESS | 18858 | RSS: Main: 211419136 20:47:41 INFO - PROCESS | 18858 | 20:47:43 INFO - PROCESS | 18858 | Cycle 1(1): loaded http://localhost:44484/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44484/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:47:44 INFO - PROCESS | 18858 | RSS: Main: 214687744 20:47:44 INFO - PROCESS | 18858 | 20:47:47 INFO - PROCESS | 18858 | Cycle 1(1): loaded http://localhost:44484/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44484/tests/kraken/driver.html?testName=imaging-darkroom) 20:47:47 INFO - PROCESS | 18858 | RSS: Main: 252600320 20:47:47 INFO - PROCESS | 18858 | 20:47:51 INFO - PROCESS | 18858 | Cycle 1(1): loaded http://localhost:44484/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44484/tests/kraken/driver.html?testName=imaging-desaturate) 20:47:51 INFO - PROCESS | 18858 | RSS: Main: 250851328 20:47:51 INFO - PROCESS | 18858 | 20:47:54 INFO - PROCESS | 18858 | Cycle 1(1): loaded http://localhost:44484/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44484/tests/kraken/driver.html?testName=json-parse-financial) 20:47:55 INFO - PROCESS | 18858 | RSS: Main: 251162624 20:47:55 INFO - PROCESS | 18858 | 20:47:56 INFO - PROCESS | 18858 | Cycle 1(1): loaded http://localhost:44484/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44484/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:47:57 INFO - PROCESS | 18858 | RSS: Main: 213749760 20:47:57 INFO - PROCESS | 18858 | 20:47:59 INFO - PROCESS | 18858 | Cycle 1(1): loaded http://localhost:44484/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44484/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:47:59 INFO - PROCESS | 18858 | RSS: Main: 221896704 20:47:59 INFO - PROCESS | 18858 | 20:48:01 INFO - PROCESS | 18858 | Cycle 1(1): loaded http://localhost:44484/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44484/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:48:02 INFO - PROCESS | 18858 | RSS: Main: 222138368 20:48:02 INFO - PROCESS | 18858 | 20:48:05 INFO - PROCESS | 18858 | Cycle 1(1): loaded http://localhost:44484/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44484/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:48:05 INFO - PROCESS | 18858 | RSS: Main: 222937088 20:48:05 INFO - PROCESS | 18858 | 20:48:08 INFO - PROCESS | 18858 | Cycle 1(1): loaded http://localhost:44484/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44484/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:48:09 INFO - PROCESS | 18858 | RSS: Main: 221405184 20:48:09 INFO - PROCESS | 18858 | 20:48:10 INFO - PROCESS | 18858 | Cycle 1(1): loaded http://localhost:44484/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44484/tests/kraken/driver.html?testName=ai-astar) 20:48:11 INFO - PROCESS | 18858 | RSS: Main: 271622144 20:48:11 INFO - PROCESS | 18858 | 20:48:11 INFO - PROCESS | 18858 | __start_tp_report 20:48:11 INFO - PROCESS | 18858 | _x_x_mozilla_page_load 20:48:11 INFO - PROCESS | 18858 | _x_x_mozilla_page_load_details 20:48:11 INFO - PROCESS | 18858 | |i|pagename|runs| 20:48:11 INFO - PROCESS | 18858 | |0;ai-astar;113;111;127;110;112;115;113;111;110;109 20:48:11 INFO - PROCESS | 18858 | |1;audio-beat-detection;145;121;158;180;120;145;149;117;181;124 20:48:11 INFO - PROCESS | 18858 | |2;audio-dft;160;161;161;161;160;162;162;160;160;163 20:48:11 INFO - PROCESS | 18858 | |3;audio-fft;75;74;75;169;75;76;75;75;164;76 20:48:11 INFO - PROCESS | 18858 | |4;audio-oscillator;82;82;165;82;82;82;150;83;82;142 20:48:11 INFO - PROCESS | 18858 | |5;imaging-gaussian-blur;118;118;118;119;118;118;118;117;118;117 20:48:11 INFO - PROCESS | 18858 | |6;imaging-darkroom;146;145;146;145;146;145;146;146;146;147 20:48:11 INFO - PROCESS | 18858 | |7;imaging-desaturate;95;95;95;96;97;96;96;96;97;96 20:48:11 INFO - PROCESS | 18858 | |8;json-parse-financial;52;51;53;51;52;54;52;52;53;53 20:48:11 INFO - PROCESS | 18858 | |9;json-stringify-tinderbox;61;62;61;61;62;62;61;62;63;62 20:48:11 INFO - PROCESS | 18858 | |10;stanford-crypto-aes;78;78;79;78;79;84;79;78;79;80 20:48:11 INFO - PROCESS | 18858 | |11;stanford-crypto-ccm;123;128;134;133;126;127;123;131;165;131 20:48:11 INFO - PROCESS | 18858 | |12;stanford-crypto-pbkdf2;168;167;161;165;163;170;170;162;166;165 20:48:11 INFO - PROCESS | 18858 | |13;stanford-crypto-sha256-iterative;65;68;66;67;65;66;66;65;63;66 20:48:11 INFO - PROCESS | 18858 | __end_tp_report 20:48:11 INFO - PROCESS | 18858 | __start_cc_report 20:48:11 INFO - PROCESS | 18858 | _x_x_mozilla_cycle_collect,1721 20:48:11 INFO - PROCESS | 18858 | __end_cc_report 20:48:11 INFO - PROCESS | 18858 | __startTimestamp1472615291073__endTimestamp 20:48:11 INFO - PROCESS | 18858 | 20:48:11 INFO - PROCESS | 18858 | ------- Summary: start ------- 20:48:11 INFO - PROCESS | 18858 | Number of tests: 14 20:48:11 INFO - PROCESS | 18858 | 20:48:11 INFO - PROCESS | 18858 | [#0] ai-astar Cycles:10 Average:113.10 Median:112.50 stddev:5.20 (4.6%) stddev-sans-first:5.51 20:48:11 INFO - PROCESS | 18858 | Values: 113.0 111.0 127.0 110.0 112.0 115.0 113.0 111.0 110.0 109.0 20:48:11 INFO - PROCESS | 18858 | 20:48:11 INFO - PROCESS | 18858 | [#1] audio-beat-detection Cycles:10 Average:144.00 Median:147.00 stddev:23.86 (16.2%) stddev-sans-first:25.30 20:48:11 INFO - PROCESS | 18858 | Values: 145.0 121.0 158.0 180.0 120.0 145.0 149.0 117.0 181.0 124.0 20:48:11 INFO - PROCESS | 18858 | 20:48:11 INFO - PROCESS | 18858 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:1.05 (0.7%) stddev-sans-first:1.05 20:48:11 INFO - PROCESS | 18858 | Values: 160.0 161.0 161.0 161.0 160.0 162.0 162.0 160.0 160.0 163.0 20:48:11 INFO - PROCESS | 18858 | 20:48:11 INFO - PROCESS | 18858 | [#3] audio-fft Cycles:10 Average:93.40 Median:75.00 stddev:38.55 (51.4%) stddev-sans-first:40.31 20:48:11 INFO - PROCESS | 18858 | Values: 75.0 74.0 75.0 169.0 75.0 76.0 75.0 75.0 164.0 76.0 20:48:11 INFO - PROCESS | 18858 | 20:48:11 INFO - PROCESS | 18858 | [#4] audio-oscillator Cycles:10 Average:103.20 Median:82.00 stddev:34.35 (41.9%) stddev-sans-first:35.57 20:48:11 INFO - PROCESS | 18858 | Values: 82.0 82.0 165.0 82.0 82.0 82.0 150.0 83.0 82.0 142.0 20:48:11 INFO - PROCESS | 18858 | 20:48:11 INFO - PROCESS | 18858 | [#5] imaging-gaussian-blur Cycles:10 Average:117.90 Median:118.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 20:48:11 INFO - PROCESS | 18858 | Values: 118.0 118.0 118.0 119.0 118.0 118.0 118.0 117.0 118.0 117.0 20:48:11 INFO - PROCESS | 18858 | 20:48:11 INFO - PROCESS | 18858 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 20:48:11 INFO - PROCESS | 18858 | Values: 146.0 145.0 146.0 145.0 146.0 145.0 146.0 146.0 146.0 147.0 20:48:11 INFO - PROCESS | 18858 | 20:48:11 INFO - PROCESS | 18858 | [#7] imaging-desaturate Cycles:10 Average:95.90 Median:96.00 stddev:0.74 (0.8%) stddev-sans-first:0.71 20:48:11 INFO - PROCESS | 18858 | Values: 95.0 95.0 95.0 96.0 97.0 96.0 96.0 96.0 97.0 96.0 20:48:11 INFO - PROCESS | 18858 | 20:48:11 INFO - PROCESS | 18858 | [#8] json-parse-financial Cycles:10 Average:52.30 Median:52.50 stddev:0.95 (1.8%) stddev-sans-first:1.00 20:48:11 INFO - PROCESS | 18858 | Values: 52.0 51.0 53.0 51.0 52.0 54.0 52.0 52.0 53.0 53.0 20:48:11 INFO - PROCESS | 18858 | 20:48:11 INFO - PROCESS | 18858 | [#9] json-stringify-tinderbox Cycles:10 Average:61.70 Median:62.00 stddev:0.67 (1.1%) stddev-sans-first:0.67 20:48:11 INFO - PROCESS | 18858 | Values: 61.0 62.0 61.0 61.0 62.0 62.0 61.0 62.0 63.0 62.0 20:48:11 INFO - PROCESS | 18858 | 20:48:11 INFO - PROCESS | 18858 | [#10] stanford-crypto-aes Cycles:10 Average:79.20 Median:79.00 stddev:1.81 (2.3%) stddev-sans-first:1.87 20:48:11 INFO - PROCESS | 18858 | Values: 78.0 78.0 79.0 78.0 79.0 84.0 79.0 78.0 79.0 80.0 20:48:11 INFO - PROCESS | 18858 | 20:48:11 INFO - PROCESS | 18858 | [#11] stanford-crypto-ccm Cycles:10 Average:132.10 Median:131.00 stddev:12.18 (9.3%) stddev-sans-first:12.46 20:48:11 INFO - PROCESS | 18858 | Values: 123.0 128.0 134.0 133.0 126.0 127.0 123.0 131.0 165.0 131.0 20:48:11 INFO - PROCESS | 18858 | 20:48:11 INFO - PROCESS | 18858 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.70 Median:166.50 stddev:3.13 (1.9%) stddev-sans-first:3.21 20:48:11 INFO - PROCESS | 18858 | Values: 168.0 167.0 161.0 165.0 163.0 170.0 170.0 162.0 166.0 165.0 20:48:11 INFO - PROCESS | 18858 | 20:48:11 INFO - PROCESS | 18858 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.70 Median:66.00 stddev:1.34 (2.0%) stddev-sans-first:1.39 20:48:11 INFO - PROCESS | 18858 | Values: 65.0 68.0 66.0 67.0 65.0 66.0 66.0 65.0 63.0 66.0 20:48:11 INFO - PROCESS | 18858 | -------- Summary: end -------- 20:48:11 INFO - PROCESS | 18858 | 20:48:12 INFO - TEST-INFO | 18858: exit 0 20:48:12 INFO - TEST-OK | kraken | took 61651ms 20:48:12 INFO - SUITE-END | took 1177s 20:48:12 INFO - Completed test suite (00:19:37) 20:48:12 INFO - javascript benchmark 20:48:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2999.4750918589243, 3013.065949555638, 3013.4123845850067, 3034.7572194224463, 3074.908407515332, 5124.590032797377, 5153.871153221171, 5198.350206224222, 5208.088584497712, 5258.3427071616, 5080.060188865063, 5086.033653605812, 5159.819406320779, 5191.974040129803, 5193.350831146106, 4741.122892264925, 4756.595689366408, 4758.333833263346, 4766.356541866839, 4898.0, 4843.878903027435, 4879.341288926003, 4894.951050489503, 4920.7785649645675, 4943.530364615356, 4646.303054541812, 4676.18166820805, 4687.156311863863, 4729.527047295277, 4731.455882573514, 4744.16977029022, 4792.2332426811545, 4817.831375901862, 4867.0, 4885.120678277908, 4666.6500012498855, 4679.298105284201, 4697.060587882403, 4729.196036673774, 4841.080194762983, 5268.078086334915, 5288.206768984644, 5290.0477913975465, 5298.894022119574, 5311.28297679816, 5487.75305111271, 5503.422140675211, 5547.140193270009, 5623.606347555673, 5636.49271565557, 5585.301837270341, 5619.129034999582, 5634.915476267818, 5639.041362968305, 5690.971545142248, 5258.973705131488, 5260.526552610283, 5269.10425227716, 5269.1832765921345, 5275.788968441258, 5652.491275785199, 5669.773209071591, 5703.17303990919, 5707.258056452634, 5744.195812586241, 5489.780408783684, 5492.176173573996, 5493.176023596492, 5494.532964698045, 5552.222688823568, 5565.387807341189, 5573.386927437979, 5574.860628484239, 5581.302337207849, 5586.413426590231, 5374.677519348774, 5548.889022219614, 5554.638948468337, 5601.383847776741, 5608.158776183605, 5593.07714227148, 5594.1608758686525, 5601.663900166003, 5604.635698679573, 5658.0, 4310.870673879788, 4355.869323920288, 4358.912821743611, 4359.367891655692, 4407.27279998803, 3794.962050379516, 3813.427985802152, 3829.4064420014943, 3846.1922996170556, 3924.0189952511873, 5212.296339994053, 5214.739263036833, 5220.477952204749, 5222.060029194706, 5272.024675435056, 5472.069748142825, 5476.151196564539, 5485.149801780731, 5492.066348720727, 5547.223388725582, 5140.408852981955, 5167.069927413102, 5311.760970756325, 5352.9732351338, 5363.356397232279, 4837.975810120927, 4843.927341089887, 4846.5638092571835, 4855.223164293696, 4919.877003074952, 5849.181114643953, 5852.853678658068, 5853.209816674695, 5858.53131749459, 5867.119932010147, 5431.6740995540395, 5566.415526369835, 5625.578081643975, 5631.380548139619, 5651.237082993744, 5792.739326730391, 5794.0, 5799.391063938226, 5806.912896306643, 5858.648481091148, 5576.9163462547285, 5578.86052848682, 5579.442055794388, 5581.609287349846, 5587.7485513152815, 5438.0, 5491.478309560585, 5546.084895992116, 5580.190872323409, 5663.235463212413, 5448.618596698193, 5486.231927530229, 5495.477929596682, 5547.140193270049, 5557.499825015768, 5418.864528386822, 5474.233607294902, 5478.643888147258, 5479.150731636602, 5483.945160548503, 5222.764975576184, 5414.160805075219, 5429.891402172014, 5437.510624043855, 5479.75341109659, 5560.332760068818, 5563.0, 5563.527100196528, 5592.245046918614, 5608.906263278622, 5435.239066530768, 5441.319835020622, 5444.455554444524, 5451.863703407446, 5455.263539422127], "value": 5166.129293427564, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [562.8626134144113, 573.0487390931054, 601.8314871835887, 608.7260732670296, 609.8749756299958, 32009.35981280376, 35013.0, 35141.12147196321, 35141.297174056504, 43207.35188972168, 41332.38001429981, 45041.0, 45084.323735144, 45140.774296128555, 45153.774231128795, 8835.690750823707, 9004.414713043663, 9009.414388064788, 9009.729708108767, 9014.729558113235, 8720.389572729911, 8761.080086590911, 8765.649374025032, 8776.341774366916, 8785.868211976825, 8821.911780882205, 8878.689245876396, 8884.466931984101, 8885.111488851127, 8891.95554022229, 8787.0, 8813.294936405073, 8817.559122043873, 8821.911780882174, 8824.558772061371, 8736.650533978633, 8853.11468853116, 8873.290136789106, 8880.289576833839, 8883.777905552348, 42191.0, 42588.57411425908, 42707.0, 42708.57291427077, 42791.35812962808, 3402.0644322811177, 3467.4798780183173, 3471.8542880849513, 3666.981665091684, 3879.9030024249337, 32752.0, 32793.50809737856, 32837.83581082103, 32854.83572582146, 32939.50590741141, 4251.319788833772, 4366.956330436687, 4407.933880991788, 4490.820367185309, 4535.206338890714, 327987.0, 328280.0, 329245.0, 329811.9999999976, 330741.0, 56929.71535142298, 57125.14312285319, 57154.14268785971, 57224.42775572191, 57240.713796430755, 60614.090788638205, 60646.0, 60746.392536074956, 60748.0, 60829.0, 16458.09480478561, 16706.0, 16709.749353759704, 16715.247813848408, 16723.49829505117, 12381.566645167375, 12487.188332758342, 12501.874981250254, 12551.811722824164, 12561.811572826415, 14397.64005899861, 16509.257083431276, 16621.257492513843, 16639.6672066558, 16929.746053809202, 5696.20253164549, 5810.1865738796605, 5952.196453478725, 6004.819855404345, 6014.6391216527145, 691.5307977204069, 993.5742209928775, 1041.598984391015, 1088.4176965323672, 1157.0916830288184, 31180.220494487818, 31504.527432088536, 31673.683263167073, 32062.19844503906, 32210.19474513109, 1080.670395529371, 1107.291333546531, 1146.5299227316927, 1163.4822503985645, 1168.5092261250295, 16218.351265949466, 17052.48842534726, 17118.743218851727, 17124.315027398763, 17228.4011077246, 518395.0, 521651.0, 522002.0, 522395.0, 524611.0, 57068.429315706315, 57378.13932791012, 57465.71267143638, 57504.71247643735, 57541.71229143828, 1213.8846809553079, 1307.130758045908, 1334.0661536924192, 1352.2224720785587, 1358.5109360630165, 30689.38621227518, 32040.83979580087, 32363.51454728228, 32652.0, 33234.83382583072, 48076.5192348072, 48527.272090917955, 48584.271235930784, 48656.2701559484, 48675.269870952674, 295787.0, 295871.0, 297406.0, 297449.0, 299256.0, 27406.45187096287, 28551.571726423706, 28639.560868065484, 29114.0, 29486.0, 46579.301310481045, 47699.284510731675, 48011.279830803265, 48125.51874481211, 49206.0, 39487.80256098701, 39794.0, 40472.3929141069, 40550.59449405586, 41566.79216603898, 39894.0, 39922.800385997885, 40254.59108931173, 40832.3875141879, 41795.0, 38768.80615596904, 39975.80012099921, 40971.1805763889, 41095.38356924708, 41393.37909931293, 34940.82529587336, 36503.26993460169, 36971.44542831806, 37203.813980929925, 37970.0, 27074.458510829278, 27386.368744100597, 27436.314092147855, 27651.308717281423, 27784.72215277902], "value": 19473.48172409369, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [374.3168717091309, 379.6924491162157, 385.498851493059, 388.8600103962573, 410.30862995851993, 17289.827101728995, 19621.705674414876, 19677.50806229845, 19851.0, 20786.272480463187, 19448.222071117172, 19711.211551537923, 20327.28854490093, 20531.692024619642, 20722.481937951557, 5251.711155886424, 5724.62789918655, 5894.263217097863, 5934.703264836741, 5942.584019118642, 5820.156077368779, 5834.358220595731, 5897.793577224798, 5899.351071382144, 5900.967330717107, 5602.1596760486045, 5776.133579962997, 5798.971005144968, 5804.303483581976, 5820.534357251431, 5599.188117722907, 5771.711414429262, 5787.50806181475, 5812.680302583356, 5824.0390335594575, 5181.844544663666, 5227.294315267428, 5235.502627250406, 5236.9738151309, 5239.397469291004, 24027.7597224029, 24280.514389712283, 24314.0, 24724.13465528698, 24860.0, 2351.776581224781, 2385.9045638174453, 2404.5912194926905, 2471.5551200783852, 2548.987255063713, 14087.985183119881, 14641.121532708072, 15029.323680434407, 15057.247137643075, 15505.0, 1882.2282864025683, 1921.28912302449, 1924.3264857299832, 1941.1361943934955, 1964.204497178645, 63498.0, 83545.58227208885, 86705.99999999937, 88233.55883220606, 89083.0, 10718.195040760575, 10754.0, 10996.23026388145, 11160.553577856796, 11182.608608698656, 15344.0, 15345.156016419203, 15389.461368852037, 15389.846101538842, 15392.076475411512, 8119.634616442274, 8239.134890836496, 8308.750737477885, 8348.248657620843, 8555.82888342242, 7909.6836126555445, 7941.64262608184, 8122.147174546706, 8148.796280093045, 8181.509109453333, 6368.395002474757, 6523.836904077436, 6549.803505894831, 6569.408753212234, 6706.195256569243, 1885.688861337864, 1888.4523488188272, 1895.9052047397574, 1930.8841469511594, 1971.7042443633284, 874.9387542872065, 921.447131720962, 1002.3334482569155, 1047.4814966591587, 1097.7969075721016, 20219.29232476856, 20596.485087872923, 20765.0, 20765.169393224103, 20768.0, 983.041534503853, 983.4019717958317, 996.461910568285, 1032.76762728387, 1074.4842475611595, 10461.26771126014, 11661.18371713989, 11683.18217724751, 11711.765764684658, 11771.529138834521, 488919.0, 496360.0, 512095.0, 514318.0, 515842.0, 44820.77589612031, 74121.0, 81745.0, 82462.0, 84481.0, 1012.0790081026378, 1049.6221360310283, 1076.0853274716428, 1082.1018554599664, 1082.3332068614402, 17388.0, 17564.73652895182, 18035.368762093265, 18067.54831129232, 18349.54126146857, 39875.40186897141, 40669.38995915123, 40933.38599920944, 41260.1747965045, 42184.56490600294, 54866.451335486134, 55687.92525083921, 56013.71993140008, 56515.196208322755, 58023.0, 8557.0, 8624.698135565224, 8630.82738345242, 8687.826243475221, 9031.864522032307, 13314.800277996032, 13378.598642040753, 13576.796348054984, 13586.048976571545, 13589.116707413987, 10910.61812836547, 11295.830562541732, 11360.431978401211, 11591.130665200313, 11890.34603096838, 5246.3704355477585, 8886.622573501118, 9943.651972180938, 10526.10528105089, 11157.609483668357, 11438.942805286253, 11653.359065251154, 11683.415829208676, 11705.707357316136, 12393.504259829866, 8457.0, 8650.524221167896, 8675.869861952202, 8749.212570868653, 8866.11338866134, 10991.395473249047, 11045.851932199284, 11503.827442588536, 11577.305361678325, 11626.651200463999], "value": 9372.141710196844, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1347.5283650722242, 1458.7155504676587, 1484.2578710644677, 1589.8728101751854, 1789.6152327249647, 8436.91563084369, 8779.824403511926, 8871.1572400622, 9075.81848363034, 9095.863562046574, 8805.95597022014, 8872.467651940871, 8911.15344042315, 8935.821283574325, 8936.8659470108, 2369.395804069964, 2371.7983971362455, 2383.249276477904, 2383.4875501767115, 2390.486045500217, 2376.4415362389823, 2390.8326417150806, 2449.889754961022, 2452.619843924195, 2455.067074511683, 2432.0, 2433.428144386077, 2436.6710494083336, 2460.4095017195923, 2461.507698460306, 2375.7980571651256, 2395.3053614451787, 2398.20859116492, 2414.4326083370393, 2419.9153029643967, 2343.367290831466, 2360.209329874494, 2362.5038741864228, 2364.2198074635503, 2366.7988221001237, 8811.162939520735, 8828.911710882938, 8897.510636914967, 8899.822003559893, 8959.776005599848, 4943.604511639061, 5111.028904508132, 5129.281900533891, 5130.204818253177, 5138.075146473633, 3313.1385839681857, 3478.89563313101, 3513.9985104245166, 3537.8584856605703, 3545.716342692604, 1127.881572434899, 1160.8781077986773, 1164.9068074554016, 1205.4876677412065, 1207.7041124924363, 12412.131150819447, 12502.937485312515, 12536.0, 12544.184627999151, 12553.874461255453, 4467.5532446755715, 4489.147062058199, 4661.230896901973, 4668.089722504079, 4776.331313616097, 9682.75793105178, 9684.176844968115, 9699.17557007648, 9714.465704386326, 9724.805503890024, 5284.920726189111, 5291.894162116736, 5299.5230429261555, 5301.363836339664, 5305.36335639718, 4390.319500477431, 4754.453237877619, 4896.0, 4935.185694360391, 4991.276264941635, 3748.5314335708035, 3757.5490941087246, 3911.5892831252877, 3913.3347330953807, 3947.743396679207, 1192.3382522699812, 1194.9522019119138, 1210.0863847794858, 1211.0977321895068, 1218.3725381428571, 946.3564775952418, 962.7833737409027, 964.8118616869643, 974.4906864823109, 982.931194816356, 5880.647161170258, 5892.646441213541, 5933.169356290123, 6031.788887388921, 6128.571000029955, 472.24676640757934, 481.7157876852674, 482.4668741041169, 482.90583336249097, 489.7649128418308, 4224.683148763855, 4235.385869048971, 4261.74429534229, 4305.203537345601, 4325.0, 10765.83851242232, 11167.162462815322, 11198.608048718255, 11219.382933938712, 11237.94381028106, 10595.0, 11002.174836887265, 11035.779284414266, 11041.116710663287, 11081.667549973514, 8190.754277371689, 8391.706290279812, 8409.32725381968, 8411.70559030431, 8412.284955778827, 6005.699715014319, 6137.478314343331, 6166.784162554289, 6219.720112595034, 6340.0, 9880.061394167542, 9895.55470003866, 9989.950050249703, 10250.846237306298, 10429.947850261004, 9883.30816842814, 9938.354006989523, 10206.693799186036, 10574.471276435994, 10656.946715266375, 6955.200151982587, 7506.887396688945, 7622.923770762443, 7708.537487750753, 7879.448438609243, 8326.167383261625, 8369.246767790928, 8373.539455330016, 8396.664133434595, 8417.579121044046, 7366.889496657662, 7491.175970643334, 7503.062117235346, 7518.097828260644, 7703.152653208031, 7043.190033146254, 7091.468139889425, 7278.67245973943, 7295.8540829184185, 7853.371730261451, 6816.693248803716, 6905.0, 6914.170299564084, 6923.8961415579815, 6925.480588955949, 5018.347614810052, 5034.345535080416, 5058.215976523645, 5148.562372198405, 5172.0, 5000.974995125001, 5082.0, 5142.588592912483, 5323.441038691037, 5448.264484294569], "value": 4571.128483891436, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [324.35777161220796, 335.64421712984233, 338.88308533555914, 341.7812599936041, 343.5585272924293, 5181.430042695305, 5226.8693282667955, 5261.316028916241, 5269.761606022586, 5270.209468579706, 4871.173310008747, 4989.825356112537, 5063.202563090105, 5112.642115051938, 5174.016936782018, 4108.897277568055, 4113.71204015719, 4116.938245926313, 4120.25835349637, 4140.171965606875, 4092.242935057009, 4099.487564054493, 4114.526829414611, 4114.855980040699, 4116.855910043149, 3894.824732887027, 3927.7643341399466, 4071.3282308419075, 4089.7750623715683, 4090.284200264957, 4084.9591504084874, 4086.5300490443383, 4095.99999999997, 4104.589541045902, 4150.3359462486005, 3658.2866341063504, 3662.974367177195, 3668.229671768905, 3676.283124790667, 3684.6499582539614, 5023.648344615878, 5099.643024988252, 5133.307003554547, 5158.509941555546, 5178.171492561172, 4680.602148817321, 4688.179568575486, 4689.358724446451, 4708.364370809965, 4730.2498075711865, 5411.404745477994, 5413.377461591928, 5423.322084739408, 5425.267588875491, 5435.157550579627, 3708.0, 3908.3160446921675, 4004.6796256299426, 4016.614268077538, 4027.516697996259, 5623.325200975909, 5625.71871406428, 5626.071698169797, 5628.577856660767, 5633.521150702195, 4007.539132999684, 4015.457913181683, 4047.6964227683043, 4057.208844275338, 4063.065494936181, 3851.441540976599, 4110.77390743512, 4115.5678653741525, 4119.526254480773, 4121.216968775923, 4605.309203619484, 4613.400257966443, 4626.699264547794, 4659.627229821606, 4693.6949098308505, 5420.864478388072, 5430.158325459481, 5435.510804027656, 5447.237386765856, 5452.863678408072, 1353.3447514577956, 1384.4116250593459, 1396.2250950722446, 1397.5737400092867, 1403.4736973634888, 911.8677791720163, 912.981740365189, 916.4455504419864, 922.1976880114344, 932.5477143585356, 4709.882252943704, 4724.834630787906, 4726.858194254178, 4729.196036673774, 4788.592969597623, 4934.901301974012, 4937.8271760488215, 4984.90030199394, 5017.222330538772, 5040.899182016339, 4555.749433781174, 4568.908621827545, 4676.438827340741, 4712.835050773275, 4831.541003604652, 2629.644997925294, 2632.328756167165, 2635.7759590434644, 2641.7754490868106, 2662.467506498708, 4971.28490670718, 4976.676516026447, 5044.351715349471, 5101.387833459934, 5146.716930568779, 4643.72297618151, 4956.083124621957, 4968.22992436173, 4986.700797952134, 4998.700077995332, 5014.247862820534, 5039.647224694236, 5047.343845300087, 5053.0798296647035, 5066.873328166826, 4846.691393323712, 4887.84733846674, 4892.755362231803, 4899.510048995072, 4923.0, 4856.757162141808, 5092.185250359925, 5542.3903370628395, 5554.416786237387, 5558.166275058774, 5748.0, 5763.077907534775, 5776.480116789509, 5786.218860453785, 5794.739236734273, 4010.218007488512, 4032.2137183248983, 4034.374671925856, 4041.81811818463, 4061.781465560261, 5285.9471405286995, 5442.891142177214, 5468.4531546845, 5596.0, 5611.551075913999, 5452.536534394462, 5453.754581043783, 5460.481254280837, 5471.425500322568, 5474.9468949144075, 5438.673679579238, 5439.374471935778, 5441.075017247156, 5457.563394928476, 5472.26124473207, 5192.792288308426, 5358.678479291255, 5376.59675524345, 5379.865503362447, 5383.192521121863, 5452.700101494456, 5472.370677371994, 5488.533474654699, 5496.725163741877, 5531.391546929754, 5248.553872920845, 5369.3556773187465, 5380.273663055437, 5401.135818269216, 5474.260974768515], "value": 4057.462316383378, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [734.6069852628841, 760.3612965109309, 782.401462880896, 794.4280118314812, 860.9139086091395, 15472.845271547281, 16968.06675632841, 17000.48998530046, 17016.4044258451, 17022.148892555368, 14121.223332716696, 14288.71422571548, 14341.784873226883, 14346.856531434683, 14392.568222953301, 807.4872455990444, 820.0, 822.1367564057715, 822.6585966823761, 823.0206054794805, 663.8871391863395, 801.390942883407, 832.5961908474385, 833.8874251975966, 838.5304229631425, 859.8409294280547, 865.4807115730574, 870.9695160669378, 872.3457406944791, 873.3449912565576, 860.6729442811692, 861.4874149880811, 862.2024627219797, 862.633380813155, 862.7325529085972, 507.2011581758708, 615.1050221927086, 628.9937100628981, 632.5793347424003, 633.6926590603554, 15660.216989150496, 15672.921635391864, 15717.13555754433, 15717.37130514778, 15720.528384148494, 14072.155670659691, 14503.41986320546, 14718.87192284078, 15056.698866022727, 15333.156676382794, 12966.157199782077, 13130.803037954438, 13135.211887286698, 13401.932990334986, 13967.79048314276, 4476.149531588988, 4515.706479078849, 4550.408446901914, 4712.458067322267, 4717.221658426355, 77069.0, 77089.61455192688, 78007.6099619504, 83265.16734832634, 84147.57926210391, 11590.536378544764, 11615.593454229062, 11648.126390520745, 11711.297322160697, 12093.218559731135, 21717.67423488649, 21737.565248694937, 21754.0, 21758.891205544187, 21882.343529694135, 8595.0, 8799.164079412445, 8818.0, 9072.092790720875, 9120.270378369714, 4693.601043911306, 4773.355596994431, 4833.879153021203, 4944.1100601891285, 5018.272350509155, 3425.1950791563963, 3553.591336996227, 3558.8576456941432, 3568.839402226906, 3570.125319296789, 606.130203158469, 608.5496732417979, 614.6496496996758, 616.3990109643062, 629.3580547841176, 628.2837565175612, 657.1785268414482, 679.7858674517511, 684.9726010959506, 694.7464175575878, 9530.95234523823, 9684.806303874024, 9908.900910990798, 9944.602215911427, 10095.24285678577, 592.602956019468, 621.8631900981777, 634.7156692326794, 644.1175589442494, 661.2825084782968, 4535.727856328631, 4823.855284341475, 4877.829275975324, 4898.461169271377, 4908.631852611068, 123909.0, 125944.37027814802, 129735.35132324467, 130378.34810825886, 133360.33319833534, 64130.6793466039, 89546.55226723825, 110728.0, 111267.0, 111573.0, 94386.0, 95971.0, 96110.0, 96536.14081394824, 97207.51396243116, 8779.429337093068, 8905.955470222607, 8922.68770593026, 9196.218321442751, 9233.676821311223, 53520.0, 54255.72872135772, 54672.0, 55936.0, 57195.0, 47581.52418475771, 72575.0, 80190.59904700672, 80948.7857682147, 84285.57857210675, 11119.72200694989, 11447.141464390037, 11473.885261147616, 11720.472578733807, 11809.291442513477, 13092.607221783363, 13366.799498007344, 14490.637734056732, 14896.702065958836, 15111.23995941275, 13342.733145337234, 13932.442702291795, 13934.19270351727, 14261.857381426467, 14444.350004249623, 14228.146311220946, 14334.49829255971, 14440.350184241524, 14767.79449366685, 14786.408543658134, 14092.22492762867, 14148.0, 14149.717005660035, 14184.716305674036, 15115.0, 11500.769984600429, 11524.064171122795, 11773.823392648947, 12097.011979070416, 12455.813162802384, 11525.0, 12067.818982715442, 12338.0, 12364.381780911099, 12759.48962041508], "value": 7721.682156051768, "unit": "score"}], "name": "dromaeo_css", "value": 7162.766485540943, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 111.0, 127.0, 110.0, 112.0, 115.0, 113.0, 111.0, 110.0, 109.0], "value": 113.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 121.0, 158.0, 180.0, 120.0, 145.0, 149.0, 117.0, 181.0, 124.0], "value": 144.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 161.0, 161.0, 160.0, 162.0, 162.0, 160.0, 160.0, 163.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 75.0, 169.0, 75.0, 76.0, 75.0, 75.0, 164.0, 76.0], "value": 93.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 165.0, 82.0, 82.0, 82.0, 150.0, 83.0, 82.0, 142.0], "value": 103.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 118.0, 119.0, 118.0, 118.0, 118.0, 117.0, 118.0, 117.0], "value": 117.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 146.0, 145.0, 146.0, 145.0, 146.0, 146.0, 146.0, 147.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, 96.0, 96.0, 96.0, 97.0, 96.0], "value": 95.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 53.0, 51.0, 52.0, 54.0, 52.0, 52.0, 53.0, 53.0], "value": 52.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 61.0, 61.0, 62.0, 62.0, 61.0, 62.0, 63.0, 62.0], "value": 61.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 79.0, 78.0, 79.0, 84.0, 79.0, 78.0, 79.0, 80.0], "value": 79.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 128.0, 134.0, 133.0, 126.0, 127.0, 123.0, 131.0, 165.0, 131.0], "value": 132.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 167.0, 161.0, 165.0, 163.0, 170.0, 170.0, 162.0, 166.0, 165.0], "value": 165.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 68.0, 66.0, 67.0, 65.0, 66.0, 66.0, 65.0, 63.0, 66.0], "value": 65.7, "unit": "score"}], "name": "kraken", "value": 1531.0000000000002, "alertThreshold": 2.0}]} 20:48:12 INFO - Return code: 0 20:48:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:48:12 INFO - # TBPL SUCCESS # 20:48:12 INFO - Running post-action listener: _resource_record_post_action 20:48:12 INFO - [mozharness: 2016-08-31 03:48:12.379967Z] Finished run-tests step (success) 20:48:12 INFO - Running post-run listener: _resource_record_post_run 20:48:12 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 12288; Write bytes: 417837056; Read time: 204; Write time: 231256 20:48:12 INFO - TinderboxPrint: CPU usage
13.1% 20:48:12 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 204 20:48:12 INFO - TinderboxPrint: I/O write bytes / time
417,837,056 / 231,256 20:48:12 INFO - TinderboxPrint: CPU idle
8,278.5 (86.9%) 20:48:12 INFO - TinderboxPrint: CPU user
1,197.8 (12.6%) 20:48:12 INFO - TinderboxPrint: Swap in / out
0 / 0 20:48:12 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 4096; Write bytes: 128786432; Read time: 96; Write time: 67004 20:48:12 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 4096; Write bytes: 288141312; Read time: 92; Write time: 164228 20:48:13 INFO - Running post-run listener: _upload_blobber_files 20:48:13 INFO - Blob upload gear active. 20:48:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:48:13 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 20:48:13 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'] 20:48:13 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 20:48:13 INFO - (blobuploader) - INFO - Open directory for files ... 20:48:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:48:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:48:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:48:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:48:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:48:14 INFO - (blobuploader) - INFO - Done attempting. 20:48:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:48:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:48:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:48:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:48:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:48:15 INFO - (blobuploader) - INFO - Done attempting. 20:48:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:48:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:48:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:48:19 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:48:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:48:19 INFO - (blobuploader) - INFO - Done attempting. 20:48:19 INFO - (blobuploader) - INFO - Iteration through files over. 20:48:19 INFO - Return code: 0 20:48:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:48:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:48:19 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9da77cf6d2dbcf3012b016977758ccfa99a3f289535c572a319a99979b665d71dd68f7769f0974fa7c2d7510592327d5d9e2302e6b39b3fda7dc9a74649ba0d5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e033cc842d44425e54fb0815c221401fe2b40567c5fa2ac678e27922430f938d46640f57218d5075b8c16aebd76bad76ff0904b8bb8a85ea1b9d8edb6bd33807", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9231e21f22c42707954154a09f39ddab660f331fec339855465105dd14723c2cf5304c7f7efadb24b2b418dafb55cbca746e313bebc021ef9516a6b84471c75b"} 20:48:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:48:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:48:19 INFO - Contents: 20:48:19 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9da77cf6d2dbcf3012b016977758ccfa99a3f289535c572a319a99979b665d71dd68f7769f0974fa7c2d7510592327d5d9e2302e6b39b3fda7dc9a74649ba0d5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e033cc842d44425e54fb0815c221401fe2b40567c5fa2ac678e27922430f938d46640f57218d5075b8c16aebd76bad76ff0904b8bb8a85ea1b9d8edb6bd33807", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9231e21f22c42707954154a09f39ddab660f331fec339855465105dd14723c2cf5304c7f7efadb24b2b418dafb55cbca746e313bebc021ef9516a6b84471c75b"} 20:48:19 INFO - Running post-run listener: copy_logs_to_upload_dir 20:48:19 INFO - Copying logs to upload dir... 20:48:19 INFO - mkdir: /builds/slave/test/build/upload/logs 20:48:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.166374 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-08-30 20:48:19.319454) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-30 20:48:19.323375) ========= 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=337fe3f4b1febe37b85fccc40000019e-1472613041.570383-482468053 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/9da77cf6d2dbcf3012b016977758ccfa99a3f289535c572a319a99979b665d71dd68f7769f0974fa7c2d7510592327d5d9e2302e6b39b3fda7dc9a74649ba0d5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e033cc842d44425e54fb0815c221401fe2b40567c5fa2ac678e27922430f938d46640f57218d5075b8c16aebd76bad76ff0904b8bb8a85ea1b9d8edb6bd33807", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9231e21f22c42707954154a09f39ddab660f331fec339855465105dd14723c2cf5304c7f7efadb24b2b418dafb55cbca746e313bebc021ef9516a6b84471c75b"} build_url:https://queue.taskcluster.net/v1/task/VXTKi3uCQpeMo1ggJWB8eg/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011350 build_url: 'https://queue.taskcluster.net/v1/task/VXTKi3uCQpeMo1ggJWB8eg/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/9da77cf6d2dbcf3012b016977758ccfa99a3f289535c572a319a99979b665d71dd68f7769f0974fa7c2d7510592327d5d9e2302e6b39b3fda7dc9a74649ba0d5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e033cc842d44425e54fb0815c221401fe2b40567c5fa2ac678e27922430f938d46640f57218d5075b8c16aebd76bad76ff0904b8bb8a85ea1b9d8edb6bd33807", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9231e21f22c42707954154a09f39ddab660f331fec339855465105dd14723c2cf5304c7f7efadb24b2b418dafb55cbca746e313bebc021ef9516a6b84471c75b"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-30 20:48:19.348761) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 20:48:19.349087) ========= 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=337fe3f4b1febe37b85fccc40000019e-1472613041.570383-482468053 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004461 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 20:48:19.431752) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-30 20:48:19.432072) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-30 20:48:19.432411) ========= ========= Total master_lag: 0.17 =========