builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-029 starttime: 1467267137.9 results: success (0) buildid: 20160629223711 builduid: dfdc4784451f4df6b87c5320910ee133 revision: 32bb090d7e625a763a80728da2c276724a2c6761 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-29 23:12:17.901823) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-29 23:12:17.902456) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-29 23:12:17.902753) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-29 23:12:17.925938) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 23:12:17.926299) ========= 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=9ee515b9ec57ce2c386832a8000001b4-1467267070.65956-799192822 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-29 23:12:18-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 231M=0s 2016-06-29 23:12:18 (231 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.071662 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 23:12:18.012683) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 23:12:18.013005) ========= 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=9ee515b9ec57ce2c386832a8000001b4-1467267070.65956-799192822 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018855 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 23:12:18.062232) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-06-29 23:12:18.062615) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 32bb090d7e625a763a80728da2c276724a2c6761 --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 32bb090d7e625a763a80728da2c276724a2c6761 --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=9ee515b9ec57ce2c386832a8000001b4-1467267070.65956-799192822 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-29 23:12:18,645 truncating revision to first 12 chars 2016-06-29 23:12:18,645 Setting DEBUG logging. 2016-06-29 23:12:18,645 attempt 1/10 2016-06-29 23:12:18,645 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/32bb090d7e62?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-29 23:12:19,894 unpacking tar archive at: fx-team-32bb090d7e62/testing/mozharness/ program finished with exit code 0 elapsedTime=2.020090 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-06-29 23:12:20.098136) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-29 23:12:20.098654) ========= script_repo_revision: 32bb090d7e625a763a80728da2c276724a2c6761 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-29 23:12:20.099214) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-29 23:12:20.099678) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-29 23:12:20.111788) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 23 mins, 17 secs) (at 2016-06-29 23:12:20.112314) ========= /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=9ee515b9ec57ce2c386832a8000001b4-1467267070.65956-799192822 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 23:12:20 INFO - MultiFileLogger online at 20160629 23:12:20 in /builds/slave/test 23:12:20 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 23:12:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:12:20 INFO - {'append_to_log': False, 23:12:20 INFO - 'base_work_dir': '/builds/slave/test', 23:12:20 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 23:12:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:12:20 INFO - 'branch': 'Fx-Team-Non-PGO', 23:12:20 INFO - 'buildbot_json_path': 'buildprops.json', 23:12:20 INFO - 'config_files': ('talos/linux_config.py',), 23:12:20 INFO - 'default_actions': ('clobber', 23:12:20 INFO - 'read-buildbot-config', 23:12:20 INFO - 'download-and-extract', 23:12:20 INFO - 'populate-webroot', 23:12:20 INFO - 'create-virtualenv', 23:12:20 INFO - 'install', 23:12:20 INFO - 'run-tests'), 23:12:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:12:20 INFO - 'download_minidump_stackwalk': True, 23:12:20 INFO - 'download_symbols': 'ondemand', 23:12:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:12:20 INFO - 'tooltool.py': '/tools/tooltool.py', 23:12:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:12:20 INFO - '/tools/misc-python/virtualenv.py')}, 23:12:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:12:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:12:20 INFO - 'installer_path': 'installer.exe', 23:12:20 INFO - 'log_level': 'info', 23:12:20 INFO - 'log_name': 'talos', 23:12:20 INFO - 'log_to_console': True, 23:12:20 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 23:12:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 23:12:20 INFO - 'opt_config_files': (), 23:12:20 INFO - 'pip_index': False, 23:12:20 INFO - 'sps_profile': False, 23:12:20 INFO - 'sps_profile_interval': 0, 23:12:20 INFO - 'suite': 'dromaeojs', 23:12:20 INFO - 'system_bits': '32', 23:12:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:12:20 INFO - 'title': 'talos-linux64-ix-029', 23:12:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:12:20 INFO - 'use_talos_json': True, 23:12:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:12:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:12:20 INFO - 'work_dir': 'build'} 23:12:20 INFO - ##### 23:12:20 INFO - ##### Running clobber step. 23:12:20 INFO - ##### 23:12:20 INFO - Running pre-action listener: _resource_record_pre_action 23:12:20 INFO - Running main action method: clobber 23:12:20 INFO - rmtree: /builds/slave/test/build 23:12:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:12:21 INFO - Running post-action listener: _resource_record_post_action 23:12:21 INFO - ##### 23:12:21 INFO - ##### Finished clobber step (success) 23:12:21 INFO - ##### 23:12:21 INFO - ##### 23:12:21 INFO - ##### Running read-buildbot-config step. 23:12:21 INFO - ##### 23:12:21 INFO - Running pre-action listener: _resource_record_pre_action 23:12:21 INFO - Running main action method: read_buildbot_config 23:12:21 INFO - Using buildbot properties: 23:12:21 INFO - { 23:12:21 INFO - "project": "", 23:12:21 INFO - "product": "firefox", 23:12:21 INFO - "script_repo_revision": "production", 23:12:21 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 23:12:21 INFO - "repository": "", 23:12:21 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 23:12:21 INFO - "buildid": "20160629223711", 23:12:21 INFO - "pgo_build": "False", 23:12:21 INFO - "basedir": "/builds/slave/test", 23:12:21 INFO - "buildnumber": 1783, 23:12:21 INFO - "slavename": "talos-linux64-ix-029", 23:12:21 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 23:12:21 INFO - "platform": "ubuntu64_hw", 23:12:21 INFO - "scheduler": "tests-fx-team-linux64-talos", 23:12:21 INFO - "branch": "fx-team", 23:12:21 INFO - "repo_path": "integration/fx-team", 23:12:21 INFO - "revision": "32bb090d7e625a763a80728da2c276724a2c6761", 23:12:21 INFO - "stage_platform": "linux64", 23:12:21 INFO - "builduid": "dfdc4784451f4df6b87c5320910ee133", 23:12:21 INFO - "slavebuilddir": "test" 23:12:21 INFO - } 23:12:21 INFO - Found installer url https://queue.taskcluster.net/v1/task/EW-Jtd8uRc6dtoThjVRfqA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 23:12:21 INFO - Running post-action listener: _resource_record_post_action 23:12:21 INFO - ##### 23:12:21 INFO - ##### Finished read-buildbot-config step (success) 23:12:21 INFO - ##### 23:12:21 INFO - ##### 23:12:21 INFO - ##### Running download-and-extract step. 23:12:21 INFO - ##### 23:12:21 INFO - Running pre-action listener: _resource_record_pre_action 23:12:21 INFO - Running main action method: download_and_extract 23:12:21 INFO - mkdir: /builds/slave/test/build/tests 23:12:21 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:12:21 INFO - https://queue.taskcluster.net/v1/task/EW-Jtd8uRc6dtoThjVRfqA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 23:12:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EW-Jtd8uRc6dtoThjVRfqA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 23:12:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EW-Jtd8uRc6dtoThjVRfqA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 23:12:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EW-Jtd8uRc6dtoThjVRfqA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 23:12:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EW-Jtd8uRc6dtoThjVRfqA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 23:12:21 INFO - Downloaded 1361 bytes. 23:12:21 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 23:12:21 INFO - Using the following test package requirements: 23:12:21 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 23:12:21 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:12:21 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 23:12:21 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:12:21 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 23:12:21 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:12:21 INFO - u'jsshell-linux-x86_64.zip'], 23:12:21 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:12:21 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 23:12:21 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 23:12:21 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:12:21 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 23:12:21 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:12:21 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 23:12:21 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:12:21 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 23:12:21 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:12:21 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 23:12:21 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 23:12:21 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:12:21 INFO - https://queue.taskcluster.net/v1/task/EW-Jtd8uRc6dtoThjVRfqA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:12:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EW-Jtd8uRc6dtoThjVRfqA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 23:12:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EW-Jtd8uRc6dtoThjVRfqA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 23:12:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EW-Jtd8uRc6dtoThjVRfqA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 23:12:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EW-Jtd8uRc6dtoThjVRfqA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 23:12:22 INFO - Downloaded 22701715 bytes. 23:12:22 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:12:22 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 23:12:24 INFO - Return code: 0 23:12:24 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 23:12:24 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:12:24 INFO - https://queue.taskcluster.net/v1/task/EW-Jtd8uRc6dtoThjVRfqA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:12:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EW-Jtd8uRc6dtoThjVRfqA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 23:12:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EW-Jtd8uRc6dtoThjVRfqA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 23:12:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EW-Jtd8uRc6dtoThjVRfqA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 23:12:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EW-Jtd8uRc6dtoThjVRfqA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 23:12:25 INFO - Downloaded 22701715 bytes. 23:12:25 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:12:25 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 23:12:27 INFO - Return code: 0 23:12:27 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:12:27 INFO - https://queue.taskcluster.net/v1/task/EW-Jtd8uRc6dtoThjVRfqA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 23:12:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EW-Jtd8uRc6dtoThjVRfqA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 23:12:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EW-Jtd8uRc6dtoThjVRfqA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 23:12:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EW-Jtd8uRc6dtoThjVRfqA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 23:12:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EW-Jtd8uRc6dtoThjVRfqA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 23:12:27 WARNING - URL Error: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EW-Jtd8uRc6dtoThjVRfqA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 23:12:27 INFO - Running command: ['nslookup', u'queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com'] 23:12:27 INFO - Copy/paste: nslookup queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com 23:12:27 INFO - Server: 10.26.75.40 23:12:27 INFO - Address: 10.26.75.40#53 23:12:27 INFO - queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com canonical name = proxxy1.srv.releng.scl3.mozilla.com. 23:12:27 INFO - Name: proxxy1.srv.releng.scl3.mozilla.com 23:12:27 INFO - Address: 10.26.48.46 23:12:27 INFO - Return code: 0 23:12:27 INFO - retry: attempt #1 caught exception: 23:12:27 INFO - retry: Failed, sleeping 30 seconds before retrying 23:12:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EW-Jtd8uRc6dtoThjVRfqA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 23:12:58 INFO - Downloaded 14405245 bytes. 23:12:58 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:12:58 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 23:12:58 INFO - Return code: 0 23:12:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:12:58 INFO - https://queue.taskcluster.net/v1/task/EW-Jtd8uRc6dtoThjVRfqA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 23:12:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EW-Jtd8uRc6dtoThjVRfqA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 23:12:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EW-Jtd8uRc6dtoThjVRfqA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 23:12:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EW-Jtd8uRc6dtoThjVRfqA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 23:12:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EW-Jtd8uRc6dtoThjVRfqA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 23:13:00 INFO - Downloaded 56461822 bytes. 23:13:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EW-Jtd8uRc6dtoThjVRfqA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 23:13:00 INFO - mkdir: /builds/slave/test/properties 23:13:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:13:00 INFO - Writing to file /builds/slave/test/properties/build_url 23:13:00 INFO - Contents: 23:13:00 INFO - build_url:https://queue.taskcluster.net/v1/task/EW-Jtd8uRc6dtoThjVRfqA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 23:13:00 INFO - Running post-action listener: _resource_record_post_action 23:13:00 INFO - Running post-action listener: set_extra_try_arguments 23:13:00 INFO - ##### 23:13:00 INFO - ##### Finished download-and-extract step (success) 23:13:00 INFO - ##### 23:13:00 INFO - ##### 23:13:00 INFO - ##### Running populate-webroot step. 23:13:00 INFO - ##### 23:13:00 INFO - Running pre-action listener: _resource_record_pre_action 23:13:00 INFO - Running main action method: populate_webroot 23:13:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:13:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:13:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:13:00 INFO - '--tppagecycles', 23:13:00 INFO - '7'], 23:13:00 INFO - 'tests': ['tsvgm']}}, 23:13:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:13:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:13:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:13:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:13:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:13:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:13:00 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:13:00 INFO - 'talos_options': ['--disable-e10s'], 23:13:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:13:00 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:13:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:13:00 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:13:00 INFO - 'talos_options': ['--disable-e10s'], 23:13:00 INFO - 'tests': ['damp', 'tps']}, 23:13:00 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:13:00 INFO - 'tests': ['damp', 'tps']}, 23:13:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:13:00 INFO - 'tests': ['dromaeo_dom']}, 23:13:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:13:00 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:13:00 INFO - 'tests': ['basic_compositor_video']}, 23:13:00 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 23:13:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:13:00 INFO - 'tests': ['a11yr', 23:13:00 INFO - 'ts_paint', 23:13:00 INFO - 'tpaint', 23:13:00 INFO - 'sessionrestore', 23:13:00 INFO - 'sessionrestore_no_auto_restore', 23:13:00 INFO - 'tabpaint']}, 23:13:00 INFO - 'other-e10s': {'tests': ['a11yr', 23:13:00 INFO - 'ts_paint', 23:13:00 INFO - 'tpaint', 23:13:00 INFO - 'sessionrestore', 23:13:00 INFO - 'sessionrestore_no_auto_restore', 23:13:00 INFO - 'tabpaint']}, 23:13:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:13:00 INFO - 'ts_paint', 23:13:00 INFO - 'tpaint', 23:13:00 INFO - 'sessionrestore', 23:13:00 INFO - 'sessionrestore_no_auto_restore', 23:13:00 INFO - 'tabpaint']}, 23:13:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:13:00 INFO - 'ts_paint', 23:13:00 INFO - 'tpaint', 23:13:00 INFO - 'sessionrestore', 23:13:00 INFO - 'sessionrestore_no_auto_restore', 23:13:00 INFO - 'tabpaint']}, 23:13:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:13:00 INFO - 'tests': ['a11yr', 23:13:00 INFO - 'ts_paint', 23:13:00 INFO - 'tpaint', 23:13:00 INFO - 'sessionrestore', 23:13:00 INFO - 'sessionrestore_no_auto_restore', 23:13:00 INFO - 'tabpaint']}, 23:13:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:13:00 INFO - 'tests': ['a11yr', 23:13:00 INFO - 'ts_paint', 23:13:00 INFO - 'tpaint', 23:13:00 INFO - 'sessionrestore', 23:13:00 INFO - 'sessionrestore_no_auto_restore', 23:13:00 INFO - 'tabpaint']}, 23:13:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:13:00 INFO - 'tests': ['tsvgx', 23:13:00 INFO - 'tsvgr_opacity', 23:13:00 INFO - 'tart', 23:13:00 INFO - 'tscrollx', 23:13:00 INFO - 'cart']}, 23:13:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:13:00 INFO - 'tsvgr_opacity', 23:13:00 INFO - 'tart', 23:13:00 INFO - 'tscrollx', 23:13:00 INFO - 'cart']}, 23:13:00 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:13:00 INFO - 'talos_options': ['--disable-e10s'], 23:13:00 INFO - 'tests': ['tp5o']}, 23:13:00 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:13:00 INFO - 'tests': ['tp5o']}, 23:13:00 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:13:00 INFO - 'talos_options': ['--disable-e10s', 23:13:00 INFO - '--xperf_path', 23:13:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:13:00 INFO - 'tests': ['tp5n']}, 23:13:00 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:13:00 INFO - 'talos_options': ['--xperf_path', 23:13:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:13:00 INFO - 'tests': ['tp5n']}}, 23:13:00 INFO - 'talos.zip': {'path': '', 23:13:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:13:00 INFO - Running post-action listener: _resource_record_post_action 23:13:00 INFO - ##### 23:13:00 INFO - ##### Finished populate-webroot step (success) 23:13:00 INFO - ##### 23:13:00 INFO - ##### 23:13:00 INFO - ##### Running create-virtualenv step. 23:13:00 INFO - ##### 23:13:00 INFO - Running pre-action listener: _resource_record_pre_action 23:13:00 INFO - Running main action method: create_virtualenv 23:13:00 INFO - Creating virtualenv /builds/slave/test/build/venv 23:13:00 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 23:13:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:13:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:13:00 INFO - Using real prefix '/usr' 23:13:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:13:01 INFO - Installing distribute.............................................................................................................................................................................................done. 23:13:03 INFO - Installing pip.................done. 23:13:03 INFO - Return code: 0 23:13:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:13: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:13:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:13:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:13: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:13:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:13:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:13:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16e8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x184b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19a9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a73b0>, '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 0x19a82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19a8740>, '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': '9ee515b9ec57ce2c386832a8000001b4-1467267070.65956-799192822', '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 23:13:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 23:13:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 23:13:03 INFO - Using env: {'DISPLAY': ':0', 23:13:03 INFO - 'HOME': '/home/cltbld', 23:13:03 INFO - 'LANG': 'en_US.UTF-8', 23:13:03 INFO - 'LANGUAGE': 'en_US:en', 23:13:03 INFO - 'LOGNAME': 'cltbld', 23:13:03 INFO - 'MAIL': '/var/mail/cltbld', 23:13:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:13:03 INFO - 'MOZ_NO_REMOTE': '1', 23:13:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:13:03 INFO - 'NO_EM_RESTART': '1', 23:13:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:13:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:13:03 INFO - 'PWD': '/builds/slave/test', 23:13:03 INFO - 'SHELL': '/bin/bash', 23:13:03 INFO - 'SHLVL': '1', 23:13:03 INFO - 'TERM': 'linux', 23:13:03 INFO - 'TMOUT': '86400', 23:13:03 INFO - 'USER': 'cltbld', 23:13:03 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1467267070.65956-799192822', 23:13:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:13:03 INFO - '_': '/tools/buildbot/bin/python'} 23:13:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:13:03 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 23:13:03 INFO - Cleaning up... 23:13:03 INFO - Return code: 0 23:13:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:13: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:13:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:13:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:13: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:13:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:13:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:13: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', '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 0x16e8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x184b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19a9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a73b0>, '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 0x19a82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19a8740>, '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': '9ee515b9ec57ce2c386832a8000001b4-1467267070.65956-799192822', '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 23:13: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', 'psutil>=3.1.1'] in /builds/slave/test/build 23:13: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 psutil>=3.1.1 23:13:03 INFO - Using env: {'DISPLAY': ':0', 23:13:03 INFO - 'HOME': '/home/cltbld', 23:13:03 INFO - 'LANG': 'en_US.UTF-8', 23:13:03 INFO - 'LANGUAGE': 'en_US:en', 23:13:03 INFO - 'LOGNAME': 'cltbld', 23:13:03 INFO - 'MAIL': '/var/mail/cltbld', 23:13:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:13:03 INFO - 'MOZ_NO_REMOTE': '1', 23:13:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:13:03 INFO - 'NO_EM_RESTART': '1', 23:13:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:13:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:13:03 INFO - 'PWD': '/builds/slave/test', 23:13:03 INFO - 'SHELL': '/bin/bash', 23:13:03 INFO - 'SHLVL': '1', 23:13:03 INFO - 'TERM': 'linux', 23:13:03 INFO - 'TMOUT': '86400', 23:13:03 INFO - 'USER': 'cltbld', 23:13:03 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1467267070.65956-799192822', 23:13:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:13:03 INFO - '_': '/tools/buildbot/bin/python'} 23:13:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:13:03 INFO - Downloading/unpacking psutil>=3.1.1 23:13: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 23:13: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 23:13: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 23:13: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 23:13: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 23:13: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 23:13:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:13:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:13:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:13:05 INFO - Installing collected packages: psutil 23:13:05 INFO - Running setup.py install for psutil 23:13:05 INFO - building 'psutil._psutil_linux' extension 23:13:05 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 23:13:06 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 23:13:06 INFO - building 'psutil._psutil_posix' extension 23:13:06 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 23:13:06 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 23:13:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:13:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:13:06 INFO - Successfully installed psutil 23:13:06 INFO - Cleaning up... 23:13:06 INFO - Return code: 0 23:13:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:13:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:13:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:13:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:13:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:13:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:13:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:13:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16e8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x184b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19a9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a73b0>, '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 0x19a82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19a8740>, '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': '9ee515b9ec57ce2c386832a8000001b4-1467267070.65956-799192822', '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 23:13:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 23:13:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 23:13:06 INFO - Using env: {'DISPLAY': ':0', 23:13:06 INFO - 'HOME': '/home/cltbld', 23:13:06 INFO - 'LANG': 'en_US.UTF-8', 23:13:06 INFO - 'LANGUAGE': 'en_US:en', 23:13:06 INFO - 'LOGNAME': 'cltbld', 23:13:06 INFO - 'MAIL': '/var/mail/cltbld', 23:13:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:13:06 INFO - 'MOZ_NO_REMOTE': '1', 23:13:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:13:06 INFO - 'NO_EM_RESTART': '1', 23:13:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:13:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:13:06 INFO - 'PWD': '/builds/slave/test', 23:13:06 INFO - 'SHELL': '/bin/bash', 23:13:06 INFO - 'SHLVL': '1', 23:13:06 INFO - 'TERM': 'linux', 23:13:06 INFO - 'TMOUT': '86400', 23:13:06 INFO - 'USER': 'cltbld', 23:13:06 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1467267070.65956-799192822', 23:13:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:13:06 INFO - '_': '/tools/buildbot/bin/python'} 23:13:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:13:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:13: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 23:13: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 23:13: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 23:13: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 23:13: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 23:13: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 23:13:08 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:13:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:13:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:13:08 INFO - Installing collected packages: mozsystemmonitor 23:13:08 INFO - Running setup.py install for mozsystemmonitor 23:13:08 INFO - Successfully installed mozsystemmonitor 23:13:08 INFO - Cleaning up... 23:13:08 INFO - Return code: 0 23:13:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:13:08 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:13:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:13:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:13:08 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:13:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:13:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:13:08 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 0x16e8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x184b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19a9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a73b0>, '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 0x19a82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19a8740>, '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': '9ee515b9ec57ce2c386832a8000001b4-1467267070.65956-799192822', '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 23:13:08 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 23:13:08 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 23:13:08 INFO - Using env: {'DISPLAY': ':0', 23:13:08 INFO - 'HOME': '/home/cltbld', 23:13:08 INFO - 'LANG': 'en_US.UTF-8', 23:13:08 INFO - 'LANGUAGE': 'en_US:en', 23:13:08 INFO - 'LOGNAME': 'cltbld', 23:13:08 INFO - 'MAIL': '/var/mail/cltbld', 23:13:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:13:08 INFO - 'MOZ_NO_REMOTE': '1', 23:13:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:13:08 INFO - 'NO_EM_RESTART': '1', 23:13:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:13:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:13:08 INFO - 'PWD': '/builds/slave/test', 23:13:08 INFO - 'SHELL': '/bin/bash', 23:13:08 INFO - 'SHLVL': '1', 23:13:08 INFO - 'TERM': 'linux', 23:13:08 INFO - 'TMOUT': '86400', 23:13:08 INFO - 'USER': 'cltbld', 23:13:08 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1467267070.65956-799192822', 23:13:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:13:08 INFO - '_': '/tools/buildbot/bin/python'} 23:13:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:13:08 INFO - Downloading/unpacking jsonschema==2.5.1 23:13:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:13:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:13:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:13:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:13:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:13:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:13:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:13:10 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:13:10 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 23:13:10 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 23:13:10 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 23:13:10 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 23:13:10 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 23:13:10 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 23:13:10 INFO - Downloading functools32-3.2.3-2.tar.gz 23:13:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:13:10 INFO - warning: no files found matching '*.txt' 23:13:10 INFO - no previously-included directories found matching 'build' 23:13:10 INFO - no previously-included directories found matching 'dist' 23:13:10 INFO - no previously-included directories found matching '.git*' 23:13:10 INFO - Installing collected packages: jsonschema, functools32 23:13:11 INFO - Running setup.py install for functools32 23:13:11 INFO - warning: no files found matching '*.txt' 23:13:11 INFO - no previously-included directories found matching 'build' 23:13:11 INFO - no previously-included directories found matching 'dist' 23:13:11 INFO - no previously-included directories found matching '.git*' 23:13:11 INFO - Successfully installed jsonschema functools32 23:13:11 INFO - Cleaning up... 23:13:11 INFO - Return code: 0 23:13:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:13:11 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:13:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:13:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:13:11 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:13:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:13:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:13:11 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 0x16e8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x184b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19a9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a73b0>, '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 0x19a82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19a8740>, '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': '9ee515b9ec57ce2c386832a8000001b4-1467267070.65956-799192822', '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 23:13:11 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 23:13:11 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 23:13:11 INFO - Using env: {'DISPLAY': ':0', 23:13:11 INFO - 'HOME': '/home/cltbld', 23:13:11 INFO - 'LANG': 'en_US.UTF-8', 23:13:11 INFO - 'LANGUAGE': 'en_US:en', 23:13:11 INFO - 'LOGNAME': 'cltbld', 23:13:11 INFO - 'MAIL': '/var/mail/cltbld', 23:13:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:13:11 INFO - 'MOZ_NO_REMOTE': '1', 23:13:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:13:11 INFO - 'NO_EM_RESTART': '1', 23:13:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:13:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:13:11 INFO - 'PWD': '/builds/slave/test', 23:13:11 INFO - 'SHELL': '/bin/bash', 23:13:11 INFO - 'SHLVL': '1', 23:13:11 INFO - 'TERM': 'linux', 23:13:11 INFO - 'TMOUT': '86400', 23:13:11 INFO - 'USER': 'cltbld', 23:13:11 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1467267070.65956-799192822', 23:13:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:13:11 INFO - '_': '/tools/buildbot/bin/python'} 23:13:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:13:11 INFO - Downloading/unpacking blobuploader==1.2.4 23:13:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:13:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:13:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:13:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:13:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:13:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:13:13 INFO - Downloading blobuploader-1.2.4.tar.gz 23:13:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:13:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:13:13 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 23:13:13 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 23:13:13 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 23:13:13 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 23:13:13 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 23:13:13 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 23:13:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:13:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:13:13 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 23:13:13 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 23:13:13 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 23:13:13 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 23:13:13 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 23:13:13 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 23:13:14 INFO - Downloading docopt-0.6.1.tar.gz 23:13:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:13:14 INFO - Installing collected packages: blobuploader, requests, docopt 23:13:14 INFO - Running setup.py install for blobuploader 23:13:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:13:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:13:14 INFO - Running setup.py install for requests 23:13:14 INFO - Running setup.py install for docopt 23:13:14 INFO - Successfully installed blobuploader requests docopt 23:13:14 INFO - Cleaning up... 23:13:14 INFO - Return code: 0 23:13:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:13: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:13:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:13:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:13: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:13:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:13:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:13:14 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 0x16e8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x184b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19a9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a73b0>, '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 0x19a82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19a8740>, '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': '9ee515b9ec57ce2c386832a8000001b4-1467267070.65956-799192822', '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 23:13:14 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 23:13:14 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 23:13:14 INFO - Using env: {'DISPLAY': ':0', 23:13:14 INFO - 'HOME': '/home/cltbld', 23:13:14 INFO - 'LANG': 'en_US.UTF-8', 23:13:14 INFO - 'LANGUAGE': 'en_US:en', 23:13:14 INFO - 'LOGNAME': 'cltbld', 23:13:14 INFO - 'MAIL': '/var/mail/cltbld', 23:13:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:13:14 INFO - 'MOZ_NO_REMOTE': '1', 23:13:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:13:14 INFO - 'NO_EM_RESTART': '1', 23:13:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:13:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:13:14 INFO - 'PWD': '/builds/slave/test', 23:13:14 INFO - 'SHELL': '/bin/bash', 23:13:14 INFO - 'SHLVL': '1', 23:13:14 INFO - 'TERM': 'linux', 23:13:14 INFO - 'TMOUT': '86400', 23:13:14 INFO - 'USER': 'cltbld', 23:13:14 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1467267070.65956-799192822', 23:13:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:13:14 INFO - '_': '/tools/buildbot/bin/python'} 23:13:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:13:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:13:14 INFO - Running setup.py (path:/tmp/pip-Odop8y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:13:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:13:14 INFO - Running setup.py (path:/tmp/pip-zLVEAP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:13:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:13:15 INFO - Running setup.py (path:/tmp/pip-n1H59p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:13:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:13:15 INFO - Running setup.py (path:/tmp/pip-GUIrM9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:13:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:13:15 INFO - Running setup.py (path:/tmp/pip-629e9Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:13:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:13:15 INFO - Running setup.py (path:/tmp/pip-OwqsuE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:13:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:13:15 INFO - Running setup.py (path:/tmp/pip-KHQsE5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:13:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:13:15 INFO - Running setup.py (path:/tmp/pip-64iWLN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:13:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:13:15 INFO - Running setup.py (path:/tmp/pip-iyzOoj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:13:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:13:15 INFO - Running setup.py (path:/tmp/pip-xhNTCA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:13:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:13:15 INFO - Running setup.py (path:/tmp/pip-_qanhL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:13:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:13:15 INFO - Running setup.py (path:/tmp/pip-xrvuru-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:13:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:13:15 INFO - Running setup.py (path:/tmp/pip-OgZc1l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:13:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:13:15 INFO - Running setup.py (path:/tmp/pip-O_lhkp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:13:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:13:15 INFO - Running setup.py (path:/tmp/pip-erz3DN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:13:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:13:15 INFO - Running setup.py (path:/tmp/pip-KLvmse-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:13:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:13:16 INFO - Running setup.py (path:/tmp/pip-qGIlhe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:13:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:13:16 INFO - Running setup.py install for manifestparser 23:13:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:13:16 INFO - Running setup.py install for mozcrash 23:13:16 INFO - Running setup.py install for mozdebug 23:13:16 INFO - Running setup.py install for mozdevice 23:13:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:13:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:13:16 INFO - Running setup.py install for mozfile 23:13:16 INFO - Running setup.py install for mozhttpd 23:13:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:13:16 INFO - Running setup.py install for mozinfo 23:13:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:13:16 INFO - Running setup.py install for mozInstall 23:13:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:13:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:13:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:13:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:13:16 INFO - Running setup.py install for mozleak 23:13:17 INFO - Running setup.py install for mozlog 23:13:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:13:17 INFO - Running setup.py install for moznetwork 23:13:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:13:17 INFO - Running setup.py install for mozprocess 23:13:17 INFO - Running setup.py install for mozprofile 23:13:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:13:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:13:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:13:17 INFO - Running setup.py install for mozrunner 23:13:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:13:17 INFO - Running setup.py install for mozscreenshot 23:13:17 INFO - Running setup.py install for moztest 23:13:17 INFO - Running setup.py install for mozversion 23:13:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:13:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:13:17 INFO - Cleaning up... 23:13:17 INFO - Return code: 0 23:13:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:13:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:13:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:13:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:13:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:13:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:13:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:13:17 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 0x16e8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x184b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19a9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a73b0>, '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 0x19a82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19a8740>, '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': '9ee515b9ec57ce2c386832a8000001b4-1467267070.65956-799192822', '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 23:13:17 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 23:13:17 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 23:13:17 INFO - Using env: {'DISPLAY': ':0', 23:13:17 INFO - 'HOME': '/home/cltbld', 23:13:17 INFO - 'LANG': 'en_US.UTF-8', 23:13:17 INFO - 'LANGUAGE': 'en_US:en', 23:13:17 INFO - 'LOGNAME': 'cltbld', 23:13:17 INFO - 'MAIL': '/var/mail/cltbld', 23:13:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:13:17 INFO - 'MOZ_NO_REMOTE': '1', 23:13:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:13:17 INFO - 'NO_EM_RESTART': '1', 23:13:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:13:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:13:17 INFO - 'PWD': '/builds/slave/test', 23:13:17 INFO - 'SHELL': '/bin/bash', 23:13:17 INFO - 'SHLVL': '1', 23:13:17 INFO - 'TERM': 'linux', 23:13:17 INFO - 'TMOUT': '86400', 23:13:17 INFO - 'USER': 'cltbld', 23:13:17 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1467267070.65956-799192822', 23:13:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:13:17 INFO - '_': '/tools/buildbot/bin/python'} 23:13:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:13:18 INFO - Running setup.py (path:/tmp/pip-aQXKx7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:13:18 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)) 23:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:13:18 INFO - Running setup.py (path:/tmp/pip-DZP1yz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:13:18 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)) 23:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:13:18 INFO - Running setup.py (path:/tmp/pip-8hLrNv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:13:18 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)) 23:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:13:18 INFO - Running setup.py (path:/tmp/pip-PChgJB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:13:18 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)) 23:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:13:18 INFO - Running setup.py (path:/tmp/pip-WvmROW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:13:18 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)) 23:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:13:18 INFO - Running setup.py (path:/tmp/pip-4gId3C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:13:18 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)) 23:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:13:18 INFO - Running setup.py (path:/tmp/pip-fTyZto-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:13:18 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)) 23:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:13:18 INFO - Running setup.py (path:/tmp/pip-0Nj8Je-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:13:18 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)) 23:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:13:18 INFO - Running setup.py (path:/tmp/pip-_jkdtc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:13:18 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)) 23:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:13:18 INFO - Running setup.py (path:/tmp/pip-g9zO4J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:13:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 23:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:13:18 INFO - Running setup.py (path:/tmp/pip-esry86-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:13:18 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)) 23:13:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:13:18 INFO - Running setup.py (path:/tmp/pip-kVpCXV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:13:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 23:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:13:19 INFO - Running setup.py (path:/tmp/pip-x8y_DO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:13:19 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)) 23:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:13:19 INFO - Running setup.py (path:/tmp/pip-qkkkxf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:13:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 23:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:13:19 INFO - Running setup.py (path:/tmp/pip-QrKUWU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:13:19 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)) 23:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:13:19 INFO - Running setup.py (path:/tmp/pip-ZaGmQA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:13:19 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)) 23:13:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:13:19 INFO - Running setup.py (path:/tmp/pip-y8ZOCR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:13:19 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)) 23:13:19 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)) 23:13:19 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)) 23:13:19 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)) 23:13:19 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)) 23:13:19 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)) 23:13:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:13:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:13:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:13:19 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 23:13:19 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 23:13:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:13:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:13:21 INFO - Downloading blessings-1.6.tar.gz 23:13:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:13:21 INFO - Installing collected packages: blessings 23:13:21 INFO - Running setup.py install for blessings 23:13:21 INFO - Successfully installed blessings 23:13:21 INFO - Cleaning up... 23:13:21 INFO - Return code: 0 23:13:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:13:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:13:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:13:21 INFO - Reading from file tmpfile_stdout 23:13:21 INFO - Current package versions: 23:13:21 INFO - argparse == 1.2.1 23:13:21 INFO - blessings == 1.6 23:13:21 INFO - blobuploader == 1.2.4 23:13:21 INFO - docopt == 0.6.1 23:13:21 INFO - functools32 == 3.2.3-2 23:13:21 INFO - jsonschema == 2.5.1 23:13:21 INFO - manifestparser == 1.1 23:13:21 INFO - mozInstall == 1.12 23:13:21 INFO - mozcrash == 0.17 23:13:21 INFO - mozdebug == 0.1 23:13:21 INFO - mozdevice == 0.48 23:13:21 INFO - mozfile == 1.2 23:13:21 INFO - mozhttpd == 0.7 23:13:21 INFO - mozinfo == 0.9 23:13:21 INFO - mozleak == 0.1 23:13:21 INFO - mozlog == 3.2 23:13:21 INFO - moznetwork == 0.27 23:13:21 INFO - mozprocess == 0.22 23:13:21 INFO - mozprofile == 0.28 23:13:21 INFO - mozrunner == 6.11 23:13:21 INFO - mozscreenshot == 0.1 23:13:21 INFO - mozsystemmonitor == 0.3 23:13:21 INFO - moztest == 0.7 23:13:21 INFO - mozversion == 1.4 23:13:21 INFO - psutil == 3.1.1 23:13:21 INFO - requests == 1.2.3 23:13:21 INFO - wsgiref == 0.1.2 23:13:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:13:21 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:13:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:13:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:13:21 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:13:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:13:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:13:21 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 0x16e8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x184b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19a9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a73b0>, '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 0x19a82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19a8740>, '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': '9ee515b9ec57ce2c386832a8000001b4-1467267070.65956-799192822', '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 23:13:21 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 23:13:21 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 23:13:21 INFO - Using env: {'DISPLAY': ':0', 23:13:21 INFO - 'HOME': '/home/cltbld', 23:13:21 INFO - 'LANG': 'en_US.UTF-8', 23:13:21 INFO - 'LANGUAGE': 'en_US:en', 23:13:21 INFO - 'LOGNAME': 'cltbld', 23:13:21 INFO - 'MAIL': '/var/mail/cltbld', 23:13:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:13:21 INFO - 'MOZ_NO_REMOTE': '1', 23:13:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:13:21 INFO - 'NO_EM_RESTART': '1', 23:13:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:13:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:13:21 INFO - 'PWD': '/builds/slave/test', 23:13:21 INFO - 'SHELL': '/bin/bash', 23:13:21 INFO - 'SHLVL': '1', 23:13:21 INFO - 'TERM': 'linux', 23:13:21 INFO - 'TMOUT': '86400', 23:13:21 INFO - 'USER': 'cltbld', 23:13:21 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1467267070.65956-799192822', 23:13:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:13:21 INFO - '_': '/tools/buildbot/bin/python'} 23:13:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:13:21 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)) 23:13:21 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)) 23:13:21 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)) 23:13:21 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)) 23:13:21 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)) 23:13:21 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)) 23:13:21 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)) 23:13:21 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)) 23:13:21 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)) 23:13:21 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)) 23:13:21 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)) 23:13:21 INFO - Cleaning up... 23:13:21 INFO - Return code: 0 23:13:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:13:21 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:13:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:13:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:13:21 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:13:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:13:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:13:21 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 0x16e8030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x184b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19a9180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19a73b0>, '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 0x19a82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19a8740>, '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': '9ee515b9ec57ce2c386832a8000001b4-1467267070.65956-799192822', '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 23:13:21 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 23:13:21 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 23:13:22 INFO - Using env: {'DISPLAY': ':0', 23:13:22 INFO - 'HOME': '/home/cltbld', 23:13:22 INFO - 'LANG': 'en_US.UTF-8', 23:13:22 INFO - 'LANGUAGE': 'en_US:en', 23:13:22 INFO - 'LOGNAME': 'cltbld', 23:13:22 INFO - 'MAIL': '/var/mail/cltbld', 23:13:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:13:22 INFO - 'MOZ_NO_REMOTE': '1', 23:13:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:13:22 INFO - 'NO_EM_RESTART': '1', 23:13:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:13:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:13:22 INFO - 'PWD': '/builds/slave/test', 23:13:22 INFO - 'SHELL': '/bin/bash', 23:13:22 INFO - 'SHLVL': '1', 23:13:22 INFO - 'TERM': 'linux', 23:13:22 INFO - 'TMOUT': '86400', 23:13:22 INFO - 'USER': 'cltbld', 23:13:22 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1467267070.65956-799192822', 23:13:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:13:22 INFO - '_': '/tools/buildbot/bin/python'} 23:13:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:13:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:13:22 INFO - Cleaning up... 23:13:22 INFO - Return code: 0 23:13:22 INFO - Running post-action listener: _resource_record_post_action 23:13:22 INFO - Running post-action listener: _start_resource_monitoring 23:13:22 INFO - Starting resource monitoring. 23:13:22 INFO - ##### 23:13:22 INFO - ##### Finished create-virtualenv step (success) 23:13:22 INFO - ##### 23:13:22 INFO - ##### 23:13:22 INFO - ##### Running install step. 23:13:22 INFO - ##### 23:13:22 INFO - Running pre-action listener: _resource_record_pre_action 23:13:22 INFO - Running main action method: install 23:13:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:13:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:13:22 INFO - Reading from file tmpfile_stdout 23:13:22 INFO - Detecting whether we're running mozinstall >=1.0... 23:13:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:13:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:13:22 INFO - Reading from file tmpfile_stdout 23:13:22 INFO - Output received: 23:13:22 INFO - Usage: mozinstall [options] installer 23:13:22 INFO - Options: 23:13:22 INFO - -h, --help show this help message and exit 23:13:22 INFO - -d DEST, --destination=DEST 23:13:22 INFO - Directory to install application into. [default: 23:13:22 INFO - "/builds/slave/test"] 23:13:22 INFO - --app=APP Application being installed. [default: firefox] 23:13:22 INFO - mkdir: /builds/slave/test/build/application 23:13:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 23:13:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 23:13:38 INFO - Reading from file tmpfile_stdout 23:13:38 INFO - Output received: 23:13:38 INFO - /builds/slave/test/build/application/firefox/firefox 23:13:38 INFO - Running post-action listener: _resource_record_post_action 23:13:38 INFO - ##### 23:13:38 INFO - ##### Finished install step (success) 23:13:38 INFO - ##### 23:13:38 INFO - ##### 23:13:38 INFO - ##### Running run-tests step. 23:13:38 INFO - ##### 23:13:38 INFO - Running pre-action listener: _resource_record_pre_action 23:13:38 INFO - Running main action method: run_tests 23:13:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:13:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:13:38 INFO - Python 2.7.3 23:13:38 INFO - Return code: 0 23:13:38 INFO - grabbing minidump binary from tooltool 23:13:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:13:38 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 0x19a73b0>, '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 0x19a82b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x19a8740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:13:38 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 23:13:38 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 23:13:38 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 23:13:38 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:13:38 INFO - Return code: 0 23:13:38 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 23:13:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:13:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:13:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 23:13:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:13:38 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/EW-Jtd8uRc6dtoThjVRfqA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--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 23:13:38 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/EW-Jtd8uRc6dtoThjVRfqA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-029 --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 23:13:38 INFO - Using env: {'DISPLAY': ':0', 23:13:38 INFO - 'HOME': '/home/cltbld', 23:13:38 INFO - 'LANG': 'en_US.UTF-8', 23:13:38 INFO - 'LANGUAGE': 'en_US:en', 23:13:38 INFO - 'LOGNAME': 'cltbld', 23:13:38 INFO - 'MAIL': '/var/mail/cltbld', 23:13:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:13:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 23:13:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:13:38 INFO - 'MOZ_NO_REMOTE': '1', 23:13:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:13:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:13:38 INFO - 'NO_EM_RESTART': '1', 23:13:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:13:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:13:38 INFO - 'PWD': '/builds/slave/test', 23:13:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:13:38 INFO - 'SHELL': '/bin/bash', 23:13:38 INFO - 'SHLVL': '1', 23:13:38 INFO - 'TERM': 'linux', 23:13:38 INFO - 'TMOUT': '86400', 23:13:38 INFO - 'USER': 'cltbld', 23:13:38 INFO - 'XDG_SESSION_COOKIE': '9ee515b9ec57ce2c386832a8000001b4-1467267070.65956-799192822', 23:13:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:13:38 INFO - '_': '/tools/buildbot/bin/python'} 23:13:38 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/EW-Jtd8uRc6dtoThjVRfqA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-029', '--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 23:13:39 INFO - mozversion application_buildid: 20160629223711 23:13:39 INFO - mozversion application_changeset: 32bb090d7e625a763a80728da2c276724a2c6761 23:13:39 INFO - mozversion application_display_name: Nightly 23:13:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:13:39 INFO - mozversion application_name: Firefox 23:13:39 INFO - mozversion application_remotingname: firefox 23:13:39 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 23:13:39 INFO - mozversion application_vendor: Mozilla 23:13:39 INFO - mozversion application_version: 50.0a1 23:13:39 INFO - mozversion platform_buildid: 20160629223711 23:13:39 INFO - mozversion platform_changeset: 32bb090d7e625a763a80728da2c276724a2c6761 23:13:39 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 23:13:39 INFO - mozversion platform_version: 50.0a1 23:13:39 INFO - using testdate: 1467267219 23:13:39 INFO - actual date: 1467267219 23:13:39 INFO - starting webserver on 'localhost:40258' 23:13:39 INFO - SUITE-START | Running 2 tests 23:13:39 INFO - TEST-START | dromaeo_css 23:13:39 INFO - operating with platform_type : linux_ 23:13:39 INFO - Initialising browser for dromaeo_css test... 23:13:39 INFO - TEST-INFO | started process 4721 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppeGx2O/profile http://localhost:40258/getInfo.html) 23:13:43 INFO - PROCESS | 4721 | __metrics Screen width/height:1600/1200 23:13:43 INFO - PROCESS | 4721 | colorDepth:24 23:13:43 INFO - PROCESS | 4721 | Browser inner width/height: 1024/697 23:13:43 INFO - PROCESS | 4721 | __metrics 23:13:44 INFO - PROCESS | 4721 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:13:45 INFO - TEST-INFO | 4721: exit 0 23:13:45 INFO - Browser initialized. 23:13:45 INFO - Running cycle 1/1 for dromaeo_css test... 23:13:45 INFO - TEST-INFO | started process 4804 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppeGx2O/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:13:57 INFO - PROCESS | 4804 | RSS: Main: 158801920 23:13:57 INFO - PROCESS | 4804 | 23:14:49 INFO - PROCESS | 4804 | 1467267289125 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] 23:17:00 INFO - PROCESS | 4804 | Cycle 1(1): loaded http://localhost:40258/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40258/tests/dromaeo/cssquery-ext.html) 23:17:01 INFO - PROCESS | 4804 | RSS: Main: 248721408 23:17:01 INFO - PROCESS | 4804 | 23:20:04 INFO - PROCESS | 4804 | Cycle 1(1): loaded http://localhost:40258/tests/dromaeo/cssquery-ext.html (next: http://localhost:40258/tests/dromaeo/cssquery-jquery.html) 23:20:05 INFO - PROCESS | 4804 | RSS: Main: 236552192 23:20:05 INFO - PROCESS | 4804 | 23:23:07 INFO - PROCESS | 4804 | Cycle 1(1): loaded http://localhost:40258/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40258/tests/dromaeo/cssquery-mootools.html) 23:23:08 INFO - PROCESS | 4804 | RSS: Main: 237371392 23:23:08 INFO - PROCESS | 4804 | 23:26:10 INFO - PROCESS | 4804 | Cycle 1(1): loaded http://localhost:40258/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40258/tests/dromaeo/cssquery-prototype.html) 23:26:10 INFO - PROCESS | 4804 | RSS: Main: 237391872 23:26:10 INFO - PROCESS | 4804 | 23:29:13 INFO - PROCESS | 4804 | Cycle 1(1): loaded http://localhost:40258/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40258/tests/dromaeo/cssquery-yui.html) 23:29:14 INFO - PROCESS | 4804 | RSS: Main: 210980864 23:29:14 INFO - PROCESS | 4804 | 23:32:17 INFO - PROCESS | 4804 | Cycle 1(1): loaded http://localhost:40258/tests/dromaeo/cssquery-yui.html (next: http://localhost:40258/tests/dromaeo/cssquery-dojo.html) 23:32:17 INFO - PROCESS | 4804 | RSS: Main: 273985536 23:32:17 INFO - PROCESS | 4804 | 23:32:17 INFO - PROCESS | 4804 | __start_tp_report 23:32:17 INFO - PROCESS | 4804 | _x_x_mozilla_page_load 23:32:17 INFO - PROCESS | 4804 | _x_x_mozilla_page_load_details 23:32:17 INFO - PROCESS | 4804 | |i|pagename|runs| 23:32:17 INFO - PROCESS | 4804 | |0;dojo.html;2633.1442281258596;2636.0773729194784;2679.209633158219;2692.596110583412;2693.6363590915234;4550.453945526541;4600.056988317395;4670.299455081735;4696;4782.856514304572;4613.354130421743;4670.556297151774;4689.788959496822;4737.692050016746;4762.1428142934255;4255.87232383029;4275.871723848274;4316.740995540262;4343.674224433164;4369.235383807837;4290.849820256291;4354.869353919387;4381.189479946204;4386.429764130675;4426.44669416323;4312.913741725148;4371.14762621289;4378.5183629800695;4387.802548885292;4409.316555933835;4168.812403441822;4222.345536441856;4238.597333253336;4247;4287.035417031143;4180.226658068267;4200.432941552882;4247.7451352918615;4270.145970805851;4302.720323178984;4670.346151538787;4674.5325467452985;4707.317438971364;4724.338592597039;4729.125111854318;4857.61139108874;4964.05682920244;5016.122178618727;5031.044101620717;5081.237814327844;5093.159628661304;5093.388793344785;5093.745312734349;5110.770015349318;5124.9743751281;4549.818007279671;4714.5521175488275;4724.102420540086;4753.073150735608;4764.618830493552;5075.847724568269;5134.922976155361;5156.226566015128;5168.483151684876;5202.323697919246;4959.181735013754;4992.575631071327;5004.799808007639;5010.223415370623;5015.699058056528;4912.877178070506;4919.827806026843;5062.392512898476;5100.48995100487;5110.8466745997675;4972.72650004246;5018.447970723218;5055.696658200446;5069.214271787879;5083.949160508421;4975.850724478271;4994.525520075587;4996.800127994839;4999.550040496373;5051.368578927634;4073.7759423231414;4250.596193361625;4253.574642535722;4263.211305908417;4314.568543145723;3635.163912300133;3662.121090938209;3669.5779985301497;3690.944635830465;3732.5520937487677;4335.783210839445;4336.046069864625;4343.891402714958;4362.629176519968;4380.036391993756;4855.368802055781;4965.652404331662;4967.453580106186;4971.701697898137;5009.699418034929;4250.681198910094;4299.914001720011;4328;4328.696991210648;4348.695591308578;4467.709598876063;4489.663275254369;4495.2582823834355;4514.796834142471;4519;4694.6244300456565;4706.0823139487475;4708.764561771829;4735.313379560013;4778.187708089564;4899.853004409873;4930.778114984762;4958.355413796184;4961.50384961501;4976.527229913152;4708.3643708100335;4728.0543891221205;4728.31439441286;4739.526047395233;4783.976080119577;4936.8765780855765;4945;4950.257461380822;4951.257311403318;4987.775550100182;4831;4989;5006;5009.649324547247;5061.139606267017;4878.512148785093;4896;4907.092187945242;4940.456549779449;4941.530554597308;4903.828366007173;4911.3369695090705;4911.68074075184;4939.036887806987;4942.5304596063315;4700.059988002345;4765.642576806684;4800.087983283304;4803.423589169322;4881.096997055556;4841.2496063110275;4857.441394239708;4870.951290487192;4878.365812444502;4917.532834380728;4802.351682522814;4817.108834865561;4818.590419814355;4820.156472617348;4864.416270047617 23:32:17 INFO - PROCESS | 4804 | |1;ext.html;580.7415700013493;581.7789240088765;583.6323116436645;591.7218907113657;593.6616128806579;38876.611233887685;39986.400203996964;40232.798836005815;40342;44363.55636443634;42193.57806421935;42657.7867110664;42705.359419608656;42711.35932961;42763.35854962178;7521.66152523135;7917;8052.033755949295;8058.4359094863385;10101.393916365041;9937.90062099377;10299.742506437324;10305.690829275134;10313.07182353588;10326.741831454236;10346.172306215485;10377.896221037769;10397.532111054983;10400.167986561095;10401.635942741968;7646.273604007611;10202.846957295647;10298.176145908346;10426.739331516772;10446.9477652612;10254;10338.53476593555;10349.379037257788;10354.896451035469;10358.896411035945;39923.600763992275;40114.39828402547;40154.19691606151;40172.39741403852;40187.59812401868;2604.8046396520335;2703.4998525272886;2732.5901114832736;2732.8496932668695;2748.573971034493;29618.70381296181;29987.250318741986;30119.247018824484;30618.38763224723;31151.37697246065;3476.6349533298894;3480.4083305838067;3504.56192975878;3520.5423294971747;3603.387424137903;271051;271615.6419217911;273134;273528;273562;46754.298685519745;53172.46827531675;53535;53645;53658.463415366125;61740.38259617346;63382;63393.366066339666;63411.68294158499;63498.682506587174;14777.26113694311;14977;15016.699666006618;15033.248337583078;15093.094414334955;10599.470026498644;11431.256968297033;11508.482118304697;11617.825732614017;12011.459484323226;15929.522114336589;16004.359825606845;16130.274137663833;16145.515634530982;16232.107234102;6262.1546091278005;6335.619862808247;6392.264889537762;6458.2895881453005;6567.408933195939;783.6277768060125;919.9696340099132;920.1672486399818;924.2328867040341;946.2099147212155;27176.728232717418;28383;28462.71537284642;28580.285492862844;28738.13785586436;944.9622015119455;967.8016006718606;994.8756405449319;999.9650012249682;1011.3729487717662;14370.425182992563;14920.253987300808;14925.07591974922;15023.924880375529;15195.544133676007;366679;370342;370479;371461;371616;49712;49927;49973;50027.749861250464;50084.74957625189;1093.3658478082693;1146.2320245435578;1163.7730642524627;1210.7275862931122;1210.878912108782;28170;29360.706392935797;29563.40873182567;29941;30180.69819301779;41701.58298416977;41744.37383439312;42308.36537451879;42324.36513452239;42336;309951;311082;313161;313439;314307;31497.685023149475;36480.63519364772;37416.438753419265;37534;39990.80004599958;41267.7936610315;41732;41773.23527362891;42289.78855105705;42692.359614605186;34026.659733402346;34335.82832085923;34811.477827832095;34970.82514587411;35210.29579408346;28605.28486787847;28611.856940715163;28791.136265912057;28805;30252.017429799365;35753.82123089368;36568.26863462663;36598.817005915866;36915;36959.44560831536;30712;31852.840735796173;32081.518777218826;32181.517277240393;32205.677943221206;24500.75499244985;25043.624345635195;25390.492190156463;25427.36431589225;25847 23:32:17 INFO - PROCESS | 4804 | |2;jquery.html;359.1864427072681;360.67719391144925;365.92864391443663;367.1958411079737;388.45422181834516;16410.753838692413;17737.37919172829;18216.72674909874;18249.726254106197;18263;16638.66722665546;17586.560335991606;17892.821071789313;17910.104494775274;17916.731249031276;5051.267566202908;5217.087009773293;5252.737363131847;5258.842234732964;5258.868528286805;3787.185755062661;3992.480977472939;5064.164412871872;6217.937820621803;6231.563790534665;6139.109829074781;6147.538934579902;6167.722452489626;6178;6181.536384771138;5232.7122008289525;5490.56075513958;5502.9174562381395;5582.637128586629;6135.570510064297;3362.747793915466;5126.846194614168;5398.514133727984;5438.619296649236;5445.673259604437;12218.938905305506;12442.440090195963;12543;14518;21259.255926042668;1813.184067169779;1924.191839427444;1970.3990282963691;2103;2165.534410101836;8933.330000250007;9155.679551215742;9172.541372931393;9567.37812042222;9665.178459830922;1642.6304081581668;1689.569159864239;1695.6015336395938;1737.1140718233667;1762.5507475987697;64607.3539264606;68833;72362.63818680926;72708;73392.633036835;9847.458389788488;10097.697069087939;10097.899021009844;10520.210984176218;10810.621628242974;13792.793108103386;13830.861691382957;13866.306684665928;13896.791548126786;13912.234827084405;6688.765893193715;6749.595024298558;6798.116244888231;6802.387785099365;6878.174619045746;6201.565890387629;6266.404691554295;6347.71435285404;6351.745930162741;6356.14192084072;4170.812313445902;4289.1421715657;4314.9784251078545;4340.370646256275;4489.169503641837;1770.1680210301395;1799.2353249868754;1814.4012475882928;1842.7051671732459;1858.9163487642818;827.4745536584312;836.1220718245818;836.761522965964;856.7900864288165;869.3697069624598;16648.25082871274;17036.914815425844;17103.14484275781;17741.11294435273;18275;827.71443851871;846.3652260804397;872.9912700873037;878.639757699353;900.7297810656777;9439.292053096045;9761.072698093813;9833.655822046194;9859.556319965619;10066.14437772783;381228;381690;409337;435923;440583;46913.76543117263;54669;77205.2279477198;78733;82265;889.7063968890251;895.5253715530779;900.8963969143632;927.874736910522;929.9860502092474;12465;15821.288042037906;15958.20208989569;16338.918305408397;17237.31050758006;26385;28111.297217569725;38178.2364352717;39016.80491597524;39421.60578394179;47433.762831185624;50942.49057509526;51335;51598.74200628973;51599.226011609106;7573.962130189314;7724.266194711493;7853.253940875607;7949.483283586548;7980.760577182694;10503.737406564896;11513.021393181338;11631.825522617337;11906.583269585522;11942.104342174198;9112.407693499901;9246.352755307063;9306.115918987685;9368.297377696565;9592.808143837225;9673.806523869624;9699.175570076623;9762.414255144715;9914.058164474363;10012.39925604466;9473.857892131486;9516.33385662997;9883.50582470888;9962.352447090916;9998.950005249928;7733.149353570991;7786.532808031537;7799.06411230656;7947.728363461818;7989.600519973862;9111.635534578807;9675.225981921289;9698.757531061498;9780.41317520951;9932.304738668512 23:32:17 INFO - PROCESS | 4804 | |3;mootools.html;1365.467467687602;1415.6673181802278;1463.1715339380044;1669.1987845834008;1682.0748588276454;7406.925930740691;7870.094939082007;7960.243776841198;7961.6019199039965;7964.362850971916;6817.795466136024;7450.515716478439;7649.120351159619;7655.387568994481;7835.8824617630635;2189.321310393775;2239.0819763896807;2240.204727321802;2240.2943072932057;2240.529488807352;2194.2429861697688;2208.6245338292447;2229.7547269800307;2230.119102954332;2235.35174799308;1910.2550005497867;1994.5013746563359;1996.8103030212108;2001.2995451591898;2127.797859203381;2140.2723074154783;2184.803367696899;2188.179432712741;2189.6496560550236;2192.725909261342;2167.1764729402885;2182.9563408731897;2185.8142057925097;2216.8448208625405;2219.1012639880873;7859.52842829432;7899.684012639487;7902.446828721992;7910.4067194960035;7912.643931023118;4505.5719706627815;4522.909541809146;4526.705764125321;4620.699654522446;4630.236011058171;2850.743433091032;2893.2043687985974;2922.985385073061;2960.289530512683;2963.7480814130827;919.6275508419101;937.301710225886;953.3803028031765;962.870012548304;964.9228061755043;10576.10103141234;10637.73405664857;10643.893561064368;10655.467226638637;10657.946710266477;3983.382575700771;4048.67610591152;4214.473190851144;4217.198732240926;4219.135077309205;9017.909820901707;9019.909800902038;9035.593398297093;9040.186383225542;9040.773980650536;4601.0567833594705;4602.861914142581;4628.629709623222;4631.444226692818;4634.096351211549;4192.748435093904;4200.810963506649;4231.322988321854;4238.724482908601;4244.342126970325;3607.639236076371;3699.5930447650735;3736;3743.812809359521;3767.604401537854;1132.2583707671488;1132.3319241647469;1134.0813940708053;1136.2330426961933;1163.1450883600546;763.171958425115;780.4185881518305;784.6312233250363;786.3473317146754;798.3054742374171;4783.258594917794;4897.632677549198;5049.57078648319;5059.797608095635;5094.592432605383;399.65230249682963;400.73351221438;410.6899291035309;411.68917467311985;421.79753718215426;3498.4927185557954;3506.088417011544;3583;3608;3627.093226693327;9313.115254050854;9568;9620.326577139673;9745.902540974641;9759.658411955548;9550.95224523873;9676.032396760267;9686.273529485317;9687.70936871895;9720.222382209406;7136.857262854817;7247.094113235846;7422.814429639194;7441.813954651068;7445.25547445262;5316.6012549058205;5385.299911011543;5408.972955135199;5497.47773961473;5667.943320566742;8977.461352318882;9050.366474346733;9153.130452606993;9164.6792362267;9165.77085572866;8328.375371847014;8636.611352489279;8649.870251946351;8898.955505222433;8910.376273660782;6760.3239676032;6916.412104971134;6918.273581273894;6927.930720692729;6945.583265004116;7326.4138868889295;7369.4841361106355;7447.329740323397;7454.44091693136;7457.589832559044;6155.938440615716;6559.508036897157;6786.728530858711;6794.3545363190415;6847.6576171190245;6389.361063893573;6686;6780.288069752605;6849.349311815369;6956.130483689539;5900;5913;5952.958232309242;5961.2846458425265;5964.701764911651;4466.397036400044;4502.061994139852;4613.3771940787565;4619.630429565559;4662.044280922335;4490.416245888014;4523.11799199153;4530.18456677788;4551.544845515422;4595 23:32:17 INFO - PROCESS | 4804 | |4;prototype.html;293.5552637753803;300.05632286459087;302.4329382407985;305.8195664557909;306.6917747663598;4898.3387242722265;4959.23131914553;4960.156773348531;4973.602111831054;4976.427710813257;4852.272159176126;4856;4927.950720492794;4936.136176169174;4945.455999840015;3657.725670574704;3768.152165762695;3783.716221283401;3833.71247156463;3886.747361421513;3810.733248672594;3846.788426636534;3849.326367885623;3856.730028897978;3863.961360386388;3812.485314482551;3826.7754318618054;3842.9807850960706;3853.344931361675;3861.2856621524966;3675.2833197526493;3702.9444558331647;3794.6015668354703;3802.923941521182;3819.5416550013897;3360.915977100592;3367.478040903664;3373.7638365314438;3380.138064793462;3380.357732030931;4734.786934587952;4745.59662428694;4768.785404656799;4773.09311230865;4776.5701086902345;4231.1537692461325;4367.694261401703;4382.715123516993;4394.835368627317;4402.889927751799;5050.141475949061;5055.721935293557;5062.594992400599;5076.492350764931;5079.695218286877;3690.5791270360837;3710.9443358349645;3720.795356255405;3743.6630703236565;3755.1550901047044;5095.566876815476;5326.121190003645;5400.270963419966;5403.918941215885;5411.485908838654;3798.468214449924;3798.753081049723;3801.239752049601;3807.162424266657;3813.5995720448955;3874.9418758718643;3885.747426417274;3885.8834234972433;3887.125396785757;3890.3775395936514;4371.781410929504;4383.9342409863875;4385.6710746694125;4391.736495810262;4401.911961760747;4878.317035615017;5075.619328550373;5078.644494885397;5092;5140;1288.606974872673;1298.4546490474024;1304.452130105358;1305.6670549010132;1312.6718320419895;823.3495538524498;831.2269589281905;843.9240468357877;844.8299105738596;849.7323343146888;4482.193205223091;4482.731036137842;4503.3245013247615;4504.2883704192855;4521.84173553924;5170.121079416433;5181;5182.948170518322;5203.531682148625;5220.451852555503;4288.656907447396;4490.12442573695;4508.1659892919915;4509.774511274423;4516.796744146455;2769.4599553086955;2774.694783573805;2779.680336761258;2794.3293609533566;2797.650293713286;4895.143349913751;4912.729799861042;4916.901661966741;4926.75366231687;4931.7040977541465;5082.898342033212;5305.867353316198;5311.49540793624;5312.62811603184;5314.123169677037;4869.683470574401;4878.658493905464;4881.658283920163;4903.387076615423;4950.282209079735;5152;5164.664296820694;5171.379434467737;5199.220116982332;5202.271681964452;4964.05682920244;5084.847454576368;5109.540141387293;5116.718580478109;5145.253938179018;4920.5817505511;4928.186849169847;4931.926021109758;4956;4966.056449274626;3694.870679526311;3708.9814550928154;3710.499082623919;3713.591504934509;3721.404575267944;4890.853274401774;5176.974115129551;5192.870178245574;5266.262723218829;5269.104252277199;4991.975040124777;5007.7746501408255;5016.698998060128;5026.472220416804;5028.874278143076;4978.626603004716;5003.699778013331;5047.7476126192805;5054.444011158696;5069.352460450442;4790.856274311776;4925.556699897026;4971.204607262821;4993.750312484434;5073;4865.416150062015;4980.676256043345;5071.898562028812;5096.898062038813;5135.584836083963;5053.974730126473;5056.317397151338;5058;5101.336826212569;5105.846824595268 23:32:17 INFO - PROCESS | 4804 | |5;yui.html;585.5900869391427;603.5262319079523;653.8398092467344;683.9110915580975;686.8797960356936;11455.198136130473;11725.824112638316;11950.701232469193;11956.163068585203;12044.69888252794;12248.571300004502;12309.507619695201;12325.260484370921;12329.63011109666;12353.814692779593;636.4144986612305;752.6462562595585;754.4304050441908;778.9727359542388;780.3834970373426;788.0740130346849;790.2887401338784;792.6710415177695;793.4049462902823;793.888855560222;840.5251033166242;841.5077179849795;842.2546046748611;842.2924743215698;842.8567143585575;657.4707360574736;669.3273260373343;669.9916625478672;678.2031113441686;836.510641274855;578.7598146769128;594.2126682146173;595.2262059322863;595.9523238140938;600.0818747316569;11756.882431175663;11975.101867359983;11983.94008029963;11986.580469683606;12008.219465734786;12141.392930353448;12445.377731113407;12637.873621263761;12651.493940242379;12926.482940682361;11754.529818807237;11755.64733058001;11768.88231117695;11783.941080294544;12129.332886691229;4114.897127571805;4126.711130220886;4145.274576949022;4158.064435502018;4178.979105104455;54124.458755412335;61729;61943.38056619421;62003.37996620021;63022;10947.616833410792;11131.554737810558;11191.384473853854;11193.16051296156;11321.547138114547;17641.7353739694;18782.342618008304;18784.436466906016;18788;18800.90599547021;7179.138503379523;7272.292207759832;7350.338469537767;7371.631418429057;7619;3842.3852183650483;3983.661388781986;3992.1416895367634;3995.6603688686787;4002.3396139637216;2938.103878317091;3048.34460590974;3049.8017628854054;3052.160655819623;3052.938941221163;483.2340739927255;486.95373939475695;491.3784063160105;493.52621483375646;535.1865164949334;522.908491014071;548.6461232505012;550.5017958747341;550.856777237921;562.1820251534008;7937.920620793833;7999.640016199169;8181.263686268146;8484.109168537338;8531.402801803939;498.32974649096906;518.3132349636671;541.0423550315998;542.6798189068469;583.125312031952;4023.75857448554;4104.4048612950955;4118.135191609796;4181.707280490397;4410.051838854662;75973.6201319001;79600.20399795928;79875.60062199652;93908;94489;63046.684766575876;67778.66110669416;76021.61989190019;80322;82033.17966820375;47339.289910651365;67740;69794.30205697878;73342.63328683322;73888.26111738815;8118.985126859143;8235.917640823754;8403.243708066304;8581.27059199975;8704.56477176126;41206;42479.3628095585;42586.36120458258;44129.77935110304;44160.77919610382;35809.82095089508;47919.76040119777;62588.68705656443;62883.6855815718;63453;10919.235653504178;11017.338959662446;11021.504032318404;11239.269447486215;11451.656450306504;11964.521419143484;11969.64091077269;12010.879891201326;12168.939155304166;12246.448909798903;7251;10977;11276.661700149009;11802.586909458129;11916;10824.134069274596;10866.96763807437;11152;11985.880141198477;12336.331747299633;11014.779704406028;11431.542738290374;11779.88220117788;12069.698257543632;12164.39178041112;9537.284703647116;9782.706518804447;9840.70477885636;10248.128909042814;10334.224933129895;9813.803723925339;10127.88593254756;10144.8479919366;10146.949265253626;10277.84583231267 23:32:17 INFO - PROCESS | 4804 | __end_tp_report 23:32:17 INFO - PROCESS | 4804 | __start_cc_report 23:32:17 INFO - PROCESS | 4804 | _x_x_mozilla_cycle_collect,956 23:32:17 INFO - PROCESS | 4804 | __end_cc_report 23:32:17 INFO - PROCESS | 4804 | __startTimestamp1467268337674__endTimestamp 23:32:17 INFO - PROCESS | 4804 | 23:32:17 INFO - PROCESS | 4804 | ------- Summary: start ------- 23:32:17 INFO - PROCESS | 4804 | Number of tests: 6 23:32:17 INFO - PROCESS | 4804 | 23:32:17 INFO - PROCESS | 4804 | [#0] dojo.html Cycles:165 Average:4634.48 Median:4764.62 stddev:484.56 (10.2%) stddev-sans-first:459.91 23:32:17 INFO - PROCESS | 4804 | Values: 2633.1 2636.1 2679.2 2692.6 2693.6 4550.5 4600.1 4670.3 4696.0 4782.9 4613.4 4670.6 4689.8 4737.7 4762.1 4255.9 4275.9 4316.7 4343.7 4369.2 4290.8 4354.9 4381.2 4386.4 4426.4 4312.9 4371.1 4378.5 4387.8 4409.3 4168.8 4222.3 4238.6 4247.0 4287.0 4180.2 4200.4 4247.7 4270.1 4302.7 4670.3 4674.5 4707.3 4724.3 4729.1 4857.6 4964.1 5016.1 5031.0 5081.2 5093.2 5093.4 5093.7 5110.8 5125.0 4549.8 4714.6 4724.1 4753.1 4764.6 5075.8 5134.9 5156.2 5168.5 5202.3 4959.2 4992.6 5004.8 5010.2 5015.7 4912.9 4919.8 5062.4 5100.5 5110.8 4972.7 5018.4 5055.7 5069.2 5083.9 4975.9 4994.5 4996.8 4999.6 5051.4 4073.8 4250.6 4253.6 4263.2 4314.6 3635.2 3662.1 3669.6 3690.9 3732.6 4335.8 4336.0 4343.9 4362.6 4380.0 4855.4 4965.7 4967.5 4971.7 5009.7 4250.7 4299.9 4328.0 4328.7 4348.7 4467.7 4489.7 4495.3 4514.8 4519.0 4694.6 4706.1 4708.8 4735.3 4778.2 4899.9 4930.8 4958.4 4961.5 4976.5 4708.4 4728.1 4728.3 4739.5 4784.0 4936.9 4945.0 4950.3 4951.3 4987.8 4831.0 4989.0 5006.0 5009.6 5061.1 4878.5 4896.0 4907.1 4940.5 4941.5 4903.8 4911.3 4911.7 4939.0 4942.5 4700.1 4765.6 4800.1 4803.4 4881.1 4841.2 4857.4 4871.0 4878.4 4917.5 4802.4 4817.1 4818.6 4820.2 4864.4 23:32:17 INFO - PROCESS | 4804 | 23:32:17 INFO - PROCESS | 4804 | [#1] ext.html Cycles:180 Average:48122.10 Median:33116.17 stddev:84178.90 (254.2%) stddev-sans-first:84339.37 23:32:17 INFO - PROCESS | 4804 | Values: 580.7 581.8 583.6 591.7 593.7 38876.6 39986.4 40232.8 40342.0 44363.6 42193.6 42657.8 42705.4 42711.4 42763.4 7521.7 7917.0 8052.0 8058.4 10101.4 9937.9 10299.7 10305.7 10313.1 10326.7 10346.2 10377.9 10397.5 10400.2 10401.6 7646.3 10202.8 10298.2 10426.7 10446.9 10254.0 10338.5 10349.4 10354.9 10358.9 39923.6 40114.4 40154.2 40172.4 40187.6 2604.8 2703.5 2732.6 2732.8 2748.6 29618.7 29987.3 30119.2 30618.4 31151.4 3476.6 3480.4 3504.6 3520.5 3603.4 271051.0 271615.6 273134.0 273528.0 273562.0 46754.3 53172.5 53535.0 53645.0 53658.5 61740.4 63382.0 63393.4 63411.7 63498.7 14777.3 14977.0 15016.7 15033.2 15093.1 10599.5 11431.3 11508.5 11617.8 12011.5 15929.5 16004.4 16130.3 16145.5 16232.1 6262.2 6335.6 6392.3 6458.3 6567.4 783.6 920.0 920.2 924.2 946.2 27176.7 28383.0 28462.7 28580.3 28738.1 945.0 967.8 994.9 1000.0 1011.4 14370.4 14920.3 14925.1 15023.9 15195.5 366679.0 370342.0 370479.0 371461.0 371616.0 49712.0 49927.0 49973.0 50027.7 50084.7 1093.4 1146.2 1163.8 1210.7 1210.9 28170.0 29360.7 29563.4 29941.0 30180.7 41701.6 41744.4 42308.4 42324.4 42336.0 309951.0 311082.0 313161.0 313439.0 314307.0 31497.7 36480.6 37416.4 37534.0 39990.8 41267.8 41732.0 41773.2 42289.8 42692.4 34026.7 34335.8 34811.5 34970.8 35210.3 28605.3 28611.9 28791.1 28805.0 30252.0 35753.8 36568.3 36598.8 36915.0 36959.4 30712.0 31852.8 32081.5 32181.5 32205.7 24500.8 25043.6 25390.5 25427.4 25847.0 23:32:17 INFO - PROCESS | 4804 | 23:32:17 INFO - PROCESS | 4804 | [#2] jquery.html Cycles:180 Average:24377.89 Median:5255.79 stddev:67574.30 (1285.7%) stddev-sans-first:67739.79 23:32:17 INFO - PROCESS | 4804 | Values: 359.2 360.7 365.9 367.2 388.5 16410.8 17737.4 18216.7 18249.7 18263.0 16638.7 17586.6 17892.8 17910.1 17916.7 5051.3 5217.1 5252.7 5258.8 5258.9 3787.2 3992.5 5064.2 6217.9 6231.6 6139.1 6147.5 6167.7 6178.0 6181.5 5232.7 5490.6 5502.9 5582.6 6135.6 3362.7 5126.8 5398.5 5438.6 5445.7 12218.9 12442.4 12543.0 14518.0 21259.3 1813.2 1924.2 1970.4 2103.0 2165.5 8933.3 9155.7 9172.5 9567.4 9665.2 1642.6 1689.6 1695.6 1737.1 1762.6 64607.4 68833.0 72362.6 72708.0 73392.6 9847.5 10097.7 10097.9 10520.2 10810.6 13792.8 13830.9 13866.3 13896.8 13912.2 6688.8 6749.6 6798.1 6802.4 6878.2 6201.6 6266.4 6347.7 6351.7 6356.1 4170.8 4289.1 4315.0 4340.4 4489.2 1770.2 1799.2 1814.4 1842.7 1858.9 827.5 836.1 836.8 856.8 869.4 16648.3 17036.9 17103.1 17741.1 18275.0 827.7 846.4 873.0 878.6 900.7 9439.3 9761.1 9833.7 9859.6 10066.1 381228.0 381690.0 409337.0 435923.0 440583.0 46913.8 54669.0 77205.2 78733.0 82265.0 889.7 895.5 900.9 927.9 930.0 12465.0 15821.3 15958.2 16338.9 17237.3 26385.0 28111.3 38178.2 39016.8 39421.6 47433.8 50942.5 51335.0 51598.7 51599.2 7574.0 7724.3 7853.3 7949.5 7980.8 10503.7 11513.0 11631.8 11906.6 11942.1 9112.4 9246.4 9306.1 9368.3 9592.8 9673.8 9699.2 9762.4 9914.1 10012.4 9473.9 9516.3 9883.5 9962.4 9999.0 7733.1 7786.5 7799.1 7947.7 7989.6 9111.6 9675.2 9698.8 9780.4 9932.3 23:32:17 INFO - PROCESS | 4804 | 23:32:17 INFO - PROCESS | 4804 | [#3] mootools.html Cycles:180 Average:5104.86 Median:4840.45 stddev:2886.21 (59.6%) stddev-sans-first:2880.63 23:32:17 INFO - PROCESS | 4804 | Values: 1365.5 1415.7 1463.2 1669.2 1682.1 7406.9 7870.1 7960.2 7961.6 7964.4 6817.8 7450.5 7649.1 7655.4 7835.9 2189.3 2239.1 2240.2 2240.3 2240.5 2194.2 2208.6 2229.8 2230.1 2235.4 1910.3 1994.5 1996.8 2001.3 2127.8 2140.3 2184.8 2188.2 2189.6 2192.7 2167.2 2183.0 2185.8 2216.8 2219.1 7859.5 7899.7 7902.4 7910.4 7912.6 4505.6 4522.9 4526.7 4620.7 4630.2 2850.7 2893.2 2923.0 2960.3 2963.7 919.6 937.3 953.4 962.9 964.9 10576.1 10637.7 10643.9 10655.5 10657.9 3983.4 4048.7 4214.5 4217.2 4219.1 9017.9 9019.9 9035.6 9040.2 9040.8 4601.1 4602.9 4628.6 4631.4 4634.1 4192.7 4200.8 4231.3 4238.7 4244.3 3607.6 3699.6 3736.0 3743.8 3767.6 1132.3 1132.3 1134.1 1136.2 1163.1 763.2 780.4 784.6 786.3 798.3 4783.3 4897.6 5049.6 5059.8 5094.6 399.7 400.7 410.7 411.7 421.8 3498.5 3506.1 3583.0 3608.0 3627.1 9313.1 9568.0 9620.3 9745.9 9759.7 9551.0 9676.0 9686.3 9687.7 9720.2 7136.9 7247.1 7422.8 7441.8 7445.3 5316.6 5385.3 5409.0 5497.5 5667.9 8977.5 9050.4 9153.1 9164.7 9165.8 8328.4 8636.6 8649.9 8899.0 8910.4 6760.3 6916.4 6918.3 6927.9 6945.6 7326.4 7369.5 7447.3 7454.4 7457.6 6155.9 6559.5 6786.7 6794.4 6847.7 6389.4 6686.0 6780.3 6849.3 6956.1 5900.0 5913.0 5953.0 5961.3 5964.7 4466.4 4502.1 4613.4 4619.6 4662.0 4490.4 4523.1 4530.2 4551.5 4595.0 23:32:17 INFO - PROCESS | 4804 | 23:32:17 INFO - PROCESS | 4804 | [#4] prototype.html Cycles:180 Average:4234.74 Median:4878.49 stddev:1217.03 (24.9%) stddev-sans-first:1183.95 23:32:17 INFO - PROCESS | 4804 | Values: 293.6 300.1 302.4 305.8 306.7 4898.3 4959.2 4960.2 4973.6 4976.4 4852.3 4856.0 4928.0 4936.1 4945.5 3657.7 3768.2 3783.7 3833.7 3886.7 3810.7 3846.8 3849.3 3856.7 3864.0 3812.5 3826.8 3843.0 3853.3 3861.3 3675.3 3702.9 3794.6 3802.9 3819.5 3360.9 3367.5 3373.8 3380.1 3380.4 4734.8 4745.6 4768.8 4773.1 4776.6 4231.2 4367.7 4382.7 4394.8 4402.9 5050.1 5055.7 5062.6 5076.5 5079.7 3690.6 3710.9 3720.8 3743.7 3755.2 5095.6 5326.1 5400.3 5403.9 5411.5 3798.5 3798.8 3801.2 3807.2 3813.6 3874.9 3885.7 3885.9 3887.1 3890.4 4371.8 4383.9 4385.7 4391.7 4401.9 4878.3 5075.6 5078.6 5092.0 5140.0 1288.6 1298.5 1304.5 1305.7 1312.7 823.3 831.2 843.9 844.8 849.7 4482.2 4482.7 4503.3 4504.3 4521.8 5170.1 5181.0 5182.9 5203.5 5220.5 4288.7 4490.1 4508.2 4509.8 4516.8 2769.5 2774.7 2779.7 2794.3 2797.7 4895.1 4912.7 4916.9 4926.8 4931.7 5082.9 5305.9 5311.5 5312.6 5314.1 4869.7 4878.7 4881.7 4903.4 4950.3 5152.0 5164.7 5171.4 5199.2 5202.3 4964.1 5084.8 5109.5 5116.7 5145.3 4920.6 4928.2 4931.9 4956.0 4966.1 3694.9 3709.0 3710.5 3713.6 3721.4 4890.9 5177.0 5192.9 5266.3 5269.1 4992.0 5007.8 5016.7 5026.5 5028.9 4978.6 5003.7 5047.7 5054.4 5069.4 4790.9 4925.6 4971.2 4993.8 5073.0 4865.4 4980.7 5071.9 5096.9 5135.6 5054.0 5056.3 5058.0 5101.3 5105.8 23:32:17 INFO - PROCESS | 4804 | 23:32:17 INFO - PROCESS | 4804 | [#5] yui.html Cycles:180 Average:16540.29 Median:45750.03 stddev:22634.48 (49.5%) stddev-sans-first:22666.27 23:32:17 INFO - PROCESS | 4804 | Values: 585.6 603.5 653.8 683.9 686.9 11455.2 11725.8 11950.7 11956.2 12044.7 12248.6 12309.5 12325.3 12329.6 12353.8 636.4 752.6 754.4 779.0 780.4 788.1 790.3 792.7 793.4 793.9 840.5 841.5 842.3 842.3 842.9 657.5 669.3 670.0 678.2 836.5 578.8 594.2 595.2 596.0 600.1 11756.9 11975.1 11983.9 11986.6 12008.2 12141.4 12445.4 12637.9 12651.5 12926.5 11754.5 11755.6 11768.9 11783.9 12129.3 4114.9 4126.7 4145.3 4158.1 4179.0 54124.5 61729.0 61943.4 62003.4 63022.0 10947.6 11131.6 11191.4 11193.2 11321.5 17641.7 18782.3 18784.4 18788.0 18800.9 7179.1 7272.3 7350.3 7371.6 7619.0 3842.4 3983.7 3992.1 3995.7 4002.3 2938.1 3048.3 3049.8 3052.2 3052.9 483.2 487.0 491.4 493.5 535.2 522.9 548.6 550.5 550.9 562.2 7937.9 7999.6 8181.3 8484.1 8531.4 498.3 518.3 541.0 542.7 583.1 4023.8 4104.4 4118.1 4181.7 4410.1 75973.6 79600.2 79875.6 93908.0 94489.0 63046.7 67778.7 76021.6 80322.0 82033.2 47339.3 67740.0 69794.3 73342.6 73888.3 8119.0 8235.9 8403.2 8581.3 8704.6 41206.0 42479.4 42586.4 44129.8 44160.8 35809.8 47919.8 62588.7 62883.7 63453.0 10919.2 11017.3 11021.5 11239.3 11451.7 11964.5 11969.6 12010.9 12168.9 12246.4 7251.0 10977.0 11276.7 11802.6 11916.0 10824.1 10867.0 11152.0 11985.9 12336.3 11014.8 11431.5 11779.9 12069.7 12164.4 9537.3 9782.7 9840.7 10248.1 10334.2 9813.8 10127.9 10144.8 10146.9 10277.8 23:32:17 INFO - PROCESS | 4804 | -------- Summary: end -------- 23:32:17 INFO - PROCESS | 4804 | 23:32:18 INFO - TEST-INFO | 4804: exit 0 23:32:18 INFO - TEST-OK | dromaeo_css | took 1119387ms 23:32:18 INFO - TEST-START | kraken 23:32:18 INFO - operating with platform_type : linux_ 23:32:18 INFO - Initialising browser for kraken test... 23:32:18 INFO - TEST-INFO | started process 4997 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6KuDeV/profile http://localhost:40258/getInfo.html) 23:32:22 INFO - PROCESS | 4997 | __metrics Screen width/height:1600/1200 23:32:22 INFO - PROCESS | 4997 | colorDepth:24 23:32:22 INFO - PROCESS | 4997 | Browser inner width/height: 1024/697 23:32:22 INFO - PROCESS | 4997 | __metrics 23:32:23 INFO - PROCESS | 4997 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:32:24 INFO - TEST-INFO | 4997: exit 0 23:32:24 INFO - Browser initialized. 23:32:24 INFO - Running cycle 1/1 for kraken test... 23:32:24 INFO - TEST-INFO | started process 5078 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6KuDeV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:32:36 INFO - PROCESS | 5078 | RSS: Main: 158732288 23:32:36 INFO - PROCESS | 5078 | 23:32:39 INFO - PROCESS | 5078 | Cycle 1(1): loaded http://localhost:40258/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40258/tests/kraken/driver.html?testName=audio-beat-detection) 23:32:40 INFO - PROCESS | 5078 | RSS: Main: 216408064 23:32:40 INFO - PROCESS | 5078 | 23:32:42 INFO - PROCESS | 5078 | Cycle 1(1): loaded http://localhost:40258/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40258/tests/kraken/driver.html?testName=audio-dft) 23:32:43 INFO - PROCESS | 5078 | RSS: Main: 203743232 23:32:43 INFO - PROCESS | 5078 | 23:32:46 INFO - PROCESS | 5078 | Cycle 1(1): loaded http://localhost:40258/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40258/tests/kraken/driver.html?testName=audio-fft) 23:32:47 INFO - PROCESS | 5078 | RSS: Main: 270958592 23:32:47 INFO - PROCESS | 5078 | 23:32:49 INFO - PROCESS | 5078 | Cycle 1(1): loaded http://localhost:40258/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40258/tests/kraken/driver.html?testName=audio-oscillator) 23:32:50 INFO - PROCESS | 5078 | RSS: Main: 181481472 23:32:50 INFO - PROCESS | 5078 | 23:32:52 INFO - PROCESS | 5078 | Cycle 1(1): loaded http://localhost:40258/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40258/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:32:52 INFO - PROCESS | 5078 | RSS: Main: 185311232 23:32:52 INFO - PROCESS | 5078 | 23:32:56 INFO - PROCESS | 5078 | Cycle 1(1): loaded http://localhost:40258/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40258/tests/kraken/driver.html?testName=imaging-darkroom) 23:32:56 INFO - PROCESS | 5078 | RSS: Main: 221483008 23:32:56 INFO - PROCESS | 5078 | 23:33:00 INFO - PROCESS | 5078 | Cycle 1(1): loaded http://localhost:40258/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40258/tests/kraken/driver.html?testName=imaging-desaturate) 23:33:00 INFO - PROCESS | 5078 | RSS: Main: 217124864 23:33:00 INFO - PROCESS | 5078 | 23:33:03 INFO - PROCESS | 5078 | Cycle 1(1): loaded http://localhost:40258/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40258/tests/kraken/driver.html?testName=json-parse-financial) 23:33:04 INFO - PROCESS | 5078 | RSS: Main: 219672576 23:33:04 INFO - PROCESS | 5078 | 23:33:06 INFO - PROCESS | 5078 | Cycle 1(1): loaded http://localhost:40258/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40258/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:33:06 INFO - PROCESS | 5078 | RSS: Main: 204607488 23:33:06 INFO - PROCESS | 5078 | 23:33:08 INFO - PROCESS | 5078 | Cycle 1(1): loaded http://localhost:40258/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40258/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:33:09 INFO - PROCESS | 5078 | RSS: Main: 212328448 23:33:09 INFO - PROCESS | 5078 | 23:33:11 INFO - PROCESS | 5078 | Cycle 1(1): loaded http://localhost:40258/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40258/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:33:12 INFO - PROCESS | 5078 | RSS: Main: 209944576 23:33:12 INFO - PROCESS | 5078 | 23:33:14 INFO - PROCESS | 5078 | Cycle 1(1): loaded http://localhost:40258/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40258/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:33:15 INFO - PROCESS | 5078 | RSS: Main: 225222656 23:33:15 INFO - PROCESS | 5078 | 23:33:18 INFO - PROCESS | 5078 | Cycle 1(1): loaded http://localhost:40258/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40258/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:33:18 INFO - PROCESS | 5078 | RSS: Main: 223617024 23:33:18 INFO - PROCESS | 5078 | 23:33:20 INFO - PROCESS | 5078 | Cycle 1(1): loaded http://localhost:40258/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40258/tests/kraken/driver.html?testName=ai-astar) 23:33:20 INFO - PROCESS | 5078 | RSS: Main: 266088448 23:33:20 INFO - PROCESS | 5078 | 23:33:20 INFO - PROCESS | 5078 | __start_tp_report 23:33:20 INFO - PROCESS | 5078 | _x_x_mozilla_page_load 23:33:20 INFO - PROCESS | 5078 | _x_x_mozilla_page_load_details 23:33:20 INFO - PROCESS | 5078 | |i|pagename|runs| 23:33:20 INFO - PROCESS | 5078 | |0;ai-astar;104;113;114;117;115;115;115;115;112;113 23:33:20 INFO - PROCESS | 5078 | |1;audio-beat-detection;152;127;122;176;125;129;153;128;167;124 23:33:20 INFO - PROCESS | 5078 | |2;audio-dft;162;161;161;160;162;161;161;161;162;161 23:33:20 INFO - PROCESS | 5078 | |3;audio-fft;75;76;76;120;76;76;75;76;100;76 23:33:20 INFO - PROCESS | 5078 | |4;audio-oscillator;86;86;112;85;85;85;113;86;85;124 23:33:20 INFO - PROCESS | 5078 | |5;imaging-gaussian-blur;117;117;117;116;116;117;117;117;116;117 23:33:20 INFO - PROCESS | 5078 | |6;imaging-darkroom;146;147;147;147;146;146;147;146;146;146 23:33:20 INFO - PROCESS | 5078 | |7;imaging-desaturate;95;96;95;95;95;95;95;94;94;95 23:33:20 INFO - PROCESS | 5078 | |8;json-parse-financial;75;64;63;62;63;62;62;73;62;62 23:33:20 INFO - PROCESS | 5078 | |9;json-stringify-tinderbox;64;64;64;65;64;65;65;65;65;65 23:33:20 INFO - PROCESS | 5078 | |10;stanford-crypto-aes;80;78;78;78;78;78;82;79;80;78 23:33:20 INFO - PROCESS | 5078 | |11;stanford-crypto-ccm;129;127;127;136;127;128;136;127;163;129 23:33:20 INFO - PROCESS | 5078 | |12;stanford-crypto-pbkdf2;169;163;164;163;170;167;166;168;166;165 23:33:20 INFO - PROCESS | 5078 | |13;stanford-crypto-sha256-iterative;67;69;67;67;66;71;66;66;70;65 23:33:20 INFO - PROCESS | 5078 | __end_tp_report 23:33:20 INFO - PROCESS | 5078 | __start_cc_report 23:33:20 INFO - PROCESS | 5078 | _x_x_mozilla_cycle_collect,1894 23:33:20 INFO - PROCESS | 5078 | __end_cc_report 23:33:20 INFO - PROCESS | 5078 | __startTimestamp1467268400756__endTimestamp 23:33:20 INFO - PROCESS | 5078 | 23:33:20 INFO - PROCESS | 5078 | ------- Summary: start ------- 23:33:20 INFO - PROCESS | 5078 | Number of tests: 14 23:33:20 INFO - PROCESS | 5078 | 23:33:20 INFO - PROCESS | 5078 | [#0] ai-astar Cycles:10 Average:113.30 Median:115.00 stddev:3.56 (3.1%) stddev-sans-first:1.50 23:33:20 INFO - PROCESS | 5078 | Values: 104.0 113.0 114.0 117.0 115.0 115.0 115.0 115.0 112.0 113.0 23:33:20 INFO - PROCESS | 5078 | 23:33:20 INFO - PROCESS | 5078 | [#1] audio-beat-detection Cycles:10 Average:140.30 Median:140.50 stddev:19.93 (14.2%) stddev-sans-first:20.69 23:33:20 INFO - PROCESS | 5078 | Values: 152.0 127.0 122.0 176.0 125.0 129.0 153.0 128.0 167.0 124.0 23:33:20 INFO - PROCESS | 5078 | 23:33:20 INFO - PROCESS | 5078 | [#2] audio-dft Cycles:10 Average:161.20 Median:161.00 stddev:0.63 (0.4%) stddev-sans-first:0.60 23:33:20 INFO - PROCESS | 5078 | Values: 162.0 161.0 161.0 160.0 162.0 161.0 161.0 161.0 162.0 161.0 23:33:20 INFO - PROCESS | 5078 | 23:33:20 INFO - PROCESS | 5078 | [#3] audio-fft Cycles:10 Average:82.60 Median:76.00 stddev:15.20 (20.0%) stddev-sans-first:15.87 23:33:20 INFO - PROCESS | 5078 | Values: 75.0 76.0 76.0 120.0 76.0 76.0 75.0 76.0 100.0 76.0 23:33:20 INFO - PROCESS | 5078 | 23:33:20 INFO - PROCESS | 5078 | [#4] audio-oscillator Cycles:10 Average:94.70 Median:85.00 stddev:15.26 (18.0%) stddev-sans-first:15.86 23:33:20 INFO - PROCESS | 5078 | Values: 86.0 86.0 112.0 85.0 85.0 85.0 113.0 86.0 85.0 124.0 23:33:20 INFO - PROCESS | 5078 | 23:33:20 INFO - PROCESS | 5078 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 23:33:20 INFO - PROCESS | 5078 | Values: 117.0 117.0 117.0 116.0 116.0 117.0 117.0 117.0 116.0 117.0 23:33:20 INFO - PROCESS | 5078 | 23:33:20 INFO - PROCESS | 5078 | [#6] imaging-darkroom Cycles:10 Average:146.40 Median:146.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 23:33:20 INFO - PROCESS | 5078 | Values: 146.0 147.0 147.0 147.0 146.0 146.0 147.0 146.0 146.0 146.0 23:33:20 INFO - PROCESS | 5078 | 23:33:20 INFO - PROCESS | 5078 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 23:33:20 INFO - PROCESS | 5078 | Values: 95.0 96.0 95.0 95.0 95.0 95.0 95.0 94.0 94.0 95.0 23:33:20 INFO - PROCESS | 5078 | 23:33:20 INFO - PROCESS | 5078 | [#8] json-parse-financial Cycles:10 Average:64.80 Median:63.00 stddev:4.92 (7.8%) stddev-sans-first:3.57 23:33:20 INFO - PROCESS | 5078 | Values: 75.0 64.0 63.0 62.0 63.0 62.0 62.0 73.0 62.0 62.0 23:33:20 INFO - PROCESS | 5078 | 23:33:20 INFO - PROCESS | 5078 | [#9] json-stringify-tinderbox Cycles:10 Average:64.60 Median:65.00 stddev:0.52 (0.8%) stddev-sans-first:0.50 23:33:20 INFO - PROCESS | 5078 | Values: 64.0 64.0 64.0 65.0 64.0 65.0 65.0 65.0 65.0 65.0 23:33:20 INFO - PROCESS | 5078 | 23:33:20 INFO - PROCESS | 5078 | [#10] stanford-crypto-aes Cycles:10 Average:78.90 Median:78.50 stddev:1.37 (1.7%) stddev-sans-first:1.39 23:33:20 INFO - PROCESS | 5078 | Values: 80.0 78.0 78.0 78.0 78.0 78.0 82.0 79.0 80.0 78.0 23:33:20 INFO - PROCESS | 5078 | 23:33:20 INFO - PROCESS | 5078 | [#11] stanford-crypto-ccm Cycles:10 Average:132.90 Median:129.00 stddev:11.15 (8.6%) stddev-sans-first:11.74 23:33:20 INFO - PROCESS | 5078 | Values: 129.0 127.0 127.0 136.0 127.0 128.0 136.0 127.0 163.0 129.0 23:33:20 INFO - PROCESS | 5078 | 23:33:20 INFO - PROCESS | 5078 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.10 Median:166.50 stddev:2.42 (1.5%) stddev-sans-first:2.33 23:33:20 INFO - PROCESS | 5078 | Values: 169.0 163.0 164.0 163.0 170.0 167.0 166.0 168.0 166.0 165.0 23:33:20 INFO - PROCESS | 5078 | 23:33:20 INFO - PROCESS | 5078 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:67.00 stddev:1.96 (2.9%) stddev-sans-first:2.07 23:33:20 INFO - PROCESS | 5078 | Values: 67.0 69.0 67.0 67.0 66.0 71.0 66.0 66.0 70.0 65.0 23:33:20 INFO - PROCESS | 5078 | -------- Summary: end -------- 23:33:20 INFO - PROCESS | 5078 | 23:33:21 INFO - TEST-INFO | 5078: exit 0 23:33:21 INFO - TEST-OK | kraken | took 63261ms 23:33:21 INFO - SUITE-END | took 1182s 23:33:21 INFO - Completed test suite (00:19:42) 23:33:21 INFO - javascript benchmark 23:33:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2633.1442281258596, 2636.0773729194784, 2679.209633158219, 2692.596110583412, 2693.6363590915234, 4550.453945526541, 4600.056988317395, 4670.299455081735, 4696.0, 4782.856514304572, 4613.354130421743, 4670.556297151774, 4689.788959496822, 4737.692050016746, 4762.1428142934255, 4255.87232383029, 4275.871723848274, 4316.740995540262, 4343.674224433164, 4369.235383807837, 4290.849820256291, 4354.869353919387, 4381.189479946204, 4386.429764130675, 4426.44669416323, 4312.913741725148, 4371.14762621289, 4378.5183629800695, 4387.802548885292, 4409.316555933835, 4168.812403441822, 4222.345536441856, 4238.597333253336, 4247.0, 4287.035417031143, 4180.226658068267, 4200.432941552882, 4247.7451352918615, 4270.145970805851, 4302.720323178984, 4670.346151538787, 4674.5325467452985, 4707.317438971364, 4724.338592597039, 4729.125111854318, 4857.61139108874, 4964.05682920244, 5016.122178618727, 5031.044101620717, 5081.237814327844, 5093.159628661304, 5093.388793344785, 5093.745312734349, 5110.770015349318, 5124.9743751281, 4549.818007279671, 4714.5521175488275, 4724.102420540086, 4753.073150735608, 4764.618830493552, 5075.847724568269, 5134.922976155361, 5156.226566015128, 5168.483151684876, 5202.323697919246, 4959.181735013754, 4992.575631071327, 5004.799808007639, 5010.223415370623, 5015.699058056528, 4912.877178070506, 4919.827806026843, 5062.392512898476, 5100.48995100487, 5110.8466745997675, 4972.72650004246, 5018.447970723218, 5055.696658200446, 5069.214271787879, 5083.949160508421, 4975.850724478271, 4994.525520075587, 4996.800127994839, 4999.550040496373, 5051.368578927634, 4073.7759423231414, 4250.596193361625, 4253.574642535722, 4263.211305908417, 4314.568543145723, 3635.163912300133, 3662.121090938209, 3669.5779985301497, 3690.944635830465, 3732.5520937487677, 4335.783210839445, 4336.046069864625, 4343.891402714958, 4362.629176519968, 4380.036391993756, 4855.368802055781, 4965.652404331662, 4967.453580106186, 4971.701697898137, 5009.699418034929, 4250.681198910094, 4299.914001720011, 4328.0, 4328.696991210648, 4348.695591308578, 4467.709598876063, 4489.663275254369, 4495.2582823834355, 4514.796834142471, 4519.0, 4694.6244300456565, 4706.0823139487475, 4708.764561771829, 4735.313379560013, 4778.187708089564, 4899.853004409873, 4930.778114984762, 4958.355413796184, 4961.50384961501, 4976.527229913152, 4708.3643708100335, 4728.0543891221205, 4728.31439441286, 4739.526047395233, 4783.976080119577, 4936.8765780855765, 4945.0, 4950.257461380822, 4951.257311403318, 4987.775550100182, 4831.0, 4989.0, 5006.0, 5009.649324547247, 5061.139606267017, 4878.512148785093, 4896.0, 4907.092187945242, 4940.456549779449, 4941.530554597308, 4903.828366007173, 4911.3369695090705, 4911.68074075184, 4939.036887806987, 4942.5304596063315, 4700.059988002345, 4765.642576806684, 4800.087983283304, 4803.423589169322, 4881.096997055556, 4841.2496063110275, 4857.441394239708, 4870.951290487192, 4878.365812444502, 4917.532834380728, 4802.351682522814, 4817.108834865561, 4818.590419814355, 4820.156472617348, 4864.416270047617], "value": 4603.982427015464, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [580.7415700013493, 581.7789240088765, 583.6323116436645, 591.7218907113657, 593.6616128806579, 38876.611233887685, 39986.400203996964, 40232.798836005815, 40342.0, 44363.55636443634, 42193.57806421935, 42657.7867110664, 42705.359419608656, 42711.35932961, 42763.35854962178, 7521.66152523135, 7917.0, 8052.033755949295, 8058.4359094863385, 10101.393916365041, 9937.90062099377, 10299.742506437324, 10305.690829275134, 10313.07182353588, 10326.741831454236, 10346.172306215485, 10377.896221037769, 10397.532111054983, 10400.167986561095, 10401.635942741968, 7646.273604007611, 10202.846957295647, 10298.176145908346, 10426.739331516772, 10446.9477652612, 10254.0, 10338.53476593555, 10349.379037257788, 10354.896451035469, 10358.896411035945, 39923.600763992275, 40114.39828402547, 40154.19691606151, 40172.39741403852, 40187.59812401868, 2604.8046396520335, 2703.4998525272886, 2732.5901114832736, 2732.8496932668695, 2748.573971034493, 29618.70381296181, 29987.250318741986, 30119.247018824484, 30618.38763224723, 31151.37697246065, 3476.6349533298894, 3480.4083305838067, 3504.56192975878, 3520.5423294971747, 3603.387424137903, 271051.0, 271615.6419217911, 273134.0, 273528.0, 273562.0, 46754.298685519745, 53172.46827531675, 53535.0, 53645.0, 53658.463415366125, 61740.38259617346, 63382.0, 63393.366066339666, 63411.68294158499, 63498.682506587174, 14777.26113694311, 14977.0, 15016.699666006618, 15033.248337583078, 15093.094414334955, 10599.470026498644, 11431.256968297033, 11508.482118304697, 11617.825732614017, 12011.459484323226, 15929.522114336589, 16004.359825606845, 16130.274137663833, 16145.515634530982, 16232.107234102, 6262.1546091278005, 6335.619862808247, 6392.264889537762, 6458.2895881453005, 6567.408933195939, 783.6277768060125, 919.9696340099132, 920.1672486399818, 924.2328867040341, 946.2099147212155, 27176.728232717418, 28383.0, 28462.71537284642, 28580.285492862844, 28738.13785586436, 944.9622015119455, 967.8016006718606, 994.8756405449319, 999.9650012249682, 1011.3729487717662, 14370.425182992563, 14920.253987300808, 14925.07591974922, 15023.924880375529, 15195.544133676007, 366679.0, 370342.0, 370479.0, 371461.0, 371616.0, 49712.0, 49927.0, 49973.0, 50027.749861250464, 50084.74957625189, 1093.3658478082693, 1146.2320245435578, 1163.7730642524627, 1210.7275862931122, 1210.878912108782, 28170.0, 29360.706392935797, 29563.40873182567, 29941.0, 30180.69819301779, 41701.58298416977, 41744.37383439312, 42308.36537451879, 42324.36513452239, 42336.0, 309951.0, 311082.0, 313161.0, 313439.0, 314307.0, 31497.685023149475, 36480.63519364772, 37416.438753419265, 37534.0, 39990.80004599958, 41267.7936610315, 41732.0, 41773.23527362891, 42289.78855105705, 42692.359614605186, 34026.659733402346, 34335.82832085923, 34811.477827832095, 34970.82514587411, 35210.29579408346, 28605.28486787847, 28611.856940715163, 28791.136265912057, 28805.0, 30252.017429799365, 35753.82123089368, 36568.26863462663, 36598.817005915866, 36915.0, 36959.44560831536, 30712.0, 31852.840735796173, 32081.518777218826, 32181.517277240393, 32205.677943221206, 24500.75499244985, 25043.624345635195, 25390.492190156463, 25427.36431589225, 25847.0], "value": 18211.887294010616, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [359.1864427072681, 360.67719391144925, 365.92864391443663, 367.1958411079737, 388.45422181834516, 16410.753838692413, 17737.37919172829, 18216.72674909874, 18249.726254106197, 18263.0, 16638.66722665546, 17586.560335991606, 17892.821071789313, 17910.104494775274, 17916.731249031276, 5051.267566202908, 5217.087009773293, 5252.737363131847, 5258.842234732964, 5258.868528286805, 3787.185755062661, 3992.480977472939, 5064.164412871872, 6217.937820621803, 6231.563790534665, 6139.109829074781, 6147.538934579902, 6167.722452489626, 6178.0, 6181.536384771138, 5232.7122008289525, 5490.56075513958, 5502.9174562381395, 5582.637128586629, 6135.570510064297, 3362.747793915466, 5126.846194614168, 5398.514133727984, 5438.619296649236, 5445.673259604437, 12218.938905305506, 12442.440090195963, 12543.0, 14518.0, 21259.255926042668, 1813.184067169779, 1924.191839427444, 1970.3990282963691, 2103.0, 2165.534410101836, 8933.330000250007, 9155.679551215742, 9172.541372931393, 9567.37812042222, 9665.178459830922, 1642.6304081581668, 1689.569159864239, 1695.6015336395938, 1737.1140718233667, 1762.5507475987697, 64607.3539264606, 68833.0, 72362.63818680926, 72708.0, 73392.633036835, 9847.458389788488, 10097.697069087939, 10097.899021009844, 10520.210984176218, 10810.621628242974, 13792.793108103386, 13830.861691382957, 13866.306684665928, 13896.791548126786, 13912.234827084405, 6688.765893193715, 6749.595024298558, 6798.116244888231, 6802.387785099365, 6878.174619045746, 6201.565890387629, 6266.404691554295, 6347.71435285404, 6351.745930162741, 6356.14192084072, 4170.812313445902, 4289.1421715657, 4314.9784251078545, 4340.370646256275, 4489.169503641837, 1770.1680210301395, 1799.2353249868754, 1814.4012475882928, 1842.7051671732459, 1858.9163487642818, 827.4745536584312, 836.1220718245818, 836.761522965964, 856.7900864288165, 869.3697069624598, 16648.25082871274, 17036.914815425844, 17103.14484275781, 17741.11294435273, 18275.0, 827.71443851871, 846.3652260804397, 872.9912700873037, 878.639757699353, 900.7297810656777, 9439.292053096045, 9761.072698093813, 9833.655822046194, 9859.556319965619, 10066.14437772783, 381228.0, 381690.0, 409337.0, 435923.0, 440583.0, 46913.76543117263, 54669.0, 77205.2279477198, 78733.0, 82265.0, 889.7063968890251, 895.5253715530779, 900.8963969143632, 927.874736910522, 929.9860502092474, 12465.0, 15821.288042037906, 15958.20208989569, 16338.918305408397, 17237.31050758006, 26385.0, 28111.297217569725, 38178.2364352717, 39016.80491597524, 39421.60578394179, 47433.762831185624, 50942.49057509526, 51335.0, 51598.74200628973, 51599.226011609106, 7573.962130189314, 7724.266194711493, 7853.253940875607, 7949.483283586548, 7980.760577182694, 10503.737406564896, 11513.021393181338, 11631.825522617337, 11906.583269585522, 11942.104342174198, 9112.407693499901, 9246.352755307063, 9306.115918987685, 9368.297377696565, 9592.808143837225, 9673.806523869624, 9699.175570076623, 9762.414255144715, 9914.058164474363, 10012.39925604466, 9473.857892131486, 9516.33385662997, 9883.50582470888, 9962.352447090916, 9998.950005249928, 7733.149353570991, 7786.532808031537, 7799.06411230656, 7947.728363461818, 7989.600519973862, 9111.635534578807, 9675.225981921289, 9698.757531061498, 9780.41317520951, 9932.304738668512], "value": 8056.096633364113, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1365.467467687602, 1415.6673181802278, 1463.1715339380044, 1669.1987845834008, 1682.0748588276454, 7406.925930740691, 7870.094939082007, 7960.243776841198, 7961.6019199039965, 7964.362850971916, 6817.795466136024, 7450.515716478439, 7649.120351159619, 7655.387568994481, 7835.8824617630635, 2189.321310393775, 2239.0819763896807, 2240.204727321802, 2240.2943072932057, 2240.529488807352, 2194.2429861697688, 2208.6245338292447, 2229.7547269800307, 2230.119102954332, 2235.35174799308, 1910.2550005497867, 1994.5013746563359, 1996.8103030212108, 2001.2995451591898, 2127.797859203381, 2140.2723074154783, 2184.803367696899, 2188.179432712741, 2189.6496560550236, 2192.725909261342, 2167.1764729402885, 2182.9563408731897, 2185.8142057925097, 2216.8448208625405, 2219.1012639880873, 7859.52842829432, 7899.684012639487, 7902.446828721992, 7910.4067194960035, 7912.643931023118, 4505.5719706627815, 4522.909541809146, 4526.705764125321, 4620.699654522446, 4630.236011058171, 2850.743433091032, 2893.2043687985974, 2922.985385073061, 2960.289530512683, 2963.7480814130827, 919.6275508419101, 937.301710225886, 953.3803028031765, 962.870012548304, 964.9228061755043, 10576.10103141234, 10637.73405664857, 10643.893561064368, 10655.467226638637, 10657.946710266477, 3983.382575700771, 4048.67610591152, 4214.473190851144, 4217.198732240926, 4219.135077309205, 9017.909820901707, 9019.909800902038, 9035.593398297093, 9040.186383225542, 9040.773980650536, 4601.0567833594705, 4602.861914142581, 4628.629709623222, 4631.444226692818, 4634.096351211549, 4192.748435093904, 4200.810963506649, 4231.322988321854, 4238.724482908601, 4244.342126970325, 3607.639236076371, 3699.5930447650735, 3736.0, 3743.812809359521, 3767.604401537854, 1132.2583707671488, 1132.3319241647469, 1134.0813940708053, 1136.2330426961933, 1163.1450883600546, 763.171958425115, 780.4185881518305, 784.6312233250363, 786.3473317146754, 798.3054742374171, 4783.258594917794, 4897.632677549198, 5049.57078648319, 5059.797608095635, 5094.592432605383, 399.65230249682963, 400.73351221438, 410.6899291035309, 411.68917467311985, 421.79753718215426, 3498.4927185557954, 3506.088417011544, 3583.0, 3608.0, 3627.093226693327, 9313.115254050854, 9568.0, 9620.326577139673, 9745.902540974641, 9759.658411955548, 9550.95224523873, 9676.032396760267, 9686.273529485317, 9687.70936871895, 9720.222382209406, 7136.857262854817, 7247.094113235846, 7422.814429639194, 7441.813954651068, 7445.25547445262, 5316.6012549058205, 5385.299911011543, 5408.972955135199, 5497.47773961473, 5667.943320566742, 8977.461352318882, 9050.366474346733, 9153.130452606993, 9164.6792362267, 9165.77085572866, 8328.375371847014, 8636.611352489279, 8649.870251946351, 8898.955505222433, 8910.376273660782, 6760.3239676032, 6916.412104971134, 6918.273581273894, 6927.930720692729, 6945.583265004116, 7326.4138868889295, 7369.4841361106355, 7447.329740323397, 7454.44091693136, 7457.589832559044, 6155.938440615716, 6559.508036897157, 6786.728530858711, 6794.3545363190415, 6847.6576171190245, 6389.361063893573, 6686.0, 6780.288069752605, 6849.349311815369, 6956.130483689539, 5900.0, 5913.0, 5952.958232309242, 5961.2846458425265, 5964.701764911651, 4466.397036400044, 4502.061994139852, 4613.3771940787565, 4619.630429565559, 4662.044280922335, 4490.416245888014, 4523.11799199153, 4530.18456677788, 4551.544845515422, 4595.0], "value": 4035.463049571154, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [293.5552637753803, 300.05632286459087, 302.4329382407985, 305.8195664557909, 306.6917747663598, 4898.3387242722265, 4959.23131914553, 4960.156773348531, 4973.602111831054, 4976.427710813257, 4852.272159176126, 4856.0, 4927.950720492794, 4936.136176169174, 4945.455999840015, 3657.725670574704, 3768.152165762695, 3783.716221283401, 3833.71247156463, 3886.747361421513, 3810.733248672594, 3846.788426636534, 3849.326367885623, 3856.730028897978, 3863.961360386388, 3812.485314482551, 3826.7754318618054, 3842.9807850960706, 3853.344931361675, 3861.2856621524966, 3675.2833197526493, 3702.9444558331647, 3794.6015668354703, 3802.923941521182, 3819.5416550013897, 3360.915977100592, 3367.478040903664, 3373.7638365314438, 3380.138064793462, 3380.357732030931, 4734.786934587952, 4745.59662428694, 4768.785404656799, 4773.09311230865, 4776.5701086902345, 4231.1537692461325, 4367.694261401703, 4382.715123516993, 4394.835368627317, 4402.889927751799, 5050.141475949061, 5055.721935293557, 5062.594992400599, 5076.492350764931, 5079.695218286877, 3690.5791270360837, 3710.9443358349645, 3720.795356255405, 3743.6630703236565, 3755.1550901047044, 5095.566876815476, 5326.121190003645, 5400.270963419966, 5403.918941215885, 5411.485908838654, 3798.468214449924, 3798.753081049723, 3801.239752049601, 3807.162424266657, 3813.5995720448955, 3874.9418758718643, 3885.747426417274, 3885.8834234972433, 3887.125396785757, 3890.3775395936514, 4371.781410929504, 4383.9342409863875, 4385.6710746694125, 4391.736495810262, 4401.911961760747, 4878.317035615017, 5075.619328550373, 5078.644494885397, 5092.0, 5140.0, 1288.606974872673, 1298.4546490474024, 1304.452130105358, 1305.6670549010132, 1312.6718320419895, 823.3495538524498, 831.2269589281905, 843.9240468357877, 844.8299105738596, 849.7323343146888, 4482.193205223091, 4482.731036137842, 4503.3245013247615, 4504.2883704192855, 4521.84173553924, 5170.121079416433, 5181.0, 5182.948170518322, 5203.531682148625, 5220.451852555503, 4288.656907447396, 4490.12442573695, 4508.1659892919915, 4509.774511274423, 4516.796744146455, 2769.4599553086955, 2774.694783573805, 2779.680336761258, 2794.3293609533566, 2797.650293713286, 4895.143349913751, 4912.729799861042, 4916.901661966741, 4926.75366231687, 4931.7040977541465, 5082.898342033212, 5305.867353316198, 5311.49540793624, 5312.62811603184, 5314.123169677037, 4869.683470574401, 4878.658493905464, 4881.658283920163, 4903.387076615423, 4950.282209079735, 5152.0, 5164.664296820694, 5171.379434467737, 5199.220116982332, 5202.271681964452, 4964.05682920244, 5084.847454576368, 5109.540141387293, 5116.718580478109, 5145.253938179018, 4920.5817505511, 4928.186849169847, 4931.926021109758, 4956.0, 4966.056449274626, 3694.870679526311, 3708.9814550928154, 3710.499082623919, 3713.591504934509, 3721.404575267944, 4890.853274401774, 5176.974115129551, 5192.870178245574, 5266.262723218829, 5269.104252277199, 4991.975040124777, 5007.7746501408255, 5016.698998060128, 5026.472220416804, 5028.874278143076, 4978.626603004716, 5003.699778013331, 5047.7476126192805, 5054.444011158696, 5069.352460450442, 4790.856274311776, 4925.556699897026, 4971.204607262821, 4993.750312484434, 5073.0, 4865.416150062015, 4980.676256043345, 5071.898562028812, 5096.898062038813, 5135.584836083963, 5053.974730126473, 5056.317397151338, 5058.0, 5101.336826212569, 5105.846824595268], "value": 3844.5273359639227, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [585.5900869391427, 603.5262319079523, 653.8398092467344, 683.9110915580975, 686.8797960356936, 11455.198136130473, 11725.824112638316, 11950.701232469193, 11956.163068585203, 12044.69888252794, 12248.571300004502, 12309.507619695201, 12325.260484370921, 12329.63011109666, 12353.814692779593, 636.4144986612305, 752.6462562595585, 754.4304050441908, 778.9727359542388, 780.3834970373426, 788.0740130346849, 790.2887401338784, 792.6710415177695, 793.4049462902823, 793.888855560222, 840.5251033166242, 841.5077179849795, 842.2546046748611, 842.2924743215698, 842.8567143585575, 657.4707360574736, 669.3273260373343, 669.9916625478672, 678.2031113441686, 836.510641274855, 578.7598146769128, 594.2126682146173, 595.2262059322863, 595.9523238140938, 600.0818747316569, 11756.882431175663, 11975.101867359983, 11983.94008029963, 11986.580469683606, 12008.219465734786, 12141.392930353448, 12445.377731113407, 12637.873621263761, 12651.493940242379, 12926.482940682361, 11754.529818807237, 11755.64733058001, 11768.88231117695, 11783.941080294544, 12129.332886691229, 4114.897127571805, 4126.711130220886, 4145.274576949022, 4158.064435502018, 4178.979105104455, 54124.458755412335, 61729.0, 61943.38056619421, 62003.37996620021, 63022.0, 10947.616833410792, 11131.554737810558, 11191.384473853854, 11193.16051296156, 11321.547138114547, 17641.7353739694, 18782.342618008304, 18784.436466906016, 18788.0, 18800.90599547021, 7179.138503379523, 7272.292207759832, 7350.338469537767, 7371.631418429057, 7619.0, 3842.3852183650483, 3983.661388781986, 3992.1416895367634, 3995.6603688686787, 4002.3396139637216, 2938.103878317091, 3048.34460590974, 3049.8017628854054, 3052.160655819623, 3052.938941221163, 483.2340739927255, 486.95373939475695, 491.3784063160105, 493.52621483375646, 535.1865164949334, 522.908491014071, 548.6461232505012, 550.5017958747341, 550.856777237921, 562.1820251534008, 7937.920620793833, 7999.640016199169, 8181.263686268146, 8484.109168537338, 8531.402801803939, 498.32974649096906, 518.3132349636671, 541.0423550315998, 542.6798189068469, 583.125312031952, 4023.75857448554, 4104.4048612950955, 4118.135191609796, 4181.707280490397, 4410.051838854662, 75973.6201319001, 79600.20399795928, 79875.60062199652, 93908.0, 94489.0, 63046.684766575876, 67778.66110669416, 76021.61989190019, 80322.0, 82033.17966820375, 47339.289910651365, 67740.0, 69794.30205697878, 73342.63328683322, 73888.26111738815, 8118.985126859143, 8235.917640823754, 8403.243708066304, 8581.27059199975, 8704.56477176126, 41206.0, 42479.3628095585, 42586.36120458258, 44129.77935110304, 44160.77919610382, 35809.82095089508, 47919.76040119777, 62588.68705656443, 62883.6855815718, 63453.0, 10919.235653504178, 11017.338959662446, 11021.504032318404, 11239.269447486215, 11451.656450306504, 11964.521419143484, 11969.64091077269, 12010.879891201326, 12168.939155304166, 12246.448909798903, 7251.0, 10977.0, 11276.661700149009, 11802.586909458129, 11916.0, 10824.134069274596, 10866.96763807437, 11152.0, 11985.880141198477, 12336.331747299633, 11014.779704406028, 11431.542738290374, 11779.88220117788, 12069.698257543632, 12164.39178041112, 9537.284703647116, 9782.706518804447, 9840.70477885636, 10248.128909042814, 10334.224933129895, 9813.803723925339, 10127.88593254756, 10144.8479919366, 10146.949265253626, 10277.84583231267], "value": 6430.506204562089, "unit": "score"}], "name": "dromaeo_css", "value": 6379.320394149645, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 113.0, 114.0, 117.0, 115.0, 115.0, 115.0, 115.0, 112.0, 113.0], "value": 113.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 127.0, 122.0, 176.0, 125.0, 129.0, 153.0, 128.0, 167.0, 124.0], "value": 140.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 161.0, 160.0, 162.0, 161.0, 161.0, 161.0, 162.0, 161.0], "value": 161.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 120.0, 76.0, 76.0, 75.0, 76.0, 100.0, 76.0], "value": 82.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 112.0, 85.0, 85.0, 85.0, 113.0, 86.0, 85.0, 124.0], "value": 94.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 116.0, 117.0, 117.0, 117.0, 116.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 147.0, 147.0, 146.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": [95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 94.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 64.0, 63.0, 62.0, 63.0, 62.0, 62.0, 73.0, 62.0, 62.0], "value": 64.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 64.0, 65.0, 64.0, 65.0, 65.0, 65.0, 65.0, 65.0], "value": 64.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 78.0, 78.0, 78.0, 78.0, 78.0, 82.0, 79.0, 80.0, 78.0], "value": 78.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 127.0, 127.0, 136.0, 127.0, 128.0, 136.0, 127.0, 163.0, 129.0], "value": 132.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 163.0, 164.0, 163.0, 170.0, 167.0, 166.0, 168.0, 166.0, 165.0], "value": 166.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 67.0, 67.0, 66.0, 71.0, 66.0, 66.0, 70.0, 65.0], "value": 67.4, "unit": "score"}], "name": "kraken", "value": 1524.8000000000002, "alertThreshold": 2.0}]} 23:33:22 INFO - Return code: 0 23:33:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:33:22 INFO - # TBPL SUCCESS # 23:33:22 INFO - Running post-action listener: _resource_record_post_action 23:33:22 INFO - ##### 23:33:22 INFO - ##### Finished run-tests step (success) 23:33:22 INFO - ##### 23:33:22 INFO - Running post-run listener: _resource_record_post_run 23:33:22 INFO - Total resource usage - Wall time: 1199s; CPU: 13.0%; Read bytes: 0; Write bytes: 404107264; Read time: 0; Write time: 427384 23:33:22 INFO - TinderboxPrint: CPU usage
13.0% 23:33:22 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 23:33:22 INFO - TinderboxPrint: I/O write bytes / time
404,107,264 / 427,384 23:33:22 INFO - TinderboxPrint: CPU idle
8,326.9 (87.0%) 23:33:22 INFO - TinderboxPrint: CPU user
1,202.1 (12.6%) 23:33:22 INFO - TinderboxPrint: Swap in / out
0 / 0 23:33:22 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 120496128; Read time: 0; Write time: 129816 23:33:22 INFO - run-tests - Wall time: 1183s; CPU: 13.0%; Read bytes: 0; Write bytes: 283611136; Read time: 0; Write time: 297568 23:33:22 INFO - Running post-run listener: _upload_blobber_files 23:33:22 INFO - Blob upload gear active. 23:33:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:33:22 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 23:33:22 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'] 23:33:22 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 23:33:23 INFO - (blobuploader) - INFO - Open directory for files ... 23:33:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:33:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:33:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:33:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:33:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:33:26 INFO - (blobuploader) - INFO - Done attempting. 23:33:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:33:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:33:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:33:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:33:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:33:27 INFO - (blobuploader) - INFO - Done attempting. 23:33:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:33:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:33:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:33:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:33:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:33:28 INFO - (blobuploader) - INFO - Done attempting. 23:33:28 INFO - (blobuploader) - INFO - Iteration through files over. 23:33:28 INFO - Return code: 0 23:33:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:33:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:33:28 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/7afb0aba81f5df8ba5ef3d1eb92d726868fc473d602d773482213af58f3a173742204f36503f73f6ba11001c8e8f042f5355e12870e4d284182a1ae8fc3618bf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/f233f9ba66ffff4ca3c7e660aa43b0b6bb8a0a531e53a969da33505276316164e71ad7df1caf989e954fe317e19c9a6007370838593979fbb5c786e89122ab04", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/936b3352ae9866228f3c278dd00b0f892fdabc18813b5096fe7e066630bbafd3c050f697bcdc42559a611cc114404f3e490c52d5f43f3c1fa40e4215834bcd0c"} 23:33:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:33:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:33:28 INFO - Contents: 23:33:28 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/7afb0aba81f5df8ba5ef3d1eb92d726868fc473d602d773482213af58f3a173742204f36503f73f6ba11001c8e8f042f5355e12870e4d284182a1ae8fc3618bf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/f233f9ba66ffff4ca3c7e660aa43b0b6bb8a0a531e53a969da33505276316164e71ad7df1caf989e954fe317e19c9a6007370838593979fbb5c786e89122ab04", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/936b3352ae9866228f3c278dd00b0f892fdabc18813b5096fe7e066630bbafd3c050f697bcdc42559a611cc114404f3e490c52d5f43f3c1fa40e4215834bcd0c"} 23:33:28 INFO - Running post-run listener: copy_logs_to_upload_dir 23:33:28 INFO - Copying logs to upload dir... 23:33:28 INFO - mkdir: /builds/slave/test/build/upload/logs 23:33:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.636546 ========= master_lag: 129.66 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 23 mins, 17 secs) (at 2016-06-29 23:35:37.406458) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-29 23:35:37.411928) ========= 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=9ee515b9ec57ce2c386832a8000001b4-1467267070.65956-799192822 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/7afb0aba81f5df8ba5ef3d1eb92d726868fc473d602d773482213af58f3a173742204f36503f73f6ba11001c8e8f042f5355e12870e4d284182a1ae8fc3618bf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/f233f9ba66ffff4ca3c7e660aa43b0b6bb8a0a531e53a969da33505276316164e71ad7df1caf989e954fe317e19c9a6007370838593979fbb5c786e89122ab04", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/936b3352ae9866228f3c278dd00b0f892fdabc18813b5096fe7e066630bbafd3c050f697bcdc42559a611cc114404f3e490c52d5f43f3c1fa40e4215834bcd0c"} build_url:https://queue.taskcluster.net/v1/task/EW-Jtd8uRc6dtoThjVRfqA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011235 build_url: 'https://queue.taskcluster.net/v1/task/EW-Jtd8uRc6dtoThjVRfqA/artifacts/public/build/firefox-50.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/7afb0aba81f5df8ba5ef3d1eb92d726868fc473d602d773482213af58f3a173742204f36503f73f6ba11001c8e8f042f5355e12870e4d284182a1ae8fc3618bf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/f233f9ba66ffff4ca3c7e660aa43b0b6bb8a0a531e53a969da33505276316164e71ad7df1caf989e954fe317e19c9a6007370838593979fbb5c786e89122ab04", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/936b3352ae9866228f3c278dd00b0f892fdabc18813b5096fe7e066630bbafd3c050f697bcdc42559a611cc114404f3e490c52d5f43f3c1fa40e4215834bcd0c"}' ========= master_lag: 0.26 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-29 23:35:37.683504) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 23:35:37.683884) ========= 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=9ee515b9ec57ce2c386832a8000001b4-1467267070.65956-799192822 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004471 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-29 23:35:37.707652) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-29 23:35:37.709874) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-29 23:35:37.712213) ========= ========= Total master_lag: 130.00 =========