builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-019 starttime: 1472744913.44 results: success (0) buildid: 20160901080621 builduid: 977bd45b4c144820ba596a5f50184de3 revision: aa1a7886af087c5c13cd087ac1c67dac7e553492 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 08:48:33.439995) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 08:48:33.440426) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 08:48:33.440708) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 08:48:33.459985) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 08:48:33.460375) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1472744913.429106-331339192 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-01 08:48:34-- 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% 50.7M=0s 2016-09-01 08:48:34 (50.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.116049 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 08:48:33.590391) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 08:48:33.590709) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1472744913.429106-331339192 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017978 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 08:48:33.640482) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 08:48:33.640841) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev aa1a7886af087c5c13cd087ac1c67dac7e553492 --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 aa1a7886af087c5c13cd087ac1c67dac7e553492 --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=78329bfc0fd5309db0a6e0ac000001b4-1472744913.429106-331339192 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-01 08:48:34,402 truncating revision to first 12 chars 2016-09-01 08:48:34,402 Setting DEBUG logging. 2016-09-01 08:48:34,402 attempt 1/10 2016-09-01 08:48:34,402 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/aa1a7886af08?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-01 08:48:34,783 unpacking tar archive at: fx-team-aa1a7886af08/testing/mozharness/ program finished with exit code 0 elapsedTime=0.724932 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 08:48:34.379602) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 08:48:34.379948) ========= script_repo_revision: aa1a7886af087c5c13cd087ac1c67dac7e553492 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 08:48:34.380351) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 08:48:34.380615) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 08:48:34.390316) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2016-09-01 08:48:34.390731) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 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-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] 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=78329bfc0fd5309db0a6e0ac000001b4-1472744913.429106-331339192 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:48:35 INFO - MultiFileLogger online at 20160901 08:48:35 in /builds/slave/test 08:48:35 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 08:48:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:48:35 INFO - {'append_to_log': False, 08:48:35 INFO - 'base_work_dir': '/builds/slave/test', 08:48:35 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 08:48:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:48:35 INFO - 'branch': 'Fx-Team-Non-PGO', 08:48:35 INFO - 'buildbot_json_path': 'buildprops.json', 08:48:35 INFO - 'config_files': ('talos/linux_config.py',), 08:48:35 INFO - 'default_actions': ('clobber', 08:48:35 INFO - 'read-buildbot-config', 08:48:35 INFO - 'download-and-extract', 08:48:35 INFO - 'populate-webroot', 08:48:35 INFO - 'create-virtualenv', 08:48:35 INFO - 'install', 08:48:35 INFO - 'run-tests'), 08:48:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:48:35 INFO - 'download_minidump_stackwalk': True, 08:48:35 INFO - 'download_symbols': 'ondemand', 08:48:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:48:35 INFO - 'tooltool.py': '/tools/tooltool.py', 08:48:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:48:35 INFO - '/tools/misc-python/virtualenv.py')}, 08:48:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:48:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:48:35 INFO - 'installer_path': 'installer.exe', 08:48:35 INFO - 'log_level': 'info', 08:48:35 INFO - 'log_name': 'talos', 08:48:35 INFO - 'log_to_console': True, 08:48:35 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:48:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:48:35 INFO - 'opt_config_files': (), 08:48:35 INFO - 'pip_index': False, 08:48:35 INFO - 'sps_profile': False, 08:48:35 INFO - 'sps_profile_interval': 0, 08:48:35 INFO - 'suite': 'dromaeojs', 08:48:35 INFO - 'system_bits': '32', 08:48:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:48:35 INFO - 'title': 'talos-linux64-ix-019', 08:48:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:48:35 INFO - 'use_talos_json': True, 08:48:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:48:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:48:35 INFO - 'work_dir': 'build'} 08:48:35 INFO - [mozharness: 2016-09-01 15:48:35.250006Z] Running clobber step. 08:48:35 INFO - Running pre-action listener: _resource_record_pre_action 08:48:35 INFO - Running main action method: clobber 08:48:35 INFO - rmtree: /builds/slave/test/build 08:48:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:48:36 INFO - Running post-action listener: _resource_record_post_action 08:48:36 INFO - [mozharness: 2016-09-01 15:48:36.222414Z] Finished clobber step (success) 08:48:36 INFO - [mozharness: 2016-09-01 15:48:36.222516Z] Running read-buildbot-config step. 08:48:36 INFO - Running pre-action listener: _resource_record_pre_action 08:48:36 INFO - Running main action method: read_buildbot_config 08:48:36 INFO - Using buildbot properties: 08:48:36 INFO - { 08:48:36 INFO - "project": "", 08:48:36 INFO - "product": "firefox", 08:48:36 INFO - "script_repo_revision": "production", 08:48:36 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 08:48:36 INFO - "repository": "", 08:48:36 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 08:48:36 INFO - "buildid": "20160901080621", 08:48:36 INFO - "pgo_build": "False", 08:48:36 INFO - "basedir": "/builds/slave/test", 08:48:36 INFO - "buildnumber": 3264, 08:48:36 INFO - "slavename": "talos-linux64-ix-019", 08:48:36 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 08:48:36 INFO - "platform": "ubuntu64_hw", 08:48:36 INFO - "scheduler": "tests-fx-team-linux64-talos", 08:48:36 INFO - "branch": "fx-team", 08:48:36 INFO - "repo_path": "integration/fx-team", 08:48:36 INFO - "revision": "aa1a7886af087c5c13cd087ac1c67dac7e553492", 08:48:36 INFO - "stage_platform": "linux64", 08:48:36 INFO - "builduid": "977bd45b4c144820ba596a5f50184de3", 08:48:36 INFO - "slavebuilddir": "test" 08:48:36 INFO - } 08:48:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/NwUwN-Q5SuiOdqmDtbfqXA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2. 08:48:36 INFO - Running post-action listener: _resource_record_post_action 08:48:36 INFO - [mozharness: 2016-09-01 15:48:36.224977Z] Finished read-buildbot-config step (success) 08:48:36 INFO - [mozharness: 2016-09-01 15:48:36.225068Z] Running download-and-extract step. 08:48:36 INFO - Running pre-action listener: _resource_record_pre_action 08:48:36 INFO - Running main action method: download_and_extract 08:48:36 INFO - mkdir: /builds/slave/test/build/tests 08:48:36 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')]} 08:48:36 INFO - trying https://queue.taskcluster.net/v1/task/NwUwN-Q5SuiOdqmDtbfqXA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 08:48:36 INFO - Downloading https://queue.taskcluster.net/v1/task/NwUwN-Q5SuiOdqmDtbfqXA/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 08:48:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NwUwN-Q5SuiOdqmDtbfqXA/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 08:48:36 INFO - Downloaded 1361 bytes. 08:48:36 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.linux-x86_64.test_packages.json 08:48:36 INFO - Using the following test package requirements: 08:48:36 INFO - {u'common': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 08:48:36 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:48:36 INFO - u'firefox-51.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 08:48:36 INFO - u'gtest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:48:36 INFO - u'firefox-51.0a1.en-US.linux-x86_64.gtest.tests.zip'], 08:48:36 INFO - u'jittest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:48:36 INFO - u'jsshell-linux-x86_64.zip'], 08:48:36 INFO - u'mochitest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:48:36 INFO - u'firefox-51.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 08:48:36 INFO - u'mozbase': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'], 08:48:36 INFO - u'reftest': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:48:36 INFO - u'firefox-51.0a1.en-US.linux-x86_64.reftest.tests.zip'], 08:48:36 INFO - u'talos': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:48:36 INFO - u'firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip'], 08:48:36 INFO - u'web-platform': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:48:36 INFO - u'firefox-51.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 08:48:36 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip', 08:48:36 INFO - u'firefox-51.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 08:48:36 INFO - Downloading packages: [u'firefox-51.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:48:36 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')]} 08:48:36 INFO - trying https://queue.taskcluster.net/v1/task/NwUwN-Q5SuiOdqmDtbfqXA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 08:48:36 INFO - Downloading https://queue.taskcluster.net/v1/task/NwUwN-Q5SuiOdqmDtbfqXA/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 08:48:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NwUwN-Q5SuiOdqmDtbfqXA/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 08:48:38 INFO - Downloaded 20335711 bytes. 08:48:38 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 08:48:43 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 08:48:43 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')]} 08:48:43 INFO - trying https://queue.taskcluster.net/v1/task/NwUwN-Q5SuiOdqmDtbfqXA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.common.tests.zip 08:48:43 INFO - Downloading https://queue.taskcluster.net/v1/task/NwUwN-Q5SuiOdqmDtbfqXA/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 08:48:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NwUwN-Q5SuiOdqmDtbfqXA/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 08:48:43 INFO - Downloaded 20335711 bytes. 08:48:43 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 08:48:48 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')]} 08:48:48 INFO - trying https://queue.taskcluster.net/v1/task/NwUwN-Q5SuiOdqmDtbfqXA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.talos.tests.zip 08:48:48 INFO - Downloading https://queue.taskcluster.net/v1/task/NwUwN-Q5SuiOdqmDtbfqXA/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 08:48:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NwUwN-Q5SuiOdqmDtbfqXA/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 08:48:50 INFO - Downloaded 14413998 bytes. 08:48:50 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 08:48:50 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')]} 08:48:50 INFO - trying https://queue.taskcluster.net/v1/task/NwUwN-Q5SuiOdqmDtbfqXA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 08:48:50 INFO - Downloading https://queue.taskcluster.net/v1/task/NwUwN-Q5SuiOdqmDtbfqXA/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 08:48:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NwUwN-Q5SuiOdqmDtbfqXA/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 08:48:52 INFO - Downloaded 55865340 bytes. 08:48:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NwUwN-Q5SuiOdqmDtbfqXA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 08:48:52 INFO - mkdir: /builds/slave/test/properties 08:48:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:48:52 INFO - Writing to file /builds/slave/test/properties/build_url 08:48:52 INFO - Contents: 08:48:52 INFO - build_url:https://queue.taskcluster.net/v1/task/NwUwN-Q5SuiOdqmDtbfqXA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 08:48:53 INFO - Running post-action listener: _resource_record_post_action 08:48:53 INFO - Running post-action listener: set_extra_try_arguments 08:48:53 INFO - [mozharness: 2016-09-01 15:48:53.442204Z] Finished download-and-extract step (success) 08:48:53 INFO - [mozharness: 2016-09-01 15:48:53.442432Z] Running populate-webroot step. 08:48:53 INFO - Running pre-action listener: _resource_record_pre_action 08:48:53 INFO - Running main action method: populate_webroot 08:48:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:48:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:48:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:48:53 INFO - '--tppagecycles', 08:48:53 INFO - '7'], 08:48:53 INFO - 'tests': ['tsvgm']}}, 08:48:53 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:48:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:48:53 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:48:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:48:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:48:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:48:53 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:48:53 INFO - 'talos_options': ['--disable-e10s'], 08:48:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:48:53 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:48:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:48:53 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:48:53 INFO - 'talos_options': ['--disable-e10s'], 08:48:53 INFO - 'tests': ['damp', 'tps']}, 08:48:53 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:48:53 INFO - 'tests': ['damp', 'tps']}, 08:48:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:48:53 INFO - 'tests': ['dromaeo_dom']}, 08:48:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:48:53 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:48:53 INFO - 'tests': ['basic_compositor_video']}, 08:48:53 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:48:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:48:53 INFO - 'tests': ['a11yr', 08:48:53 INFO - 'ts_paint', 08:48:53 INFO - 'tpaint', 08:48:53 INFO - 'sessionrestore', 08:48:53 INFO - 'sessionrestore_no_auto_restore', 08:48:53 INFO - 'tabpaint']}, 08:48:53 INFO - 'other-e10s': {'tests': ['a11yr', 08:48:53 INFO - 'ts_paint', 08:48:53 INFO - 'tpaint', 08:48:53 INFO - 'sessionrestore', 08:48:53 INFO - 'sessionrestore_no_auto_restore', 08:48:53 INFO - 'tabpaint']}, 08:48:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:48:53 INFO - 'ts_paint', 08:48:53 INFO - 'tpaint', 08:48:53 INFO - 'sessionrestore', 08:48:53 INFO - 'sessionrestore_no_auto_restore', 08:48:53 INFO - 'tabpaint']}, 08:48:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:48:53 INFO - 'ts_paint', 08:48:53 INFO - 'tpaint', 08:48:53 INFO - 'sessionrestore', 08:48:53 INFO - 'sessionrestore_no_auto_restore', 08:48:53 INFO - 'tabpaint']}, 08:48:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:48:53 INFO - 'tests': ['a11yr', 08:48:53 INFO - 'ts_paint', 08:48:53 INFO - 'tpaint', 08:48:53 INFO - 'sessionrestore', 08:48:53 INFO - 'sessionrestore_no_auto_restore', 08:48:53 INFO - 'tabpaint']}, 08:48:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:48:53 INFO - 'tests': ['a11yr', 08:48:53 INFO - 'ts_paint', 08:48:53 INFO - 'tpaint', 08:48:53 INFO - 'sessionrestore', 08:48:53 INFO - 'sessionrestore_no_auto_restore', 08:48:53 INFO - 'tabpaint']}, 08:48:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:48:53 INFO - 'tests': ['tsvgx', 08:48:53 INFO - 'tsvgr_opacity', 08:48:53 INFO - 'tart', 08:48:53 INFO - 'tscrollx', 08:48:53 INFO - 'cart']}, 08:48:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:48:53 INFO - 'tsvgr_opacity', 08:48:53 INFO - 'tart', 08:48:53 INFO - 'tscrollx', 08:48:53 INFO - 'cart']}, 08:48:53 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:48:53 INFO - 'talos_options': ['--disable-e10s'], 08:48:53 INFO - 'tests': ['tp5o']}, 08:48:53 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:48:53 INFO - 'tests': ['tp5o']}, 08:48:53 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:48:53 INFO - 'talos_options': ['--disable-e10s', 08:48:53 INFO - '--xperf_path', 08:48:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:48:53 INFO - 'tests': ['tp5n']}, 08:48:53 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:48:53 INFO - 'talos_options': ['--xperf_path', 08:48:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:48:53 INFO - 'tests': ['tp5n']}}, 08:48:53 INFO - 'talos.zip': {'path': '', 08:48:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:48:53 INFO - Running post-action listener: _resource_record_post_action 08:48:53 INFO - [mozharness: 2016-09-01 15:48:53.457866Z] Finished populate-webroot step (success) 08:48:53 INFO - [mozharness: 2016-09-01 15:48:53.457956Z] Running create-virtualenv step. 08:48:53 INFO - Running pre-action listener: _resource_record_pre_action 08:48:53 INFO - Running main action method: create_virtualenv 08:48:53 INFO - Creating virtualenv /builds/slave/test/build/venv 08:48:53 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 08:48:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:48:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:48:53 INFO - Using real prefix '/usr' 08:48:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:48:54 INFO - Installing distribute.............................................................................................................................................................................................done. 08:48:56 INFO - Installing pip.................done. 08:48:56 INFO - Return code: 0 08:48:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:48:56 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')]} 08:48:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:48:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:56 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')]} 08:48:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:48:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:56 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 0x7fc871243f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a548a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b23230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b1d090>, '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 0x2b209b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b20e40>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1472744913.429106-331339192', '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 08:48:56 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 08:48:56 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 08:48:56 INFO - Using env: {'DISPLAY': ':0', 08:48:56 INFO - 'HOME': '/home/cltbld', 08:48:56 INFO - 'LANG': 'en_US.UTF-8', 08:48:56 INFO - 'LANGUAGE': 'en_US:en', 08:48:56 INFO - 'LOGNAME': 'cltbld', 08:48:56 INFO - 'MAIL': '/var/mail/cltbld', 08:48:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:48:56 INFO - 'MOZ_NO_REMOTE': '1', 08:48:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:48:56 INFO - 'NO_EM_RESTART': '1', 08:48:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:48:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:48:56 INFO - 'PWD': '/builds/slave/test', 08:48:56 INFO - 'SHELL': '/bin/bash', 08:48:56 INFO - 'SHLVL': '1', 08:48:56 INFO - 'TERM': 'linux', 08:48:56 INFO - 'TMOUT': '86400', 08:48:56 INFO - 'USER': 'cltbld', 08:48:56 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1472744913.429106-331339192', 08:48:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:48:56 INFO - '_': '/tools/buildbot/bin/python'} 08:48:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:48:56 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 08:48:56 INFO - Cleaning up... 08:48:56 INFO - Return code: 0 08:48:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:48:56 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')]} 08:48:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:48:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:56 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')]} 08:48:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:48:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:56 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 0x7fc871243f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a548a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b23230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b1d090>, '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 0x2b209b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b20e40>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1472744913.429106-331339192', '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 08:48:56 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 08:48:56 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 08:48:56 INFO - Using env: {'DISPLAY': ':0', 08:48:56 INFO - 'HOME': '/home/cltbld', 08:48:56 INFO - 'LANG': 'en_US.UTF-8', 08:48:56 INFO - 'LANGUAGE': 'en_US:en', 08:48:56 INFO - 'LOGNAME': 'cltbld', 08:48:56 INFO - 'MAIL': '/var/mail/cltbld', 08:48:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:48:56 INFO - 'MOZ_NO_REMOTE': '1', 08:48:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:48:56 INFO - 'NO_EM_RESTART': '1', 08:48:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:48:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:48:56 INFO - 'PWD': '/builds/slave/test', 08:48:56 INFO - 'SHELL': '/bin/bash', 08:48:56 INFO - 'SHLVL': '1', 08:48:56 INFO - 'TERM': 'linux', 08:48:56 INFO - 'TMOUT': '86400', 08:48:56 INFO - 'USER': 'cltbld', 08:48:56 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1472744913.429106-331339192', 08:48:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:48:56 INFO - '_': '/tools/buildbot/bin/python'} 08:48:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:48:56 INFO - Downloading/unpacking psutil>=3.1.1 08:48:56 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 08:48:56 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 08:48:56 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 08:48:56 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 08:48:56 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 08:48:56 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 08:48:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:48:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:48:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:48:58 INFO - Installing collected packages: psutil 08:48:58 INFO - Running setup.py install for psutil 08:48:58 INFO - building 'psutil._psutil_linux' extension 08:48:58 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 08:48:58 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 08:48:58 INFO - building 'psutil._psutil_posix' extension 08:48:58 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 08:48:58 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 08:48:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:48:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:48:58 INFO - Successfully installed psutil 08:48:58 INFO - Cleaning up... 08:48:59 INFO - Return code: 0 08:48:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:48:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:48:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:48:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:59 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 0x7fc871243f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a548a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b23230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b1d090>, '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 0x2b209b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b20e40>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1472744913.429106-331339192', '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 08:48:59 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 08:48:59 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 08:48:59 INFO - Using env: {'DISPLAY': ':0', 08:48:59 INFO - 'HOME': '/home/cltbld', 08:48:59 INFO - 'LANG': 'en_US.UTF-8', 08:48:59 INFO - 'LANGUAGE': 'en_US:en', 08:48:59 INFO - 'LOGNAME': 'cltbld', 08:48:59 INFO - 'MAIL': '/var/mail/cltbld', 08:48:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:48:59 INFO - 'MOZ_NO_REMOTE': '1', 08:48:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:48:59 INFO - 'NO_EM_RESTART': '1', 08:48:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:48:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:48:59 INFO - 'PWD': '/builds/slave/test', 08:48:59 INFO - 'SHELL': '/bin/bash', 08:48:59 INFO - 'SHLVL': '1', 08:48:59 INFO - 'TERM': 'linux', 08:48:59 INFO - 'TMOUT': '86400', 08:48:59 INFO - 'USER': 'cltbld', 08:48:59 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1472744913.429106-331339192', 08:48:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:48:59 INFO - '_': '/tools/buildbot/bin/python'} 08:48:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:48:59 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:48:59 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 08:48:59 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 08:48:59 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 08:48:59 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 08:48:59 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 08:48:59 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 08:49:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:49:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:49:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:49:01 INFO - Installing collected packages: mozsystemmonitor 08:49:01 INFO - Running setup.py install for mozsystemmonitor 08:49:01 INFO - Successfully installed mozsystemmonitor 08:49:01 INFO - Cleaning up... 08:49:01 INFO - Return code: 0 08:49:01 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:49: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')]} 08:49:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49: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')]} 08:49:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49: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', '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 0x7fc871243f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a548a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b23230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b1d090>, '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 0x2b209b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b20e40>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1472744913.429106-331339192', '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 08:49: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:49: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 jsonschema==2.5.1 08:49:01 INFO - Using env: {'DISPLAY': ':0', 08:49:01 INFO - 'HOME': '/home/cltbld', 08:49:01 INFO - 'LANG': 'en_US.UTF-8', 08:49:01 INFO - 'LANGUAGE': 'en_US:en', 08:49:01 INFO - 'LOGNAME': 'cltbld', 08:49:01 INFO - 'MAIL': '/var/mail/cltbld', 08:49:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:01 INFO - 'MOZ_NO_REMOTE': '1', 08:49:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:49:01 INFO - 'NO_EM_RESTART': '1', 08:49:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:49:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:01 INFO - 'PWD': '/builds/slave/test', 08:49:01 INFO - 'SHELL': '/bin/bash', 08:49:01 INFO - 'SHLVL': '1', 08:49:01 INFO - 'TERM': 'linux', 08:49:01 INFO - 'TMOUT': '86400', 08:49:01 INFO - 'USER': 'cltbld', 08:49:01 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1472744913.429106-331339192', 08:49:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:01 INFO - '_': '/tools/buildbot/bin/python'} 08:49:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:01 INFO - Downloading/unpacking jsonschema==2.5.1 08:49: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 08:49: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 08:49: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 08:49: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 08:49: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 08:49: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 08:49:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:49:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:49: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 08:49: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 08:49: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 08:49: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 08:49: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 08:49: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 08:49:03 INFO - Downloading functools32-3.2.3-2.tar.gz 08:49:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:49:03 INFO - warning: no files found matching '*.txt' 08:49:03 INFO - no previously-included directories found matching 'build' 08:49:03 INFO - no previously-included directories found matching 'dist' 08:49:03 INFO - no previously-included directories found matching '.git*' 08:49:03 INFO - Installing collected packages: jsonschema, functools32 08:49:03 INFO - Running setup.py install for functools32 08:49:03 INFO - warning: no files found matching '*.txt' 08:49:03 INFO - no previously-included directories found matching 'build' 08:49:03 INFO - no previously-included directories found matching 'dist' 08:49:03 INFO - no previously-included directories found matching '.git*' 08:49:03 INFO - Successfully installed jsonschema functools32 08:49:03 INFO - Cleaning up... 08:49:03 INFO - Return code: 0 08:49:03 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:49: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')]} 08:49:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49: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')]} 08:49:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49: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', '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 0x7fc871243f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a548a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b23230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b1d090>, '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 0x2b209b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b20e40>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1472744913.429106-331339192', '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 08:49: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:49: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 functools32==3.2.3-2 08:49:03 INFO - Using env: {'DISPLAY': ':0', 08:49:03 INFO - 'HOME': '/home/cltbld', 08:49:03 INFO - 'LANG': 'en_US.UTF-8', 08:49:03 INFO - 'LANGUAGE': 'en_US:en', 08:49:03 INFO - 'LOGNAME': 'cltbld', 08:49:03 INFO - 'MAIL': '/var/mail/cltbld', 08:49:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:03 INFO - 'MOZ_NO_REMOTE': '1', 08:49:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:49:03 INFO - 'NO_EM_RESTART': '1', 08:49:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:49:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:03 INFO - 'PWD': '/builds/slave/test', 08:49:03 INFO - 'SHELL': '/bin/bash', 08:49:03 INFO - 'SHLVL': '1', 08:49:03 INFO - 'TERM': 'linux', 08:49:03 INFO - 'TMOUT': '86400', 08:49:03 INFO - 'USER': 'cltbld', 08:49:03 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1472744913.429106-331339192', 08:49:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:03 INFO - '_': '/tools/buildbot/bin/python'} 08:49:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:04 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:49:04 INFO - Cleaning up... 08:49:04 INFO - Return code: 0 08:49:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:49:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fc871243f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a548a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b23230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b1d090>, '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 0x2b209b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b20e40>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1472744913.429106-331339192', '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 08:49:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:49:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:49:04 INFO - Using env: {'DISPLAY': ':0', 08:49:04 INFO - 'HOME': '/home/cltbld', 08:49:04 INFO - 'LANG': 'en_US.UTF-8', 08:49:04 INFO - 'LANGUAGE': 'en_US:en', 08:49:04 INFO - 'LOGNAME': 'cltbld', 08:49:04 INFO - 'MAIL': '/var/mail/cltbld', 08:49:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:04 INFO - 'MOZ_NO_REMOTE': '1', 08:49:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:49:04 INFO - 'NO_EM_RESTART': '1', 08:49:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:49:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:04 INFO - 'PWD': '/builds/slave/test', 08:49:04 INFO - 'SHELL': '/bin/bash', 08:49:04 INFO - 'SHLVL': '1', 08:49:04 INFO - 'TERM': 'linux', 08:49:04 INFO - 'TMOUT': '86400', 08:49:04 INFO - 'USER': 'cltbld', 08:49:04 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1472744913.429106-331339192', 08:49:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:04 INFO - '_': '/tools/buildbot/bin/python'} 08:49:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:04 INFO - Downloading/unpacking blobuploader==1.2.4 08:49:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:06 INFO - Downloading blobuploader-1.2.4.tar.gz 08:49:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:49:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:49: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 08:49: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 08:49: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 08:49: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 08:49: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 08:49: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 08:49:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:49:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:49: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 08:49: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 08:49: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 08:49: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 08:49: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 08:49: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 08:49:06 INFO - Downloading docopt-0.6.1.tar.gz 08:49:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:49:07 INFO - Installing collected packages: blobuploader, requests, docopt 08:49:07 INFO - Running setup.py install for blobuploader 08:49:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:49:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:49:07 INFO - Running setup.py install for requests 08:49:07 INFO - Running setup.py install for docopt 08:49:07 INFO - Successfully installed blobuploader requests docopt 08:49:07 INFO - Cleaning up... 08:49:07 INFO - Return code: 0 08:49:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:49:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:07 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 0x7fc871243f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a548a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b23230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b1d090>, '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 0x2b209b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b20e40>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1472744913.429106-331339192', '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 08:49:07 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 08:49:07 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 08:49:07 INFO - Using env: {'DISPLAY': ':0', 08:49:07 INFO - 'HOME': '/home/cltbld', 08:49:07 INFO - 'LANG': 'en_US.UTF-8', 08:49:07 INFO - 'LANGUAGE': 'en_US:en', 08:49:07 INFO - 'LOGNAME': 'cltbld', 08:49:07 INFO - 'MAIL': '/var/mail/cltbld', 08:49:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:07 INFO - 'MOZ_NO_REMOTE': '1', 08:49:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:49:07 INFO - 'NO_EM_RESTART': '1', 08:49:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:49:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:07 INFO - 'PWD': '/builds/slave/test', 08:49:07 INFO - 'SHELL': '/bin/bash', 08:49:07 INFO - 'SHLVL': '1', 08:49:07 INFO - 'TERM': 'linux', 08:49:07 INFO - 'TMOUT': '86400', 08:49:07 INFO - 'USER': 'cltbld', 08:49:07 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1472744913.429106-331339192', 08:49:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:07 INFO - '_': '/tools/buildbot/bin/python'} 08:49:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:49:07 INFO - Running setup.py (path:/tmp/pip-rpvNhA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:49:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:49:07 INFO - Running setup.py (path:/tmp/pip-JVN2mu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:49:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:49:07 INFO - Running setup.py (path:/tmp/pip-gO8nxu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:49:08 INFO - Running setup.py (path:/tmp/pip-xy7DM6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:49:08 INFO - Running setup.py (path:/tmp/pip-1_aZ2f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:49:08 INFO - Running setup.py (path:/tmp/pip-twm9Te-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:49:08 INFO - Running setup.py (path:/tmp/pip-tGqcnh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:49:08 INFO - Running setup.py (path:/tmp/pip-eFiUHO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:49:08 INFO - Running setup.py (path:/tmp/pip-udxUa6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:49:08 INFO - Running setup.py (path:/tmp/pip-ZWUW37-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:49:08 INFO - Running setup.py (path:/tmp/pip-WNPMVz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:49:08 INFO - Running setup.py (path:/tmp/pip-sfns3y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:49:08 INFO - Running setup.py (path:/tmp/pip-8vOImR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:49:08 INFO - Running setup.py (path:/tmp/pip-muEba4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:49:08 INFO - Running setup.py (path:/tmp/pip-HUW00V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:49:08 INFO - Running setup.py (path:/tmp/pip-tV7qfQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:49:08 INFO - Running setup.py (path:/tmp/pip-tiRIOF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:49:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:49:09 INFO - Running setup.py install for manifestparser 08:49:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:49:09 INFO - Running setup.py install for mozcrash 08:49:09 INFO - Running setup.py install for mozdebug 08:49:09 INFO - Running setup.py install for mozdevice 08:49:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:49:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:49:09 INFO - Running setup.py install for mozfile 08:49:09 INFO - Running setup.py install for mozhttpd 08:49:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:49:09 INFO - Running setup.py install for mozinfo 08:49:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:49:09 INFO - Running setup.py install for mozInstall 08:49:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:49:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:49:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:49:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:49:09 INFO - Running setup.py install for mozleak 08:49:09 INFO - Running setup.py install for mozlog 08:49:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:49:10 INFO - Running setup.py install for moznetwork 08:49:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:49:10 INFO - Running setup.py install for mozprocess 08:49:10 INFO - Running setup.py install for mozprofile 08:49:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:49:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:49:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:49:10 INFO - Running setup.py install for mozrunner 08:49:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:49:10 INFO - Running setup.py install for mozscreenshot 08:49:10 INFO - Running setup.py install for moztest 08:49:10 INFO - Running setup.py install for mozversion 08:49:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:49:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:49:10 INFO - Cleaning up... 08:49:10 INFO - Return code: 0 08:49:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:49:10 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')]} 08:49:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:10 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')]} 08:49:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:10 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 0x7fc871243f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a548a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b23230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b1d090>, '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 0x2b209b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b20e40>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1472744913.429106-331339192', '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 08:49:10 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 08:49:10 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 08:49:10 INFO - Using env: {'DISPLAY': ':0', 08:49:10 INFO - 'HOME': '/home/cltbld', 08:49:10 INFO - 'LANG': 'en_US.UTF-8', 08:49:10 INFO - 'LANGUAGE': 'en_US:en', 08:49:10 INFO - 'LOGNAME': 'cltbld', 08:49:10 INFO - 'MAIL': '/var/mail/cltbld', 08:49:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:10 INFO - 'MOZ_NO_REMOTE': '1', 08:49:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:49:10 INFO - 'NO_EM_RESTART': '1', 08:49:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:49:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:10 INFO - 'PWD': '/builds/slave/test', 08:49:10 INFO - 'SHELL': '/bin/bash', 08:49:10 INFO - 'SHLVL': '1', 08:49:10 INFO - 'TERM': 'linux', 08:49:10 INFO - 'TMOUT': '86400', 08:49:10 INFO - 'USER': 'cltbld', 08:49:10 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1472744913.429106-331339192', 08:49:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:10 INFO - '_': '/tools/buildbot/bin/python'} 08:49:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:49:11 INFO - Running setup.py (path:/tmp/pip-cBUqN9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:49:11 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)) 08:49:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:49:11 INFO - Running setup.py (path:/tmp/pip-VJz__p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:49:11 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)) 08:49:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:49:11 INFO - Running setup.py (path:/tmp/pip-0K0Sx2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:49:11 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)) 08:49:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:49:11 INFO - Running setup.py (path:/tmp/pip-AobQPd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:49:11 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)) 08:49:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:49:11 INFO - Running setup.py (path:/tmp/pip-hmrp5k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:49:11 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)) 08:49:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:49:11 INFO - Running setup.py (path:/tmp/pip-Epu0sB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:49:11 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)) 08:49:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:49:11 INFO - Running setup.py (path:/tmp/pip-CltN2Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:49:11 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)) 08:49:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:49:11 INFO - Running setup.py (path:/tmp/pip-cRRMKB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:49:11 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)) 08:49:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:49:11 INFO - Running setup.py (path:/tmp/pip-TWKhoD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:49:11 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)) 08:49:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:49:11 INFO - Running setup.py (path:/tmp/pip-qYyQmW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:49:11 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)) 08:49:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:49:11 INFO - Running setup.py (path:/tmp/pip-ZiIrzr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:49:11 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)) 08:49:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:49:11 INFO - Running setup.py (path:/tmp/pip-Hok7yB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:49:11 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)) 08:49:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:49:11 INFO - Running setup.py (path:/tmp/pip-M8Y5n3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:49:11 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)) 08:49:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:49:12 INFO - Running setup.py (path:/tmp/pip-8SzSLJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:49:12 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)) 08:49:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:49:12 INFO - Running setup.py (path:/tmp/pip-f0OU31-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:49:12 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)) 08:49:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:49:12 INFO - Running setup.py (path:/tmp/pip-AwN7GI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:49:12 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)) 08:49:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:49:12 INFO - Running setup.py (path:/tmp/pip-in9f72-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:49:12 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)) 08:49:12 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)) 08:49:12 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)) 08:49:12 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)) 08:49:12 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)) 08:49:12 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)) 08:49:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:49:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:14 INFO - Downloading blessings-1.6.tar.gz 08:49:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:49:14 INFO - Installing collected packages: blessings 08:49:14 INFO - Running setup.py install for blessings 08:49:14 INFO - Successfully installed blessings 08:49:14 INFO - Cleaning up... 08:49:14 INFO - Return code: 0 08:49:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:49:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:49:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:49:14 INFO - Reading from file tmpfile_stdout 08:49:14 INFO - Current package versions: 08:49:14 INFO - argparse == 1.2.1 08:49:14 INFO - blessings == 1.6 08:49:14 INFO - blobuploader == 1.2.4 08:49:14 INFO - docopt == 0.6.1 08:49:14 INFO - functools32 == 3.2.3-2 08:49:14 INFO - jsonschema == 2.5.1 08:49:14 INFO - manifestparser == 1.1 08:49:14 INFO - mozInstall == 1.12 08:49:14 INFO - mozcrash == 0.17 08:49:14 INFO - mozdebug == 0.1 08:49:14 INFO - mozdevice == 0.48 08:49:14 INFO - mozfile == 1.2 08:49:14 INFO - mozhttpd == 0.7 08:49:14 INFO - mozinfo == 0.9 08:49:14 INFO - mozleak == 0.1 08:49:14 INFO - mozlog == 3.3 08:49:14 INFO - moznetwork == 0.27 08:49:14 INFO - mozprocess == 0.23 08:49:14 INFO - mozprofile == 0.28 08:49:14 INFO - mozrunner == 6.12 08:49:14 INFO - mozscreenshot == 0.1 08:49:14 INFO - mozsystemmonitor == 0.3 08:49:14 INFO - moztest == 0.7 08:49:14 INFO - mozversion == 1.4 08:49:14 INFO - psutil == 3.1.1 08:49:14 INFO - requests == 1.2.3 08:49:14 INFO - wsgiref == 0.1.2 08:49:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:49:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:14 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 0x7fc871243f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a548a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b23230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b1d090>, '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 0x2b209b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b20e40>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1472744913.429106-331339192', '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 08:49:14 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 08:49:14 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 08:49:14 INFO - Using env: {'DISPLAY': ':0', 08:49:14 INFO - 'HOME': '/home/cltbld', 08:49:14 INFO - 'LANG': 'en_US.UTF-8', 08:49:14 INFO - 'LANGUAGE': 'en_US:en', 08:49:14 INFO - 'LOGNAME': 'cltbld', 08:49:14 INFO - 'MAIL': '/var/mail/cltbld', 08:49:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:14 INFO - 'MOZ_NO_REMOTE': '1', 08:49:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:49:14 INFO - 'NO_EM_RESTART': '1', 08:49:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:49:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:14 INFO - 'PWD': '/builds/slave/test', 08:49:14 INFO - 'SHELL': '/bin/bash', 08:49:14 INFO - 'SHLVL': '1', 08:49:14 INFO - 'TERM': 'linux', 08:49:14 INFO - 'TMOUT': '86400', 08:49:14 INFO - 'USER': 'cltbld', 08:49:14 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1472744913.429106-331339192', 08:49:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:14 INFO - '_': '/tools/buildbot/bin/python'} 08:49:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:14 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)) 08:49:14 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)) 08:49:14 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)) 08:49:14 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)) 08:49:14 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)) 08:49:14 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)) 08:49:14 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)) 08:49:14 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)) 08:49:14 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)) 08:49:14 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)) 08:49:14 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)) 08:49:14 INFO - Cleaning up... 08:49:14 INFO - Return code: 0 08:49:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:49:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:14 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 0x7fc871243f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a548a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b23230>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b1d090>, '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 0x2b209b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b20e40>, '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': '78329bfc0fd5309db0a6e0ac000001b4-1472744913.429106-331339192', '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 08:49:14 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 08:49:14 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 08:49:14 INFO - Using env: {'DISPLAY': ':0', 08:49:14 INFO - 'HOME': '/home/cltbld', 08:49:14 INFO - 'LANG': 'en_US.UTF-8', 08:49:14 INFO - 'LANGUAGE': 'en_US:en', 08:49:14 INFO - 'LOGNAME': 'cltbld', 08:49:14 INFO - 'MAIL': '/var/mail/cltbld', 08:49:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:14 INFO - 'MOZ_NO_REMOTE': '1', 08:49:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:49:14 INFO - 'NO_EM_RESTART': '1', 08:49:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:49:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:14 INFO - 'PWD': '/builds/slave/test', 08:49:14 INFO - 'SHELL': '/bin/bash', 08:49:14 INFO - 'SHLVL': '1', 08:49:14 INFO - 'TERM': 'linux', 08:49:14 INFO - 'TMOUT': '86400', 08:49:14 INFO - 'USER': 'cltbld', 08:49:14 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1472744913.429106-331339192', 08:49:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:14 INFO - '_': '/tools/buildbot/bin/python'} 08:49:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:15 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:49:15 INFO - Cleaning up... 08:49:15 INFO - Return code: 0 08:49:15 INFO - Running post-action listener: _resource_record_post_action 08:49:15 INFO - Running post-action listener: _start_resource_monitoring 08:49:15 INFO - Starting resource monitoring. 08:49:15 INFO - [mozharness: 2016-09-01 15:49:15.097268Z] Finished create-virtualenv step (success) 08:49:15 INFO - [mozharness: 2016-09-01 15:49:15.097864Z] Running install step. 08:49:15 INFO - Running pre-action listener: _resource_record_pre_action 08:49:15 INFO - Running main action method: install 08:49:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:49:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:49:15 INFO - Reading from file tmpfile_stdout 08:49:15 INFO - Detecting whether we're running mozinstall >=1.0... 08:49:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:49:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:49:15 INFO - Reading from file tmpfile_stdout 08:49:15 INFO - Output received: 08:49:15 INFO - Usage: mozinstall [options] installer 08:49:15 INFO - Options: 08:49:15 INFO - -h, --help show this help message and exit 08:49:15 INFO - -d DEST, --destination=DEST 08:49:15 INFO - Directory to install application into. [default: 08:49:15 INFO - "/builds/slave/test"] 08:49:15 INFO - --app=APP Application being installed. [default: firefox] 08:49:15 INFO - mkdir: /builds/slave/test/build/application 08:49:15 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'] 08:49:15 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 08:49:32 INFO - Reading from file tmpfile_stdout 08:49:32 INFO - Output received: 08:49:32 INFO - /builds/slave/test/build/application/firefox/firefox 08:49:32 INFO - Running post-action listener: _resource_record_post_action 08:49:32 INFO - [mozharness: 2016-09-01 15:49:32.320329Z] Finished install step (success) 08:49:32 INFO - [mozharness: 2016-09-01 15:49:32.320610Z] Running run-tests step. 08:49:32 INFO - Running pre-action listener: _resource_record_pre_action 08:49:32 INFO - Running main action method: run_tests 08:49:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:49:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:49:32 INFO - Python 2.7.3 08:49:32 INFO - Return code: 0 08:49:32 INFO - grabbing minidump binary from tooltool 08:49:32 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')]} 08:49:32 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 0x2b1d090>, '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 0x2b209b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b20e40>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:49:32 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 08:49:32 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 08:49:32 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 08:49:32 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:49:32 INFO - Return code: 0 08:49:32 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:49:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:49:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:49:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:49:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:49:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NwUwN-Q5SuiOdqmDtbfqXA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-019', '--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 08:49:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/NwUwN-Q5SuiOdqmDtbfqXA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-019 --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 08:49:32 INFO - Using env: {'DISPLAY': ':0', 08:49:32 INFO - 'HOME': '/home/cltbld', 08:49:32 INFO - 'LANG': 'en_US.UTF-8', 08:49:32 INFO - 'LANGUAGE': 'en_US:en', 08:49:32 INFO - 'LOGNAME': 'cltbld', 08:49:32 INFO - 'MAIL': '/var/mail/cltbld', 08:49:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:49:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:49:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:32 INFO - 'MOZ_NO_REMOTE': '1', 08:49:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:49:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:49:32 INFO - 'NO_EM_RESTART': '1', 08:49:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:49:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:32 INFO - 'PWD': '/builds/slave/test', 08:49:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:49:32 INFO - 'SHELL': '/bin/bash', 08:49:32 INFO - 'SHLVL': '1', 08:49:32 INFO - 'TERM': 'linux', 08:49:32 INFO - 'TMOUT': '86400', 08:49:32 INFO - 'USER': 'cltbld', 08:49:32 INFO - 'XDG_SESSION_COOKIE': '78329bfc0fd5309db0a6e0ac000001b4-1472744913.429106-331339192', 08:49:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:32 INFO - '_': '/tools/buildbot/bin/python'} 08:49:32 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NwUwN-Q5SuiOdqmDtbfqXA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-019', '--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 08:49:32 INFO - mozversion application_buildid: 20160901080621 08:49:32 INFO - mozversion application_changeset: aa1a7886af087c5c13cd087ac1c67dac7e553492 08:49:32 INFO - mozversion application_display_name: Nightly 08:49:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:49:32 INFO - mozversion application_name: Firefox 08:49:32 INFO - mozversion application_remotingname: firefox 08:49:32 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 08:49:32 INFO - mozversion application_vendor: Mozilla 08:49:32 INFO - mozversion application_version: 51.0a1 08:49:32 INFO - mozversion platform_buildid: 20160901080621 08:49:32 INFO - mozversion platform_changeset: aa1a7886af087c5c13cd087ac1c67dac7e553492 08:49:32 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 08:49:32 INFO - mozversion platform_version: 51.0a1 08:49:32 INFO - using testdate: 1472744972 08:49:32 INFO - actual date: 1472744972 08:49:32 INFO - starting webserver on 'localhost:51247' 08:49:32 INFO - SUITE-START | Running 2 tests 08:49:32 INFO - TEST-START | dromaeo_css 08:49:32 INFO - operating with platform_type : linux_ 08:49:32 INFO - Initialising browser for dromaeo_css test... 08:49:32 INFO - TEST-INFO | started process 28225 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzgPnBW/profile http://localhost:51247/getInfo.html) 08:49:37 INFO - PROCESS | 28225 | __metrics Screen width/height:1600/1200 08:49:37 INFO - PROCESS | 28225 | colorDepth:24 08:49:37 INFO - PROCESS | 28225 | Browser inner width/height: 1024/697 08:49:37 INFO - PROCESS | 28225 | __metrics 08:49:38 INFO - PROCESS | 28225 | 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" 08:49:38 INFO - PROCESS | 28225 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:49:38 INFO - PROCESS | 28225 | console.error: PushService: 08:49:38 INFO - PROCESS | 28225 | stateChangeProcessEnqueue: Error transitioning state 08:49:38 INFO - PROCESS | 28225 | UnknownError 08:49:39 INFO - TEST-INFO | 28225: exit 0 08:49:39 INFO - Browser initialized. 08:49:39 INFO - Running cycle 1/1 for dromaeo_css test... 08:49:39 INFO - TEST-INFO | started process 28308 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzgPnBW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:49:51 INFO - PROCESS | 28308 | RSS: Main: 181403648 08:49:51 INFO - PROCESS | 28308 | 08:50:42 INFO - PROCESS | 28308 | 1472745042912 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] 08:52:56 INFO - PROCESS | 28308 | Cycle 1(1): loaded http://localhost:51247/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51247/tests/dromaeo/cssquery-ext.html) 08:52:57 INFO - PROCESS | 28308 | RSS: Main: 270983168 08:52:57 INFO - PROCESS | 28308 | 08:55:59 INFO - PROCESS | 28308 | Cycle 1(1): loaded http://localhost:51247/tests/dromaeo/cssquery-ext.html (next: http://localhost:51247/tests/dromaeo/cssquery-jquery.html) 08:56:00 INFO - PROCESS | 28308 | RSS: Main: 272990208 08:56:00 INFO - PROCESS | 28308 | 08:59:02 INFO - PROCESS | 28308 | Cycle 1(1): loaded http://localhost:51247/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51247/tests/dromaeo/cssquery-mootools.html) 08:59:03 INFO - PROCESS | 28308 | RSS: Main: 214142976 08:59:03 INFO - PROCESS | 28308 | 09:02:05 INFO - PROCESS | 28308 | Cycle 1(1): loaded http://localhost:51247/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51247/tests/dromaeo/cssquery-prototype.html) 09:02:06 INFO - PROCESS | 28308 | RSS: Main: 217690112 09:02:06 INFO - PROCESS | 28308 | 09:05:09 INFO - PROCESS | 28308 | Cycle 1(1): loaded http://localhost:51247/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51247/tests/dromaeo/cssquery-yui.html) 09:05:10 INFO - PROCESS | 28308 | RSS: Main: 218365952 09:05:10 INFO - PROCESS | 28308 | 09:08:13 INFO - PROCESS | 28308 | Cycle 1(1): loaded http://localhost:51247/tests/dromaeo/cssquery-yui.html (next: http://localhost:51247/tests/dromaeo/cssquery-dojo.html) 09:08:13 INFO - PROCESS | 28308 | RSS: Main: 308121600 09:08:13 INFO - PROCESS | 28308 | 09:08:13 INFO - PROCESS | 28308 | __start_tp_report 09:08:13 INFO - PROCESS | 28308 | _x_x_mozilla_page_load 09:08:13 INFO - PROCESS | 28308 | _x_x_mozilla_page_load_details 09:08:13 INFO - PROCESS | 28308 | |i|pagename|runs| 09:08:13 INFO - PROCESS | 28308 | |0;dojo.html;2536.606825941979;2593.8314009589385;2607.2048025352274;2620.9737902620973;2627.1199148285323;4480.686351955364;4553.180427523044;4629.004763975745;4691.179043667362;4718.693284936477;4556.1571109344795;4621.722696638196;4627.421572303462;4644.117617652643;4682.976585117078;4200.180964711883;4218.557051509593;4290.54949230331;4350.608445239928;4411.779411029435;4305.806238719249;4338.891527711801;4358.150160718661;4383.276759334722;4388.363687265344;4155.7506549606815;4250.171216612762;4275;4285.721428107163;4285.828566857322;4219.725717828332;4234.047339348653;4245.766482843443;4275.337322714984;4278.486581610196;4262.1688770689725;4268.253055715269;4270.978645106786;4293.549177336397;4307.892302692427;4473.7315761054115;4562.657800664931;4631.907361852744;4652.55800698933;4698.459677137139;4877.707337559757;4899.069176856386;4900.093482705711;4900.681455705368;5010.72441015744;4968.527989840995;4996.650234483587;5025.572826309768;5033.395992480926;5033.84898453047;4519.683622146418;4609.055143695535;4651.976740116345;4663.48701642819;4733.124371991124;5075.771590278447;5089.541941225308;5098.796048158107;5104.489551044866;5162.9741851291255;4882.145624515695;4887.560119589254;4889.584385327287;4903.901921961612;4940.283658869444;4797.736124513115;4888.853334399973;4899.951000489949;4915.262710593368;4954.033963377179;4864.294677271847;4891.192953162759;4894.216925291937;4901.166801643658;4966.354373931365;4885.120678277873;4898.559129678346;4898.90202195954;4900.681455705368;4965.155923493015;4130.504339479282;4169.478815148106;4171.8957026074595;4209.726367786146;4223.113146239324;3647.5805282392334;3721.423179407196;3731.869384571527;3780.584135745066;3815.9236815264094;4266.168097221012;4282.850100246539;4292.334688123346;4314.050908800059;4348.369486424451;4979.053979743837;5032.446430892599;5034.219695947134;5036.32009678696;5101.234814777813;4140.3375459926265;4229.6404805591255;4272;4280.957190428118;4286.721363111388;4522.34426008227;4524.253498172765;4533.342665313511;4576.359309696645;4583.037562111994;4646.279826626946;4647.047355292157;4651.651126165619;4655.4180727409075;4703.8824029398165;4812.109759694467;4968.478309777422;4992.051510213193;5025.497450255091;5045.520675535819;4667.929981050219;4670.322803193586;4689.249720044776;4734.076855013377;4748.667593268577;4945.134601444661;4949.232868905326;4953.678010929278;4959;5012.573931215742;4843.4430040545785;4922.827701030446;4959.876003099807;5034.546890779848;5082.517160869711;4856.878578035577;4857.077155340474;4859.441164266013;4906.607471402351;4916.041371932439;4842.6368022397755;4855.223164293696;4859.101066302604;4859.222524396079;4921.015796840575;4574.428196475441;4701.764911754467;4733.739644319596;4753.429588449409;4759.9286010709175;4854.78153483087;4885.560299573055;4887.828925987574;4919.975400122977;4929.728864912464;4776.283557466408;4777.928331075106;4778.092162489116;4781.737004464788;4821.325014498043 09:08:13 INFO - PROCESS | 28308 | |1;ext.html;535.7455208775832;555.3890720207769;562.3673367461606;567.6423852972625;568.2981517825488;29305.706942930563;39281;40600;40651.18697626049;43569.0393932273;39765.60234397655;40220.597794022055;40231.59768402315;40320.193596128134;40420.19159616813;7133.500654954155;7585.203553626886;7731.845363092762;7735.071791385041;7738.380929525624;9736.464494452803;10000.449975251358;10011.048950349705;10324.070833624972;10375.16998640107;9689.467079310634;9804.411735295906;9808.21534277256;9811.215102791795;9812.116909478145;9146.954265228664;9214.493202873839;9289.442633441948;9290.16388525036;9327.393719408217;9743.561539730728;9804;9827.213822894151;9828.754281142958;9834.80330393388;37007.25985480302;37449.25101497982;37451.62548374536;37478.25391429542;37486;2848.1740295314335;2880.0783749200264;2997.925051873699;3036.1347016100526;3111.595492585972;28040;28174.718252817416;28446.28884277889;28589.71410285912;28933;3375.0718552398043;3406.625271220164;3407.5740532433447;3416.684576438089;3492;255249;257768;257977;261797;262607.9999999981;51813;51928.508936382954;51972.22041669378;52042;52256.47743522592;31695.20761980923;32643.510347344807;32691.673083268863;34475.482867757004;34523.65476345255;13217.801732974012;13273.269970151541;13284.269365185008;13285.202887826568;13288.601341959757;9356.625734970661;10428.061474467335;10667.51996160159;11195.2723073;11292.887071129348;17339.913300433418;17438.47142164379;17439.65120697579;17502.47492575225;17573.824261757476;6377.968110159512;6455.935440645628;6461.418472337512;6666.300038495985;6701.832454188587;777.9649915753805;845.7631863078348;872.376250980552;882.4440602420434;899.6401439424152;26328;26559.867200663874;27028.32429189286;27158.86420567924;27319.317017074733;750.2572453271331;771.7530390275058;780.1808101493409;817.9182081791773;937.634322614181;14145;14527.418903243963;14686.706265874624;14800.877397121329;14884.851151488563;399085.1509500958;402012;402245;402295;402962;48326.75836620794;48864.26703599449;48997.75501122543;49028.75485622549;49080.50919490831;1066.5520481397832;1137.391495549877;1172.3434876469205;1175.1656324010048;1183.3432444993111;26474.338141545846;27568.58647120335;27912.58131127994;28050.859745701142;28668.139955801358;39832.402513962894;40432.79783601063;40441.59558404496;40459.59540404558;40462.595374045886;249882;250027;250476;251546;522879;35014.82492587521;35676.64323356733;35768;35849;36186;38544.80727596344;38699;38809.80595097006;38930.61069389383;38945.41581876331;33220.833895830365;33435.83282083672;33527;33551.83224083864;33683.15792105217;30265.394692105594;31627.20931976627;31832;32150.83924580362;32599.348013039133;32580.185495362806;32770.344593107526;33284;33469.66530334666;33939;28952.131436056952;31230.531542026434;32613.510797338535;32867.50698739569;35533;24441.584384736387;24735.87632061828;24758;24936.37659058538;24997.750022499542 09:08:13 INFO - PROCESS | 28308 | |2;jquery.html;340.80717488789236;365.36426617685237;367.84550488794713;368.09815950920245;383.4822988964898;12730.236185828846;14998.325075371607;15098.773518397233;15286.464973725908;15346.232688365579;15526.922365388185;15985.760213596805;16097.517074487754;16258.83741162588;16573.17134143294;4656.697314674535;4667.719936803795;4722.50413706561;4728.929066064012;4813.422389313269;3431.519587257786;4700.882477938045;4763.214069678499;4771.355866957968;4771.403574553181;4986.526280003394;5250.474952504757;5251;5285.550728188108;5335.493128152819;5239.711815850127;5271.525562699357;5283.920741188886;5290.994711004916;5783.537317014629;5230.241614965847;5230.555402790767;5232.9738351308;5236.921446178272;5257.658252213632;11033.393163376091;11148.888511114867;11198.664040078811;13903.513377031755;17555.56111097233;1739.4781565530416;1825.9452216433529;1855.499015265884;1895.848332133426;1926.1043614719222;8833.469991800512;8862.291016718711;8901.465912045298;9322.25421966241;9546;1611.395726602524;1641.4255010746263;1683.7306031035098;1693.9745903811452;1758.7273972534279;50037;61789.38210617926;65470.67264663646;66373.3362666372;66885;7888.368930485547;7957.801054973672;8365.1216622255;8402.28580570646;9797.559109840075;10809.67570972872;10914.508847101899;10923.180761442924;10929.289596176382;10932.125429965583;6163.322034576193;6320.810375688737;6385.936140638534;6471.320511346241;6495.7076931538195;6160.322364539897;6299.244090709144;6401.359864013654;6402.551821372553;6555.737770489127;3459.2735525539415;3482.6343233960574;3502.3345564343203;3541.2386336937684;3549.6982756465472;1811.96717870815;1815.827496387841;1829.8627602929566;1854.5827188882392;1864.4779461750732;819.1358117186426;828.1097819843571;837.1879277101202;846.8010017645848;932.1703683721494;15599.554127073956;15994;16002.279897404645;16876.409325673787;16930.661386772197;811.5495899775681;833.0752864320599;839.6263662670175;852.2585350744891;909.7452713240303;8992.775180620405;9306.488143152192;9534.28492863038;9662.3719458235;9821.656242031495;348905;359104;359485;361595;367674;46012.53987460083;57683.71158144182;57968.420315796306;66768.33231667719;68213.31786682169;864.148813418782;870.7039606533809;873.816498535302;894.6510860764307;899.2670973156856;15129;15196.848031519985;15760;15982.440614578434;16075.356985720908;35237.47143792794;35693;36095;36276.63723362733;36416.81791591025;45903.3114503276;46070;46680.7665961668;46968;49400;7313;7445;7641.044869391326;7666.731664391719;7722.806929826799;10787.298825576312;10881.292715973437;11118.722031948942;11126.944365278121;11670.474828632561;9574.377665451724;9619.951900240454;9802.852957205505;10116.027374662164;10532.631357902437;9615.615375384907;10180.287379883337;10213.438260895722;10344.948275258575;10379.792404152025;9760.853587196056;9959.551820167964;10189.54147063369;10422.791544169226;10622.73443163927;7174.784756457315;7217.927820721936;7465.664045117874;7543.321101100927;7545.943567900608;9224.215941645036;9730.562124704264;10048.296619236584;10484.895151048697;10582 09:08:13 INFO - PROCESS | 28308 | |3;mootools.html;1157.3923690062716;1307.6796184934688;1352.3914238592636;1646.9752953705688;1656.246407884412;7254.8911766323545;7626.65680044398;7638.350740187091;7709.884351734729;7710.1518832928405;6681.331866813316;7350.77947661571;7520.39836813055;7574.469787114904;7615.314621684048;2185.945351366213;2199.6590528468114;2203.4271089516683;2209.7016902718087;2212.811910987568;2110.7783682713325;2168.4253672776726;2192.068370942351;2194.133317339658;2195.2865318771383;2129.936101916945;2133.445304220906;2147.9248226312084;2152.461884528868;2154.1168121070364;2108.33587419963;2111.461577297779;2116.396826904325;2116.703661487393;2117.1531387444993;2115.1433669363932;2140.6146893559153;2140.860844045132;2152.7093842331396;2166.1335465813645;7448.627568621548;7538.283863033003;7559.848803023964;7563.886541701879;7585.051868516435;4338.132373525276;4470;4474.888127796831;4482.753448560328;4504.527024662429;2846.9145925622265;2872.8707208175683;2943.1611990582796;2962.955555666667;2966.480865848468;893.3166127912152;942.4109931292942;957.9808403831885;963.2919803944095;977.1108291454742;10174.86327871148;10177.440240786755;10303.845442318372;10360.740981475448;10394.376337419704;3917.5494818095713;4083.1221287423346;4244.787760611957;4267.637250833652;4279.251131052053;8783.472991620523;8784.868226976601;8786.63807109899;8802.427842190236;8842.690505832265;4454.688171827941;4469.08383781324;4471;4495.775211239425;4497.168023915586;3503.404421248394;3970.7617542947514;4065.6137666921595;4106.219818234586;4120.587941205916;3422.863085476603;3430.811305378178;3482.495038219444;3559.857605695743;3565.554305711786;1155.4453862146217;1160.8839116088322;1166.0787977497682;1168.0830548019765;1190.0836356005827;620.43230444144;630.6909614288966;671.6742379945722;707.4800021983838;712.9251428600026;4914.70511769295;4971.651984361132;5000.6999580024585;5096.490350964948;5245.580353571705;365.6909911125095;383.17999481110445;412.4143715923336;422.9281022226229;426.8292682926854;3395.7453191010773;3435.5362026126654;3477.200243943907;3480.390931587013;3662.157703728104;8649.351298652626;9064.184223419792;9082.86375704365;9099.1810737034;9136.406133601295;9046.728598142066;9054.094590540893;9058.637654493878;9069.18377346042;9073.319501037317;6949.478789090838;6991.16106067265;6993.335633114846;7016.438684905195;7029;4844.4186697596515;4902.901941961212;4969.751512424292;4999.500049994971;5154.639175257696;8341;8375.790605234917;8380.874286885579;8430.662773489237;8440.704575339834;8444;8556.614952327285;8579.699710510102;8602.268807151211;8647.567621618768;5571.387147413862;5896.970515147397;5954.255718035246;6093.146959425772;6307.400796924285;7127.928720712726;7192.172900116344;7198.5320954137815;7253.854922901407;7260.854782904206;6273.309935906956;6416.197975253093;6506.121673574007;6577.375149360803;6664.333566643297;6302.023186406114;6306.716197771203;6348.079528468438;6349.460295874902;6381.744730210739;5999.70001499932;6104;6105.145279660762;6115.694215289307;6117.082437634391;4259.212045771542;4312.978435107804;4396.1867054595;4496.505384407778;4527.2303708369;4306.720063195882;4436.778161091997;4509.954900450954;4557.931631025471;4629.375034370317 09:08:13 INFO - PROCESS | 28308 | |4;prototype.html;275.20191444810047;284.1135656750937;294.85257371314344;304.932914758753;308.66201509347286;4781.593564547014;4782.163121453749;4786.856394308167;4787.329773831662;4843.588294994921;4681.531846815316;4714.740689262089;4772.379590653219;4774.045190961813;4791.760411979396;3461.4115600348;3599.262151258986;3614.5843228028853;3616.8191590420506;3638.8362523686496;3568.92862142757;3681.263747250547;3701.7223708221854;3715.2755212733528;3741.625837416264;3667.3582123128344;3672.981635091821;3675.2833197526493;3681.7606855554304;3684.6315368463206;3581.4448760442174;3685.207680348738;3687.6128006559193;3689.797061161635;3703.5926048134684;3241.3031198292247;3241.4975678769592;3247.9188020299443;3253.4957081652146;3265.363254165439;4539.978504836418;4571.451425828922;4590.288505281686;4600.194965880991;4632.8146874125;4181.289180839234;4184.91630167398;4188.099558594886;4191.559886211934;4203.453551038376;4795.448523419816;4922.680025798311;4934.876628084291;4945.615227736203;4959.776810043557;3531.876384326536;3543.8936831895085;3543.929121417583;3548.7515873888838;3549.2014296783277;5154.690718556899;5160.407237558576;5168.8449346519665;5172.922406163911;5181.792728290864;3480.8781692640637;3631.2374401376005;3635.5092062571744;3637.9636203637624;3640.7633503822167;3645.216278500135;3675.1547144156993;3676.5918323498154;3678.6756767563656;3698.167912219783;4163.8959026024595;4171.374293855946;4178.644815190682;4180.602842729936;4187.706860519796;4882.926756098661;4906.95093049065;4945.184044632595;4947.505249475023;4975.353204083446;1190.33936165429;1249.4877100388796;1255.2217625072512;1262.6843289177705;1267.8732126787247;791.5488171742167;801.1867954026668;815.649270813544;819.9139090395541;822.7658512231652;3922.5096862892137;4182.56083111275;4300.741955482681;4401.801918913657;4402.383666286659;5125.820596279113;5133.512316329943;5152.819651312261;5176.696370115263;5183.637145399785;4416.911661766747;4419.18245124653;4426.26966550522;4441.822327106945;4444.42223010833;2624.8054591164837;2696.245051385615;2713.2228390404316;2726.6319046928807;2729.6178535005115;4772.141014617402;4782.665213435026;4792.832250871203;4799.83211080685;4814.8925747078365;5221.295125158131;5241.31862857826;5241.68549887008;5241.816536421207;5245.186996015624;4540.614047805973;4783.971446139097;4811.195801574409;4830.734309613005;4837.419509658864;5142.328743158563;5146.099432599355;5168.3539557555305;5180.715060671549;5192.844969337682;5081.72050537224;5099.413567439792;5101.474769988879;5102.132637451717;5108.897822043464;4602.585767280961;4774.355462012584;4781.832635857728;4797.592204662643;4810.39870016248;3651.7808931464197;3652.269546090846;3685.870994515179;3689.963100368962;3695.8336874841234;5152.484751524968;5163.380394352702;5177.265616018525;5180.585553155814;5188.066148093229;4780.665353425227;5021.012497563944;5037.319961805109;5041.6722913010535;5055.898882022266;5004.823866391323;5033.723145226902;5036.169032109661;5039.143345631178;5041.143005689115;4954.77703503336;4967.627427942846;4975.726335051607;4977.776000080077;4978.228374601942;4848.200046992348;4872.537108974641;5049.520295571915;5053.974730126326;5069.087564238473;4799.760011999456;4919.778609962489;4957.67835550175;4962.379702537183;4976.427710813158 09:08:13 INFO - PROCESS | 28308 | |5;yui.html;583.3641330949265;617.9505639548837;641.3746597067857;646.6120327803319;649.7043845050501;11801.70495737607;12623.179493332927;12653.936730316347;12662.303573303465;12928.418221180045;12401.751964960718;12446.128770986012;12450.128491005633;12462.813057804118;12464.875351246485;606.7815586388897;734.8493558820443;752.4206361101948;755.6146365353654;756.6216891554222;729.8321386081204;764.4228607401404;764.7782143178472;766.2529034191674;768.4121646940096;801.7995501124719;802.4543310548854;803.441608082383;803.8070862992898;804.1034246814818;625.5027253333566;801.9117897031322;802.3060053054095;802.450321529751;802.7350974178507;355.24864910713654;363.15566308332967;363.40401741144547;364.8558819266375;365.56132640830845;11694.70763230926;11707.765844683145;11734.823977640342;11736.178467507278;11738.941305293505;11738.823917641243;12909.935450322782;12924.935375323157;13260.867391326059;13502.932485337511;11782.646520604396;11881.168318217728;11986.340751258764;12206.450709718085;12822.038347124004;3950.3679411294056;3955.0903292242656;4108.50697916249;4183.5398106208595;4184.727992680497;62193.02035687836;62424.687876560325;62655.37344626541;62719.6864015677;62761;10325.432101234504;10439.791204176026;10455.94772026135;10457.320274182306;10561.821126614059;17682.29270829178;17690.115494225236;17696;17710.734338984927;17729.46811595654;6389;6491.285958544557;6530.085787989685;6760.290169532227;6761.526693131532;3546.3261980223674;3552.520409744703;3554.644535546425;3665.193657395422;3673.063368840982;2709.8509581972785;2795.706450822675;2809.606655068292;2814.690384057752;2816.493031254353;474.8836535048935;479.549223729693;482.57533370633615;493.19609037269737;498.12081675842165;478.7079881272421;498.47410981414953;517.9020476589579;521.5462547583627;521.8757876321366;7417.109946806418;7698.461107722405;7736.613169341623;7844.646990885424;8085.070216925129;518.9161300564581;534.6685055265682;536.4206656810635;542.0568211312288;550.6925980143187;3527.624226551647;3601.1537288737127;3747.2880152770886;3765.9058523537133;3848.9037774055873;94457;103530.48234758778;104981.47509262405;105919.4704026475;106661;59410;88913.55543222242;92156;92926.07073929309;93292;76662;80525.59737201277;81288.59355703302;81331;81345;7066.893996590158;7295.306945840137;7296.817579560554;7387.741429049957;7455.888161677471;45221;46149.30776038429;46337.53662463332;46490.535094649975;47347.52652473431;62689.68655156695;62952.37047629465;69054.65472672605;71134;71383;8798.912010879809;8864.73405797827;9025.368224224241;9027.052159523155;9777.560009799434;10347.534360953625;10374.844377334195;10667.199960003187;10788.892111078789;11100.105979418471;9710.320277580784;9734.172595329193;10074.899251007397;10157.746056348651;10492.055714985689;9258.490783006999;9424.246060315021;9867.901320986986;10159.84760228612;10174.084332410119;9099.363044586815;9507.286953478379;9528.571214295513;9765.560549775135;9970.601175952881;8043.638036288498;8200.507969521848;8328.500289982621;8358.122397148456;8468.407211495136;6879.793606191823;7344.30229128232;7421.2949769771785;7494.5128566643;8149.388795840454 09:08:13 INFO - PROCESS | 28308 | __end_tp_report 09:08:13 INFO - PROCESS | 28308 | __start_cc_report 09:08:13 INFO - PROCESS | 28308 | _x_x_mozilla_cycle_collect,884 09:08:13 INFO - PROCESS | 28308 | __end_cc_report 09:08:13 INFO - PROCESS | 28308 | __startTimestamp1472746093331__endTimestamp 09:08:13 INFO - PROCESS | 28308 | 09:08:13 INFO - PROCESS | 28308 | ------- Summary: start ------- 09:08:13 INFO - PROCESS | 28308 | Number of tests: 6 09:08:13 INFO - PROCESS | 28308 | 09:08:13 INFO - PROCESS | 28308 | [#0] dojo.html Cycles:165 Average:4593.78 Median:4703.88 stddev:478.33 (10.2%) stddev-sans-first:451.76 09:08:13 INFO - PROCESS | 28308 | Values: 2536.6 2593.8 2607.2 2621.0 2627.1 4480.7 4553.2 4629.0 4691.2 4718.7 4556.2 4621.7 4627.4 4644.1 4683.0 4200.2 4218.6 4290.5 4350.6 4411.8 4305.8 4338.9 4358.2 4383.3 4388.4 4155.8 4250.2 4275.0 4285.7 4285.8 4219.7 4234.0 4245.8 4275.3 4278.5 4262.2 4268.3 4271.0 4293.5 4307.9 4473.7 4562.7 4631.9 4652.6 4698.5 4877.7 4899.1 4900.1 4900.7 5010.7 4968.5 4996.7 5025.6 5033.4 5033.8 4519.7 4609.1 4652.0 4663.5 4733.1 5075.8 5089.5 5098.8 5104.5 5163.0 4882.1 4887.6 4889.6 4903.9 4940.3 4797.7 4888.9 4900.0 4915.3 4954.0 4864.3 4891.2 4894.2 4901.2 4966.4 4885.1 4898.6 4898.9 4900.7 4965.2 4130.5 4169.5 4171.9 4209.7 4223.1 3647.6 3721.4 3731.9 3780.6 3815.9 4266.2 4282.9 4292.3 4314.1 4348.4 4979.1 5032.4 5034.2 5036.3 5101.2 4140.3 4229.6 4272.0 4281.0 4286.7 4522.3 4524.3 4533.3 4576.4 4583.0 4646.3 4647.0 4651.7 4655.4 4703.9 4812.1 4968.5 4992.1 5025.5 5045.5 4667.9 4670.3 4689.2 4734.1 4748.7 4945.1 4949.2 4953.7 4959.0 5012.6 4843.4 4922.8 4959.9 5034.5 5082.5 4856.9 4857.1 4859.4 4906.6 4916.0 4842.6 4855.2 4859.1 4859.2 4921.0 4574.4 4701.8 4733.7 4753.4 4759.9 4854.8 4885.6 4887.8 4920.0 4929.7 4776.3 4777.9 4778.1 4781.7 4821.3 09:08:13 INFO - PROCESS | 28308 | 09:08:13 INFO - PROCESS | 28308 | [#1] ext.html Cycles:180 Average:46891.23 Median:19007.83 stddev:88099.11 (463.5%) stddev-sans-first:88277.50 09:08:13 INFO - PROCESS | 28308 | Values: 535.7 555.4 562.4 567.6 568.3 29305.7 39281.0 40600.0 40651.2 43569.0 39765.6 40220.6 40231.6 40320.2 40420.2 7133.5 7585.2 7731.8 7735.1 7738.4 9736.5 10000.4 10011.0 10324.1 10375.2 9689.5 9804.4 9808.2 9811.2 9812.1 9147.0 9214.5 9289.4 9290.2 9327.4 9743.6 9804.0 9827.2 9828.8 9834.8 37007.3 37449.3 37451.6 37478.3 37486.0 2848.2 2880.1 2997.9 3036.1 3111.6 28040.0 28174.7 28446.3 28589.7 28933.0 3375.1 3406.6 3407.6 3416.7 3492.0 255249.0 257768.0 257977.0 261797.0 262608.0 51813.0 51928.5 51972.2 52042.0 52256.5 31695.2 32643.5 32691.7 34475.5 34523.7 13217.8 13273.3 13284.3 13285.2 13288.6 9356.6 10428.1 10667.5 11195.3 11292.9 17339.9 17438.5 17439.7 17502.5 17573.8 6378.0 6455.9 6461.4 6666.3 6701.8 778.0 845.8 872.4 882.4 899.6 26328.0 26559.9 27028.3 27158.9 27319.3 750.3 771.8 780.2 817.9 937.6 14145.0 14527.4 14686.7 14800.9 14884.9 399085.2 402012.0 402245.0 402295.0 402962.0 48326.8 48864.3 48997.8 49028.8 49080.5 1066.6 1137.4 1172.3 1175.2 1183.3 26474.3 27568.6 27912.6 28050.9 28668.1 39832.4 40432.8 40441.6 40459.6 40462.6 249882.0 250027.0 250476.0 251546.0 522879.0 35014.8 35676.6 35768.0 35849.0 36186.0 38544.8 38699.0 38809.8 38930.6 38945.4 33220.8 33435.8 33527.0 33551.8 33683.2 30265.4 31627.2 31832.0 32150.8 32599.3 32580.2 32770.3 33284.0 33469.7 33939.0 28952.1 31230.5 32613.5 32867.5 35533.0 24441.6 24735.9 24758.0 24936.4 24997.8 09:08:13 INFO - PROCESS | 28308 | 09:08:13 INFO - PROCESS | 28308 | [#2] jquery.html Cycles:180 Average:22035.32 Median:4711.69 stddev:59106.93 (1254.5%) stddev-sans-first:59250.29 09:08:13 INFO - PROCESS | 28308 | Values: 340.8 365.4 367.8 368.1 383.5 12730.2 14998.3 15098.8 15286.5 15346.2 15526.9 15985.8 16097.5 16258.8 16573.2 4656.7 4667.7 4722.5 4728.9 4813.4 3431.5 4700.9 4763.2 4771.4 4771.4 4986.5 5250.5 5251.0 5285.6 5335.5 5239.7 5271.5 5283.9 5291.0 5783.5 5230.2 5230.6 5233.0 5236.9 5257.7 11033.4 11148.9 11198.7 13903.5 17555.6 1739.5 1825.9 1855.5 1895.8 1926.1 8833.5 8862.3 8901.5 9322.3 9546.0 1611.4 1641.4 1683.7 1694.0 1758.7 50037.0 61789.4 65470.7 66373.3 66885.0 7888.4 7957.8 8365.1 8402.3 9797.6 10809.7 10914.5 10923.2 10929.3 10932.1 6163.3 6320.8 6385.9 6471.3 6495.7 6160.3 6299.2 6401.4 6402.6 6555.7 3459.3 3482.6 3502.3 3541.2 3549.7 1812.0 1815.8 1829.9 1854.6 1864.5 819.1 828.1 837.2 846.8 932.2 15599.6 15994.0 16002.3 16876.4 16930.7 811.5 833.1 839.6 852.3 909.7 8992.8 9306.5 9534.3 9662.4 9821.7 348905.0 359104.0 359485.0 361595.0 367674.0 46012.5 57683.7 57968.4 66768.3 68213.3 864.1 870.7 873.8 894.7 899.3 15129.0 15196.8 15760.0 15982.4 16075.4 35237.5 35693.0 36095.0 36276.6 36416.8 45903.3 46070.0 46680.8 46968.0 49400.0 7313.0 7445.0 7641.0 7666.7 7722.8 10787.3 10881.3 11118.7 11126.9 11670.5 9574.4 9620.0 9802.9 10116.0 10532.6 9615.6 10180.3 10213.4 10344.9 10379.8 9760.9 9959.6 10189.5 10422.8 10622.7 7174.8 7217.9 7465.7 7543.3 7545.9 9224.2 9730.6 10048.3 10484.9 10582.0 09:08:13 INFO - PROCESS | 28308 | 09:08:13 INFO - PROCESS | 28308 | [#3] mootools.html Cycles:180 Average:4913.92 Median:4873.66 stddev:2741.67 (56.3%) stddev-sans-first:2734.82 09:08:13 INFO - PROCESS | 28308 | Values: 1157.4 1307.7 1352.4 1647.0 1656.2 7254.9 7626.7 7638.4 7709.9 7710.2 6681.3 7350.8 7520.4 7574.5 7615.3 2185.9 2199.7 2203.4 2209.7 2212.8 2110.8 2168.4 2192.1 2194.1 2195.3 2129.9 2133.4 2147.9 2152.5 2154.1 2108.3 2111.5 2116.4 2116.7 2117.2 2115.1 2140.6 2140.9 2152.7 2166.1 7448.6 7538.3 7559.8 7563.9 7585.1 4338.1 4470.0 4474.9 4482.8 4504.5 2846.9 2872.9 2943.2 2963.0 2966.5 893.3 942.4 958.0 963.3 977.1 10174.9 10177.4 10303.8 10360.7 10394.4 3917.5 4083.1 4244.8 4267.6 4279.3 8783.5 8784.9 8786.6 8802.4 8842.7 4454.7 4469.1 4471.0 4495.8 4497.2 3503.4 3970.8 4065.6 4106.2 4120.6 3422.9 3430.8 3482.5 3559.9 3565.6 1155.4 1160.9 1166.1 1168.1 1190.1 620.4 630.7 671.7 707.5 712.9 4914.7 4971.7 5000.7 5096.5 5245.6 365.7 383.2 412.4 422.9 426.8 3395.7 3435.5 3477.2 3480.4 3662.2 8649.4 9064.2 9082.9 9099.2 9136.4 9046.7 9054.1 9058.6 9069.2 9073.3 6949.5 6991.2 6993.3 7016.4 7029.0 4844.4 4902.9 4969.8 4999.5 5154.6 8341.0 8375.8 8380.9 8430.7 8440.7 8444.0 8556.6 8579.7 8602.3 8647.6 5571.4 5897.0 5954.3 6093.1 6307.4 7127.9 7192.2 7198.5 7253.9 7260.9 6273.3 6416.2 6506.1 6577.4 6664.3 6302.0 6306.7 6348.1 6349.5 6381.7 5999.7 6104.0 6105.1 6115.7 6117.1 4259.2 4313.0 4396.2 4496.5 4527.2 4306.7 4436.8 4510.0 4557.9 4629.4 09:08:13 INFO - PROCESS | 28308 | 09:08:13 INFO - PROCESS | 28308 | [#4] prototype.html Cycles:180 Average:4128.23 Median:4773.21 stddev:1212.28 (25.4%) stddev-sans-first:1180.68 09:08:13 INFO - PROCESS | 28308 | Values: 275.2 284.1 294.9 304.9 308.7 4781.6 4782.2 4786.9 4787.3 4843.6 4681.5 4714.7 4772.4 4774.0 4791.8 3461.4 3599.3 3614.6 3616.8 3638.8 3568.9 3681.3 3701.7 3715.3 3741.6 3667.4 3673.0 3675.3 3681.8 3684.6 3581.4 3685.2 3687.6 3689.8 3703.6 3241.3 3241.5 3247.9 3253.5 3265.4 4540.0 4571.5 4590.3 4600.2 4632.8 4181.3 4184.9 4188.1 4191.6 4203.5 4795.4 4922.7 4934.9 4945.6 4959.8 3531.9 3543.9 3543.9 3548.8 3549.2 5154.7 5160.4 5168.8 5172.9 5181.8 3480.9 3631.2 3635.5 3638.0 3640.8 3645.2 3675.2 3676.6 3678.7 3698.2 4163.9 4171.4 4178.6 4180.6 4187.7 4882.9 4907.0 4945.2 4947.5 4975.4 1190.3 1249.5 1255.2 1262.7 1267.9 791.5 801.2 815.6 819.9 822.8 3922.5 4182.6 4300.7 4401.8 4402.4 5125.8 5133.5 5152.8 5176.7 5183.6 4416.9 4419.2 4426.3 4441.8 4444.4 2624.8 2696.2 2713.2 2726.6 2729.6 4772.1 4782.7 4792.8 4799.8 4814.9 5221.3 5241.3 5241.7 5241.8 5245.2 4540.6 4784.0 4811.2 4830.7 4837.4 5142.3 5146.1 5168.4 5180.7 5192.8 5081.7 5099.4 5101.5 5102.1 5108.9 4602.6 4774.4 4781.8 4797.6 4810.4 3651.8 3652.3 3685.9 3690.0 3695.8 5152.5 5163.4 5177.3 5180.6 5188.1 4780.7 5021.0 5037.3 5041.7 5055.9 5004.8 5033.7 5036.2 5039.1 5041.1 4954.8 4967.6 4975.7 4977.8 4978.2 4848.2 4872.5 5049.5 5054.0 5069.1 4799.8 4919.8 4957.7 4962.4 4976.4 09:08:13 INFO - PROCESS | 28308 | 09:08:13 INFO - PROCESS | 28308 | [#5] yui.html Cycles:180 Average:17808.06 Median:535.54 stddev:26822.22 (5008.4%) stddev-sans-first:26866.28 09:08:13 INFO - PROCESS | 28308 | Values: 583.4 618.0 641.4 646.6 649.7 11801.7 12623.2 12653.9 12662.3 12928.4 12401.8 12446.1 12450.1 12462.8 12464.9 606.8 734.8 752.4 755.6 756.6 729.8 764.4 764.8 766.3 768.4 801.8 802.5 803.4 803.8 804.1 625.5 801.9 802.3 802.5 802.7 355.2 363.2 363.4 364.9 365.6 11694.7 11707.8 11734.8 11736.2 11738.9 11738.8 12909.9 12924.9 13260.9 13502.9 11782.6 11881.2 11986.3 12206.5 12822.0 3950.4 3955.1 4108.5 4183.5 4184.7 62193.0 62424.7 62655.4 62719.7 62761.0 10325.4 10439.8 10455.9 10457.3 10561.8 17682.3 17690.1 17696.0 17710.7 17729.5 6389.0 6491.3 6530.1 6760.3 6761.5 3546.3 3552.5 3554.6 3665.2 3673.1 2709.9 2795.7 2809.6 2814.7 2816.5 474.9 479.5 482.6 493.2 498.1 478.7 498.5 517.9 521.5 521.9 7417.1 7698.5 7736.6 7844.6 8085.1 518.9 534.7 536.4 542.1 550.7 3527.6 3601.2 3747.3 3765.9 3848.9 94457.0 103530.5 104981.5 105919.5 106661.0 59410.0 88913.6 92156.0 92926.1 93292.0 76662.0 80525.6 81288.6 81331.0 81345.0 7066.9 7295.3 7296.8 7387.7 7455.9 45221.0 46149.3 46337.5 46490.5 47347.5 62689.7 62952.4 69054.7 71134.0 71383.0 8798.9 8864.7 9025.4 9027.1 9777.6 10347.5 10374.8 10667.2 10788.9 11100.1 9710.3 9734.2 10074.9 10157.7 10492.1 9258.5 9424.2 9867.9 10159.8 10174.1 9099.4 9507.3 9528.6 9765.6 9970.6 8043.6 8200.5 8328.5 8358.1 8468.4 6879.8 7344.3 7421.3 7494.5 8149.4 09:08:13 INFO - PROCESS | 28308 | -------- Summary: end -------- 09:08:13 INFO - PROCESS | 28308 | 09:08:14 INFO - TEST-INFO | 28308: exit 0 09:08:14 INFO - TEST-OK | dromaeo_css | took 1121622ms 09:08:14 INFO - TEST-START | kraken 09:08:14 INFO - operating with platform_type : linux_ 09:08:14 INFO - Initialising browser for kraken test... 09:08:14 INFO - TEST-INFO | started process 28624 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6FO9Kp/profile http://localhost:51247/getInfo.html) 09:08:19 INFO - PROCESS | 28624 | __metrics Screen width/height:1600/1200 09:08:19 INFO - PROCESS | 28624 | colorDepth:24 09:08:19 INFO - PROCESS | 28624 | Browser inner width/height: 1024/697 09:08:19 INFO - PROCESS | 28624 | __metrics 09:08:19 INFO - PROCESS | 28624 | 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" 09:08:19 INFO - PROCESS | 28624 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:08:20 INFO - PROCESS | 28624 | console.error: PushService: 09:08:20 INFO - PROCESS | 28624 | stateChangeProcessEnqueue: Error transitioning state 09:08:20 INFO - PROCESS | 28624 | UnknownError 09:08:20 INFO - TEST-INFO | 28624: exit 0 09:08:20 INFO - Browser initialized. 09:08:20 INFO - Running cycle 1/1 for kraken test... 09:08:20 INFO - TEST-INFO | started process 28705 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6FO9Kp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:08:33 INFO - PROCESS | 28705 | RSS: Main: 182173696 09:08:33 INFO - PROCESS | 28705 | 09:08:35 INFO - PROCESS | 28705 | Cycle 1(1): loaded http://localhost:51247/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51247/tests/kraken/driver.html?testName=audio-beat-detection) 09:08:36 INFO - PROCESS | 28705 | RSS: Main: 235511808 09:08:36 INFO - PROCESS | 28705 | 09:08:39 INFO - PROCESS | 28705 | Cycle 1(1): loaded http://localhost:51247/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51247/tests/kraken/driver.html?testName=audio-dft) 09:08:40 INFO - PROCESS | 28705 | RSS: Main: 224710656 09:08:40 INFO - PROCESS | 28705 | 09:08:43 INFO - PROCESS | 28705 | Cycle 1(1): loaded http://localhost:51247/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51247/tests/kraken/driver.html?testName=audio-fft) 09:08:44 INFO - PROCESS | 28705 | RSS: Main: 302743552 09:08:44 INFO - PROCESS | 28705 | 09:08:46 INFO - PROCESS | 28705 | Cycle 1(1): loaded http://localhost:51247/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51247/tests/kraken/driver.html?testName=audio-oscillator) 09:08:46 INFO - PROCESS | 28705 | RSS: Main: 212742144 09:08:46 INFO - PROCESS | 28705 | 09:08:49 INFO - PROCESS | 28705 | Cycle 1(1): loaded http://localhost:51247/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51247/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:08:49 INFO - PROCESS | 28705 | RSS: Main: 215384064 09:08:49 INFO - PROCESS | 28705 | 09:08:52 INFO - PROCESS | 28705 | Cycle 1(1): loaded http://localhost:51247/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51247/tests/kraken/driver.html?testName=imaging-darkroom) 09:08:53 INFO - PROCESS | 28705 | RSS: Main: 253968384 09:08:53 INFO - PROCESS | 28705 | 09:08:57 INFO - PROCESS | 28705 | Cycle 1(1): loaded http://localhost:51247/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51247/tests/kraken/driver.html?testName=imaging-desaturate) 09:08:57 INFO - PROCESS | 28705 | RSS: Main: 248074240 09:08:57 INFO - PROCESS | 28705 | 09:09:00 INFO - PROCESS | 28705 | Cycle 1(1): loaded http://localhost:51247/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51247/tests/kraken/driver.html?testName=json-parse-financial) 09:09:01 INFO - PROCESS | 28705 | RSS: Main: 251080704 09:09:01 INFO - PROCESS | 28705 | 09:09:02 INFO - PROCESS | 28705 | Cycle 1(1): loaded http://localhost:51247/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51247/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:09:03 INFO - PROCESS | 28705 | RSS: Main: 215175168 09:09:03 INFO - PROCESS | 28705 | 09:09:05 INFO - PROCESS | 28705 | Cycle 1(1): loaded http://localhost:51247/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51247/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:09:06 INFO - PROCESS | 28705 | RSS: Main: 222498816 09:09:06 INFO - PROCESS | 28705 | 09:09:08 INFO - PROCESS | 28705 | Cycle 1(1): loaded http://localhost:51247/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51247/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:09:08 INFO - PROCESS | 28705 | RSS: Main: 223653888 09:09:08 INFO - PROCESS | 28705 | 09:09:11 INFO - PROCESS | 28705 | Cycle 1(1): loaded http://localhost:51247/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51247/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:09:12 INFO - PROCESS | 28705 | RSS: Main: 224546816 09:09:12 INFO - PROCESS | 28705 | 09:09:15 INFO - PROCESS | 28705 | Cycle 1(1): loaded http://localhost:51247/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51247/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:09:16 INFO - PROCESS | 28705 | RSS: Main: 222052352 09:09:16 INFO - PROCESS | 28705 | 09:09:17 INFO - PROCESS | 28705 | Cycle 1(1): loaded http://localhost:51247/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51247/tests/kraken/driver.html?testName=ai-astar) 09:09:18 INFO - PROCESS | 28705 | RSS: Main: 272896000 09:09:18 INFO - PROCESS | 28705 | 09:09:18 INFO - PROCESS | 28705 | __start_tp_report 09:09:18 INFO - PROCESS | 28705 | _x_x_mozilla_page_load 09:09:18 INFO - PROCESS | 28705 | _x_x_mozilla_page_load_details 09:09:18 INFO - PROCESS | 28705 | |i|pagename|runs| 09:09:18 INFO - PROCESS | 28705 | |0;ai-astar;114;115;133;115;112;115;116;114;113;115 09:09:18 INFO - PROCESS | 28705 | |1;audio-beat-detection;153;135;156;188;122;152;154;125;187;122 09:09:18 INFO - PROCESS | 28705 | |2;audio-dft;160;161;162;161;161;162;162;163;161;161 09:09:18 INFO - PROCESS | 28705 | |3;audio-fft;75;77;77;163;78;76;78;76;160;76 09:09:18 INFO - PROCESS | 28705 | |4;audio-oscillator;84;83;157;83;83;83;133;81;83;138 09:09:18 INFO - PROCESS | 28705 | |5;imaging-gaussian-blur;117;117;117;118;117;117;117;117;117;117 09:09:18 INFO - PROCESS | 28705 | |6;imaging-darkroom;147;146;147;146;147;146;147;146;146;146 09:09:18 INFO - PROCESS | 28705 | |7;imaging-desaturate;94;95;95;95;95;96;95;94;95;95 09:09:18 INFO - PROCESS | 28705 | |8;json-parse-financial;64;64;64;65;66;66;65;66;66;65 09:09:18 INFO - PROCESS | 28705 | |9;json-stringify-tinderbox;66;67;67;66;66;66;67;66;66;66 09:09:18 INFO - PROCESS | 28705 | |10;stanford-crypto-aes;84;87;81;82;88;81;81;81;81;93 09:09:18 INFO - PROCESS | 28705 | |11;stanford-crypto-ccm;134;134;133;141;164;145;134;131;176;132 09:09:18 INFO - PROCESS | 28705 | |12;stanford-crypto-pbkdf2;177;178;172;173;171;174;182;179;171;173 09:09:18 INFO - PROCESS | 28705 | |13;stanford-crypto-sha256-iterative;70;71;71;75;68;68;69;70;67;68 09:09:18 INFO - PROCESS | 28705 | __end_tp_report 09:09:18 INFO - PROCESS | 28705 | __start_cc_report 09:09:18 INFO - PROCESS | 28705 | _x_x_mozilla_cycle_collect,1977 09:09:18 INFO - PROCESS | 28705 | __end_cc_report 09:09:18 INFO - PROCESS | 28705 | __startTimestamp1472746158056__endTimestamp 09:09:18 INFO - PROCESS | 28705 | 09:09:18 INFO - PROCESS | 28705 | ------- Summary: start ------- 09:09:18 INFO - PROCESS | 28705 | Number of tests: 14 09:09:18 INFO - PROCESS | 28705 | 09:09:18 INFO - PROCESS | 28705 | [#0] ai-astar Cycles:10 Average:116.20 Median:115.00 stddev:6.01 (5.2%) stddev-sans-first:6.33 09:09:18 INFO - PROCESS | 28705 | Values: 114.0 115.0 133.0 115.0 112.0 115.0 116.0 114.0 113.0 115.0 09:09:18 INFO - PROCESS | 28705 | 09:09:18 INFO - PROCESS | 28705 | [#1] audio-beat-detection Cycles:10 Average:149.40 Median:153.50 stddev:24.25 (15.8%) stddev-sans-first:25.69 09:09:18 INFO - PROCESS | 28705 | Values: 153.0 135.0 156.0 188.0 122.0 152.0 154.0 125.0 187.0 122.0 09:09:18 INFO - PROCESS | 28705 | 09:09:18 INFO - PROCESS | 28705 | [#2] audio-dft Cycles:10 Average:161.40 Median:161.50 stddev:0.84 (0.5%) stddev-sans-first:0.73 09:09:18 INFO - PROCESS | 28705 | Values: 160.0 161.0 162.0 161.0 161.0 162.0 162.0 163.0 161.0 161.0 09:09:18 INFO - PROCESS | 28705 | 09:09:18 INFO - PROCESS | 28705 | [#3] audio-fft Cycles:10 Average:93.60 Median:76.50 stddev:35.81 (46.8%) stddev-sans-first:37.34 09:09:18 INFO - PROCESS | 28705 | Values: 75.0 77.0 77.0 163.0 78.0 76.0 78.0 76.0 160.0 76.0 09:09:18 INFO - PROCESS | 28705 | 09:09:18 INFO - PROCESS | 28705 | [#4] audio-oscillator Cycles:10 Average:100.80 Median:83.00 stddev:29.51 (35.6%) stddev-sans-first:30.67 09:09:18 INFO - PROCESS | 28705 | Values: 84.0 83.0 157.0 83.0 83.0 83.0 133.0 81.0 83.0 138.0 09:09:18 INFO - PROCESS | 28705 | 09:09:18 INFO - PROCESS | 28705 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 09:09:18 INFO - PROCESS | 28705 | Values: 117.0 117.0 117.0 118.0 117.0 117.0 117.0 117.0 117.0 117.0 09:09:18 INFO - PROCESS | 28705 | 09:09:18 INFO - PROCESS | 28705 | [#6] imaging-darkroom Cycles:10 Average:146.40 Median:146.50 stddev:0.52 (0.4%) stddev-sans-first:0.50 09:09:18 INFO - PROCESS | 28705 | Values: 147.0 146.0 147.0 146.0 147.0 146.0 147.0 146.0 146.0 146.0 09:09:18 INFO - PROCESS | 28705 | 09:09:18 INFO - PROCESS | 28705 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.50 09:09:18 INFO - PROCESS | 28705 | Values: 94.0 95.0 95.0 95.0 95.0 96.0 95.0 94.0 95.0 95.0 09:09:18 INFO - PROCESS | 28705 | 09:09:18 INFO - PROCESS | 28705 | [#8] json-parse-financial Cycles:10 Average:65.10 Median:65.50 stddev:0.88 (1.3%) stddev-sans-first:0.83 09:09:18 INFO - PROCESS | 28705 | Values: 64.0 64.0 64.0 65.0 66.0 66.0 65.0 66.0 66.0 65.0 09:09:18 INFO - PROCESS | 28705 | 09:09:18 INFO - PROCESS | 28705 | [#9] json-stringify-tinderbox Cycles:10 Average:66.30 Median:66.00 stddev:0.48 (0.7%) stddev-sans-first:0.50 09:09:18 INFO - PROCESS | 28705 | Values: 66.0 67.0 67.0 66.0 66.0 66.0 67.0 66.0 66.0 66.0 09:09:18 INFO - PROCESS | 28705 | 09:09:18 INFO - PROCESS | 28705 | [#10] stanford-crypto-aes Cycles:10 Average:83.90 Median:83.00 stddev:4.15 (5.0%) stddev-sans-first:4.40 09:09:18 INFO - PROCESS | 28705 | Values: 84.0 87.0 81.0 82.0 88.0 81.0 81.0 81.0 81.0 93.0 09:09:18 INFO - PROCESS | 28705 | 09:09:18 INFO - PROCESS | 28705 | [#11] stanford-crypto-ccm Cycles:10 Average:142.40 Median:137.50 stddev:15.43 (11.2%) stddev-sans-first:16.06 09:09:18 INFO - PROCESS | 28705 | Values: 134.0 134.0 133.0 141.0 164.0 145.0 134.0 131.0 176.0 132.0 09:09:18 INFO - PROCESS | 28705 | 09:09:18 INFO - PROCESS | 28705 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.00 Median:175.50 stddev:3.77 (2.1%) stddev-sans-first:3.93 09:09:18 INFO - PROCESS | 28705 | Values: 177.0 178.0 172.0 173.0 171.0 174.0 182.0 179.0 171.0 173.0 09:09:18 INFO - PROCESS | 28705 | 09:09:18 INFO - PROCESS | 28705 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.70 Median:70.00 stddev:2.31 (3.3%) stddev-sans-first:2.45 09:09:18 INFO - PROCESS | 28705 | Values: 70.0 71.0 71.0 75.0 68.0 68.0 69.0 70.0 67.0 68.0 09:09:18 INFO - PROCESS | 28705 | -------- Summary: end -------- 09:09:18 INFO - PROCESS | 28705 | 09:09:19 INFO - TEST-INFO | 28705: exit 0 09:09:19 INFO - TEST-OK | kraken | took 65136ms 09:09:19 INFO - SUITE-END | took 1186s 09:09:19 INFO - Completed test suite (00:19:47) 09:09:19 INFO - javascript benchmark 09:09:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2536.606825941979, 2593.8314009589385, 2607.2048025352274, 2620.9737902620973, 2627.1199148285323, 4480.686351955364, 4553.180427523044, 4629.004763975745, 4691.179043667362, 4718.693284936477, 4556.1571109344795, 4621.722696638196, 4627.421572303462, 4644.117617652643, 4682.976585117078, 4200.180964711883, 4218.557051509593, 4290.54949230331, 4350.608445239928, 4411.779411029435, 4305.806238719249, 4338.891527711801, 4358.150160718661, 4383.276759334722, 4388.363687265344, 4155.7506549606815, 4250.171216612762, 4275.0, 4285.721428107163, 4285.828566857322, 4219.725717828332, 4234.047339348653, 4245.766482843443, 4275.337322714984, 4278.486581610196, 4262.1688770689725, 4268.253055715269, 4270.978645106786, 4293.549177336397, 4307.892302692427, 4473.7315761054115, 4562.657800664931, 4631.907361852744, 4652.55800698933, 4698.459677137139, 4877.707337559757, 4899.069176856386, 4900.093482705711, 4900.681455705368, 5010.72441015744, 4968.527989840995, 4996.650234483587, 5025.572826309768, 5033.395992480926, 5033.84898453047, 4519.683622146418, 4609.055143695535, 4651.976740116345, 4663.48701642819, 4733.124371991124, 5075.771590278447, 5089.541941225308, 5098.796048158107, 5104.489551044866, 5162.9741851291255, 4882.145624515695, 4887.560119589254, 4889.584385327287, 4903.901921961612, 4940.283658869444, 4797.736124513115, 4888.853334399973, 4899.951000489949, 4915.262710593368, 4954.033963377179, 4864.294677271847, 4891.192953162759, 4894.216925291937, 4901.166801643658, 4966.354373931365, 4885.120678277873, 4898.559129678346, 4898.90202195954, 4900.681455705368, 4965.155923493015, 4130.504339479282, 4169.478815148106, 4171.8957026074595, 4209.726367786146, 4223.113146239324, 3647.5805282392334, 3721.423179407196, 3731.869384571527, 3780.584135745066, 3815.9236815264094, 4266.168097221012, 4282.850100246539, 4292.334688123346, 4314.050908800059, 4348.369486424451, 4979.053979743837, 5032.446430892599, 5034.219695947134, 5036.32009678696, 5101.234814777813, 4140.3375459926265, 4229.6404805591255, 4272.0, 4280.957190428118, 4286.721363111388, 4522.34426008227, 4524.253498172765, 4533.342665313511, 4576.359309696645, 4583.037562111994, 4646.279826626946, 4647.047355292157, 4651.651126165619, 4655.4180727409075, 4703.8824029398165, 4812.109759694467, 4968.478309777422, 4992.051510213193, 5025.497450255091, 5045.520675535819, 4667.929981050219, 4670.322803193586, 4689.249720044776, 4734.076855013377, 4748.667593268577, 4945.134601444661, 4949.232868905326, 4953.678010929278, 4959.0, 5012.573931215742, 4843.4430040545785, 4922.827701030446, 4959.876003099807, 5034.546890779848, 5082.517160869711, 4856.878578035577, 4857.077155340474, 4859.441164266013, 4906.607471402351, 4916.041371932439, 4842.6368022397755, 4855.223164293696, 4859.101066302604, 4859.222524396079, 4921.015796840575, 4574.428196475441, 4701.764911754467, 4733.739644319596, 4753.429588449409, 4759.9286010709175, 4854.78153483087, 4885.560299573055, 4887.828925987574, 4919.975400122977, 4929.728864912464, 4776.283557466408, 4777.928331075106, 4778.092162489116, 4781.737004464788, 4821.325014498043], "value": 4563.461481693022, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [535.7455208775832, 555.3890720207769, 562.3673367461606, 567.6423852972625, 568.2981517825488, 29305.706942930563, 39281.0, 40600.0, 40651.18697626049, 43569.0393932273, 39765.60234397655, 40220.597794022055, 40231.59768402315, 40320.193596128134, 40420.19159616813, 7133.500654954155, 7585.203553626886, 7731.845363092762, 7735.071791385041, 7738.380929525624, 9736.464494452803, 10000.449975251358, 10011.048950349705, 10324.070833624972, 10375.16998640107, 9689.467079310634, 9804.411735295906, 9808.21534277256, 9811.215102791795, 9812.116909478145, 9146.954265228664, 9214.493202873839, 9289.442633441948, 9290.16388525036, 9327.393719408217, 9743.561539730728, 9804.0, 9827.213822894151, 9828.754281142958, 9834.80330393388, 37007.25985480302, 37449.25101497982, 37451.62548374536, 37478.25391429542, 37486.0, 2848.1740295314335, 2880.0783749200264, 2997.925051873699, 3036.1347016100526, 3111.595492585972, 28040.0, 28174.718252817416, 28446.28884277889, 28589.71410285912, 28933.0, 3375.0718552398043, 3406.625271220164, 3407.5740532433447, 3416.684576438089, 3492.0, 255249.0, 257768.0, 257977.0, 261797.0, 262607.9999999981, 51813.0, 51928.508936382954, 51972.22041669378, 52042.0, 52256.47743522592, 31695.20761980923, 32643.510347344807, 32691.673083268863, 34475.482867757004, 34523.65476345255, 13217.801732974012, 13273.269970151541, 13284.269365185008, 13285.202887826568, 13288.601341959757, 9356.625734970661, 10428.061474467335, 10667.51996160159, 11195.2723073, 11292.887071129348, 17339.913300433418, 17438.47142164379, 17439.65120697579, 17502.47492575225, 17573.824261757476, 6377.968110159512, 6455.935440645628, 6461.418472337512, 6666.300038495985, 6701.832454188587, 777.9649915753805, 845.7631863078348, 872.376250980552, 882.4440602420434, 899.6401439424152, 26328.0, 26559.867200663874, 27028.32429189286, 27158.86420567924, 27319.317017074733, 750.2572453271331, 771.7530390275058, 780.1808101493409, 817.9182081791773, 937.634322614181, 14145.0, 14527.418903243963, 14686.706265874624, 14800.877397121329, 14884.851151488563, 399085.1509500958, 402012.0, 402245.0, 402295.0, 402962.0, 48326.75836620794, 48864.26703599449, 48997.75501122543, 49028.75485622549, 49080.50919490831, 1066.5520481397832, 1137.391495549877, 1172.3434876469205, 1175.1656324010048, 1183.3432444993111, 26474.338141545846, 27568.58647120335, 27912.58131127994, 28050.859745701142, 28668.139955801358, 39832.402513962894, 40432.79783601063, 40441.59558404496, 40459.59540404558, 40462.595374045886, 249882.0, 250027.0, 250476.0, 251546.0, 522879.0, 35014.82492587521, 35676.64323356733, 35768.0, 35849.0, 36186.0, 38544.80727596344, 38699.0, 38809.80595097006, 38930.61069389383, 38945.41581876331, 33220.833895830365, 33435.83282083672, 33527.0, 33551.83224083864, 33683.15792105217, 30265.394692105594, 31627.20931976627, 31832.0, 32150.83924580362, 32599.348013039133, 32580.185495362806, 32770.344593107526, 33284.0, 33469.66530334666, 33939.0, 28952.131436056952, 31230.531542026434, 32613.510797338535, 32867.50698739569, 35533.0, 24441.584384736387, 24735.87632061828, 24758.0, 24936.37659058538, 24997.750022499542], "value": 17304.475846890993, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.80717488789236, 365.36426617685237, 367.84550488794713, 368.09815950920245, 383.4822988964898, 12730.236185828846, 14998.325075371607, 15098.773518397233, 15286.464973725908, 15346.232688365579, 15526.922365388185, 15985.760213596805, 16097.517074487754, 16258.83741162588, 16573.17134143294, 4656.697314674535, 4667.719936803795, 4722.50413706561, 4728.929066064012, 4813.422389313269, 3431.519587257786, 4700.882477938045, 4763.214069678499, 4771.355866957968, 4771.403574553181, 4986.526280003394, 5250.474952504757, 5251.0, 5285.550728188108, 5335.493128152819, 5239.711815850127, 5271.525562699357, 5283.920741188886, 5290.994711004916, 5783.537317014629, 5230.241614965847, 5230.555402790767, 5232.9738351308, 5236.921446178272, 5257.658252213632, 11033.393163376091, 11148.888511114867, 11198.664040078811, 13903.513377031755, 17555.56111097233, 1739.4781565530416, 1825.9452216433529, 1855.499015265884, 1895.848332133426, 1926.1043614719222, 8833.469991800512, 8862.291016718711, 8901.465912045298, 9322.25421966241, 9546.0, 1611.395726602524, 1641.4255010746263, 1683.7306031035098, 1693.9745903811452, 1758.7273972534279, 50037.0, 61789.38210617926, 65470.67264663646, 66373.3362666372, 66885.0, 7888.368930485547, 7957.801054973672, 8365.1216622255, 8402.28580570646, 9797.559109840075, 10809.67570972872, 10914.508847101899, 10923.180761442924, 10929.289596176382, 10932.125429965583, 6163.322034576193, 6320.810375688737, 6385.936140638534, 6471.320511346241, 6495.7076931538195, 6160.322364539897, 6299.244090709144, 6401.359864013654, 6402.551821372553, 6555.737770489127, 3459.2735525539415, 3482.6343233960574, 3502.3345564343203, 3541.2386336937684, 3549.6982756465472, 1811.96717870815, 1815.827496387841, 1829.8627602929566, 1854.5827188882392, 1864.4779461750732, 819.1358117186426, 828.1097819843571, 837.1879277101202, 846.8010017645848, 932.1703683721494, 15599.554127073956, 15994.0, 16002.279897404645, 16876.409325673787, 16930.661386772197, 811.5495899775681, 833.0752864320599, 839.6263662670175, 852.2585350744891, 909.7452713240303, 8992.775180620405, 9306.488143152192, 9534.28492863038, 9662.3719458235, 9821.656242031495, 348905.0, 359104.0, 359485.0, 361595.0, 367674.0, 46012.53987460083, 57683.71158144182, 57968.420315796306, 66768.33231667719, 68213.31786682169, 864.148813418782, 870.7039606533809, 873.816498535302, 894.6510860764307, 899.2670973156856, 15129.0, 15196.848031519985, 15760.0, 15982.440614578434, 16075.356985720908, 35237.47143792794, 35693.0, 36095.0, 36276.63723362733, 36416.81791591025, 45903.3114503276, 46070.0, 46680.7665961668, 46968.0, 49400.0, 7313.0, 7445.0, 7641.044869391326, 7666.731664391719, 7722.806929826799, 10787.298825576312, 10881.292715973437, 11118.722031948942, 11126.944365278121, 11670.474828632561, 9574.377665451724, 9619.951900240454, 9802.852957205505, 10116.027374662164, 10532.631357902437, 9615.615375384907, 10180.287379883337, 10213.438260895722, 10344.948275258575, 10379.792404152025, 9760.853587196056, 9959.551820167964, 10189.54147063369, 10422.791544169226, 10622.73443163927, 7174.784756457315, 7217.927820721936, 7465.664045117874, 7543.321101100927, 7545.943567900608, 9224.215941645036, 9730.562124704264, 10048.296619236584, 10484.895151048697, 10582.0], "value": 7604.394351903388, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1157.3923690062716, 1307.6796184934688, 1352.3914238592636, 1646.9752953705688, 1656.246407884412, 7254.8911766323545, 7626.65680044398, 7638.350740187091, 7709.884351734729, 7710.1518832928405, 6681.331866813316, 7350.77947661571, 7520.39836813055, 7574.469787114904, 7615.314621684048, 2185.945351366213, 2199.6590528468114, 2203.4271089516683, 2209.7016902718087, 2212.811910987568, 2110.7783682713325, 2168.4253672776726, 2192.068370942351, 2194.133317339658, 2195.2865318771383, 2129.936101916945, 2133.445304220906, 2147.9248226312084, 2152.461884528868, 2154.1168121070364, 2108.33587419963, 2111.461577297779, 2116.396826904325, 2116.703661487393, 2117.1531387444993, 2115.1433669363932, 2140.6146893559153, 2140.860844045132, 2152.7093842331396, 2166.1335465813645, 7448.627568621548, 7538.283863033003, 7559.848803023964, 7563.886541701879, 7585.051868516435, 4338.132373525276, 4470.0, 4474.888127796831, 4482.753448560328, 4504.527024662429, 2846.9145925622265, 2872.8707208175683, 2943.1611990582796, 2962.955555666667, 2966.480865848468, 893.3166127912152, 942.4109931292942, 957.9808403831885, 963.2919803944095, 977.1108291454742, 10174.86327871148, 10177.440240786755, 10303.845442318372, 10360.740981475448, 10394.376337419704, 3917.5494818095713, 4083.1221287423346, 4244.787760611957, 4267.637250833652, 4279.251131052053, 8783.472991620523, 8784.868226976601, 8786.63807109899, 8802.427842190236, 8842.690505832265, 4454.688171827941, 4469.08383781324, 4471.0, 4495.775211239425, 4497.168023915586, 3503.404421248394, 3970.7617542947514, 4065.6137666921595, 4106.219818234586, 4120.587941205916, 3422.863085476603, 3430.811305378178, 3482.495038219444, 3559.857605695743, 3565.554305711786, 1155.4453862146217, 1160.8839116088322, 1166.0787977497682, 1168.0830548019765, 1190.0836356005827, 620.43230444144, 630.6909614288966, 671.6742379945722, 707.4800021983838, 712.9251428600026, 4914.70511769295, 4971.651984361132, 5000.6999580024585, 5096.490350964948, 5245.580353571705, 365.6909911125095, 383.17999481110445, 412.4143715923336, 422.9281022226229, 426.8292682926854, 3395.7453191010773, 3435.5362026126654, 3477.200243943907, 3480.390931587013, 3662.157703728104, 8649.351298652626, 9064.184223419792, 9082.86375704365, 9099.1810737034, 9136.406133601295, 9046.728598142066, 9054.094590540893, 9058.637654493878, 9069.18377346042, 9073.319501037317, 6949.478789090838, 6991.16106067265, 6993.335633114846, 7016.438684905195, 7029.0, 4844.4186697596515, 4902.901941961212, 4969.751512424292, 4999.500049994971, 5154.639175257696, 8341.0, 8375.790605234917, 8380.874286885579, 8430.662773489237, 8440.704575339834, 8444.0, 8556.614952327285, 8579.699710510102, 8602.268807151211, 8647.567621618768, 5571.387147413862, 5896.970515147397, 5954.255718035246, 6093.146959425772, 6307.400796924285, 7127.928720712726, 7192.172900116344, 7198.5320954137815, 7253.854922901407, 7260.854782904206, 6273.309935906956, 6416.197975253093, 6506.121673574007, 6577.375149360803, 6664.333566643297, 6302.023186406114, 6306.716197771203, 6348.079528468438, 6349.460295874902, 6381.744730210739, 5999.70001499932, 6104.0, 6105.145279660762, 6115.694215289307, 6117.082437634391, 4259.212045771542, 4312.978435107804, 4396.1867054595, 4496.505384407778, 4527.2303708369, 4306.720063195882, 4436.778161091997, 4509.954900450954, 4557.931631025471, 4629.375034370317], "value": 3901.1796833404114, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [275.20191444810047, 284.1135656750937, 294.85257371314344, 304.932914758753, 308.66201509347286, 4781.593564547014, 4782.163121453749, 4786.856394308167, 4787.329773831662, 4843.588294994921, 4681.531846815316, 4714.740689262089, 4772.379590653219, 4774.045190961813, 4791.760411979396, 3461.4115600348, 3599.262151258986, 3614.5843228028853, 3616.8191590420506, 3638.8362523686496, 3568.92862142757, 3681.263747250547, 3701.7223708221854, 3715.2755212733528, 3741.625837416264, 3667.3582123128344, 3672.981635091821, 3675.2833197526493, 3681.7606855554304, 3684.6315368463206, 3581.4448760442174, 3685.207680348738, 3687.6128006559193, 3689.797061161635, 3703.5926048134684, 3241.3031198292247, 3241.4975678769592, 3247.9188020299443, 3253.4957081652146, 3265.363254165439, 4539.978504836418, 4571.451425828922, 4590.288505281686, 4600.194965880991, 4632.8146874125, 4181.289180839234, 4184.91630167398, 4188.099558594886, 4191.559886211934, 4203.453551038376, 4795.448523419816, 4922.680025798311, 4934.876628084291, 4945.615227736203, 4959.776810043557, 3531.876384326536, 3543.8936831895085, 3543.929121417583, 3548.7515873888838, 3549.2014296783277, 5154.690718556899, 5160.407237558576, 5168.8449346519665, 5172.922406163911, 5181.792728290864, 3480.8781692640637, 3631.2374401376005, 3635.5092062571744, 3637.9636203637624, 3640.7633503822167, 3645.216278500135, 3675.1547144156993, 3676.5918323498154, 3678.6756767563656, 3698.167912219783, 4163.8959026024595, 4171.374293855946, 4178.644815190682, 4180.602842729936, 4187.706860519796, 4882.926756098661, 4906.95093049065, 4945.184044632595, 4947.505249475023, 4975.353204083446, 1190.33936165429, 1249.4877100388796, 1255.2217625072512, 1262.6843289177705, 1267.8732126787247, 791.5488171742167, 801.1867954026668, 815.649270813544, 819.9139090395541, 822.7658512231652, 3922.5096862892137, 4182.56083111275, 4300.741955482681, 4401.801918913657, 4402.383666286659, 5125.820596279113, 5133.512316329943, 5152.819651312261, 5176.696370115263, 5183.637145399785, 4416.911661766747, 4419.18245124653, 4426.26966550522, 4441.822327106945, 4444.42223010833, 2624.8054591164837, 2696.245051385615, 2713.2228390404316, 2726.6319046928807, 2729.6178535005115, 4772.141014617402, 4782.665213435026, 4792.832250871203, 4799.83211080685, 4814.8925747078365, 5221.295125158131, 5241.31862857826, 5241.68549887008, 5241.816536421207, 5245.186996015624, 4540.614047805973, 4783.971446139097, 4811.195801574409, 4830.734309613005, 4837.419509658864, 5142.328743158563, 5146.099432599355, 5168.3539557555305, 5180.715060671549, 5192.844969337682, 5081.72050537224, 5099.413567439792, 5101.474769988879, 5102.132637451717, 5108.897822043464, 4602.585767280961, 4774.355462012584, 4781.832635857728, 4797.592204662643, 4810.39870016248, 3651.7808931464197, 3652.269546090846, 3685.870994515179, 3689.963100368962, 3695.8336874841234, 5152.484751524968, 5163.380394352702, 5177.265616018525, 5180.585553155814, 5188.066148093229, 4780.665353425227, 5021.012497563944, 5037.319961805109, 5041.6722913010535, 5055.898882022266, 5004.823866391323, 5033.723145226902, 5036.169032109661, 5039.143345631178, 5041.143005689115, 4954.77703503336, 4967.627427942846, 4975.726335051607, 4977.776000080077, 4978.228374601942, 4848.200046992348, 4872.537108974641, 5049.520295571915, 5053.974730126326, 5069.087564238473, 4799.760011999456, 4919.778609962489, 4957.67835550175, 4962.379702537183, 4976.427710813158], "value": 3739.9276649699377, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [583.3641330949265, 617.9505639548837, 641.3746597067857, 646.6120327803319, 649.7043845050501, 11801.70495737607, 12623.179493332927, 12653.936730316347, 12662.303573303465, 12928.418221180045, 12401.751964960718, 12446.128770986012, 12450.128491005633, 12462.813057804118, 12464.875351246485, 606.7815586388897, 734.8493558820443, 752.4206361101948, 755.6146365353654, 756.6216891554222, 729.8321386081204, 764.4228607401404, 764.7782143178472, 766.2529034191674, 768.4121646940096, 801.7995501124719, 802.4543310548854, 803.441608082383, 803.8070862992898, 804.1034246814818, 625.5027253333566, 801.9117897031322, 802.3060053054095, 802.450321529751, 802.7350974178507, 355.24864910713654, 363.15566308332967, 363.40401741144547, 364.8558819266375, 365.56132640830845, 11694.70763230926, 11707.765844683145, 11734.823977640342, 11736.178467507278, 11738.941305293505, 11738.823917641243, 12909.935450322782, 12924.935375323157, 13260.867391326059, 13502.932485337511, 11782.646520604396, 11881.168318217728, 11986.340751258764, 12206.450709718085, 12822.038347124004, 3950.3679411294056, 3955.0903292242656, 4108.50697916249, 4183.5398106208595, 4184.727992680497, 62193.02035687836, 62424.687876560325, 62655.37344626541, 62719.6864015677, 62761.0, 10325.432101234504, 10439.791204176026, 10455.94772026135, 10457.320274182306, 10561.821126614059, 17682.29270829178, 17690.115494225236, 17696.0, 17710.734338984927, 17729.46811595654, 6389.0, 6491.285958544557, 6530.085787989685, 6760.290169532227, 6761.526693131532, 3546.3261980223674, 3552.520409744703, 3554.644535546425, 3665.193657395422, 3673.063368840982, 2709.8509581972785, 2795.706450822675, 2809.606655068292, 2814.690384057752, 2816.493031254353, 474.8836535048935, 479.549223729693, 482.57533370633615, 493.19609037269737, 498.12081675842165, 478.7079881272421, 498.47410981414953, 517.9020476589579, 521.5462547583627, 521.8757876321366, 7417.109946806418, 7698.461107722405, 7736.613169341623, 7844.646990885424, 8085.070216925129, 518.9161300564581, 534.6685055265682, 536.4206656810635, 542.0568211312288, 550.6925980143187, 3527.624226551647, 3601.1537288737127, 3747.2880152770886, 3765.9058523537133, 3848.9037774055873, 94457.0, 103530.48234758778, 104981.47509262405, 105919.4704026475, 106661.0, 59410.0, 88913.55543222242, 92156.0, 92926.07073929309, 93292.0, 76662.0, 80525.59737201277, 81288.59355703302, 81331.0, 81345.0, 7066.893996590158, 7295.306945840137, 7296.817579560554, 7387.741429049957, 7455.888161677471, 45221.0, 46149.30776038429, 46337.53662463332, 46490.535094649975, 47347.52652473431, 62689.68655156695, 62952.37047629465, 69054.65472672605, 71134.0, 71383.0, 8798.912010879809, 8864.73405797827, 9025.368224224241, 9027.052159523155, 9777.560009799434, 10347.534360953625, 10374.844377334195, 10667.199960003187, 10788.892111078789, 11100.105979418471, 9710.320277580784, 9734.172595329193, 10074.899251007397, 10157.746056348651, 10492.055714985689, 9258.490783006999, 9424.246060315021, 9867.901320986986, 10159.84760228612, 10174.084332410119, 9099.363044586815, 9507.286953478379, 9528.571214295513, 9765.560549775135, 9970.601175952881, 8043.638036288498, 8200.507969521848, 8328.500289982621, 8358.122397148456, 8468.407211495136, 6879.793606191823, 7344.30229128232, 7421.2949769771785, 7494.5128566643, 8149.388795840454], "value": 6140.637358070417, "unit": "score"}], "name": "dromaeo_css", "value": 6144.316600753895, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 115.0, 133.0, 115.0, 112.0, 115.0, 116.0, 114.0, 113.0, 115.0], "value": 116.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 135.0, 156.0, 188.0, 122.0, 152.0, 154.0, 125.0, 187.0, 122.0], "value": 149.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 162.0, 161.0, 161.0, 162.0, 162.0, 163.0, 161.0, 161.0], "value": 161.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 77.0, 163.0, 78.0, 76.0, 78.0, 76.0, 160.0, 76.0], "value": 93.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 157.0, 83.0, 83.0, 83.0, 133.0, 81.0, 83.0, 138.0], "value": 100.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 118.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 147.0, 146.0, 147.0, 146.0, 147.0, 146.0, 146.0, 146.0], "value": 146.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 94.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 64.0, 65.0, 66.0, 66.0, 65.0, 66.0, 66.0, 65.0], "value": 65.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 67.0, 66.0, 66.0, 66.0, 67.0, 66.0, 66.0, 66.0], "value": 66.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 87.0, 81.0, 82.0, 88.0, 81.0, 81.0, 81.0, 81.0, 93.0], "value": 83.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 134.0, 133.0, 141.0, 164.0, 145.0, 134.0, 131.0, 176.0, 132.0], "value": 142.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 178.0, 172.0, 173.0, 171.0, 174.0, 182.0, 179.0, 171.0, 173.0], "value": 175.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 71.0, 71.0, 75.0, 68.0, 68.0, 69.0, 70.0, 67.0, 68.0], "value": 69.7, "unit": "score"}], "name": "kraken", "value": 1582.2, "alertThreshold": 2.0}]} 09:09:19 INFO - Return code: 0 09:09:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:09:19 INFO - # TBPL SUCCESS # 09:09:19 INFO - Running post-action listener: _resource_record_post_action 09:09:19 INFO - [mozharness: 2016-09-01 16:09:19.862051Z] Finished run-tests step (success) 09:09:19 INFO - Running post-run listener: _resource_record_post_run 09:09:19 INFO - Total resource usage - Wall time: 1204s; CPU: 13.0%; Read bytes: 4096; Write bytes: 409956352; Read time: 16; Write time: 467296 09:09:19 INFO - TinderboxPrint: CPU usage
13.0% 09:09:19 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 09:09:19 INFO - TinderboxPrint: I/O write bytes / time
409,956,352 / 467,296 09:09:19 INFO - TinderboxPrint: CPU idle
8,353.2 (86.9%) 09:09:19 INFO - TinderboxPrint: CPU user
1,200.4 (12.5%) 09:09:19 INFO - TinderboxPrint: Swap in / out
0 / 0 09:09:19 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 119197696; Read time: 0; Write time: 144984 09:09:19 INFO - run-tests - Wall time: 1188s; CPU: 13.0%; Read bytes: 0; Write bytes: 290234368; Read time: 0; Write time: 322292 09:09:20 INFO - Running post-run listener: _upload_blobber_files 09:09:20 INFO - Blob upload gear active. 09:09:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:09:20 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 09:09:20 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-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:09:20 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-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:09:20 INFO - (blobuploader) - INFO - Open directory for files ... 09:09:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:09:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:09:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:09:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:09:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:09:21 INFO - (blobuploader) - INFO - Done attempting. 09:09:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:09:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:09:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:09:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:09:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:09:22 INFO - (blobuploader) - INFO - Done attempting. 09:09:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:09:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:09:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:09:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:09:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:09:26 INFO - (blobuploader) - INFO - Done attempting. 09:09:26 INFO - (blobuploader) - INFO - Iteration through files over. 09:09:26 INFO - Return code: 0 09:09:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:09:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:09:26 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/f6ae4046f20180cc08fcb6fa7838eaa2e21bccd99dd96a7bba95ac7745b0b5574dfba4cdb5cc41f00e2ae5ea57bae1eec0ddc1ebe40a7b48cf87191ef34231fd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ef6791356555d4077d9de85eb88b0302f5a43ef122eb4b61103811a52e98992649e90cb66a6bbaa2b73a8477d862e088c7bb767b57e3ba54da1d1517a062fe5f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/1a659a6af0bec7d77ac1ad7ceb67d35cd265371d8e34353e2d012d031f54eb9efceb0bb05b17bf8e176009191e1457702498c718115b0b8125efe22e6dfc078b"} 09:09:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:09:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:09:26 INFO - Contents: 09:09:26 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/f6ae4046f20180cc08fcb6fa7838eaa2e21bccd99dd96a7bba95ac7745b0b5574dfba4cdb5cc41f00e2ae5ea57bae1eec0ddc1ebe40a7b48cf87191ef34231fd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ef6791356555d4077d9de85eb88b0302f5a43ef122eb4b61103811a52e98992649e90cb66a6bbaa2b73a8477d862e088c7bb767b57e3ba54da1d1517a062fe5f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/1a659a6af0bec7d77ac1ad7ceb67d35cd265371d8e34353e2d012d031f54eb9efceb0bb05b17bf8e176009191e1457702498c718115b0b8125efe22e6dfc078b"} 09:09:26 INFO - Running post-run listener: copy_logs_to_upload_dir 09:09:26 INFO - Copying logs to upload dir... 09:09:26 INFO - mkdir: /builds/slave/test/build/upload/logs 09:09:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1251.646298 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 51 secs) (at 2016-09-01 09:09:26.051216) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 09:09:26.055122) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1472744913.429106-331339192 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-Non-PGO/sha512/f6ae4046f20180cc08fcb6fa7838eaa2e21bccd99dd96a7bba95ac7745b0b5574dfba4cdb5cc41f00e2ae5ea57bae1eec0ddc1ebe40a7b48cf87191ef34231fd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ef6791356555d4077d9de85eb88b0302f5a43ef122eb4b61103811a52e98992649e90cb66a6bbaa2b73a8477d862e088c7bb767b57e3ba54da1d1517a062fe5f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/1a659a6af0bec7d77ac1ad7ceb67d35cd265371d8e34353e2d012d031f54eb9efceb0bb05b17bf8e176009191e1457702498c718115b0b8125efe22e6dfc078b"} build_url:https://queue.taskcluster.net/v1/task/NwUwN-Q5SuiOdqmDtbfqXA/artifacts/public/build/firefox-51.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011345 build_url: 'https://queue.taskcluster.net/v1/task/NwUwN-Q5SuiOdqmDtbfqXA/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-Non-PGO/sha512/f6ae4046f20180cc08fcb6fa7838eaa2e21bccd99dd96a7bba95ac7745b0b5574dfba4cdb5cc41f00e2ae5ea57bae1eec0ddc1ebe40a7b48cf87191ef34231fd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/ef6791356555d4077d9de85eb88b0302f5a43ef122eb4b61103811a52e98992649e90cb66a6bbaa2b73a8477d862e088c7bb767b57e3ba54da1d1517a062fe5f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/1a659a6af0bec7d77ac1ad7ceb67d35cd265371d8e34353e2d012d031f54eb9efceb0bb05b17bf8e176009191e1457702498c718115b0b8125efe22e6dfc078b"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 09:09:26.080489) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 09:09:26.080836) ========= 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=78329bfc0fd5309db0a6e0ac000001b4-1472744913.429106-331339192 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004481 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 09:09:26.163393) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 09:09:26.163728) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 09:09:26.164013) ========= ========= Total master_lag: 0.17 =========