builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-028 starttime: 1460635754.02 results: success (0) buildid: 20160414023029 builduid: 18e8974363ef4a34ba670f61af54efcc revision: ef461673c13f81ff4bbb162f739227ab75844941 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-14 05:09:14.023258) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-14 05:09:14.023658) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-14 05:09:14.023931) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-14 05:09:14.038424) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 05:09:14.038686) ========= 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=836cfdc75598d54cb747368e000001a9-1460632537.155154-999909945 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-04-14 05:09:14-- 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% 224M=0s 2016-04-14 05:09:14 (224 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.158341 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 05:09:14.212865) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 05:09:14.213419) ========= 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=836cfdc75598d54cb747368e000001a9-1460632537.155154-999909945 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014710 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 05:09:14.263834) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 05:09:14.264204) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev ef461673c13f81ff4bbb162f739227ab75844941 --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 ef461673c13f81ff4bbb162f739227ab75844941 --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=836cfdc75598d54cb747368e000001a9-1460632537.155154-999909945 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-04-14 05:09:14,571 truncating revision to first 12 chars 2016-04-14 05:09:14,572 Setting DEBUG logging. 2016-04-14 05:09:14,572 attempt 1/10 2016-04-14 05:09:14,572 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/ef461673c13f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-14 05:09:14,798 unpacking tar archive at: fx-team-ef461673c13f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.549768 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 05:09:14.827087) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-14 05:09:14.827399) ========= script_repo_revision: ef461673c13f81ff4bbb162f739227ab75844941 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-14 05:09:14.827809) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-14 05:09:14.828115) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-14 05:09:14.838826) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-04-14 05:09:14.839130) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=836cfdc75598d54cb747368e000001a9-1460632537.155154-999909945 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:09:15 INFO - MultiFileLogger online at 20160414 05:09:15 in /builds/slave/test 05:09:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 05:09:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:09:15 INFO - {'append_to_log': False, 05:09:15 INFO - 'base_work_dir': '/builds/slave/test', 05:09:15 INFO - 'blob_upload_branch': 'Fx-Team', 05:09:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:09:15 INFO - 'branch': 'Fx-Team', 05:09:15 INFO - 'buildbot_json_path': 'buildprops.json', 05:09:15 INFO - 'config_files': ('talos/linux_config.py',), 05:09:15 INFO - 'default_actions': ('clobber', 05:09:15 INFO - 'read-buildbot-config', 05:09:15 INFO - 'download-and-extract', 05:09:15 INFO - 'populate-webroot', 05:09:15 INFO - 'create-virtualenv', 05:09:15 INFO - 'install', 05:09:15 INFO - 'run-tests'), 05:09:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:09:15 INFO - 'download_minidump_stackwalk': True, 05:09:15 INFO - 'download_symbols': 'ondemand', 05:09:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:09:15 INFO - 'tooltool.py': '/tools/tooltool.py', 05:09:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:09:15 INFO - '/tools/misc-python/virtualenv.py')}, 05:09:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:09:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:09:15 INFO - 'installer_path': 'installer.exe', 05:09:15 INFO - 'log_level': 'info', 05:09:15 INFO - 'log_name': 'talos', 05:09:15 INFO - 'log_to_console': True, 05:09:15 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:09:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:09:15 INFO - 'opt_config_files': (), 05:09:15 INFO - 'pip_index': False, 05:09:15 INFO - 'sps_profile': False, 05:09:15 INFO - 'sps_profile_interval': 0, 05:09:15 INFO - 'suite': 'dromaeojs', 05:09:15 INFO - 'system_bits': '32', 05:09:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:09:15 INFO - 'title': 'talos-linux64-ix-028', 05:09:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:09:15 INFO - 'use_talos_json': True, 05:09:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:09:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:09:15 INFO - 'work_dir': 'build'} 05:09:15 INFO - ##### 05:09:15 INFO - ##### Running clobber step. 05:09:15 INFO - ##### 05:09:15 INFO - Running pre-action listener: _resource_record_pre_action 05:09:15 INFO - Running main action method: clobber 05:09:15 INFO - rmtree: /builds/slave/test/build 05:09:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:09:15 INFO - Running post-action listener: _resource_record_post_action 05:09:15 INFO - ##### 05:09:15 INFO - ##### Running read-buildbot-config step. 05:09:15 INFO - ##### 05:09:15 INFO - Running pre-action listener: _resource_record_pre_action 05:09:15 INFO - Running main action method: read_buildbot_config 05:09:15 INFO - Using buildbot properties: 05:09:15 INFO - { 05:09:15 INFO - "project": "", 05:09:15 INFO - "product": "firefox", 05:09:15 INFO - "script_repo_revision": "production", 05:09:15 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 05:09:15 INFO - "repository": "", 05:09:15 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 05:09:15 INFO - "buildid": "20160414023029", 05:09:15 INFO - "pgo_build": "True", 05:09:15 INFO - "basedir": "/builds/slave/test", 05:09:15 INFO - "buildnumber": 1097, 05:09:15 INFO - "slavename": "talos-linux64-ix-028", 05:09:15 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 05:09:15 INFO - "platform": "ubuntu64_hw", 05:09:15 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 05:09:15 INFO - "branch": "fx-team", 05:09:15 INFO - "repo_path": "integration/fx-team", 05:09:15 INFO - "revision": "ef461673c13f81ff4bbb162f739227ab75844941", 05:09:15 INFO - "stage_platform": "linux64-pgo", 05:09:15 INFO - "builduid": "18e8974363ef4a34ba670f61af54efcc", 05:09:15 INFO - "slavebuilddir": "test" 05:09:15 INFO - } 05:09:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/D1BhgNObSliTbzWWbBPf2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2. 05:09:15 INFO - Running post-action listener: _resource_record_post_action 05:09:15 INFO - ##### 05:09:15 INFO - ##### Running download-and-extract step. 05:09:15 INFO - ##### 05:09:15 INFO - Running pre-action listener: _resource_record_pre_action 05:09:15 INFO - Running main action method: download_and_extract 05:09:15 INFO - mkdir: /builds/slave/test/build/tests 05:09:15 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')]} 05:09:15 INFO - https://queue.taskcluster.net/v1/task/D1BhgNObSliTbzWWbBPf2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 05:09:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1BhgNObSliTbzWWbBPf2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 05:09:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1BhgNObSliTbzWWbBPf2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 05:09:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1BhgNObSliTbzWWbBPf2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 05:09:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1BhgNObSliTbzWWbBPf2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 05:09:16 INFO - Downloaded 1361 bytes. 05:09:16 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 05:09:16 INFO - Using the following test package requirements: 05:09:16 INFO - {u'common': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'], 05:09:16 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 05:09:16 INFO - u'firefox-48.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:09:16 INFO - u'gtest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 05:09:16 INFO - u'firefox-48.0a1.en-US.linux-x86_64.gtest.tests.zip'], 05:09:17 INFO - u'jittest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 05:09:17 INFO - u'jsshell-linux-x86_64.zip'], 05:09:17 INFO - u'mochitest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 05:09:17 INFO - u'firefox-48.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:09:17 INFO - u'mozbase': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'], 05:09:17 INFO - u'reftest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 05:09:17 INFO - u'firefox-48.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:09:17 INFO - u'talos': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 05:09:17 INFO - u'firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:09:17 INFO - u'web-platform': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 05:09:17 INFO - u'firefox-48.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:09:17 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 05:09:17 INFO - u'firefox-48.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:09:17 INFO - Downloading packages: [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:09: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')]} 05:09:17 INFO - https://queue.taskcluster.net/v1/task/D1BhgNObSliTbzWWbBPf2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:09:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1BhgNObSliTbzWWbBPf2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 05:09:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1BhgNObSliTbzWWbBPf2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 05:09:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1BhgNObSliTbzWWbBPf2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 05:09:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1BhgNObSliTbzWWbBPf2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:09:18 INFO - Downloaded 23774606 bytes. 05:09:18 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:09:18 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 05:09:19 INFO - Return code: 0 05:09:19 INFO - Downloading packages: [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 05:09:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:09:19 INFO - https://queue.taskcluster.net/v1/task/D1BhgNObSliTbzWWbBPf2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:09:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1BhgNObSliTbzWWbBPf2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 05:09:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1BhgNObSliTbzWWbBPf2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 05:09:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1BhgNObSliTbzWWbBPf2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 05:09:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1BhgNObSliTbzWWbBPf2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 05:09:21 INFO - Downloaded 23774606 bytes. 05:09:21 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:09:21 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 05:09:22 INFO - Return code: 0 05:09:22 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')]} 05:09:22 INFO - https://queue.taskcluster.net/v1/task/D1BhgNObSliTbzWWbBPf2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:09:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1BhgNObSliTbzWWbBPf2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 05:09:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1BhgNObSliTbzWWbBPf2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 05:09:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1BhgNObSliTbzWWbBPf2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 05:09:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1BhgNObSliTbzWWbBPf2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 05:09:24 INFO - Downloaded 10841076 bytes. 05:09:24 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:09:24 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 05:09:24 INFO - Return code: 0 05:09: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')]} 05:09:24 INFO - https://queue.taskcluster.net/v1/task/D1BhgNObSliTbzWWbBPf2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:09:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1BhgNObSliTbzWWbBPf2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 05:09:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1BhgNObSliTbzWWbBPf2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 05:09:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1BhgNObSliTbzWWbBPf2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 05:09:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1BhgNObSliTbzWWbBPf2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 05:09:29 INFO - Downloaded 57895416 bytes. 05:09:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/D1BhgNObSliTbzWWbBPf2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 05:09:29 INFO - mkdir: /builds/slave/test/properties 05:09:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:09:29 INFO - Writing to file /builds/slave/test/properties/build_url 05:09:29 INFO - Contents: 05:09:29 INFO - build_url:https://queue.taskcluster.net/v1/task/D1BhgNObSliTbzWWbBPf2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 05:09:30 INFO - Running post-action listener: _resource_record_post_action 05:09:30 INFO - Running post-action listener: set_extra_try_arguments 05:09:30 INFO - ##### 05:09:30 INFO - ##### Running populate-webroot step. 05:09:30 INFO - ##### 05:09:30 INFO - Running pre-action listener: _resource_record_pre_action 05:09:30 INFO - Running main action method: populate_webroot 05:09:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:09:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:09:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:09:30 INFO - '--tppagecycles', 05:09:30 INFO - '7'], 05:09:30 INFO - 'tests': ['tsvgm']}}, 05:09:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:09:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:09:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:09:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:09:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:09:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:09:30 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:30 INFO - 'talos_options': ['--disable-e10s'], 05:09:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:09:30 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:09:30 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:30 INFO - 'talos_options': ['--disable-e10s'], 05:09:30 INFO - 'tests': ['damp', 'tps']}, 05:09:30 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:30 INFO - 'tests': ['damp', 'tps']}, 05:09:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:09:30 INFO - 'tests': ['dromaeo_dom']}, 05:09:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:09:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:09:30 INFO - 'tests': ['a11yr', 05:09:30 INFO - 'ts_paint', 05:09:30 INFO - 'tpaint', 05:09:30 INFO - 'sessionrestore', 05:09:30 INFO - 'sessionrestore_no_auto_restore', 05:09:30 INFO - 'tabpaint']}, 05:09:30 INFO - 'other-e10s': {'tests': ['a11yr', 05:09:30 INFO - 'ts_paint', 05:09:30 INFO - 'tpaint', 05:09:30 INFO - 'sessionrestore', 05:09:30 INFO - 'sessionrestore_no_auto_restore', 05:09:30 INFO - 'tabpaint']}, 05:09:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:09:30 INFO - 'ts_paint', 05:09:30 INFO - 'tpaint', 05:09:30 INFO - 'sessionrestore', 05:09:30 INFO - 'sessionrestore_no_auto_restore', 05:09:30 INFO - 'tabpaint']}, 05:09:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:09:30 INFO - 'ts_paint', 05:09:30 INFO - 'tpaint', 05:09:30 INFO - 'sessionrestore', 05:09:30 INFO - 'sessionrestore_no_auto_restore', 05:09:30 INFO - 'tabpaint']}, 05:09:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:09:30 INFO - 'tests': ['a11yr', 05:09:30 INFO - 'ts_paint', 05:09:30 INFO - 'tpaint', 05:09:30 INFO - 'sessionrestore', 05:09:30 INFO - 'sessionrestore_no_auto_restore', 05:09:30 INFO - 'tabpaint']}, 05:09:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:09:30 INFO - 'tests': ['a11yr', 05:09:30 INFO - 'ts_paint', 05:09:30 INFO - 'tpaint', 05:09:30 INFO - 'sessionrestore', 05:09:30 INFO - 'sessionrestore_no_auto_restore', 05:09:30 INFO - 'tabpaint']}, 05:09:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:09:30 INFO - 'tests': ['tsvgx', 05:09:30 INFO - 'tsvgr_opacity', 05:09:30 INFO - 'tart', 05:09:30 INFO - 'tscrollx', 05:09:30 INFO - 'cart']}, 05:09:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:09:30 INFO - 'tsvgr_opacity', 05:09:30 INFO - 'tart', 05:09:30 INFO - 'tscrollx', 05:09:30 INFO - 'cart']}, 05:09:30 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:30 INFO - 'talos_options': ['--disable-e10s'], 05:09:30 INFO - 'tests': ['tp5o']}, 05:09:30 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:30 INFO - 'tests': ['tp5o']}, 05:09:30 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:30 INFO - 'talos_options': ['--disable-e10s', 05:09:30 INFO - '--xperf_path', 05:09:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:09:30 INFO - 'tests': ['tp5n']}, 05:09:30 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:09:30 INFO - 'talos_options': ['--xperf_path', 05:09:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:09:30 INFO - 'tests': ['tp5n']}}, 05:09:30 INFO - 'talos.zip': {'path': '', 05:09:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:09:30 INFO - Running post-action listener: _resource_record_post_action 05:09:30 INFO - ##### 05:09:30 INFO - ##### Running create-virtualenv step. 05:09:30 INFO - ##### 05:09:30 INFO - Running pre-action listener: _resource_record_pre_action 05:09:30 INFO - Running main action method: create_virtualenv 05:09:30 INFO - Creating virtualenv /builds/slave/test/build/venv 05:09:30 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:09:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:09:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:09:30 INFO - Using real prefix '/usr' 05:09:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:09:30 INFO - Installing distribute.............................................................................................................................................................................................done. 05:09:32 INFO - Installing pip.................done. 05:09:32 INFO - Return code: 0 05:09:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:09:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:09:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:09:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:32 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 0x7f7a4de60f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22118a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22dc9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x236b810>, '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 0x236c710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x236cba0>, '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': '836cfdc75598d54cb747368e000001a9-1460632537.155154-999909945', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:09:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:09:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:09:32 INFO - Using env: {'DISPLAY': ':0', 05:09:32 INFO - 'HOME': '/home/cltbld', 05:09:32 INFO - 'LANG': 'en_US.UTF-8', 05:09:32 INFO - 'LANGUAGE': 'en_US:en', 05:09:32 INFO - 'LOGNAME': 'cltbld', 05:09:32 INFO - 'MAIL': '/var/mail/cltbld', 05:09:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:32 INFO - 'MOZ_NO_REMOTE': '1', 05:09:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:32 INFO - 'NO_EM_RESTART': '1', 05:09:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:32 INFO - 'PWD': '/builds/slave/test', 05:09:32 INFO - 'SHELL': '/bin/bash', 05:09:32 INFO - 'SHLVL': '1', 05:09:32 INFO - 'TERM': 'linux', 05:09:32 INFO - 'TMOUT': '86400', 05:09:32 INFO - 'USER': 'cltbld', 05:09:32 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1460632537.155154-999909945', 05:09:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:32 INFO - '_': '/tools/buildbot/bin/python'} 05:09:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:32 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:09:32 INFO - Cleaning up... 05:09:32 INFO - Return code: 0 05:09:32 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:09:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:09:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:09:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:32 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>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7a4de60f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22118a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22dc9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x236b810>, '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 0x236c710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x236cba0>, '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': '836cfdc75598d54cb747368e000001a9-1460632537.155154-999909945', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:09:32 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>=0.7.1'] in /builds/slave/test/build 05:09:32 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>=0.7.1 05:09:32 INFO - Using env: {'DISPLAY': ':0', 05:09:32 INFO - 'HOME': '/home/cltbld', 05:09:32 INFO - 'LANG': 'en_US.UTF-8', 05:09:32 INFO - 'LANGUAGE': 'en_US:en', 05:09:32 INFO - 'LOGNAME': 'cltbld', 05:09:32 INFO - 'MAIL': '/var/mail/cltbld', 05:09:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:32 INFO - 'MOZ_NO_REMOTE': '1', 05:09:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:32 INFO - 'NO_EM_RESTART': '1', 05:09:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:32 INFO - 'PWD': '/builds/slave/test', 05:09:32 INFO - 'SHELL': '/bin/bash', 05:09:32 INFO - 'SHLVL': '1', 05:09:32 INFO - 'TERM': 'linux', 05:09:32 INFO - 'TMOUT': '86400', 05:09:32 INFO - 'USER': 'cltbld', 05:09:32 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1460632537.155154-999909945', 05:09:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:32 INFO - '_': '/tools/buildbot/bin/python'} 05:09:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:33 INFO - Downloading/unpacking psutil>=0.7.1 05:09:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:09:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:09:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:09:35 INFO - Installing collected packages: psutil 05:09:35 INFO - Running setup.py install for psutil 05:09:35 INFO - building 'psutil._psutil_linux' extension 05:09:35 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 05:09:35 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 05:09:35 INFO - building 'psutil._psutil_posix' extension 05:09:35 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 05:09:35 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 05:09:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:09:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:09:35 INFO - Successfully installed psutil 05:09:35 INFO - Cleaning up... 05:09:35 INFO - Return code: 0 05:09:35 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:09:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:09:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:09:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7a4de60f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22118a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22dc9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x236b810>, '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 0x236c710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x236cba0>, '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': '836cfdc75598d54cb747368e000001a9-1460632537.155154-999909945', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:09:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 05:09:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 05:09:35 INFO - Using env: {'DISPLAY': ':0', 05:09:35 INFO - 'HOME': '/home/cltbld', 05:09:35 INFO - 'LANG': 'en_US.UTF-8', 05:09:35 INFO - 'LANGUAGE': 'en_US:en', 05:09:35 INFO - 'LOGNAME': 'cltbld', 05:09:35 INFO - 'MAIL': '/var/mail/cltbld', 05:09:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:35 INFO - 'MOZ_NO_REMOTE': '1', 05:09:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:35 INFO - 'NO_EM_RESTART': '1', 05:09:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:35 INFO - 'PWD': '/builds/slave/test', 05:09:35 INFO - 'SHELL': '/bin/bash', 05:09:35 INFO - 'SHLVL': '1', 05:09:35 INFO - 'TERM': 'linux', 05:09:35 INFO - 'TMOUT': '86400', 05:09:35 INFO - 'USER': 'cltbld', 05:09:35 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1460632537.155154-999909945', 05:09:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:35 INFO - '_': '/tools/buildbot/bin/python'} 05:09:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:35 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:09:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:37 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:09:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:09:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:09:37 INFO - Installing collected packages: mozsystemmonitor 05:09:37 INFO - Running setup.py install for mozsystemmonitor 05:09:37 INFO - Successfully installed mozsystemmonitor 05:09:37 INFO - Cleaning up... 05:09:37 INFO - Return code: 0 05:09:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:09:37 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')]} 05:09:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:37 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')]} 05:09:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:37 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 0x7f7a4de60f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22118a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22dc9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x236b810>, '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 0x236c710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x236cba0>, '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': '836cfdc75598d54cb747368e000001a9-1460632537.155154-999909945', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:09:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:09:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:09:37 INFO - Using env: {'DISPLAY': ':0', 05:09:37 INFO - 'HOME': '/home/cltbld', 05:09:37 INFO - 'LANG': 'en_US.UTF-8', 05:09:37 INFO - 'LANGUAGE': 'en_US:en', 05:09:37 INFO - 'LOGNAME': 'cltbld', 05:09:37 INFO - 'MAIL': '/var/mail/cltbld', 05:09:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:37 INFO - 'MOZ_NO_REMOTE': '1', 05:09:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:37 INFO - 'NO_EM_RESTART': '1', 05:09:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:37 INFO - 'PWD': '/builds/slave/test', 05:09:37 INFO - 'SHELL': '/bin/bash', 05:09:37 INFO - 'SHLVL': '1', 05:09:37 INFO - 'TERM': 'linux', 05:09:37 INFO - 'TMOUT': '86400', 05:09:37 INFO - 'USER': 'cltbld', 05:09:37 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1460632537.155154-999909945', 05:09:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:37 INFO - '_': '/tools/buildbot/bin/python'} 05:09:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:38 INFO - Downloading/unpacking blobuploader==1.2.4 05:09:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:39 INFO - Downloading blobuploader-1.2.4.tar.gz 05:09:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:09:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:09:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:09:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:09:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:40 INFO - Downloading docopt-0.6.1.tar.gz 05:09:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:09:40 INFO - Installing collected packages: blobuploader, requests, docopt 05:09:40 INFO - Running setup.py install for blobuploader 05:09:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:09:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:09:40 INFO - Running setup.py install for requests 05:09:41 INFO - Running setup.py install for docopt 05:09:41 INFO - Successfully installed blobuploader requests docopt 05:09:41 INFO - Cleaning up... 05:09:41 INFO - Return code: 0 05:09:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:09:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:09:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:09:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:41 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 0x7f7a4de60f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22118a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22dc9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x236b810>, '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 0x236c710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x236cba0>, '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': '836cfdc75598d54cb747368e000001a9-1460632537.155154-999909945', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:09:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:09:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:09:41 INFO - Using env: {'DISPLAY': ':0', 05:09:41 INFO - 'HOME': '/home/cltbld', 05:09:41 INFO - 'LANG': 'en_US.UTF-8', 05:09:41 INFO - 'LANGUAGE': 'en_US:en', 05:09:41 INFO - 'LOGNAME': 'cltbld', 05:09:41 INFO - 'MAIL': '/var/mail/cltbld', 05:09:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:41 INFO - 'MOZ_NO_REMOTE': '1', 05:09:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:41 INFO - 'NO_EM_RESTART': '1', 05:09:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:41 INFO - 'PWD': '/builds/slave/test', 05:09:41 INFO - 'SHELL': '/bin/bash', 05:09:41 INFO - 'SHLVL': '1', 05:09:41 INFO - 'TERM': 'linux', 05:09:41 INFO - 'TMOUT': '86400', 05:09:41 INFO - 'USER': 'cltbld', 05:09:41 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1460632537.155154-999909945', 05:09:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:41 INFO - '_': '/tools/buildbot/bin/python'} 05:09:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:09:41 INFO - Running setup.py (path:/tmp/pip-QxRTcP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:09:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:09:41 INFO - Running setup.py (path:/tmp/pip-yjf9cb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:09:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:09:41 INFO - Running setup.py (path:/tmp/pip-F3esCm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:09:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:09:41 INFO - Running setup.py (path:/tmp/pip-zlpUwn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:09:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:09:41 INFO - Running setup.py (path:/tmp/pip-QooKy3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:09:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:09:41 INFO - Running setup.py (path:/tmp/pip-2qg8xf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:09:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:09:41 INFO - Running setup.py (path:/tmp/pip-yiV6Dy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:09:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:09:42 INFO - Running setup.py (path:/tmp/pip-SrZQQo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:09:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:09:42 INFO - Running setup.py (path:/tmp/pip-qBtWhC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:09:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:09:42 INFO - Running setup.py (path:/tmp/pip-92UgSG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:09:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:09:42 INFO - Running setup.py (path:/tmp/pip-eorpNn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:09:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:09:42 INFO - Running setup.py (path:/tmp/pip-IWCVBJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:09:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:09:42 INFO - Running setup.py (path:/tmp/pip-XItfnu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:09:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:09:42 INFO - Running setup.py (path:/tmp/pip-qFLSiv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:09:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:09:42 INFO - Running setup.py (path:/tmp/pip-yCX9Io-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:09:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:09:42 INFO - Running setup.py (path:/tmp/pip-jekvPH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:09:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:09:42 INFO - Running setup.py (path:/tmp/pip-8Z_B1x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:09:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:09:42 INFO - Running setup.py install for manifestparser 05:09:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:09:42 INFO - Running setup.py install for mozcrash 05:09:42 INFO - Running setup.py install for mozdebug 05:09:43 INFO - Running setup.py install for mozdevice 05:09:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:09:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:09:43 INFO - Running setup.py install for mozfile 05:09:43 INFO - Running setup.py install for mozhttpd 05:09:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:09:43 INFO - Running setup.py install for mozinfo 05:09:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:09:43 INFO - Running setup.py install for mozInstall 05:09:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:09:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:09:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:09:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:09:43 INFO - Running setup.py install for mozleak 05:09:43 INFO - Running setup.py install for mozlog 05:09:43 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:09:43 INFO - Running setup.py install for moznetwork 05:09:43 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:09:43 INFO - Running setup.py install for mozprocess 05:09:43 INFO - Running setup.py install for mozprofile 05:09:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:09:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:09:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:09:44 INFO - Running setup.py install for mozrunner 05:09:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:09:44 INFO - Running setup.py install for mozscreenshot 05:09:44 INFO - Running setup.py install for moztest 05:09:44 INFO - Running setup.py install for mozversion 05:09:44 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:09:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:09:44 INFO - Cleaning up... 05:09:44 INFO - Return code: 0 05:09:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:09:44 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')]} 05:09:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:44 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')]} 05:09:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:44 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 0x7f7a4de60f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22118a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22dc9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x236b810>, '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 0x236c710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x236cba0>, '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': '836cfdc75598d54cb747368e000001a9-1460632537.155154-999909945', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:09:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:09:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:09:44 INFO - Using env: {'DISPLAY': ':0', 05:09:44 INFO - 'HOME': '/home/cltbld', 05:09:44 INFO - 'LANG': 'en_US.UTF-8', 05:09:44 INFO - 'LANGUAGE': 'en_US:en', 05:09:44 INFO - 'LOGNAME': 'cltbld', 05:09:44 INFO - 'MAIL': '/var/mail/cltbld', 05:09:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:44 INFO - 'MOZ_NO_REMOTE': '1', 05:09:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:44 INFO - 'NO_EM_RESTART': '1', 05:09:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:44 INFO - 'PWD': '/builds/slave/test', 05:09:44 INFO - 'SHELL': '/bin/bash', 05:09:44 INFO - 'SHLVL': '1', 05:09:44 INFO - 'TERM': 'linux', 05:09:44 INFO - 'TMOUT': '86400', 05:09:44 INFO - 'USER': 'cltbld', 05:09:44 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1460632537.155154-999909945', 05:09:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:44 INFO - '_': '/tools/buildbot/bin/python'} 05:09:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:09:44 INFO - Running setup.py (path:/tmp/pip-n0vkq0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:09:44 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:09:44 INFO - Running setup.py (path:/tmp/pip-zVRBvJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:09:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:09:44 INFO - Running setup.py (path:/tmp/pip-fBd6iC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:09:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:09:44 INFO - Running setup.py (path:/tmp/pip-khRoVR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:09:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:09:45 INFO - Running setup.py (path:/tmp/pip-_qfzQB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:09:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:09:45 INFO - Running setup.py (path:/tmp/pip-aTowqK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:09:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:09:45 INFO - Running setup.py (path:/tmp/pip-wFsgXh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:09:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:09:45 INFO - Running setup.py (path:/tmp/pip-snY97J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:09:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:09:45 INFO - Running setup.py (path:/tmp/pip-wpvjY4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:09:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:09:45 INFO - Running setup.py (path:/tmp/pip-wxINf6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:09:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:09:45 INFO - Running setup.py (path:/tmp/pip-tl4b0L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:09:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:09:45 INFO - Running setup.py (path:/tmp/pip-Echydl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:09:45 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)) 05:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:09:45 INFO - Running setup.py (path:/tmp/pip-uMUP6E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:09:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:09:45 INFO - Running setup.py (path:/tmp/pip-rnOhAK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:09:45 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)) 05:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:09:45 INFO - Running setup.py (path:/tmp/pip-LSdEoA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:09:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:09:45 INFO - Running setup.py (path:/tmp/pip-VDt5Zx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:09:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:09:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:09:45 INFO - Running setup.py (path:/tmp/pip-wEwArb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:09:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:09:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:09:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:09:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:09:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:09:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:09:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:09:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:47 INFO - Downloading blessings-1.6.tar.gz 05:09:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:09:47 INFO - Installing collected packages: blessings 05:09:47 INFO - Running setup.py install for blessings 05:09:48 INFO - Successfully installed blessings 05:09:48 INFO - Cleaning up... 05:09:48 INFO - Return code: 0 05:09:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:09:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:09:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:09:48 INFO - Reading from file tmpfile_stdout 05:09:48 INFO - Current package versions: 05:09:48 INFO - argparse == 1.2.1 05:09:48 INFO - blessings == 1.6 05:09:48 INFO - blobuploader == 1.2.4 05:09:48 INFO - docopt == 0.6.1 05:09:48 INFO - manifestparser == 1.1 05:09:48 INFO - mozInstall == 1.12 05:09:48 INFO - mozcrash == 0.17 05:09:48 INFO - mozdebug == 0.1 05:09:48 INFO - mozdevice == 0.48 05:09:48 INFO - mozfile == 1.2 05:09:48 INFO - mozhttpd == 0.7 05:09:48 INFO - mozinfo == 0.9 05:09:48 INFO - mozleak == 0.1 05:09:48 INFO - mozlog == 3.1 05:09:48 INFO - moznetwork == 0.27 05:09:48 INFO - mozprocess == 0.22 05:09:48 INFO - mozprofile == 0.28 05:09:48 INFO - mozrunner == 6.11 05:09:48 INFO - mozscreenshot == 0.1 05:09:48 INFO - mozsystemmonitor == 0.0 05:09:48 INFO - moztest == 0.7 05:09:48 INFO - mozversion == 1.4 05:09:48 INFO - psutil == 3.1.1 05:09:48 INFO - requests == 1.2.3 05:09:48 INFO - wsgiref == 0.1.2 05:09:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:09:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:09:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:09:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:48 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 0x7f7a4de60f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22118a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22dc9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x236b810>, '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 0x236c710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x236cba0>, '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': '836cfdc75598d54cb747368e000001a9-1460632537.155154-999909945', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:09:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:09:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:09:48 INFO - Using env: {'DISPLAY': ':0', 05:09:48 INFO - 'HOME': '/home/cltbld', 05:09:48 INFO - 'LANG': 'en_US.UTF-8', 05:09:48 INFO - 'LANGUAGE': 'en_US:en', 05:09:48 INFO - 'LOGNAME': 'cltbld', 05:09:48 INFO - 'MAIL': '/var/mail/cltbld', 05:09:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:48 INFO - 'MOZ_NO_REMOTE': '1', 05:09:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:48 INFO - 'NO_EM_RESTART': '1', 05:09:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:48 INFO - 'PWD': '/builds/slave/test', 05:09:48 INFO - 'SHELL': '/bin/bash', 05:09:48 INFO - 'SHLVL': '1', 05:09:48 INFO - 'TERM': 'linux', 05:09:48 INFO - 'TMOUT': '86400', 05:09:48 INFO - 'USER': 'cltbld', 05:09:48 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1460632537.155154-999909945', 05:09:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:48 INFO - '_': '/tools/buildbot/bin/python'} 05:09:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:09:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:09:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:09:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:09:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:09:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:09:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:09:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:09:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:09:48 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:09:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:09:48 INFO - Cleaning up... 05:09:48 INFO - Return code: 0 05:09:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:09:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:09:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:09:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:48 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 0x7f7a4de60f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22118a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22dc9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x236b810>, '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 0x236c710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x236cba0>, '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': '836cfdc75598d54cb747368e000001a9-1460632537.155154-999909945', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:09:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:09:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:09:48 INFO - Using env: {'DISPLAY': ':0', 05:09:48 INFO - 'HOME': '/home/cltbld', 05:09:48 INFO - 'LANG': 'en_US.UTF-8', 05:09:48 INFO - 'LANGUAGE': 'en_US:en', 05:09:48 INFO - 'LOGNAME': 'cltbld', 05:09:48 INFO - 'MAIL': '/var/mail/cltbld', 05:09:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:48 INFO - 'MOZ_NO_REMOTE': '1', 05:09:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:48 INFO - 'NO_EM_RESTART': '1', 05:09:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:48 INFO - 'PWD': '/builds/slave/test', 05:09:48 INFO - 'SHELL': '/bin/bash', 05:09:48 INFO - 'SHLVL': '1', 05:09:48 INFO - 'TERM': 'linux', 05:09:48 INFO - 'TMOUT': '86400', 05:09:48 INFO - 'USER': 'cltbld', 05:09:48 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1460632537.155154-999909945', 05:09:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:48 INFO - '_': '/tools/buildbot/bin/python'} 05:09:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:48 INFO - Downloading/unpacking jsonschema 05:09:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:09:50 INFO - Downloading/unpacking functools32 (from jsonschema) 05:09:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:50 INFO - Downloading functools32-3.2.3-2.tar.gz 05:09:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:09:50 INFO - warning: no files found matching '*.txt' 05:09:50 INFO - no previously-included directories found matching 'build' 05:09:50 INFO - no previously-included directories found matching 'dist' 05:09:50 INFO - no previously-included directories found matching '.git*' 05:09:50 INFO - Installing collected packages: jsonschema, functools32 05:09:50 INFO - Running setup.py install for functools32 05:09:51 INFO - warning: no files found matching '*.txt' 05:09:51 INFO - no previously-included directories found matching 'build' 05:09:51 INFO - no previously-included directories found matching 'dist' 05:09:51 INFO - no previously-included directories found matching '.git*' 05:09:51 INFO - Successfully installed jsonschema functools32 05:09:51 INFO - Cleaning up... 05:09:51 INFO - Return code: 0 05:09:51 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:09:51 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')]} 05:09:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:09:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:51 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')]} 05:09:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:09:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:09:51 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f7a4de60f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22118a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x22dc9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x236b810>, '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 0x236c710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x236cba0>, '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': '836cfdc75598d54cb747368e000001a9-1460632537.155154-999909945', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:09:51 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', 'flake8'] in /builds/slave/test/build 05:09:51 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 flake8 05:09:51 INFO - Using env: {'DISPLAY': ':0', 05:09:51 INFO - 'HOME': '/home/cltbld', 05:09:51 INFO - 'LANG': 'en_US.UTF-8', 05:09:51 INFO - 'LANGUAGE': 'en_US:en', 05:09:51 INFO - 'LOGNAME': 'cltbld', 05:09:51 INFO - 'MAIL': '/var/mail/cltbld', 05:09:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:51 INFO - 'MOZ_NO_REMOTE': '1', 05:09:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:51 INFO - 'NO_EM_RESTART': '1', 05:09:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:51 INFO - 'PWD': '/builds/slave/test', 05:09:51 INFO - 'SHELL': '/bin/bash', 05:09:51 INFO - 'SHLVL': '1', 05:09:51 INFO - 'TERM': 'linux', 05:09:51 INFO - 'TMOUT': '86400', 05:09:51 INFO - 'USER': 'cltbld', 05:09:51 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1460632537.155154-999909945', 05:09:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:51 INFO - '_': '/tools/buildbot/bin/python'} 05:09:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:09:51 INFO - Downloading/unpacking flake8 05:09:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:53 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:09:53 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:09:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:53 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:09:53 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:09:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:53 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:09:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:09:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:09:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:09:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:09:53 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:09:53 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:09:53 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:09:53 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:09:53 INFO - no previously-included directories found matching 'docs/_build' 05:09:53 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:09:54 INFO - Running setup.py install for pep8 05:09:54 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:09:54 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:09:54 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:09:54 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:09:54 INFO - no previously-included directories found matching 'docs/_build' 05:09:54 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:09:54 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:09:54 INFO - Cleaning up... 05:09:54 INFO - Return code: 0 05:09:54 INFO - Running post-action listener: _resource_record_post_action 05:09:54 INFO - Running post-action listener: _start_resource_monitoring 05:09:54 INFO - Starting resource monitoring. 05:09:54 INFO - ##### 05:09:54 INFO - ##### Running install step. 05:09:54 INFO - ##### 05:09:54 INFO - Running pre-action listener: _resource_record_pre_action 05:09:54 INFO - Running main action method: install 05:09:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:09:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:09:54 INFO - Reading from file tmpfile_stdout 05:09:54 INFO - Detecting whether we're running mozinstall >=1.0... 05:09:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:09:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:09:54 INFO - Reading from file tmpfile_stdout 05:09:54 INFO - Output received: 05:09:54 INFO - Usage: mozinstall [options] installer 05:09:54 INFO - Options: 05:09:54 INFO - -h, --help show this help message and exit 05:09:54 INFO - -d DEST, --destination=DEST 05:09:54 INFO - Directory to install application into. [default: 05:09:54 INFO - "/builds/slave/test"] 05:09:54 INFO - --app=APP Application being installed. [default: firefox] 05:09:54 INFO - mkdir: /builds/slave/test/build/application 05:09:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 05:09:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 05:10:10 INFO - Reading from file tmpfile_stdout 05:10:10 INFO - Output received: 05:10:10 INFO - /builds/slave/test/build/application/firefox/firefox 05:10:10 INFO - Running post-action listener: _resource_record_post_action 05:10:10 INFO - ##### 05:10:10 INFO - ##### Running run-tests step. 05:10:10 INFO - ##### 05:10:10 INFO - Running pre-action listener: _resource_record_pre_action 05:10:10 INFO - Running main action method: run_tests 05:10:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:10:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:10:10 INFO - Python 2.7.3 05:10:10 INFO - Return code: 0 05:10:10 INFO - grabbing minidump binary from tooltool 05:10:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 05:10:10 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x236b810>, '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 0x236c710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x236cba0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:10:10 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:10:10 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 05:10:11 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:10:11 INFO - Return code: 0 05:10:11 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:10:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:10:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:10:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:10:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:10:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:10:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:10:12 INFO - Return code: 0 05:10:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/D1BhgNObSliTbzWWbBPf2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-028', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 05:10:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/D1BhgNObSliTbzWWbBPf2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-028 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 05:10:12 INFO - Using env: {'DISPLAY': ':0', 05:10:12 INFO - 'HOME': '/home/cltbld', 05:10:12 INFO - 'LANG': 'en_US.UTF-8', 05:10:12 INFO - 'LANGUAGE': 'en_US:en', 05:10:12 INFO - 'LOGNAME': 'cltbld', 05:10:12 INFO - 'MAIL': '/var/mail/cltbld', 05:10:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:10:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:10:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:10:12 INFO - 'MOZ_NO_REMOTE': '1', 05:10:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:10:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:10:12 INFO - 'NO_EM_RESTART': '1', 05:10:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:10:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:10:12 INFO - 'PWD': '/builds/slave/test', 05:10:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:10:12 INFO - 'SHELL': '/bin/bash', 05:10:12 INFO - 'SHLVL': '1', 05:10:12 INFO - 'TERM': 'linux', 05:10:12 INFO - 'TMOUT': '86400', 05:10:12 INFO - 'USER': 'cltbld', 05:10:12 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1460632537.155154-999909945', 05:10:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:10:12 INFO - '_': '/tools/buildbot/bin/python'} 05:10:12 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/D1BhgNObSliTbzWWbBPf2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-028', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 05:10:12 INFO - mozversion application_buildid: 20160414023029 05:10:12 INFO - mozversion application_changeset: ef461673c13f81ff4bbb162f739227ab75844941 05:10:12 INFO - mozversion application_display_name: Nightly 05:10:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:10:12 INFO - mozversion application_name: Firefox 05:10:12 INFO - mozversion application_remotingname: firefox 05:10:12 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:10:12 INFO - mozversion application_vendor: Mozilla 05:10:12 INFO - mozversion application_version: 48.0a1 05:10:12 INFO - mozversion platform_buildid: 20160414023029 05:10:12 INFO - mozversion platform_changeset: ef461673c13f81ff4bbb162f739227ab75844941 05:10:12 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:10:12 INFO - mozversion platform_version: 48.0a1 05:10:12 INFO - using testdate: 1460635812 05:10:12 INFO - actual date: 1460635812 05:10:12 INFO - starting webserver on 'localhost:47804' 05:10:12 INFO - SUITE-START | Running 2 tests 05:10:12 INFO - TEST-START | dromaeo_css 05:10:12 INFO - operating with platform_type : linux_ 05:10:12 INFO - Initialising browser for dromaeo_css test... 05:10:12 INFO - TEST-INFO | started process 23384 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFjfDbL/profile http://localhost:47804/getInfo.html) 05:10:16 INFO - PROCESS | 23384 | __metrics Screen width/height:1600/1200 05:10:16 INFO - PROCESS | 23384 | colorDepth:24 05:10:16 INFO - PROCESS | 23384 | Browser inner width/height: 1024/697 05:10:16 INFO - PROCESS | 23384 | __metrics 05:10:17 ERROR - PROCESS | 23384 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:10:18 INFO - TEST-INFO | 23384: exit 0 05:10:18 INFO - Browser initialized. 05:10:18 INFO - Running cycle 1/1 for dromaeo_css test... 05:10:18 INFO - TEST-INFO | started process 23465 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFjfDbL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:10:30 INFO - PROCESS | 23465 | RSS: Main: 152580096 05:10:30 INFO - PROCESS | 23465 | 05:11:21 INFO - PROCESS | 23465 | 1460635881282 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:13:28 INFO - PROCESS | 23465 | Cycle 1(1): loaded http://localhost:47804/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47804/tests/dromaeo/cssquery-ext.html) 05:13:29 INFO - PROCESS | 23465 | RSS: Main: 253157376 05:13:29 INFO - PROCESS | 23465 | 05:16:32 INFO - PROCESS | 23465 | Cycle 1(1): loaded http://localhost:47804/tests/dromaeo/cssquery-ext.html (next: http://localhost:47804/tests/dromaeo/cssquery-jquery.html) 05:16:32 INFO - PROCESS | 23465 | RSS: Main: 235659264 05:16:32 INFO - PROCESS | 23465 | 05:19:34 INFO - PROCESS | 23465 | Cycle 1(1): loaded http://localhost:47804/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47804/tests/dromaeo/cssquery-mootools.html) 05:19:35 INFO - PROCESS | 23465 | RSS: Main: 232349696 05:19:35 INFO - PROCESS | 23465 | 05:22:37 INFO - PROCESS | 23465 | Cycle 1(1): loaded http://localhost:47804/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47804/tests/dromaeo/cssquery-prototype.html) 05:22:37 INFO - PROCESS | 23465 | RSS: Main: 229208064 05:22:37 INFO - PROCESS | 23465 | 05:25:40 INFO - PROCESS | 23465 | Cycle 1(1): loaded http://localhost:47804/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47804/tests/dromaeo/cssquery-yui.html) 05:25:40 INFO - PROCESS | 23465 | RSS: Main: 220102656 05:25:40 INFO - PROCESS | 23465 | 05:28:43 INFO - PROCESS | 23465 | Cycle 1(1): loaded http://localhost:47804/tests/dromaeo/cssquery-yui.html (next: http://localhost:47804/tests/dromaeo/cssquery-dojo.html) 05:28:43 INFO - PROCESS | 23465 | RSS: Main: 251256832 05:28:43 INFO - PROCESS | 23465 | 05:28:43 INFO - PROCESS | 23465 | __start_tp_report 05:28:43 INFO - PROCESS | 23465 | _x_x_mozilla_page_load 05:28:43 INFO - PROCESS | 23465 | _x_x_mozilla_page_load_details 05:28:43 INFO - PROCESS | 23465 | |i|pagename|runs| 05:28:43 INFO - PROCESS | 23465 | |0;dojo.html;3099.380123975203;3104.317050248945;3129.82785946773;3132.0916934089114;3165.1454107391005;5137.049645815527;5212.530872221504;5224.111900976834;5249.606279529036;5255.81604643838;5377.5966802489875;5393.703346315951;5407.540359069484;5411.458854114587;5459.099248623972;4749.52504749524;4802.25565037418;4872.220444728844;4919.827806026789;4920.950790492103;4855.902881942359;4879.70721756694;4918.090153321629;4920.040592084545;4934.925976110361;4643.791029403685;4752.12085764133;4798.352222449959;4817.903641927177;4853.441854186778;4836.129496690594;4855.053264613402;4863.929935414203;4864.854054378339;4896.436909755388;4659.138059459011;4834.154023045988;4843.539863712942;4846.5880400166025;4894.094592500399;5212.55693266069;5289.365276166846;5299.788008479694;5311.309529761106;5326.786928522854;5650.406707295757;5652.491275785158;5653.519450846683;5655.208270842086;5656.236408084897;5546.972265138648;5631.774729010835;5710.086386178233;5710.229119068914;5715;5320.8403747888015;5328.520433161034;5347.812826551052;5357.651752636105;5376.596755243372;5802.622829516068;5811.506021988178;5821.27234095738;5826.621269617462;5827.475527202572;5397.487238712316;5525.0883604204855;5578.693171875586;5592.468715472105;5636.8308950730725;5668.631538949955;5676.4323567642905;5676.574256930747;5681.545476361881;5714.857128571819;5653.180288858175;5656.830295091154;5657.32112146545;5660.037793575019;5663.773449061991;5407.242985981966;5555.194496797942;5640.492355688008;5653.095504719226;5670.347909990322;4284.485861696554;4290.785460726951;4292.184484947912;4306.074194048295;4325.697201195949;3847.2882516734494;3855.5951625079524;3859.3053250415196;3881.2237552489614;3889;5278.683279003272;5368.704721240291;5431.2667789847865;5488.259085023471;5496.835094947158;5705.914411283834;5710.200571919934;5721.742521586539;5726.627769194989;5742.253507044141;5241.711705856138;5426;5441.755121019484;5446.400895901447;5489.313835770528;4838.612910967114;4883.291922671193;4886.60907127429;4923.040007198562;4928.802847886116;5812.273465816773;5813.941860581424;5818.185454036438;5822.243108395882;5822.650640961556;5528.281323427928;5642.463965923313;5648.435156484319;5701.40135285789;5718.685472299064;5900.996830538733;5922.200502932049;5927.288725352985;5931.080682494047;5942.61373010753;5614.747336369955;5616.1014237721765;5627.549796016391;5627.577931655224;5630.23991761107;5441.238226648194;5710.400407957105;5712.742926568231;5745.597808153389;5802.912956305574;5560.721963901708;5564.666120032811;5575.526080283059;5584.692841893735;5615.3823079462045;5539.529140023144;5543.307086614173;5554.028045092174;5557;5558.249636299048;5299.33758280215;5426.891462170655;5458.962797068544;5501.504864562208;5506.944930550643;5573.359063707563;5614.85962850932;5615.634983726045;5617.7191140442;5633;5485.6982865942755;5501.587380946525;5501.669899806025;5505.9724701376235;5557.055300598827 05:28:43 INFO - PROCESS | 23465 | |1;ext.html;596.4780816785313;642.4539141729531;648.5751832549681;657.2540166910557;675.3988949834645;29804.403911921774;38179.42730859039;39115.608843911556;39121.804390978075;39129.21741565171;40296.79851600745;41164.58835411645;41286;41368;41393.37909931353;7734.497257678261;8392.58037098144;8648.870266946002;8669.21977022068;9960.950195249014;10011.048950349705;10084.798304033915;10086.394816311009;10188.337758045704;10196.847047294297;10000.249981251436;10142.746431339201;10189.54147063384;10207.33652312601;10220.74448138795;8001.759947201623;10160.085592296691;10246.231532635007;10661.413622250773;10676.733081672943;10111.544980475895;10265.127464165555;10272.486375681186;10276.94861525695;10281.126104281146;37581.62418375809;38107.8094609528;38128.42807357892;38132.23735525302;38149.237015259816;3337.1323455901397;3395.100173890498;3409.6078950920714;3540.964590354089;3655.94516082259;31743.682563174305;31785.52321715176;31803;31962.360752784814;32803.39162692102;4258.957410425887;4272.807723652443;4286.657067434598;4302.634276086536;4324.56754324565;299681;300022;301097;302295;302724.4863775689;35911.4613280801;60110;61069.38930610726;61161;61170.38829611736;35095.4735678965;35180.47229291563;35196;35226.47160292598;35253.64746352555;13593.660158496117;13788;13794.103383280055;13795.862041379658;13796.172229666252;11395.943020284845;11488.88511114895;11494.310341379543;11495.88504114965;11500.539978400937;8402.957985210158;8609.096044915319;8610;8652.653893844175;8666.56667166639;3074.4927087030837;3184.824834634071;3313.8508767105536;3379.678930501598;3425.6403077676982;979.4221409368507;982.8771403574553;1070.8755806403246;1075.9704905130336;1166.6175581935433;32872;32891.3421731564;32960.67039329576;32994;33596.49605255923;1075.2312096850633;1077.1436707817304;1113.8997490225918;1130.9604163854226;1171.1626187276138;15449.768253476206;16027.19864006795;16173.433929812636;16257.268422920997;16497.340106395608;439769;442608;445310;446169;448768;56223;57242.42757572454;57328.14007789887;57332.42667573354;57349;1359.238826257299;1378.5657517881834;1398.7692030815006;1399.5241617849777;1413.5335339337994;30523.694763052972;31060.53409198909;31760.841195793873;31827;32387.67612323941;51589.742051289504;52454.47545524496;52553.21170182374;52577.474225257254;52613.21080183724;394708;403217;403228;404248;404865;25047.248582542554;25717;27640.512353888247;27776.861115694293;28428.85785571059;41041;45577.54422455733;46054.078918420775;46583;46762.29856552222;38519.42220866633;38835.223295534495;39444.40833387559;39540;40411.595884040784;38374.80812595919;38542.56760472582;38870.80564597159;39638.801805990784;39763.204735905696;39942.600573993885;40455.393169103074;40894.38658420066;40928.590714092476;41102.17795644011;33772.32455350929;34716.652833471344;34997.82501087478;35490;35658.28683426369;26843.463130736884;27356;27484.450310994067;27773.722262777115;27851.44297114087 05:28:43 INFO - PROCESS | 23465 | |2;jquery.html;378.8652984311986;385.20070852980075;389.606497437588;399.9800009999503;401.33378591538053;17121.914390428046;19255.807441925575;19478.902605486972;19511.609767804635;19529.804701952995;18646.160922758474;18843.811561884377;19002.904985475052;19146.138423770928;19305.13126909289;5503.367112782032;5543.6119471637;5576.438597192792;5687.971560142193;5726.856828579298;5460.781568737246;5656.830295091154;5665.461781130786;5690.800821971232;5691.715414229293;5303.867403314929;5627.296587926509;5629.831105066834;5631.1271752878365;5640.323161220639;5458.426865179139;5611.270534830487;5614.775408983656;5648.406917273668;5653.378128405872;5709.600327977043;5710;5734.225879506255;5742.081266997302;5747.770089196427;13309.800352994616;13570.45718171272;20081.497962550908;21284;21307.8934605326;2279.5782780185555;2406.7352591214953;2559.744025597425;2607.5827867541293;2691.986540067287;9660.082292182231;9850.60597576096;10260.948695256477;12191.87808121925;13812.422292017221;1723.1298194411868;1835.4768890866173;1886.2643569008098;1917.4439412570339;1944.1931598386652;59723.70138149325;73956;77627.6118619409;79524.20475795283;81650.59174704147;10205.438700871524;10428.27002109845;10444.268901176996;10548.261621686563;10648.307859989076;12802.167859089315;13088.280144591949;13449.596512104652;13550.864491355158;13594.048416610742;5733.741981610838;5868.794592189253;6521.706523206467;7515.398768098539;7616.466847320745;7194.028806111108;7200.531965422231;7218.458615603746;7390.150132734804;7458.5151965122095;4184.623383895464;4227.661787057028;4297.72064815786;4342.435483387139;4628.629709623222;1916.48254971157;1933.980660193408;1948.1525536391714;1995.2318357432493;1998.3114268443142;903.3450748207629;929.7675581104724;930.9487978161267;1011.014261095426;1020.433659319085;16780.496585102468;19183.52041198981;19187.808121918883;19342.516437089187;19490.610187796166;948.0661548374845;952.7284723853669;989.2234595842373;1002.7493126718321;1012.7215015870547;10426.165906727443;10901.182411319025;10962.177836662282;10995;11004.339739615649;445594;447173;452836;476075;478700;89399;95709;96196.51901740447;96993.5150324258;97476;1006.5621454667244;1016.7305663999047;1023.6621914768109;1023.9897601024044;1041.9010194031555;16265.532963157777;16470.42353517621;16604.25280862388;16735.414260500824;16876;37602.283349245336;38028.619713803615;38647.806760966014;39329.60670393259;39379.21241575095;53643.46356536541;54118.458815411344;54351.59190012588;54862.45137548573;55884.44115558793;8474.152584741476;8475.74572762818;8702.82594348122;8738.300935925237;8751.037385887419;8421.452605580618;9495.620175192915;11463.624365076244;12140.33228172459;13262.668433289246;6046.727897244723;10824.025837674648;10966.945165274123;12059.939700301442;12320.876791232331;11809.729922913202;11935.582254621047;11999;12012.819807703067;12139.210951288138;12311.261324320569;12433.784798730716;12640.936795315965;12699.174553653984;12719.55481558141;8313.916860831556;8574.31405487572;8642.78393040179;8703.608337624948;8741.08218637034;8933.330000250136;11086.501107450023;11861.406929653655;11973.221740586834;12203.145779795685 05:28:43 INFO - PROCESS | 23465 | |3;mootools.html;1371.3417559571399;1525.3517255166555;1570.9921450392749;1749.5975925537125;1854.6198029403974;7923.207679232081;8320.209580089897;8324.458910170843;8328.37537184712;8329.66681332746;8072.233137851895;8223.259906608404;8225.67097316108;8227.794305142359;8233.629486673099;2048.1909645689943;2301.6547517872286;2331.0442718485424;2362.633791762271;2370.040133745827;2354.164271683545;2376.0139542090096;2376.0495801679294;2379.690640216778;2380.1312520929823;2304.124432715566;2352.1532248390567;2352.9529409411803;2354.1878052072093;2356.352003199125;1996.3811218522233;2276.35123989663;2348.9060437582475;2349.682792822964;2352.3178278299274;2215.656573231152;2223.8776867272295;2224.944376390587;2228.5320082782637;2279.566882292376;8333.041700204494;8384.622691978875;8402.663893444254;8422.957885210595;8454.027786804534;5034.546890779848;5132.076226279269;5176.7411629418375;5213.165893457029;5236.659617124875;3238.2875767331147;3323.80057196569;3342.7994320340613;3379.831008449568;3416.350893330259;1201.7356181640025;1224.2103843021291;1235.8269842222005;1253.5834507007114;1255.8179064035664;11453.427328633536;11462.541498340057;11471.541138354456;11475.713107172303;11505.884941150565;4375.387445757597;4438.733675979452;4576.039031803292;4604;4643.605293550086;9566.234701223886;9678.080582344666;9682.70951871445;9692.32153749245;9721.270904682036;4515.390422292949;5089.465606111379;5095.363079615048;5107.821226257063;5113.769880355393;4550.180967425829;4587.632989360844;4588.357629931812;4618.976905115453;4638.0260145369175;3745.4756334113245;3750.774953502744;3819.560750513671;3823.9808800956375;3866.304065268279;1220.45079714129;1234.9814752778716;1249.731307768834;1257.7547378261334;1263.286243272548;793.2701914238914;945.6879229854131;951.1107114847501;965.2229954886244;969.539468752337;5742.827715168552;5812.070068788974;5857.531397488191;5867.647941123546;5930.733117009745;870.1907226279494;885.6059053721161;942.4204114469552;987.8221920054459;1019.99490002551;3981.820818063194;4005.7796821174534;4011.7793521356607;4035.7982100894837;4036.535798383165;9946.154576860903;10392.324498907547;10437.634682786065;10464.895351046544;10472.319299245524;10364.06723394883;10402.947985260176;10563.788724225473;10566.418846963337;10566.630167944086;7442.330190282901;7866.7639970800965;7949.562774047367;7960.1243863175005;8008.27925486709;5835.533157347487;5928.199693041384;5930.258717660293;6015.187949626919;6057.121717350871;9074.410163339362;9290.442573445614;9442.763930901783;9557.283203759886;9569.330146889652;9244.95377523108;9518.048195180427;9620.37467564606;9753.122219000325;9756.707298781048;6725.226598940989;7303.452241082046;7381.335679788845;7482.70069197226;7646.502977306457;8257.545834978893;8258.958705206436;8263.421560490948;8347.207015333533;8357.37319701037;7061.223265440694;7152.320529549685;7223.638818059181;7341.59621220838;7390.6674199660065;7488.5506869588;7494.100707915085;7501.2123727007875;7530.623468826428;7553.395728341829;6735.730570777114;6750.493712971449;6809.148856392951;6817.5909445433435;6818.045473633596;4995.800167993385;5046.545810877039;5155.994581056808;5162.251473536241;5200.4279529251;5182.663126896739;5185.170372740344;5232.136697444879;5252.107141785982;5298.788048478018 05:28:43 INFO - PROCESS | 23465 | |4;prototype.html;321.32201055772333;331.74289925307886;332.41826803094585;336.40624298113835;338.9508521264418;5497.670139791615;5678.6024978251535;5729.65622062676;5763.51010164136;5799.159121927323;5655.604107712462;5697.6866272355;5734.856628584287;5737.483626473617;5745.59780815342;4448.132614140228;4448.777561121931;4449.377087207794;4462.174497717924;4465.642748580106;4235.7246778959425;4380.67144964129;4402.229609818285;4470.128324976631;4482.350059241425;4317.71934824237;4320.481542214939;4342.435483387155;4361.716488428257;4362.956370436303;4316.956830431687;4344.97827510862;4354.129174165164;4362.17118747437;4373.2565463871215;3728.272986767581;3824.9426258606145;3825.08198032473;3825.9426108608395;3829.157585331223;5474.37044739856;5507.256520369739;5508.724563771795;5511.724413779335;5522.889542209134;5029.949700503022;5033;5034.521720436553;5041.294218809369;5049.2173713074535;5700.429957004308;5972.492338151263;5974;6011.819645410645;6026.457618814284;4163.2298024865195;4177.081042170717;4182;4192.161567686475;4200.390943313202;6203.379662033805;6264.966280563702;6266.216722909637;6287.1198032275515;6291.653959032252;4206.957930420718;4297.892552686145;4307.396964424983;4309.913801723948;4326.286162783168;4319.157764235943;4341.305391137466;4344.652427805831;4346.891327716769;4356.1287742451;5019.146745053276;5021.84934451967;5029.647924645313;5036.924446133311;5042.9747851261245;5909.911351329733;5962.4633782959745;6005.858886811579;6019.337872833985;6060.454559089703;1494.0811400988314;1495.7681559358316;1502.6393665520416;1504.8269449013285;1515.454436402894;962.5957098018849;970.6942313171328;981.2052237687496;993.215359865698;995.930284880066;5828.59199856006;5832.88334233313;5847.152162936435;5859.267591551056;5874.471297583153;6148.200733904564;6157.415045570664;6177.69111544421;6180.907286390708;6193.566450348432;5612.214289999403;5818.359980402152;5821.592488525763;5842.883142337129;5851.853703657443;3280.967190328066;3308.520264561625;3309.255417531036;3310.2717402171484;3316.1378041708845;6154.79981403631;6434;6435.903461448082;6452.677366131675;6476.740930362827;6386.233651961701;6393.808185754435;6419.454346380517;6425.971844504857;6426.1003459515705;6260.812175634738;6459.1914264006155;6492.285848556655;6502.739890404331;6523.217213934358;6195.2255968004;6227.470664993526;6236.407541283571;6241.750329986749;6263.185785847811;6088.726007329592;6124.816255512342;6130.444342281555;6154.476869466145;6174.506039516918;6064;6276.215473065867;6318.810435686937;6461.547691661539;6478.6760661967655;4173.144505376451;4177.582241775798;4212.115455754326;4300.376445415334;4307.719998200232;6180.412860778219;6186.752529898754;6202.689865506797;6210.968945155245;6214.130021797043;5873.3832947541605;5979.611325264018;6012.518998480198;6049.78825741097;6055.1522786809;5960.3443621202505;5978.671173085522;5982.820515384546;6008.399160083956;6036.849078773066;5789.363170051375;5880.911786323123;5886.3525012247765;5926.822195334147;5941.31674857397;5998;6005.3694362091355;6017.879642407215;6028.186194863637;6060.909086363797;5835.854103647443;5886.646801191942;5894;5904.5866789326055;5910.852228694317 05:28:43 INFO - PROCESS | 23465 | |5;yui.html;815.8327542853714;850.0327699549168;850.366476974654;862.4825104937039;865.4417900454204;14453.349599268033;14850.034747741389;14902.329395177216;14903.701925961474;14930.477433289838;13467.730645387086;14124.858751412508;14163.858361416384;14260.786088208684;14294;828.5691440450947;838.6226198210829;839.920207580282;841.5750046226647;844.0673056272822;829.0838623321233;839.5382539603224;840.6889450903175;841.5413599588239;841.9452735572199;877.7059685005531;883.4787475389519;883.730462209026;884.332329091535;884.5886662701843;876.0538618292229;877.0177401310509;877.7279043496504;878.2447095497898;879.0330636300018;637.3308026572128;641.2529403245213;641.2849672615;641.9358064193543;642.399356601579;13252.867471325357;13332.666683332995;13353.065285430024;13356.732865342736;13404.932975335158;12046.397680115959;12248.755024899452;12313.076519261002;12314.199577027553;12407.62777116679;9771.364861284064;9896.901030989742;10097.293189476739;10140.797184056351;10300.948495257475;3750.774953502799;3762.285165818486;3780.4329350597354;3819.2743378758223;3855.3445914194654;45983.31025034627;52347;53357;53841;53980.73009634927;10375.584976600852;10426.530806113744;10525.684229472974;10599.788004240027;10845.620403286004;19177.616447670967;19407.835529868255;19428.320008799907;19441.125149368312;19452.027398630013;7764.3788496920115;7919.604019798987;7977.441579089524;8057.113717491071;8460.534670593177;4635.351050852883;4710.222813235778;4711.199096153594;4713.626993058527;4722.52774722525;3345.381104495676;3419.5212670226188;3438.381091403571;3438.5529881115294;3444.5522082129664;639.6002498438476;645.7320212111921;650.0703993289546;655.7638850766244;662.2681936460142;649.4706813946618;676.736072931557;692.2385376086265;694.5381321421299;708.624429052603;9306.488143152192;9323.300752443594;9379.202767764817;9692;9821.410715357102;615.4030590327375;670.2392784189882;700.58315302395;730.2587873308596;739.5193124469138;4290.627217642892;4423.535528769497;4471.552844715502;4542.704724192917;4547.493942989317;62654.37345626576;62664;63577.36422635807;63842.68078659577;64216;44982;51974.740126299126;56498;57446.712766436736;59052.11421828676;48013.5198648016;48097.75951120222;48347.758261208466;48532.27201591979;50501.49498504968;9103.317251206054;9268.629254829732;9330.486823224788;9650.52084634414;9824.312298139348;29107.417851643273;30494.390112198074;30712.692873070984;31053.534196986613;31535.684643153276;41363.1727365445;41426.37860432036;42023.57976420319;43372.783136084116;43399.34900976425;12664.873351266739;12833.422495987516;12890.67773305675;12970.416331265304;13007.089503734647;13180.406881690154;13334.466621335037;13441.193528387937;13656.65858353549;13725;13216.735665286447;13256.734865302833;13312.267825269315;13379.598612041655;13436.395362208528;12641.977283634942;12735.426905789076;12798.104132710621;13210.603681889559;13891.305434728425;13066.477340906258;13238.4042718076;13367.264800436069;13437.596872093853;13819;11058.778824423305;11119.388433636228;11164.046291899047;11239.438028098726;11545.480453379787;11241.156913231704;11267.492962816528;11336.829947550958;11375.658730238107;11446.198766086627 05:28:43 INFO - PROCESS | 23465 | __end_tp_report 05:28:43 INFO - PROCESS | 23465 | __start_cc_report 05:28:43 INFO - PROCESS | 23465 | _x_x_mozilla_cycle_collect,833 05:28:43 INFO - PROCESS | 23465 | __end_cc_report 05:28:43 INFO - PROCESS | 23465 | __startTimestamp1460636923551__endTimestamp 05:28:43 INFO - PROCESS | 23465 | 05:28:43 INFO - PROCESS | 23465 | ------- Summary: start ------- 05:28:43 INFO - PROCESS | 23465 | Number of tests: 6 05:28:43 INFO - PROCESS | 23465 | 05:28:43 INFO - PROCESS | 23465 | [#0] dojo.html Cycles:165 Average:5278.45 Median:5501.50 stddev:594.13 (10.8%) stddev-sans-first:570.83 05:28:43 INFO - PROCESS | 23465 | Values: 3099.4 3104.3 3129.8 3132.1 3165.1 5137.0 5212.5 5224.1 5249.6 5255.8 5377.6 5393.7 5407.5 5411.5 5459.1 4749.5 4802.3 4872.2 4919.8 4921.0 4855.9 4879.7 4918.1 4920.0 4934.9 4643.8 4752.1 4798.4 4817.9 4853.4 4836.1 4855.1 4863.9 4864.9 4896.4 4659.1 4834.2 4843.5 4846.6 4894.1 5212.6 5289.4 5299.8 5311.3 5326.8 5650.4 5652.5 5653.5 5655.2 5656.2 5547.0 5631.8 5710.1 5710.2 5715.0 5320.8 5328.5 5347.8 5357.7 5376.6 5802.6 5811.5 5821.3 5826.6 5827.5 5397.5 5525.1 5578.7 5592.5 5636.8 5668.6 5676.4 5676.6 5681.5 5714.9 5653.2 5656.8 5657.3 5660.0 5663.8 5407.2 5555.2 5640.5 5653.1 5670.3 4284.5 4290.8 4292.2 4306.1 4325.7 3847.3 3855.6 3859.3 3881.2 3889.0 5278.7 5368.7 5431.3 5488.3 5496.8 5705.9 5710.2 5721.7 5726.6 5742.3 5241.7 5426.0 5441.8 5446.4 5489.3 4838.6 4883.3 4886.6 4923.0 4928.8 5812.3 5813.9 5818.2 5822.2 5822.7 5528.3 5642.5 5648.4 5701.4 5718.7 5901.0 5922.2 5927.3 5931.1 5942.6 5614.7 5616.1 5627.5 5627.6 5630.2 5441.2 5710.4 5712.7 5745.6 5802.9 5560.7 5564.7 5575.5 5584.7 5615.4 5539.5 5543.3 5554.0 5557.0 5558.2 5299.3 5426.9 5459.0 5501.5 5506.9 5573.4 5614.9 5615.6 5617.7 5633.0 5485.7 5501.6 5501.7 5506.0 5557.1 05:28:43 INFO - PROCESS | 23465 | 05:28:43 INFO - PROCESS | 23465 | [#1] ext.html Cycles:180 Average:53580.38 Median:35188.24 stddev:102398.82 (291.0%) stddev-sans-first:102608.80 05:28:43 INFO - PROCESS | 23465 | Values: 596.5 642.5 648.6 657.3 675.4 29804.4 38179.4 39115.6 39121.8 39129.2 40296.8 41164.6 41286.0 41368.0 41393.4 7734.5 8392.6 8648.9 8669.2 9961.0 10011.0 10084.8 10086.4 10188.3 10196.8 10000.2 10142.7 10189.5 10207.3 10220.7 8001.8 10160.1 10246.2 10661.4 10676.7 10111.5 10265.1 10272.5 10276.9 10281.1 37581.6 38107.8 38128.4 38132.2 38149.2 3337.1 3395.1 3409.6 3541.0 3655.9 31743.7 31785.5 31803.0 31962.4 32803.4 4259.0 4272.8 4286.7 4302.6 4324.6 299681.0 300022.0 301097.0 302295.0 302724.5 35911.5 60110.0 61069.4 61161.0 61170.4 35095.5 35180.5 35196.0 35226.5 35253.6 13593.7 13788.0 13794.1 13795.9 13796.2 11395.9 11488.9 11494.3 11495.9 11500.5 8403.0 8609.1 8610.0 8652.7 8666.6 3074.5 3184.8 3313.9 3379.7 3425.6 979.4 982.9 1070.9 1076.0 1166.6 32872.0 32891.3 32960.7 32994.0 33596.5 1075.2 1077.1 1113.9 1131.0 1171.2 15449.8 16027.2 16173.4 16257.3 16497.3 439769.0 442608.0 445310.0 446169.0 448768.0 56223.0 57242.4 57328.1 57332.4 57349.0 1359.2 1378.6 1398.8 1399.5 1413.5 30523.7 31060.5 31760.8 31827.0 32387.7 51589.7 52454.5 52553.2 52577.5 52613.2 394708.0 403217.0 403228.0 404248.0 404865.0 25047.2 25717.0 27640.5 27776.9 28428.9 41041.0 45577.5 46054.1 46583.0 46762.3 38519.4 38835.2 39444.4 39540.0 40411.6 38374.8 38542.6 38870.8 39638.8 39763.2 39942.6 40455.4 40894.4 40928.6 41102.2 33772.3 34716.7 34997.8 35490.0 35658.3 26843.5 27356.0 27484.5 27773.7 27851.4 05:28:43 INFO - PROCESS | 23465 | 05:28:43 INFO - PROCESS | 23465 | [#2] jquery.html Cycles:180 Average:27511.09 Median:19206.91 stddev:76057.66 (396.0%) stddev-sans-first:76243.73 05:28:43 INFO - PROCESS | 23465 | Values: 378.9 385.2 389.6 400.0 401.3 17121.9 19255.8 19478.9 19511.6 19529.8 18646.2 18843.8 19002.9 19146.1 19305.1 5503.4 5543.6 5576.4 5688.0 5726.9 5460.8 5656.8 5665.5 5690.8 5691.7 5303.9 5627.3 5629.8 5631.1 5640.3 5458.4 5611.3 5614.8 5648.4 5653.4 5709.6 5710.0 5734.2 5742.1 5747.8 13309.8 13570.5 20081.5 21284.0 21307.9 2279.6 2406.7 2559.7 2607.6 2692.0 9660.1 9850.6 10260.9 12191.9 13812.4 1723.1 1835.5 1886.3 1917.4 1944.2 59723.7 73956.0 77627.6 79524.2 81650.6 10205.4 10428.3 10444.3 10548.3 10648.3 12802.2 13088.3 13449.6 13550.9 13594.0 5733.7 5868.8 6521.7 7515.4 7616.5 7194.0 7200.5 7218.5 7390.2 7458.5 4184.6 4227.7 4297.7 4342.4 4628.6 1916.5 1934.0 1948.2 1995.2 1998.3 903.3 929.8 930.9 1011.0 1020.4 16780.5 19183.5 19187.8 19342.5 19490.6 948.1 952.7 989.2 1002.7 1012.7 10426.2 10901.2 10962.2 10995.0 11004.3 445594.0 447173.0 452836.0 476075.0 478700.0 89399.0 95709.0 96196.5 96993.5 97476.0 1006.6 1016.7 1023.7 1024.0 1041.9 16265.5 16470.4 16604.3 16735.4 16876.0 37602.3 38028.6 38647.8 39329.6 39379.2 53643.5 54118.5 54351.6 54862.5 55884.4 8474.2 8475.7 8702.8 8738.3 8751.0 8421.5 9495.6 11463.6 12140.3 13262.7 6046.7 10824.0 10966.9 12059.9 12320.9 11809.7 11935.6 11999.0 12012.8 12139.2 12311.3 12433.8 12640.9 12699.2 12719.6 8313.9 8574.3 8642.8 8703.6 8741.1 8933.3 11086.5 11861.4 11973.2 12203.1 05:28:43 INFO - PROCESS | 23465 | 05:28:43 INFO - PROCESS | 23465 | [#3] mootools.html Cycles:180 Average:5577.44 Median:5169.50 stddev:3076.06 (59.5%) stddev-sans-first:3068.44 05:28:43 INFO - PROCESS | 23465 | Values: 1371.3 1525.4 1571.0 1749.6 1854.6 7923.2 8320.2 8324.5 8328.4 8329.7 8072.2 8223.3 8225.7 8227.8 8233.6 2048.2 2301.7 2331.0 2362.6 2370.0 2354.2 2376.0 2376.0 2379.7 2380.1 2304.1 2352.2 2353.0 2354.2 2356.4 1996.4 2276.4 2348.9 2349.7 2352.3 2215.7 2223.9 2224.9 2228.5 2279.6 8333.0 8384.6 8402.7 8423.0 8454.0 5034.5 5132.1 5176.7 5213.2 5236.7 3238.3 3323.8 3342.8 3379.8 3416.4 1201.7 1224.2 1235.8 1253.6 1255.8 11453.4 11462.5 11471.5 11475.7 11505.9 4375.4 4438.7 4576.0 4604.0 4643.6 9566.2 9678.1 9682.7 9692.3 9721.3 4515.4 5089.5 5095.4 5107.8 5113.8 4550.2 4587.6 4588.4 4619.0 4638.0 3745.5 3750.8 3819.6 3824.0 3866.3 1220.5 1235.0 1249.7 1257.8 1263.3 793.3 945.7 951.1 965.2 969.5 5742.8 5812.1 5857.5 5867.6 5930.7 870.2 885.6 942.4 987.8 1020.0 3981.8 4005.8 4011.8 4035.8 4036.5 9946.2 10392.3 10437.6 10464.9 10472.3 10364.1 10402.9 10563.8 10566.4 10566.6 7442.3 7866.8 7949.6 7960.1 8008.3 5835.5 5928.2 5930.3 6015.2 6057.1 9074.4 9290.4 9442.8 9557.3 9569.3 9245.0 9518.0 9620.4 9753.1 9756.7 6725.2 7303.5 7381.3 7482.7 7646.5 8257.5 8259.0 8263.4 8347.2 8357.4 7061.2 7152.3 7223.6 7341.6 7390.7 7488.6 7494.1 7501.2 7530.6 7553.4 6735.7 6750.5 6809.1 6817.6 6818.0 4995.8 5046.5 5156.0 5162.3 5200.4 5182.7 5185.2 5232.1 5252.1 5298.8 05:28:43 INFO - PROCESS | 23465 | 05:28:43 INFO - PROCESS | 23465 | [#4] prototype.html Cycles:180 Average:5039.26 Median:5830.74 stddev:1515.80 (26.0%) stddev-sans-first:1478.11 05:28:43 INFO - PROCESS | 23465 | Values: 321.3 331.7 332.4 336.4 339.0 5497.7 5678.6 5729.7 5763.5 5799.2 5655.6 5697.7 5734.9 5737.5 5745.6 4448.1 4448.8 4449.4 4462.2 4465.6 4235.7 4380.7 4402.2 4470.1 4482.4 4317.7 4320.5 4342.4 4361.7 4363.0 4317.0 4345.0 4354.1 4362.2 4373.3 3728.3 3824.9 3825.1 3825.9 3829.2 5474.4 5507.3 5508.7 5511.7 5522.9 5029.9 5033.0 5034.5 5041.3 5049.2 5700.4 5972.5 5974.0 6011.8 6026.5 4163.2 4177.1 4182.0 4192.2 4200.4 6203.4 6265.0 6266.2 6287.1 6291.7 4207.0 4297.9 4307.4 4309.9 4326.3 4319.2 4341.3 4344.7 4346.9 4356.1 5019.1 5021.8 5029.6 5036.9 5043.0 5909.9 5962.5 6005.9 6019.3 6060.5 1494.1 1495.8 1502.6 1504.8 1515.5 962.6 970.7 981.2 993.2 995.9 5828.6 5832.9 5847.2 5859.3 5874.5 6148.2 6157.4 6177.7 6180.9 6193.6 5612.2 5818.4 5821.6 5842.9 5851.9 3281.0 3308.5 3309.3 3310.3 3316.1 6154.8 6434.0 6435.9 6452.7 6476.7 6386.2 6393.8 6419.5 6426.0 6426.1 6260.8 6459.2 6492.3 6502.7 6523.2 6195.2 6227.5 6236.4 6241.8 6263.2 6088.7 6124.8 6130.4 6154.5 6174.5 6064.0 6276.2 6318.8 6461.5 6478.7 4173.1 4177.6 4212.1 4300.4 4307.7 6180.4 6186.8 6202.7 6211.0 6214.1 5873.4 5979.6 6012.5 6049.8 6055.2 5960.3 5978.7 5982.8 6008.4 6036.8 5789.4 5880.9 5886.4 5926.8 5941.3 5998.0 6005.4 6017.9 6028.2 6060.9 5835.9 5886.6 5894.0 5904.6 5910.9 05:28:43 INFO - PROCESS | 23465 | 05:28:43 INFO - PROCESS | 23465 | [#5] yui.html Cycles:180 Average:14563.57 Median:24726.78 stddev:16635.13 (67.3%) stddev-sans-first:16649.76 05:28:43 INFO - PROCESS | 23465 | Values: 815.8 850.0 850.4 862.5 865.4 14453.3 14850.0 14902.3 14903.7 14930.5 13467.7 14124.9 14163.9 14260.8 14294.0 828.6 838.6 839.9 841.6 844.1 829.1 839.5 840.7 841.5 841.9 877.7 883.5 883.7 884.3 884.6 876.1 877.0 877.7 878.2 879.0 637.3 641.3 641.3 641.9 642.4 13252.9 13332.7 13353.1 13356.7 13404.9 12046.4 12248.8 12313.1 12314.2 12407.6 9771.4 9896.9 10097.3 10140.8 10300.9 3750.8 3762.3 3780.4 3819.3 3855.3 45983.3 52347.0 53357.0 53841.0 53980.7 10375.6 10426.5 10525.7 10599.8 10845.6 19177.6 19407.8 19428.3 19441.1 19452.0 7764.4 7919.6 7977.4 8057.1 8460.5 4635.4 4710.2 4711.2 4713.6 4722.5 3345.4 3419.5 3438.4 3438.6 3444.6 639.6 645.7 650.1 655.8 662.3 649.5 676.7 692.2 694.5 708.6 9306.5 9323.3 9379.2 9692.0 9821.4 615.4 670.2 700.6 730.3 739.5 4290.6 4423.5 4471.6 4542.7 4547.5 62654.4 62664.0 63577.4 63842.7 64216.0 44982.0 51974.7 56498.0 57446.7 59052.1 48013.5 48097.8 48347.8 48532.3 50501.5 9103.3 9268.6 9330.5 9650.5 9824.3 29107.4 30494.4 30712.7 31053.5 31535.7 41363.2 41426.4 42023.6 43372.8 43399.3 12664.9 12833.4 12890.7 12970.4 13007.1 13180.4 13334.5 13441.2 13656.7 13725.0 13216.7 13256.7 13312.3 13379.6 13436.4 12642.0 12735.4 12798.1 13210.6 13891.3 13066.5 13238.4 13367.3 13437.6 13819.0 11058.8 11119.4 11164.0 11239.4 11545.5 11241.2 11267.5 11336.8 11375.7 11446.2 05:28:43 INFO - PROCESS | 23465 | -------- Summary: end -------- 05:28:43 INFO - PROCESS | 23465 | 05:28:44 INFO - TEST-INFO | 23465: exit 0 05:28:44 INFO - TEST-OK | dromaeo_css | took 1111924ms 05:28:44 INFO - TEST-START | kraken 05:28:44 INFO - operating with platform_type : linux_ 05:28:44 INFO - Initialising browser for kraken test... 05:28:44 INFO - TEST-INFO | started process 23650 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2pun6X/profile http://localhost:47804/getInfo.html) 05:28:48 INFO - PROCESS | 23650 | __metrics Screen width/height:1600/1200 05:28:48 INFO - PROCESS | 23650 | colorDepth:24 05:28:48 INFO - PROCESS | 23650 | Browser inner width/height: 1024/697 05:28:48 INFO - PROCESS | 23650 | __metrics 05:28:49 ERROR - PROCESS | 23650 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 05:28:50 INFO - TEST-INFO | 23650: exit 0 05:28:50 INFO - Browser initialized. 05:28:50 INFO - Running cycle 1/1 for kraken test... 05:28:50 INFO - TEST-INFO | started process 23731 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2pun6X/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:29:02 INFO - PROCESS | 23731 | RSS: Main: 153567232 05:29:02 INFO - PROCESS | 23731 | 05:29:04 INFO - PROCESS | 23731 | Cycle 1(1): loaded http://localhost:47804/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47804/tests/kraken/driver.html?testName=audio-beat-detection) 05:29:05 INFO - PROCESS | 23731 | RSS: Main: 208510976 05:29:05 INFO - PROCESS | 23731 | 05:29:08 INFO - PROCESS | 23731 | Cycle 1(1): loaded http://localhost:47804/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47804/tests/kraken/driver.html?testName=audio-dft) 05:29:08 INFO - PROCESS | 23731 | RSS: Main: 193822720 05:29:08 INFO - PROCESS | 23731 | 05:29:11 INFO - PROCESS | 23731 | Cycle 1(1): loaded http://localhost:47804/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47804/tests/kraken/driver.html?testName=audio-fft) 05:29:12 INFO - PROCESS | 23731 | RSS: Main: 263712768 05:29:12 INFO - PROCESS | 23731 | 05:29:14 INFO - PROCESS | 23731 | Cycle 1(1): loaded http://localhost:47804/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47804/tests/kraken/driver.html?testName=audio-oscillator) 05:29:14 INFO - PROCESS | 23731 | RSS: Main: 172568576 05:29:14 INFO - PROCESS | 23731 | 05:29:17 INFO - PROCESS | 23731 | Cycle 1(1): loaded http://localhost:47804/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47804/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:29:17 INFO - PROCESS | 23731 | RSS: Main: 177143808 05:29:17 INFO - PROCESS | 23731 | 05:29:20 INFO - PROCESS | 23731 | Cycle 1(1): loaded http://localhost:47804/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47804/tests/kraken/driver.html?testName=imaging-darkroom) 05:29:21 INFO - PROCESS | 23731 | RSS: Main: 213508096 05:29:21 INFO - PROCESS | 23731 | 05:29:24 INFO - PROCESS | 23731 | Cycle 1(1): loaded http://localhost:47804/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47804/tests/kraken/driver.html?testName=imaging-desaturate) 05:29:25 INFO - PROCESS | 23731 | RSS: Main: 213635072 05:29:25 INFO - PROCESS | 23731 | 05:29:28 INFO - PROCESS | 23731 | Cycle 1(1): loaded http://localhost:47804/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47804/tests/kraken/driver.html?testName=json-parse-financial) 05:29:28 INFO - PROCESS | 23731 | RSS: Main: 214065152 05:29:28 INFO - PROCESS | 23731 | 05:29:30 INFO - PROCESS | 23731 | Cycle 1(1): loaded http://localhost:47804/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47804/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:29:30 INFO - PROCESS | 23731 | RSS: Main: 201478144 05:29:30 INFO - PROCESS | 23731 | 05:29:32 INFO - PROCESS | 23731 | Cycle 1(1): loaded http://localhost:47804/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47804/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:29:33 INFO - PROCESS | 23731 | RSS: Main: 201900032 05:29:33 INFO - PROCESS | 23731 | 05:29:35 INFO - PROCESS | 23731 | Cycle 1(1): loaded http://localhost:47804/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47804/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:29:35 INFO - PROCESS | 23731 | RSS: Main: 218046464 05:29:35 INFO - PROCESS | 23731 | 05:29:38 INFO - PROCESS | 23731 | Cycle 1(1): loaded http://localhost:47804/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47804/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:29:38 INFO - PROCESS | 23731 | RSS: Main: 221126656 05:29:38 INFO - PROCESS | 23731 | 05:29:41 INFO - PROCESS | 23731 | Cycle 1(1): loaded http://localhost:47804/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47804/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:29:42 INFO - PROCESS | 23731 | RSS: Main: 218664960 05:29:42 INFO - PROCESS | 23731 | 05:29:44 INFO - PROCESS | 23731 | Cycle 1(1): loaded http://localhost:47804/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47804/tests/kraken/driver.html?testName=ai-astar) 05:29:44 INFO - PROCESS | 23731 | RSS: Main: 281690112 05:29:44 INFO - PROCESS | 23731 | 05:29:44 INFO - PROCESS | 23731 | __start_tp_report 05:29:44 INFO - PROCESS | 23731 | _x_x_mozilla_page_load 05:29:44 INFO - PROCESS | 23731 | _x_x_mozilla_page_load_details 05:29:44 INFO - PROCESS | 23731 | |i|pagename|runs| 05:29:44 INFO - PROCESS | 23731 | |0;ai-astar;113;114;108;113;113;112;101;112;111;111 05:29:44 INFO - PROCESS | 23731 | |1;audio-beat-detection;151;126;157;188;131;156;154;129;182;127 05:29:44 INFO - PROCESS | 23731 | |2;audio-dft;161;162;160;159;162;158;162;162;161;161 05:29:44 INFO - PROCESS | 23731 | |3;audio-fft;72;79;114;107;76;75;72;119;100;76 05:29:44 INFO - PROCESS | 23731 | |4;audio-oscillator;93;92;147;92;93;92;152;94;93;146 05:29:44 INFO - PROCESS | 23731 | |5;imaging-gaussian-blur;116;117;116;117;116;117;117;117;117;117 05:29:44 INFO - PROCESS | 23731 | |6;imaging-darkroom;145;145;146;146;146;145;146;146;147;146 05:29:44 INFO - PROCESS | 23731 | |7;imaging-desaturate;95;95;95;95;95;95;95;96;96;96 05:29:44 INFO - PROCESS | 23731 | |8;json-parse-financial;55;55;56;56;56;56;58;72;55;52 05:29:44 INFO - PROCESS | 23731 | |9;json-stringify-tinderbox;62;63;62;62;63;62;62;61;61;63 05:29:44 INFO - PROCESS | 23731 | |10;stanford-crypto-aes;79;79;79;79;80;81;81;79;81;82 05:29:44 INFO - PROCESS | 23731 | |11;stanford-crypto-ccm;132;130;131;129;134;125;136;126;171;128 05:29:44 INFO - PROCESS | 23731 | |12;stanford-crypto-pbkdf2;170;164;166;164;163;161;163;166;167;162 05:29:44 INFO - PROCESS | 23731 | |13;stanford-crypto-sha256-iterative;68;68;68;69;68;68;69;67;67;66 05:29:44 INFO - PROCESS | 23731 | __end_tp_report 05:29:44 INFO - PROCESS | 23731 | __start_cc_report 05:29:44 INFO - PROCESS | 23731 | _x_x_mozilla_cycle_collect,1610 05:29:44 INFO - PROCESS | 23731 | __end_cc_report 05:29:44 INFO - PROCESS | 23731 | __startTimestamp1460636984289__endTimestamp 05:29:44 INFO - PROCESS | 23731 | 05:29:44 INFO - PROCESS | 23731 | ------- Summary: start ------- 05:29:44 INFO - PROCESS | 23731 | Number of tests: 14 05:29:44 INFO - PROCESS | 23731 | 05:29:44 INFO - PROCESS | 23731 | [#0] ai-astar Cycles:10 Average:110.80 Median:112.50 stddev:3.82 (3.4%) stddev-sans-first:3.97 05:29:44 INFO - PROCESS | 23731 | Values: 113.0 114.0 108.0 113.0 113.0 112.0 101.0 112.0 111.0 111.0 05:29:44 INFO - PROCESS | 23731 | 05:29:44 INFO - PROCESS | 23731 | [#1] audio-beat-detection Cycles:10 Average:150.10 Median:155.00 stddev:22.30 (14.4%) stddev-sans-first:23.65 05:29:44 INFO - PROCESS | 23731 | Values: 151.0 126.0 157.0 188.0 131.0 156.0 154.0 129.0 182.0 127.0 05:29:44 INFO - PROCESS | 23731 | 05:29:44 INFO - PROCESS | 23731 | [#2] audio-dft Cycles:10 Average:160.80 Median:161.50 stddev:1.40 (0.9%) stddev-sans-first:1.48 05:29:44 INFO - PROCESS | 23731 | Values: 161.0 162.0 160.0 159.0 162.0 158.0 162.0 162.0 161.0 161.0 05:29:44 INFO - PROCESS | 23731 | 05:29:44 INFO - PROCESS | 23731 | [#3] audio-fft Cycles:10 Average:89.00 Median:73.50 stddev:18.80 (25.6%) stddev-sans-first:18.91 05:29:44 INFO - PROCESS | 23731 | Values: 72.0 79.0 114.0 107.0 76.0 75.0 72.0 119.0 100.0 76.0 05:29:44 INFO - PROCESS | 23731 | 05:29:44 INFO - PROCESS | 23731 | [#4] audio-oscillator Cycles:10 Average:109.40 Median:92.50 stddev:26.92 (29.1%) stddev-sans-first:27.89 05:29:44 INFO - PROCESS | 23731 | Values: 93.0 92.0 147.0 92.0 93.0 92.0 152.0 94.0 93.0 146.0 05:29:44 INFO - PROCESS | 23731 | 05:29:44 INFO - PROCESS | 23731 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 05:29:44 INFO - PROCESS | 23731 | Values: 116.0 117.0 116.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 05:29:44 INFO - PROCESS | 23731 | 05:29:44 INFO - PROCESS | 23731 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.63 (0.4%) stddev-sans-first:0.60 05:29:44 INFO - PROCESS | 23731 | Values: 145.0 145.0 146.0 146.0 146.0 145.0 146.0 146.0 147.0 146.0 05:29:44 INFO - PROCESS | 23731 | 05:29:44 INFO - PROCESS | 23731 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 05:29:44 INFO - PROCESS | 23731 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 96.0 96.0 96.0 05:29:44 INFO - PROCESS | 23731 | 05:29:44 INFO - PROCESS | 23731 | [#8] json-parse-financial Cycles:10 Average:57.10 Median:56.00 stddev:5.45 (9.7%) stddev-sans-first:5.72 05:29:44 INFO - PROCESS | 23731 | Values: 55.0 55.0 56.0 56.0 56.0 56.0 58.0 72.0 55.0 52.0 05:29:44 INFO - PROCESS | 23731 | 05:29:44 INFO - PROCESS | 23731 | [#9] json-stringify-tinderbox Cycles:10 Average:62.10 Median:62.00 stddev:0.74 (1.2%) stddev-sans-first:0.78 05:29:44 INFO - PROCESS | 23731 | Values: 62.0 63.0 62.0 62.0 63.0 62.0 62.0 61.0 61.0 63.0 05:29:44 INFO - PROCESS | 23731 | 05:29:44 INFO - PROCESS | 23731 | [#10] stanford-crypto-aes Cycles:10 Average:80.00 Median:80.50 stddev:1.15 (1.4%) stddev-sans-first:1.17 05:29:44 INFO - PROCESS | 23731 | Values: 79.0 79.0 79.0 79.0 80.0 81.0 81.0 79.0 81.0 82.0 05:29:44 INFO - PROCESS | 23731 | 05:29:44 INFO - PROCESS | 23731 | [#11] stanford-crypto-ccm Cycles:10 Average:134.20 Median:131.50 stddev:13.36 (10.2%) stddev-sans-first:14.15 05:29:44 INFO - PROCESS | 23731 | Values: 132.0 130.0 131.0 129.0 134.0 125.0 136.0 126.0 171.0 128.0 05:29:44 INFO - PROCESS | 23731 | 05:29:44 INFO - PROCESS | 23731 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.60 Median:165.00 stddev:2.67 (1.6%) stddev-sans-first:2.00 05:29:44 INFO - PROCESS | 23731 | Values: 170.0 164.0 166.0 164.0 163.0 161.0 163.0 166.0 167.0 162.0 05:29:44 INFO - PROCESS | 23731 | 05:29:44 INFO - PROCESS | 23731 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.80 Median:68.00 stddev:0.92 (1.4%) stddev-sans-first:0.97 05:29:44 INFO - PROCESS | 23731 | Values: 68.0 68.0 68.0 69.0 68.0 68.0 69.0 67.0 67.0 66.0 05:29:44 INFO - PROCESS | 23731 | -------- Summary: end -------- 05:29:44 INFO - PROCESS | 23731 | 05:29:45 INFO - TEST-INFO | 23731: exit 0 05:29:45 INFO - TEST-OK | kraken | took 61110ms 05:29:45 INFO - SUITE-END | took 1173s 05:29:45 INFO - Completed test suite (00:19:33) 05:29:45 INFO - javascript benchmark 05:29:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3099.380123975203, 3104.317050248945, 3129.82785946773, 3132.0916934089114, 3165.1454107391005, 5137.049645815527, 5212.530872221504, 5224.111900976834, 5249.606279529036, 5255.81604643838, 5377.5966802489875, 5393.703346315951, 5407.540359069484, 5411.458854114587, 5459.099248623972, 4749.52504749524, 4802.25565037418, 4872.220444728844, 4919.827806026789, 4920.950790492103, 4855.902881942359, 4879.70721756694, 4918.090153321629, 4920.040592084545, 4934.925976110361, 4643.791029403685, 4752.12085764133, 4798.352222449959, 4817.903641927177, 4853.441854186778, 4836.129496690594, 4855.053264613402, 4863.929935414203, 4864.854054378339, 4896.436909755388, 4659.138059459011, 4834.154023045988, 4843.539863712942, 4846.5880400166025, 4894.094592500399, 5212.55693266069, 5289.365276166846, 5299.788008479694, 5311.309529761106, 5326.786928522854, 5650.406707295757, 5652.491275785158, 5653.519450846683, 5655.208270842086, 5656.236408084897, 5546.972265138648, 5631.774729010835, 5710.086386178233, 5710.229119068914, 5715.0, 5320.8403747888015, 5328.520433161034, 5347.812826551052, 5357.651752636105, 5376.596755243372, 5802.622829516068, 5811.506021988178, 5821.27234095738, 5826.621269617462, 5827.475527202572, 5397.487238712316, 5525.0883604204855, 5578.693171875586, 5592.468715472105, 5636.8308950730725, 5668.631538949955, 5676.4323567642905, 5676.574256930747, 5681.545476361881, 5714.857128571819, 5653.180288858175, 5656.830295091154, 5657.32112146545, 5660.037793575019, 5663.773449061991, 5407.242985981966, 5555.194496797942, 5640.492355688008, 5653.095504719226, 5670.347909990322, 4284.485861696554, 4290.785460726951, 4292.184484947912, 4306.074194048295, 4325.697201195949, 3847.2882516734494, 3855.5951625079524, 3859.3053250415196, 3881.2237552489614, 3889.0, 5278.683279003272, 5368.704721240291, 5431.2667789847865, 5488.259085023471, 5496.835094947158, 5705.914411283834, 5710.200571919934, 5721.742521586539, 5726.627769194989, 5742.253507044141, 5241.711705856138, 5426.0, 5441.755121019484, 5446.400895901447, 5489.313835770528, 4838.612910967114, 4883.291922671193, 4886.60907127429, 4923.040007198562, 4928.802847886116, 5812.273465816773, 5813.941860581424, 5818.185454036438, 5822.243108395882, 5822.650640961556, 5528.281323427928, 5642.463965923313, 5648.435156484319, 5701.40135285789, 5718.685472299064, 5900.996830538733, 5922.200502932049, 5927.288725352985, 5931.080682494047, 5942.61373010753, 5614.747336369955, 5616.1014237721765, 5627.549796016391, 5627.577931655224, 5630.23991761107, 5441.238226648194, 5710.400407957105, 5712.742926568231, 5745.597808153389, 5802.912956305574, 5560.721963901708, 5564.666120032811, 5575.526080283059, 5584.692841893735, 5615.3823079462045, 5539.529140023144, 5543.307086614173, 5554.028045092174, 5557.0, 5558.249636299048, 5299.33758280215, 5426.891462170655, 5458.962797068544, 5501.504864562208, 5506.944930550643, 5573.359063707563, 5614.85962850932, 5615.634983726045, 5617.7191140442, 5633.0, 5485.6982865942755, 5501.587380946525, 5501.669899806025, 5505.9724701376235, 5557.055300598827], "value": 5238.897866162414, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [596.4780816785313, 642.4539141729531, 648.5751832549681, 657.2540166910557, 675.3988949834645, 29804.403911921774, 38179.42730859039, 39115.608843911556, 39121.804390978075, 39129.21741565171, 40296.79851600745, 41164.58835411645, 41286.0, 41368.0, 41393.37909931353, 7734.497257678261, 8392.58037098144, 8648.870266946002, 8669.21977022068, 9960.950195249014, 10011.048950349705, 10084.798304033915, 10086.394816311009, 10188.337758045704, 10196.847047294297, 10000.249981251436, 10142.746431339201, 10189.54147063384, 10207.33652312601, 10220.74448138795, 8001.759947201623, 10160.085592296691, 10246.231532635007, 10661.413622250773, 10676.733081672943, 10111.544980475895, 10265.127464165555, 10272.486375681186, 10276.94861525695, 10281.126104281146, 37581.62418375809, 38107.8094609528, 38128.42807357892, 38132.23735525302, 38149.237015259816, 3337.1323455901397, 3395.100173890498, 3409.6078950920714, 3540.964590354089, 3655.94516082259, 31743.682563174305, 31785.52321715176, 31803.0, 31962.360752784814, 32803.39162692102, 4258.957410425887, 4272.807723652443, 4286.657067434598, 4302.634276086536, 4324.56754324565, 299681.0, 300022.0, 301097.0, 302295.0, 302724.4863775689, 35911.4613280801, 60110.0, 61069.38930610726, 61161.0, 61170.38829611736, 35095.4735678965, 35180.47229291563, 35196.0, 35226.47160292598, 35253.64746352555, 13593.660158496117, 13788.0, 13794.103383280055, 13795.862041379658, 13796.172229666252, 11395.943020284845, 11488.88511114895, 11494.310341379543, 11495.88504114965, 11500.539978400937, 8402.957985210158, 8609.096044915319, 8610.0, 8652.653893844175, 8666.56667166639, 3074.4927087030837, 3184.824834634071, 3313.8508767105536, 3379.678930501598, 3425.6403077676982, 979.4221409368507, 982.8771403574553, 1070.8755806403246, 1075.9704905130336, 1166.6175581935433, 32872.0, 32891.3421731564, 32960.67039329576, 32994.0, 33596.49605255923, 1075.2312096850633, 1077.1436707817304, 1113.8997490225918, 1130.9604163854226, 1171.1626187276138, 15449.768253476206, 16027.19864006795, 16173.433929812636, 16257.268422920997, 16497.340106395608, 439769.0, 442608.0, 445310.0, 446169.0, 448768.0, 56223.0, 57242.42757572454, 57328.14007789887, 57332.42667573354, 57349.0, 1359.238826257299, 1378.5657517881834, 1398.7692030815006, 1399.5241617849777, 1413.5335339337994, 30523.694763052972, 31060.53409198909, 31760.841195793873, 31827.0, 32387.67612323941, 51589.742051289504, 52454.47545524496, 52553.21170182374, 52577.474225257254, 52613.21080183724, 394708.0, 403217.0, 403228.0, 404248.0, 404865.0, 25047.248582542554, 25717.0, 27640.512353888247, 27776.861115694293, 28428.85785571059, 41041.0, 45577.54422455733, 46054.078918420775, 46583.0, 46762.29856552222, 38519.42220866633, 38835.223295534495, 39444.40833387559, 39540.0, 40411.595884040784, 38374.80812595919, 38542.56760472582, 38870.80564597159, 39638.801805990784, 39763.204735905696, 39942.600573993885, 40455.393169103074, 40894.38658420066, 40928.590714092476, 41102.17795644011, 33772.32455350929, 34716.652833471344, 34997.82501087478, 35490.0, 35658.28683426369, 26843.463130736884, 27356.0, 27484.450310994067, 27773.722262777115, 27851.44297114087], "value": 18546.43246708369, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [378.8652984311986, 385.20070852980075, 389.606497437588, 399.9800009999503, 401.33378591538053, 17121.914390428046, 19255.807441925575, 19478.902605486972, 19511.609767804635, 19529.804701952995, 18646.160922758474, 18843.811561884377, 19002.904985475052, 19146.138423770928, 19305.13126909289, 5503.367112782032, 5543.6119471637, 5576.438597192792, 5687.971560142193, 5726.856828579298, 5460.781568737246, 5656.830295091154, 5665.461781130786, 5690.800821971232, 5691.715414229293, 5303.867403314929, 5627.296587926509, 5629.831105066834, 5631.1271752878365, 5640.323161220639, 5458.426865179139, 5611.270534830487, 5614.775408983656, 5648.406917273668, 5653.378128405872, 5709.600327977043, 5710.0, 5734.225879506255, 5742.081266997302, 5747.770089196427, 13309.800352994616, 13570.45718171272, 20081.497962550908, 21284.0, 21307.8934605326, 2279.5782780185555, 2406.7352591214953, 2559.744025597425, 2607.5827867541293, 2691.986540067287, 9660.082292182231, 9850.60597576096, 10260.948695256477, 12191.87808121925, 13812.422292017221, 1723.1298194411868, 1835.4768890866173, 1886.2643569008098, 1917.4439412570339, 1944.1931598386652, 59723.70138149325, 73956.0, 77627.6118619409, 79524.20475795283, 81650.59174704147, 10205.438700871524, 10428.27002109845, 10444.268901176996, 10548.261621686563, 10648.307859989076, 12802.167859089315, 13088.280144591949, 13449.596512104652, 13550.864491355158, 13594.048416610742, 5733.741981610838, 5868.794592189253, 6521.706523206467, 7515.398768098539, 7616.466847320745, 7194.028806111108, 7200.531965422231, 7218.458615603746, 7390.150132734804, 7458.5151965122095, 4184.623383895464, 4227.661787057028, 4297.72064815786, 4342.435483387139, 4628.629709623222, 1916.48254971157, 1933.980660193408, 1948.1525536391714, 1995.2318357432493, 1998.3114268443142, 903.3450748207629, 929.7675581104724, 930.9487978161267, 1011.014261095426, 1020.433659319085, 16780.496585102468, 19183.52041198981, 19187.808121918883, 19342.516437089187, 19490.610187796166, 948.0661548374845, 952.7284723853669, 989.2234595842373, 1002.7493126718321, 1012.7215015870547, 10426.165906727443, 10901.182411319025, 10962.177836662282, 10995.0, 11004.339739615649, 445594.0, 447173.0, 452836.0, 476075.0, 478700.0, 89399.0, 95709.0, 96196.51901740447, 96993.5150324258, 97476.0, 1006.5621454667244, 1016.7305663999047, 1023.6621914768109, 1023.9897601024044, 1041.9010194031555, 16265.532963157777, 16470.42353517621, 16604.25280862388, 16735.414260500824, 16876.0, 37602.283349245336, 38028.619713803615, 38647.806760966014, 39329.60670393259, 39379.21241575095, 53643.46356536541, 54118.458815411344, 54351.59190012588, 54862.45137548573, 55884.44115558793, 8474.152584741476, 8475.74572762818, 8702.82594348122, 8738.300935925237, 8751.037385887419, 8421.452605580618, 9495.620175192915, 11463.624365076244, 12140.33228172459, 13262.668433289246, 6046.727897244723, 10824.025837674648, 10966.945165274123, 12059.939700301442, 12320.876791232331, 11809.729922913202, 11935.582254621047, 11999.0, 12012.819807703067, 12139.210951288138, 12311.261324320569, 12433.784798730716, 12640.936795315965, 12699.174553653984, 12719.55481558141, 8313.916860831556, 8574.31405487572, 8642.78393040179, 8703.608337624948, 8741.08218637034, 8933.330000250136, 11086.501107450023, 11861.406929653655, 11973.221740586834, 12203.145779795685], "value": 8876.163587141427, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1371.3417559571399, 1525.3517255166555, 1570.9921450392749, 1749.5975925537125, 1854.6198029403974, 7923.207679232081, 8320.209580089897, 8324.458910170843, 8328.37537184712, 8329.66681332746, 8072.233137851895, 8223.259906608404, 8225.67097316108, 8227.794305142359, 8233.629486673099, 2048.1909645689943, 2301.6547517872286, 2331.0442718485424, 2362.633791762271, 2370.040133745827, 2354.164271683545, 2376.0139542090096, 2376.0495801679294, 2379.690640216778, 2380.1312520929823, 2304.124432715566, 2352.1532248390567, 2352.9529409411803, 2354.1878052072093, 2356.352003199125, 1996.3811218522233, 2276.35123989663, 2348.9060437582475, 2349.682792822964, 2352.3178278299274, 2215.656573231152, 2223.8776867272295, 2224.944376390587, 2228.5320082782637, 2279.566882292376, 8333.041700204494, 8384.622691978875, 8402.663893444254, 8422.957885210595, 8454.027786804534, 5034.546890779848, 5132.076226279269, 5176.7411629418375, 5213.165893457029, 5236.659617124875, 3238.2875767331147, 3323.80057196569, 3342.7994320340613, 3379.831008449568, 3416.350893330259, 1201.7356181640025, 1224.2103843021291, 1235.8269842222005, 1253.5834507007114, 1255.8179064035664, 11453.427328633536, 11462.541498340057, 11471.541138354456, 11475.713107172303, 11505.884941150565, 4375.387445757597, 4438.733675979452, 4576.039031803292, 4604.0, 4643.605293550086, 9566.234701223886, 9678.080582344666, 9682.70951871445, 9692.32153749245, 9721.270904682036, 4515.390422292949, 5089.465606111379, 5095.363079615048, 5107.821226257063, 5113.769880355393, 4550.180967425829, 4587.632989360844, 4588.357629931812, 4618.976905115453, 4638.0260145369175, 3745.4756334113245, 3750.774953502744, 3819.560750513671, 3823.9808800956375, 3866.304065268279, 1220.45079714129, 1234.9814752778716, 1249.731307768834, 1257.7547378261334, 1263.286243272548, 793.2701914238914, 945.6879229854131, 951.1107114847501, 965.2229954886244, 969.539468752337, 5742.827715168552, 5812.070068788974, 5857.531397488191, 5867.647941123546, 5930.733117009745, 870.1907226279494, 885.6059053721161, 942.4204114469552, 987.8221920054459, 1019.99490002551, 3981.820818063194, 4005.7796821174534, 4011.7793521356607, 4035.7982100894837, 4036.535798383165, 9946.154576860903, 10392.324498907547, 10437.634682786065, 10464.895351046544, 10472.319299245524, 10364.06723394883, 10402.947985260176, 10563.788724225473, 10566.418846963337, 10566.630167944086, 7442.330190282901, 7866.7639970800965, 7949.562774047367, 7960.1243863175005, 8008.27925486709, 5835.533157347487, 5928.199693041384, 5930.258717660293, 6015.187949626919, 6057.121717350871, 9074.410163339362, 9290.442573445614, 9442.763930901783, 9557.283203759886, 9569.330146889652, 9244.95377523108, 9518.048195180427, 9620.37467564606, 9753.122219000325, 9756.707298781048, 6725.226598940989, 7303.452241082046, 7381.335679788845, 7482.70069197226, 7646.502977306457, 8257.545834978893, 8258.958705206436, 8263.421560490948, 8347.207015333533, 8357.37319701037, 7061.223265440694, 7152.320529549685, 7223.638818059181, 7341.59621220838, 7390.6674199660065, 7488.5506869588, 7494.100707915085, 7501.2123727007875, 7530.623468826428, 7553.395728341829, 6735.730570777114, 6750.493712971449, 6809.148856392951, 6817.5909445433435, 6818.045473633596, 4995.800167993385, 5046.545810877039, 5155.994581056808, 5162.251473536241, 5200.4279529251, 5182.663126896739, 5185.170372740344, 5232.136697444879, 5252.107141785982, 5298.788048478018], "value": 4518.686191251883, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [321.32201055772333, 331.74289925307886, 332.41826803094585, 336.40624298113835, 338.9508521264418, 5497.670139791615, 5678.6024978251535, 5729.65622062676, 5763.51010164136, 5799.159121927323, 5655.604107712462, 5697.6866272355, 5734.856628584287, 5737.483626473617, 5745.59780815342, 4448.132614140228, 4448.777561121931, 4449.377087207794, 4462.174497717924, 4465.642748580106, 4235.7246778959425, 4380.67144964129, 4402.229609818285, 4470.128324976631, 4482.350059241425, 4317.71934824237, 4320.481542214939, 4342.435483387155, 4361.716488428257, 4362.956370436303, 4316.956830431687, 4344.97827510862, 4354.129174165164, 4362.17118747437, 4373.2565463871215, 3728.272986767581, 3824.9426258606145, 3825.08198032473, 3825.9426108608395, 3829.157585331223, 5474.37044739856, 5507.256520369739, 5508.724563771795, 5511.724413779335, 5522.889542209134, 5029.949700503022, 5033.0, 5034.521720436553, 5041.294218809369, 5049.2173713074535, 5700.429957004308, 5972.492338151263, 5974.0, 6011.819645410645, 6026.457618814284, 4163.2298024865195, 4177.081042170717, 4182.0, 4192.161567686475, 4200.390943313202, 6203.379662033805, 6264.966280563702, 6266.216722909637, 6287.1198032275515, 6291.653959032252, 4206.957930420718, 4297.892552686145, 4307.396964424983, 4309.913801723948, 4326.286162783168, 4319.157764235943, 4341.305391137466, 4344.652427805831, 4346.891327716769, 4356.1287742451, 5019.146745053276, 5021.84934451967, 5029.647924645313, 5036.924446133311, 5042.9747851261245, 5909.911351329733, 5962.4633782959745, 6005.858886811579, 6019.337872833985, 6060.454559089703, 1494.0811400988314, 1495.7681559358316, 1502.6393665520416, 1504.8269449013285, 1515.454436402894, 962.5957098018849, 970.6942313171328, 981.2052237687496, 993.215359865698, 995.930284880066, 5828.59199856006, 5832.88334233313, 5847.152162936435, 5859.267591551056, 5874.471297583153, 6148.200733904564, 6157.415045570664, 6177.69111544421, 6180.907286390708, 6193.566450348432, 5612.214289999403, 5818.359980402152, 5821.592488525763, 5842.883142337129, 5851.853703657443, 3280.967190328066, 3308.520264561625, 3309.255417531036, 3310.2717402171484, 3316.1378041708845, 6154.79981403631, 6434.0, 6435.903461448082, 6452.677366131675, 6476.740930362827, 6386.233651961701, 6393.808185754435, 6419.454346380517, 6425.971844504857, 6426.1003459515705, 6260.812175634738, 6459.1914264006155, 6492.285848556655, 6502.739890404331, 6523.217213934358, 6195.2255968004, 6227.470664993526, 6236.407541283571, 6241.750329986749, 6263.185785847811, 6088.726007329592, 6124.816255512342, 6130.444342281555, 6154.476869466145, 6174.506039516918, 6064.0, 6276.215473065867, 6318.810435686937, 6461.547691661539, 6478.6760661967655, 4173.144505376451, 4177.582241775798, 4212.115455754326, 4300.376445415334, 4307.719998200232, 6180.412860778219, 6186.752529898754, 6202.689865506797, 6210.968945155245, 6214.130021797043, 5873.3832947541605, 5979.611325264018, 6012.518998480198, 6049.78825741097, 6055.1522786809, 5960.3443621202505, 5978.671173085522, 5982.820515384546, 6008.399160083956, 6036.849078773066, 5789.363170051375, 5880.911786323123, 5886.3525012247765, 5926.822195334147, 5941.31674857397, 5998.0, 6005.3694362091355, 6017.879642407215, 6028.186194863637, 6060.909086363797, 5835.854103647443, 5886.646801191942, 5894.0, 5904.5866789326055, 5910.852228694317], "value": 4545.772207575927, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [815.8327542853714, 850.0327699549168, 850.366476974654, 862.4825104937039, 865.4417900454204, 14453.349599268033, 14850.034747741389, 14902.329395177216, 14903.701925961474, 14930.477433289838, 13467.730645387086, 14124.858751412508, 14163.858361416384, 14260.786088208684, 14294.0, 828.5691440450947, 838.6226198210829, 839.920207580282, 841.5750046226647, 844.0673056272822, 829.0838623321233, 839.5382539603224, 840.6889450903175, 841.5413599588239, 841.9452735572199, 877.7059685005531, 883.4787475389519, 883.730462209026, 884.332329091535, 884.5886662701843, 876.0538618292229, 877.0177401310509, 877.7279043496504, 878.2447095497898, 879.0330636300018, 637.3308026572128, 641.2529403245213, 641.2849672615, 641.9358064193543, 642.399356601579, 13252.867471325357, 13332.666683332995, 13353.065285430024, 13356.732865342736, 13404.932975335158, 12046.397680115959, 12248.755024899452, 12313.076519261002, 12314.199577027553, 12407.62777116679, 9771.364861284064, 9896.901030989742, 10097.293189476739, 10140.797184056351, 10300.948495257475, 3750.774953502799, 3762.285165818486, 3780.4329350597354, 3819.2743378758223, 3855.3445914194654, 45983.31025034627, 52347.0, 53357.0, 53841.0, 53980.73009634927, 10375.584976600852, 10426.530806113744, 10525.684229472974, 10599.788004240027, 10845.620403286004, 19177.616447670967, 19407.835529868255, 19428.320008799907, 19441.125149368312, 19452.027398630013, 7764.3788496920115, 7919.604019798987, 7977.441579089524, 8057.113717491071, 8460.534670593177, 4635.351050852883, 4710.222813235778, 4711.199096153594, 4713.626993058527, 4722.52774722525, 3345.381104495676, 3419.5212670226188, 3438.381091403571, 3438.5529881115294, 3444.5522082129664, 639.6002498438476, 645.7320212111921, 650.0703993289546, 655.7638850766244, 662.2681936460142, 649.4706813946618, 676.736072931557, 692.2385376086265, 694.5381321421299, 708.624429052603, 9306.488143152192, 9323.300752443594, 9379.202767764817, 9692.0, 9821.410715357102, 615.4030590327375, 670.2392784189882, 700.58315302395, 730.2587873308596, 739.5193124469138, 4290.627217642892, 4423.535528769497, 4471.552844715502, 4542.704724192917, 4547.493942989317, 62654.37345626576, 62664.0, 63577.36422635807, 63842.68078659577, 64216.0, 44982.0, 51974.740126299126, 56498.0, 57446.712766436736, 59052.11421828676, 48013.5198648016, 48097.75951120222, 48347.758261208466, 48532.27201591979, 50501.49498504968, 9103.317251206054, 9268.629254829732, 9330.486823224788, 9650.52084634414, 9824.312298139348, 29107.417851643273, 30494.390112198074, 30712.692873070984, 31053.534196986613, 31535.684643153276, 41363.1727365445, 41426.37860432036, 42023.57976420319, 43372.783136084116, 43399.34900976425, 12664.873351266739, 12833.422495987516, 12890.67773305675, 12970.416331265304, 13007.089503734647, 13180.406881690154, 13334.466621335037, 13441.193528387937, 13656.65858353549, 13725.0, 13216.735665286447, 13256.734865302833, 13312.267825269315, 13379.598612041655, 13436.395362208528, 12641.977283634942, 12735.426905789076, 12798.104132710621, 13210.603681889559, 13891.305434728425, 13066.477340906258, 13238.4042718076, 13367.264800436069, 13437.596872093853, 13819.0, 11058.778824423305, 11119.388433636228, 11164.046291899047, 11239.438028098726, 11545.480453379787, 11241.156913231704, 11267.492962816528, 11336.829947550958, 11375.658730238107, 11446.198766086627], "value": 6752.093071981128, "unit": "score"}], "name": "dromaeo_css", "value": 7019.464424235213, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 108.0, 113.0, 113.0, 112.0, 101.0, 112.0, 111.0, 111.0], "value": 110.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 126.0, 157.0, 188.0, 131.0, 156.0, 154.0, 129.0, 182.0, 127.0], "value": 150.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 160.0, 159.0, 162.0, 158.0, 162.0, 162.0, 161.0, 161.0], "value": 160.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 79.0, 114.0, 107.0, 76.0, 75.0, 72.0, 119.0, 100.0, 76.0], "value": 89.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 147.0, 92.0, 93.0, 92.0, 152.0, 94.0, 93.0, 146.0], "value": 109.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 116.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 146.0, 146.0, 146.0, 145.0, 146.0, 146.0, 147.0, 146.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 96.0, 96.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 56.0, 56.0, 56.0, 56.0, 58.0, 72.0, 55.0, 52.0], "value": 57.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 62.0, 62.0, 63.0, 62.0, 62.0, 61.0, 61.0, 63.0], "value": 62.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 79.0, 79.0, 80.0, 81.0, 81.0, 79.0, 81.0, 82.0], "value": 80.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 130.0, 131.0, 129.0, 134.0, 125.0, 136.0, 126.0, 171.0, 128.0], "value": 134.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 164.0, 166.0, 164.0, 163.0, 161.0, 163.0, 166.0, 167.0, 162.0], "value": 164.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 68.0, 69.0, 68.0, 68.0, 69.0, 67.0, 67.0, 66.0], "value": 67.8, "unit": "score"}], "name": "kraken", "value": 1543.6999999999998, "alertThreshold": 2.0}]} 05:29:45 INFO - Return code: 0 05:29:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:29:45 ERROR - # TBPL SUCCESS # 05:29:45 INFO - Running post-action listener: _resource_record_post_action 05:29:45 INFO - Running post-run listener: _resource_record_post_run 05:29:46 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 4096; Write bytes: 542326784; Read time: 16; Write time: 467452 05:29:46 INFO - install - Wall time: 17s; CPU: 15.0%; Read bytes: 0; Write bytes: 145866752; Read time: 0; Write time: 213208 05:29:46 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 4096; Write bytes: 288989184; Read time: 16; Write time: 174384 05:29:46 INFO - Running post-run listener: _upload_blobber_files 05:29:46 INFO - Blob upload gear active. 05:29:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:29:46 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:29:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:29:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:29:47 INFO - (blobuploader) - INFO - Open directory for files ... 05:29:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:29:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:29:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:29:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:29:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:29:47 INFO - (blobuploader) - INFO - Done attempting. 05:29:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:29:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:29:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:29:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:29:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:29:48 INFO - (blobuploader) - INFO - Done attempting. 05:29:48 INFO - (blobuploader) - INFO - Iteration through files over. 05:29:48 INFO - Return code: 0 05:29:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:29:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:29:48 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fc3fbc0fbcc815c82d5e961246ead3e77f86315c7cd327ab2132ac07088a050413d32a9f6fd4848cb26ea8e650b0998ba56d625389f9a5d506952bb64d953636", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dae66b85626fbf4595393911749593e77a8af61359f1bc25d61afd5471cc6824fdcba2623c2deceded7711d990d4f41f836c0da7ebe385d9b8ebeef9407d0ea6"} 05:29:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:29:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:29:48 INFO - Contents: 05:29:48 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fc3fbc0fbcc815c82d5e961246ead3e77f86315c7cd327ab2132ac07088a050413d32a9f6fd4848cb26ea8e650b0998ba56d625389f9a5d506952bb64d953636", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dae66b85626fbf4595393911749593e77a8af61359f1bc25d61afd5471cc6824fdcba2623c2deceded7711d990d4f41f836c0da7ebe385d9b8ebeef9407d0ea6"} 05:29:48 INFO - Running post-run listener: copy_logs_to_upload_dir 05:29:48 INFO - Copying logs to upload dir... 05:29:48 INFO - mkdir: /builds/slave/test/build/upload/logs 05:29:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1233.453261 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-04-14 05:29:48.309710) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-14 05:29:48.313561) ========= 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=836cfdc75598d54cb747368e000001a9-1460632537.155154-999909945 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fc3fbc0fbcc815c82d5e961246ead3e77f86315c7cd327ab2132ac07088a050413d32a9f6fd4848cb26ea8e650b0998ba56d625389f9a5d506952bb64d953636", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dae66b85626fbf4595393911749593e77a8af61359f1bc25d61afd5471cc6824fdcba2623c2deceded7711d990d4f41f836c0da7ebe385d9b8ebeef9407d0ea6"} build_url:https://queue.taskcluster.net/v1/task/D1BhgNObSliTbzWWbBPf2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011431 build_url: 'https://queue.taskcluster.net/v1/task/D1BhgNObSliTbzWWbBPf2g/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fc3fbc0fbcc815c82d5e961246ead3e77f86315c7cd327ab2132ac07088a050413d32a9f6fd4848cb26ea8e650b0998ba56d625389f9a5d506952bb64d953636", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/dae66b85626fbf4595393911749593e77a8af61359f1bc25d61afd5471cc6824fdcba2623c2deceded7711d990d4f41f836c0da7ebe385d9b8ebeef9407d0ea6"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-14 05:29:48.339097) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 05:29:48.339440) ========= 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=836cfdc75598d54cb747368e000001a9-1460632537.155154-999909945 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004682 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 05:29:48.423964) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-14 05:29:48.424305) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-14 05:29:48.424632) ========= ========= Total master_lag: 0.18 =========