builder: fx-team_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-031 starttime: 1461686380.93 results: success (0) buildid: 20160426084357 builduid: f8ee86fbc5814290997e348edec02a2b revision: 8c55d8beba757967f6f5f7ff386499e8c6823c11 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-26 08:59:40.928098) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-26 08:59:40.928508) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-26 08:59:40.928795) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-26 08:59:40.945097) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 08:59:40.945367) ========= 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=db0483c52d987ecb441a263b000001ad-1461686160.610556-1271485723 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-04-26 08:59:41-- 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% 51.1M=0s 2016-04-26 08:59:41 (51.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.085674 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 08:59:41.046212) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 08:59:41.046540) ========= 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=db0483c52d987ecb441a263b000001ad-1461686160.610556-1271485723 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020098 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 08:59:41.097139) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 08:59:41.097507) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 8c55d8beba757967f6f5f7ff386499e8c6823c11 --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 8c55d8beba757967f6f5f7ff386499e8c6823c11 --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=db0483c52d987ecb441a263b000001ad-1461686160.610556-1271485723 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-04-26 08:59:41,643 truncating revision to first 12 chars 2016-04-26 08:59:41,643 Setting DEBUG logging. 2016-04-26 08:59:41,643 attempt 1/10 2016-04-26 08:59:41,643 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/8c55d8beba75?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-26 08:59:41,915 unpacking tar archive at: fx-team-8c55d8beba75/testing/mozharness/ program finished with exit code 0 elapsedTime=0.544585 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 08:59:41.657905) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-26 08:59:41.658195) ========= script_repo_revision: 8c55d8beba757967f6f5f7ff386499e8c6823c11 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-26 08:59:41.658541) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-26 08:59:41.658813) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-26 08:59:41.668590) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2016-04-26 08:59:41.668878) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=db0483c52d987ecb441a263b000001ad-1461686160.610556-1271485723 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:59:42 INFO - MultiFileLogger online at 20160426 08:59:42 in /builds/slave/test 08:59:42 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 08:59:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:59:42 INFO - {'append_to_log': False, 08:59:42 INFO - 'base_work_dir': '/builds/slave/test', 08:59:42 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 08:59:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:59:42 INFO - 'branch': 'Fx-Team-Non-PGO', 08:59:42 INFO - 'buildbot_json_path': 'buildprops.json', 08:59:42 INFO - 'config_files': ('talos/linux_config.py',), 08:59:42 INFO - 'default_actions': ('clobber', 08:59:42 INFO - 'read-buildbot-config', 08:59:42 INFO - 'download-and-extract', 08:59:42 INFO - 'populate-webroot', 08:59:42 INFO - 'create-virtualenv', 08:59:42 INFO - 'install', 08:59:42 INFO - 'run-tests'), 08:59:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:59:42 INFO - 'download_minidump_stackwalk': True, 08:59:42 INFO - 'download_symbols': 'ondemand', 08:59:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:59:42 INFO - 'tooltool.py': '/tools/tooltool.py', 08:59:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:59:42 INFO - '/tools/misc-python/virtualenv.py')}, 08:59:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:59:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:59:42 INFO - 'installer_path': 'installer.exe', 08:59:42 INFO - 'log_level': 'info', 08:59:42 INFO - 'log_name': 'talos', 08:59:42 INFO - 'log_to_console': True, 08:59:42 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:59:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:59:42 INFO - 'opt_config_files': (), 08:59:42 INFO - 'pip_index': False, 08:59:42 INFO - 'sps_profile': False, 08:59:42 INFO - 'sps_profile_interval': 0, 08:59:42 INFO - 'suite': 'dromaeojs', 08:59:42 INFO - 'system_bits': '32', 08:59:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:59:42 INFO - 'title': 'talos-linux64-ix-031', 08:59:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:59:42 INFO - 'use_talos_json': True, 08:59:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:59:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:59:42 INFO - 'work_dir': 'build'} 08:59:42 INFO - ##### 08:59:42 INFO - ##### Running clobber step. 08:59:42 INFO - ##### 08:59:42 INFO - Running pre-action listener: _resource_record_pre_action 08:59:42 INFO - Running main action method: clobber 08:59:42 INFO - rmtree: /builds/slave/test/build 08:59:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:59:43 INFO - Running post-action listener: _resource_record_post_action 08:59:43 INFO - ##### 08:59:43 INFO - ##### Running read-buildbot-config step. 08:59:43 INFO - ##### 08:59:43 INFO - Running pre-action listener: _resource_record_pre_action 08:59:43 INFO - Running main action method: read_buildbot_config 08:59:43 INFO - Using buildbot properties: 08:59:43 INFO - { 08:59:43 INFO - "project": "", 08:59:43 INFO - "product": "firefox", 08:59:43 INFO - "script_repo_revision": "production", 08:59:43 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 08:59:43 INFO - "repository": "", 08:59:43 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs", 08:59:43 INFO - "buildid": "20160426084357", 08:59:43 INFO - "pgo_build": "False", 08:59:43 INFO - "basedir": "/builds/slave/test", 08:59:43 INFO - "buildnumber": 2895, 08:59:43 INFO - "slavename": "talos-linux64-ix-031", 08:59:43 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 08:59:43 INFO - "platform": "ubuntu64_hw", 08:59:43 INFO - "scheduler": "tests-fx-team-linux64-talos", 08:59:43 INFO - "branch": "fx-team", 08:59:43 INFO - "repo_path": "integration/fx-team", 08:59:43 INFO - "revision": "8c55d8beba757967f6f5f7ff386499e8c6823c11", 08:59:43 INFO - "stage_platform": "linux64", 08:59:43 INFO - "builduid": "f8ee86fbc5814290997e348edec02a2b", 08:59:43 INFO - "slavebuilddir": "test" 08:59:43 INFO - } 08:59:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/YKlNETTlQYWFAp1Pdi_NLg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 08:59:43 INFO - Running post-action listener: _resource_record_post_action 08:59:43 INFO - ##### 08:59:43 INFO - ##### Running download-and-extract step. 08:59:43 INFO - ##### 08:59:43 INFO - Running pre-action listener: _resource_record_pre_action 08:59:43 INFO - Running main action method: download_and_extract 08:59:43 INFO - mkdir: /builds/slave/test/build/tests 08:59:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 08:59:43 INFO - https://queue.taskcluster.net/v1/task/YKlNETTlQYWFAp1Pdi_NLg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 08:59:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YKlNETTlQYWFAp1Pdi_NLg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 08:59:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YKlNETTlQYWFAp1Pdi_NLg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 08:59:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YKlNETTlQYWFAp1Pdi_NLg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 08:59:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YKlNETTlQYWFAp1Pdi_NLg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 08:59:45 INFO - Downloaded 1361 bytes. 08:59:45 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 08:59:45 INFO - Using the following test package requirements: 08:59:45 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 08:59:45 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:59:45 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 08:59:45 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:59:45 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 08:59:45 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:59:45 INFO - u'jsshell-linux-x86_64.zip'], 08:59:45 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:59:45 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 08:59:45 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 08:59:45 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:59:45 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 08:59:45 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:59:45 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 08:59:45 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:59:45 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 08:59:45 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:59:45 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 08:59:45 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:59:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 08:59:45 INFO - https://queue.taskcluster.net/v1/task/YKlNETTlQYWFAp1Pdi_NLg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:59:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YKlNETTlQYWFAp1Pdi_NLg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 08:59:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YKlNETTlQYWFAp1Pdi_NLg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 08:59:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YKlNETTlQYWFAp1Pdi_NLg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 08:59:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YKlNETTlQYWFAp1Pdi_NLg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:59:50 INFO - Downloaded 22291517 bytes. 08:59:50 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:59:50 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 08:59:51 INFO - Return code: 0 08:59:51 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 08:59: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')]} 08:59:51 INFO - https://queue.taskcluster.net/v1/task/YKlNETTlQYWFAp1Pdi_NLg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:59:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YKlNETTlQYWFAp1Pdi_NLg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 08:59:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YKlNETTlQYWFAp1Pdi_NLg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 08:59:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YKlNETTlQYWFAp1Pdi_NLg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 08:59:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YKlNETTlQYWFAp1Pdi_NLg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:59:59 INFO - Downloaded 22291517 bytes. 08:59:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:59:59 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 09:00:00 INFO - Return code: 0 09:00:00 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')]} 09:00:00 INFO - https://queue.taskcluster.net/v1/task/YKlNETTlQYWFAp1Pdi_NLg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:00:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YKlNETTlQYWFAp1Pdi_NLg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 09:00:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YKlNETTlQYWFAp1Pdi_NLg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 09:00:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YKlNETTlQYWFAp1Pdi_NLg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 09:00:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YKlNETTlQYWFAp1Pdi_NLg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:00:02 INFO - Downloaded 10840145 bytes. 09:00:02 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:00:02 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 09:00:02 INFO - Return code: 0 09:00:02 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')]} 09:00:02 INFO - https://queue.taskcluster.net/v1/task/YKlNETTlQYWFAp1Pdi_NLg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:00:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YKlNETTlQYWFAp1Pdi_NLg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 09:00:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YKlNETTlQYWFAp1Pdi_NLg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 09:00:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YKlNETTlQYWFAp1Pdi_NLg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 09:00:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YKlNETTlQYWFAp1Pdi_NLg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:00:04 INFO - Downloaded 54818399 bytes. 09:00:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YKlNETTlQYWFAp1Pdi_NLg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 09:00:04 INFO - mkdir: /builds/slave/test/properties 09:00:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:00:04 INFO - Writing to file /builds/slave/test/properties/build_url 09:00:04 INFO - Contents: 09:00:04 INFO - build_url:https://queue.taskcluster.net/v1/task/YKlNETTlQYWFAp1Pdi_NLg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 09:00:05 INFO - Running post-action listener: _resource_record_post_action 09:00:05 INFO - Running post-action listener: set_extra_try_arguments 09:00:05 INFO - ##### 09:00:05 INFO - ##### Running populate-webroot step. 09:00:05 INFO - ##### 09:00:05 INFO - Running pre-action listener: _resource_record_pre_action 09:00:05 INFO - Running main action method: populate_webroot 09:00:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:00:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:00:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:00:05 INFO - '--tppagecycles', 09:00:05 INFO - '7'], 09:00:05 INFO - 'tests': ['tsvgm']}}, 09:00:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:00:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:00:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:00:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:00:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:00:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:00:05 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:05 INFO - 'talos_options': ['--disable-e10s'], 09:00:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:00:05 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:00:05 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:05 INFO - 'talos_options': ['--disable-e10s'], 09:00:05 INFO - 'tests': ['damp', 'tps']}, 09:00:05 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:05 INFO - 'tests': ['damp', 'tps']}, 09:00:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:00:05 INFO - 'tests': ['dromaeo_dom']}, 09:00:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:00:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:00:05 INFO - 'tests': ['a11yr', 09:00:05 INFO - 'ts_paint', 09:00:05 INFO - 'tpaint', 09:00:05 INFO - 'sessionrestore', 09:00:05 INFO - 'sessionrestore_no_auto_restore', 09:00:05 INFO - 'tabpaint']}, 09:00:05 INFO - 'other-e10s': {'tests': ['a11yr', 09:00:05 INFO - 'ts_paint', 09:00:05 INFO - 'tpaint', 09:00:05 INFO - 'sessionrestore', 09:00:05 INFO - 'sessionrestore_no_auto_restore', 09:00:05 INFO - 'tabpaint']}, 09:00:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:00:05 INFO - 'ts_paint', 09:00:05 INFO - 'tpaint', 09:00:05 INFO - 'sessionrestore', 09:00:05 INFO - 'sessionrestore_no_auto_restore', 09:00:05 INFO - 'tabpaint']}, 09:00:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:00:05 INFO - 'ts_paint', 09:00:05 INFO - 'tpaint', 09:00:05 INFO - 'sessionrestore', 09:00:05 INFO - 'sessionrestore_no_auto_restore', 09:00:05 INFO - 'tabpaint']}, 09:00:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:00:05 INFO - 'tests': ['a11yr', 09:00:05 INFO - 'ts_paint', 09:00:05 INFO - 'tpaint', 09:00:05 INFO - 'sessionrestore', 09:00:05 INFO - 'sessionrestore_no_auto_restore', 09:00:05 INFO - 'tabpaint']}, 09:00:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:00:05 INFO - 'tests': ['a11yr', 09:00:05 INFO - 'ts_paint', 09:00:05 INFO - 'tpaint', 09:00:05 INFO - 'sessionrestore', 09:00:05 INFO - 'sessionrestore_no_auto_restore', 09:00:05 INFO - 'tabpaint']}, 09:00:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:00:05 INFO - 'tests': ['tsvgx', 09:00:05 INFO - 'tsvgr_opacity', 09:00:05 INFO - 'tart', 09:00:05 INFO - 'tscrollx', 09:00:05 INFO - 'cart']}, 09:00:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:00:05 INFO - 'tsvgr_opacity', 09:00:05 INFO - 'tart', 09:00:05 INFO - 'tscrollx', 09:00:05 INFO - 'cart']}, 09:00:05 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:05 INFO - 'talos_options': ['--disable-e10s'], 09:00:05 INFO - 'tests': ['tp5o']}, 09:00:05 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:05 INFO - 'tests': ['tp5o']}, 09:00:05 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:05 INFO - 'talos_options': ['--disable-e10s', 09:00:05 INFO - '--xperf_path', 09:00:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:00:05 INFO - 'tests': ['tp5n']}, 09:00:05 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:05 INFO - 'talos_options': ['--xperf_path', 09:00:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:00:05 INFO - 'tests': ['tp5n']}}, 09:00:05 INFO - 'talos.zip': {'path': '', 09:00:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:00:05 INFO - Running post-action listener: _resource_record_post_action 09:00:05 INFO - ##### 09:00:05 INFO - ##### Running create-virtualenv step. 09:00:05 INFO - ##### 09:00:05 INFO - Running pre-action listener: _resource_record_pre_action 09:00:05 INFO - Running main action method: create_virtualenv 09:00:05 INFO - Creating virtualenv /builds/slave/test/build/venv 09:00:05 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 09:00:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:00:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:00:05 INFO - Using real prefix '/usr' 09:00:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:00:06 INFO - Installing distribute.............................................................................................................................................................................................done. 09:00:07 INFO - Installing pip.................done. 09:00:07 INFO - Return code: 0 09:00:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:00:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:00:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:00:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:07 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 0x7f7e70efbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd775a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe06410>, '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 0xe07310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe077a0>, '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': 'db0483c52d987ecb441a263b000001ad-1461686160.610556-1271485723', '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 09:00:07 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 09:00:07 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 09:00:07 INFO - Using env: {'DISPLAY': ':0', 09:00:07 INFO - 'HOME': '/home/cltbld', 09:00:07 INFO - 'LANG': 'en_US.UTF-8', 09:00:07 INFO - 'LANGUAGE': 'en_US:en', 09:00:07 INFO - 'LOGNAME': 'cltbld', 09:00:07 INFO - 'MAIL': '/var/mail/cltbld', 09:00:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:07 INFO - 'MOZ_NO_REMOTE': '1', 09:00:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:07 INFO - 'NO_EM_RESTART': '1', 09:00:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:07 INFO - 'PWD': '/builds/slave/test', 09:00:07 INFO - 'SHELL': '/bin/bash', 09:00:07 INFO - 'SHLVL': '1', 09:00:07 INFO - 'TERM': 'linux', 09:00:07 INFO - 'TMOUT': '86400', 09:00:07 INFO - 'USER': 'cltbld', 09:00:07 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1461686160.610556-1271485723', 09:00:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:07 INFO - '_': '/tools/buildbot/bin/python'} 09:00:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:08 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 09:00:08 INFO - Cleaning up... 09:00:08 INFO - Return code: 0 09:00:08 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:00:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:00:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f7e70efbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd775a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe06410>, '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 0xe07310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe077a0>, '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': 'db0483c52d987ecb441a263b000001ad-1461686160.610556-1271485723', '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 09:00:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 09:00:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 09:00:08 INFO - Using env: {'DISPLAY': ':0', 09:00:08 INFO - 'HOME': '/home/cltbld', 09:00:08 INFO - 'LANG': 'en_US.UTF-8', 09:00:08 INFO - 'LANGUAGE': 'en_US:en', 09:00:08 INFO - 'LOGNAME': 'cltbld', 09:00:08 INFO - 'MAIL': '/var/mail/cltbld', 09:00:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:08 INFO - 'MOZ_NO_REMOTE': '1', 09:00:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:08 INFO - 'NO_EM_RESTART': '1', 09:00:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:08 INFO - 'PWD': '/builds/slave/test', 09:00:08 INFO - 'SHELL': '/bin/bash', 09:00:08 INFO - 'SHLVL': '1', 09:00:08 INFO - 'TERM': 'linux', 09:00:08 INFO - 'TMOUT': '86400', 09:00:08 INFO - 'USER': 'cltbld', 09:00:08 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1461686160.610556-1271485723', 09:00:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:08 INFO - '_': '/tools/buildbot/bin/python'} 09:00:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:08 INFO - Downloading/unpacking psutil>=0.7.1 09:00:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:00:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:00:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:00:10 INFO - Installing collected packages: psutil 09:00:10 INFO - Running setup.py install for psutil 09:00:10 INFO - building 'psutil._psutil_linux' extension 09:00:10 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 09:00:10 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 09:00:10 INFO - building 'psutil._psutil_posix' extension 09:00:10 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 09:00:10 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 09:00:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:00:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:00:10 INFO - Successfully installed psutil 09:00:10 INFO - Cleaning up... 09:00:10 INFO - Return code: 0 09:00:10 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:00: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')]} 09:00:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00: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')]} 09:00:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:10 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 0x7f7e70efbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd775a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe06410>, '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 0xe07310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe077a0>, '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': 'db0483c52d987ecb441a263b000001ad-1461686160.610556-1271485723', '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 09:00:10 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 09:00:10 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 09:00:10 INFO - Using env: {'DISPLAY': ':0', 09:00:10 INFO - 'HOME': '/home/cltbld', 09:00:10 INFO - 'LANG': 'en_US.UTF-8', 09:00:10 INFO - 'LANGUAGE': 'en_US:en', 09:00:10 INFO - 'LOGNAME': 'cltbld', 09:00:10 INFO - 'MAIL': '/var/mail/cltbld', 09:00:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:10 INFO - 'MOZ_NO_REMOTE': '1', 09:00:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:10 INFO - 'NO_EM_RESTART': '1', 09:00:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:10 INFO - 'PWD': '/builds/slave/test', 09:00:10 INFO - 'SHELL': '/bin/bash', 09:00:10 INFO - 'SHLVL': '1', 09:00:10 INFO - 'TERM': 'linux', 09:00:10 INFO - 'TMOUT': '86400', 09:00:10 INFO - 'USER': 'cltbld', 09:00:10 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1461686160.610556-1271485723', 09:00:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:10 INFO - '_': '/tools/buildbot/bin/python'} 09:00:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:11 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:00:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:13 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:00:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:00:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:00:13 INFO - Installing collected packages: mozsystemmonitor 09:00:13 INFO - Running setup.py install for mozsystemmonitor 09:00:13 INFO - Successfully installed mozsystemmonitor 09:00:13 INFO - Cleaning up... 09:00:13 INFO - Return code: 0 09:00:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:00:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:00:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:00:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:13 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 0x7f7e70efbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd775a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe06410>, '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 0xe07310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe077a0>, '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': 'db0483c52d987ecb441a263b000001ad-1461686160.610556-1271485723', '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 09:00:13 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 09:00:13 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 09:00:13 INFO - Using env: {'DISPLAY': ':0', 09:00:13 INFO - 'HOME': '/home/cltbld', 09:00:13 INFO - 'LANG': 'en_US.UTF-8', 09:00:13 INFO - 'LANGUAGE': 'en_US:en', 09:00:13 INFO - 'LOGNAME': 'cltbld', 09:00:13 INFO - 'MAIL': '/var/mail/cltbld', 09:00:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:13 INFO - 'MOZ_NO_REMOTE': '1', 09:00:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:13 INFO - 'NO_EM_RESTART': '1', 09:00:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:13 INFO - 'PWD': '/builds/slave/test', 09:00:13 INFO - 'SHELL': '/bin/bash', 09:00:13 INFO - 'SHLVL': '1', 09:00:13 INFO - 'TERM': 'linux', 09:00:13 INFO - 'TMOUT': '86400', 09:00:13 INFO - 'USER': 'cltbld', 09:00:13 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1461686160.610556-1271485723', 09:00:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:13 INFO - '_': '/tools/buildbot/bin/python'} 09:00:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:13 INFO - Downloading/unpacking blobuploader==1.2.4 09:00:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:15 INFO - Downloading blobuploader-1.2.4.tar.gz 09:00:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:00:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:00:15 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 09:00:15 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 09:00:15 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 09:00:15 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 09:00:15 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 09:00:15 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 09:00:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:00:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:00:15 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 09:00:15 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 09:00:15 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 09:00:15 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 09:00:15 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 09:00:15 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 09:00:16 INFO - Downloading docopt-0.6.1.tar.gz 09:00:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:00:16 INFO - Installing collected packages: blobuploader, requests, docopt 09:00:16 INFO - Running setup.py install for blobuploader 09:00:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:00:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:00:16 INFO - Running setup.py install for requests 09:00:16 INFO - Running setup.py install for docopt 09:00:16 INFO - Successfully installed blobuploader requests docopt 09:00:16 INFO - Cleaning up... 09:00:16 INFO - Return code: 0 09:00:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:00:16 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')]} 09:00:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:16 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')]} 09:00:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:16 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 0x7f7e70efbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd775a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe06410>, '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 0xe07310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe077a0>, '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': 'db0483c52d987ecb441a263b000001ad-1461686160.610556-1271485723', '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 09:00:16 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 09:00:16 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 09:00:16 INFO - Using env: {'DISPLAY': ':0', 09:00:16 INFO - 'HOME': '/home/cltbld', 09:00:16 INFO - 'LANG': 'en_US.UTF-8', 09:00:16 INFO - 'LANGUAGE': 'en_US:en', 09:00:16 INFO - 'LOGNAME': 'cltbld', 09:00:16 INFO - 'MAIL': '/var/mail/cltbld', 09:00:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:16 INFO - 'MOZ_NO_REMOTE': '1', 09:00:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:16 INFO - 'NO_EM_RESTART': '1', 09:00:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:16 INFO - 'PWD': '/builds/slave/test', 09:00:16 INFO - 'SHELL': '/bin/bash', 09:00:16 INFO - 'SHLVL': '1', 09:00:16 INFO - 'TERM': 'linux', 09:00:16 INFO - 'TMOUT': '86400', 09:00:16 INFO - 'USER': 'cltbld', 09:00:16 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1461686160.610556-1271485723', 09:00:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:16 INFO - '_': '/tools/buildbot/bin/python'} 09:00:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:00:16 INFO - Running setup.py (path:/tmp/pip-cSLp7K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:00:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:00:16 INFO - Running setup.py (path:/tmp/pip-Jnkttf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:00:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:00:17 INFO - Running setup.py (path:/tmp/pip-cMvzeB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:00:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:00:17 INFO - Running setup.py (path:/tmp/pip-Fl_oi1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:00:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:00:17 INFO - Running setup.py (path:/tmp/pip-rd8uKK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:00:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:00:17 INFO - Running setup.py (path:/tmp/pip-JPjTP7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:00:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:00:17 INFO - Running setup.py (path:/tmp/pip-izYZVo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:00:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:00:17 INFO - Running setup.py (path:/tmp/pip-Zzpd55-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:00:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:00:17 INFO - Running setup.py (path:/tmp/pip-ekEa5f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:00:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:00:17 INFO - Running setup.py (path:/tmp/pip-Mn3rXs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:00:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:00:17 INFO - Running setup.py (path:/tmp/pip-nmdB1J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:00:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:00:17 INFO - Running setup.py (path:/tmp/pip-kNlsni-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:00:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:00:17 INFO - Running setup.py (path:/tmp/pip-jTLGS6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:00:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:00:17 INFO - Running setup.py (path:/tmp/pip-MneYxR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:00:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:17 INFO - Running setup.py (path:/tmp/pip-uUAR6j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:00:18 INFO - Running setup.py (path:/tmp/pip-JfsVA1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:00:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:00:18 INFO - Running setup.py (path:/tmp/pip-5GMbfk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:00:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:00:18 INFO - Running setup.py install for manifestparser 09:00:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:00:18 INFO - Running setup.py install for mozcrash 09:00:18 INFO - Running setup.py install for mozdebug 09:00:18 INFO - Running setup.py install for mozdevice 09:00:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:00:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:00:18 INFO - Running setup.py install for mozfile 09:00:18 INFO - Running setup.py install for mozhttpd 09:00:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:00:18 INFO - Running setup.py install for mozinfo 09:00:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:00:18 INFO - Running setup.py install for mozInstall 09:00:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:00:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:00:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:00:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:00:18 INFO - Running setup.py install for mozleak 09:00:19 INFO - Running setup.py install for mozlog 09:00:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:00:19 INFO - Running setup.py install for moznetwork 09:00:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:00:19 INFO - Running setup.py install for mozprocess 09:00:19 INFO - Running setup.py install for mozprofile 09:00:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:00:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:00:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:00:19 INFO - Running setup.py install for mozrunner 09:00:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:00:19 INFO - Running setup.py install for mozscreenshot 09:00:19 INFO - Running setup.py install for moztest 09:00:19 INFO - Running setup.py install for mozversion 09:00:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:00:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:00:19 INFO - Cleaning up... 09:00:19 INFO - Return code: 0 09:00:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:00: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')]} 09:00:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00: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')]} 09:00:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:19 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 0x7f7e70efbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd775a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe06410>, '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 0xe07310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe077a0>, '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': 'db0483c52d987ecb441a263b000001ad-1461686160.610556-1271485723', '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 09:00:19 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 09:00:19 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 09:00:19 INFO - Using env: {'DISPLAY': ':0', 09:00:19 INFO - 'HOME': '/home/cltbld', 09:00:19 INFO - 'LANG': 'en_US.UTF-8', 09:00:19 INFO - 'LANGUAGE': 'en_US:en', 09:00:19 INFO - 'LOGNAME': 'cltbld', 09:00:19 INFO - 'MAIL': '/var/mail/cltbld', 09:00:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:19 INFO - 'MOZ_NO_REMOTE': '1', 09:00:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:19 INFO - 'NO_EM_RESTART': '1', 09:00:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:19 INFO - 'PWD': '/builds/slave/test', 09:00:19 INFO - 'SHELL': '/bin/bash', 09:00:19 INFO - 'SHLVL': '1', 09:00:19 INFO - 'TERM': 'linux', 09:00:19 INFO - 'TMOUT': '86400', 09:00:19 INFO - 'USER': 'cltbld', 09:00:19 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1461686160.610556-1271485723', 09:00:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:19 INFO - '_': '/tools/buildbot/bin/python'} 09:00:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:00:20 INFO - Running setup.py (path:/tmp/pip-4pSP6q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:00:20 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)) 09:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:00:20 INFO - Running setup.py (path:/tmp/pip-k94lx9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:00:20 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)) 09:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:00:20 INFO - Running setup.py (path:/tmp/pip-iuIe_z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:00:20 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)) 09:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:00:20 INFO - Running setup.py (path:/tmp/pip-r8u8MW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:00:20 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)) 09:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:00:20 INFO - Running setup.py (path:/tmp/pip-X3HgRP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:00:20 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)) 09:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:00:20 INFO - Running setup.py (path:/tmp/pip-rTKwRR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:00:20 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)) 09:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:00:20 INFO - Running setup.py (path:/tmp/pip-GQv2yP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:00:20 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)) 09:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:00:20 INFO - Running setup.py (path:/tmp/pip-IfIU1E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:00:20 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)) 09:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:00:20 INFO - Running setup.py (path:/tmp/pip-hHxlpL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:00:20 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)) 09:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:00:20 INFO - Running setup.py (path:/tmp/pip-qey2KU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:00:20 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)) 09:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:00:20 INFO - Running setup.py (path:/tmp/pip-Enttud-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:00:20 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)) 09:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:00:20 INFO - Running setup.py (path:/tmp/pip-dANuBM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:00:20 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)) 09:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:00:21 INFO - Running setup.py (path:/tmp/pip-8pHeH8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:00:21 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)) 09:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:00:21 INFO - Running setup.py (path:/tmp/pip-C3BYuI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:00:21 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)) 09:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:21 INFO - Running setup.py (path:/tmp/pip-xEBnWI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:21 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)) 09:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:00:21 INFO - Running setup.py (path:/tmp/pip-LXxfdP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:00:21 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)) 09:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:00:21 INFO - Running setup.py (path:/tmp/pip-fcKGDO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:00:21 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)) 09:00:21 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)) 09:00:21 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)) 09:00:21 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)) 09:00:21 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)) 09:00:21 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)) 09:00:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:00:21 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 09:00:21 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 09:00:21 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 09:00:21 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 09:00:21 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 09:00:21 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 09:00:23 INFO - Downloading blessings-1.6.tar.gz 09:00:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:00:23 INFO - Installing collected packages: blessings 09:00:23 INFO - Running setup.py install for blessings 09:00:23 INFO - Successfully installed blessings 09:00:23 INFO - Cleaning up... 09:00:23 INFO - Return code: 0 09:00:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:00:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:00:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:00:23 INFO - Reading from file tmpfile_stdout 09:00:23 INFO - Current package versions: 09:00:23 INFO - argparse == 1.2.1 09:00:23 INFO - blessings == 1.6 09:00:23 INFO - blobuploader == 1.2.4 09:00:23 INFO - docopt == 0.6.1 09:00:23 INFO - manifestparser == 1.1 09:00:23 INFO - mozInstall == 1.12 09:00:23 INFO - mozcrash == 0.17 09:00:23 INFO - mozdebug == 0.1 09:00:23 INFO - mozdevice == 0.48 09:00:23 INFO - mozfile == 1.2 09:00:23 INFO - mozhttpd == 0.7 09:00:23 INFO - mozinfo == 0.9 09:00:23 INFO - mozleak == 0.1 09:00:23 INFO - mozlog == 3.1 09:00:23 INFO - moznetwork == 0.27 09:00:23 INFO - mozprocess == 0.22 09:00:23 INFO - mozprofile == 0.28 09:00:23 INFO - mozrunner == 6.11 09:00:23 INFO - mozscreenshot == 0.1 09:00:23 INFO - mozsystemmonitor == 0.0 09:00:23 INFO - moztest == 0.7 09:00:23 INFO - mozversion == 1.4 09:00:23 INFO - psutil == 3.1.1 09:00:23 INFO - requests == 1.2.3 09:00:23 INFO - wsgiref == 0.1.2 09:00:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:00:23 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')]} 09:00:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:23 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')]} 09:00:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:23 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 0x7f7e70efbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd775a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe06410>, '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 0xe07310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe077a0>, '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': 'db0483c52d987ecb441a263b000001ad-1461686160.610556-1271485723', '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 09:00:23 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 09:00:23 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 09:00:23 INFO - Using env: {'DISPLAY': ':0', 09:00:23 INFO - 'HOME': '/home/cltbld', 09:00:23 INFO - 'LANG': 'en_US.UTF-8', 09:00:23 INFO - 'LANGUAGE': 'en_US:en', 09:00:23 INFO - 'LOGNAME': 'cltbld', 09:00:23 INFO - 'MAIL': '/var/mail/cltbld', 09:00:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:23 INFO - 'MOZ_NO_REMOTE': '1', 09:00:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:23 INFO - 'NO_EM_RESTART': '1', 09:00:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:23 INFO - 'PWD': '/builds/slave/test', 09:00:23 INFO - 'SHELL': '/bin/bash', 09:00:23 INFO - 'SHLVL': '1', 09:00:23 INFO - 'TERM': 'linux', 09:00:23 INFO - 'TMOUT': '86400', 09:00:23 INFO - 'USER': 'cltbld', 09:00:23 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1461686160.610556-1271485723', 09:00:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:23 INFO - '_': '/tools/buildbot/bin/python'} 09:00:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:23 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)) 09:00:23 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)) 09:00:23 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)) 09:00:23 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)) 09:00:23 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)) 09:00:23 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)) 09:00:23 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)) 09:00:23 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)) 09:00:23 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)) 09:00:23 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)) 09:00:23 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)) 09:00:23 INFO - Cleaning up... 09:00:23 INFO - Return code: 0 09:00:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:00:23 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')]} 09:00:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:23 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')]} 09:00:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:23 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 0x7f7e70efbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd775a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe06410>, '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 0xe07310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe077a0>, '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': 'db0483c52d987ecb441a263b000001ad-1461686160.610556-1271485723', '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 09:00:23 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 09:00:23 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 09:00:23 INFO - Using env: {'DISPLAY': ':0', 09:00:23 INFO - 'HOME': '/home/cltbld', 09:00:23 INFO - 'LANG': 'en_US.UTF-8', 09:00:23 INFO - 'LANGUAGE': 'en_US:en', 09:00:23 INFO - 'LOGNAME': 'cltbld', 09:00:23 INFO - 'MAIL': '/var/mail/cltbld', 09:00:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:23 INFO - 'MOZ_NO_REMOTE': '1', 09:00:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:23 INFO - 'NO_EM_RESTART': '1', 09:00:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:23 INFO - 'PWD': '/builds/slave/test', 09:00:23 INFO - 'SHELL': '/bin/bash', 09:00:23 INFO - 'SHLVL': '1', 09:00:23 INFO - 'TERM': 'linux', 09:00:23 INFO - 'TMOUT': '86400', 09:00:23 INFO - 'USER': 'cltbld', 09:00:23 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1461686160.610556-1271485723', 09:00:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:23 INFO - '_': '/tools/buildbot/bin/python'} 09:00:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:24 INFO - Downloading/unpacking jsonschema 09:00:24 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 09:00:24 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 09:00:24 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 09:00:24 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 09:00:24 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 09:00:24 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 09:00:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:00:26 INFO - Downloading/unpacking functools32 (from jsonschema) 09:00:26 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 09:00:26 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 09:00:26 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 09:00:26 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 09:00:26 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 09:00:26 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 09:00:26 INFO - Downloading functools32-3.2.3-2.tar.gz 09:00:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:00:26 INFO - warning: no files found matching '*.txt' 09:00:26 INFO - no previously-included directories found matching 'build' 09:00:26 INFO - no previously-included directories found matching 'dist' 09:00:26 INFO - no previously-included directories found matching '.git*' 09:00:26 INFO - Installing collected packages: jsonschema, functools32 09:00:26 INFO - Running setup.py install for functools32 09:00:26 INFO - warning: no files found matching '*.txt' 09:00:26 INFO - no previously-included directories found matching 'build' 09:00:26 INFO - no previously-included directories found matching 'dist' 09:00:26 INFO - no previously-included directories found matching '.git*' 09:00:26 INFO - Successfully installed jsonschema functools32 09:00:26 INFO - Cleaning up... 09:00:26 INFO - Return code: 0 09:00:26 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:00:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:00:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:00:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:26 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 0x7f7e70efbf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xcac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xd775a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe06410>, '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 0xe07310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe077a0>, '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': 'db0483c52d987ecb441a263b000001ad-1461686160.610556-1271485723', '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 09:00:26 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 09:00:26 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 09:00:26 INFO - Using env: {'DISPLAY': ':0', 09:00:26 INFO - 'HOME': '/home/cltbld', 09:00:26 INFO - 'LANG': 'en_US.UTF-8', 09:00:26 INFO - 'LANGUAGE': 'en_US:en', 09:00:26 INFO - 'LOGNAME': 'cltbld', 09:00:26 INFO - 'MAIL': '/var/mail/cltbld', 09:00:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:26 INFO - 'MOZ_NO_REMOTE': '1', 09:00:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:26 INFO - 'NO_EM_RESTART': '1', 09:00:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:26 INFO - 'PWD': '/builds/slave/test', 09:00:26 INFO - 'SHELL': '/bin/bash', 09:00:26 INFO - 'SHLVL': '1', 09:00:26 INFO - 'TERM': 'linux', 09:00:26 INFO - 'TMOUT': '86400', 09:00:26 INFO - 'USER': 'cltbld', 09:00:26 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1461686160.610556-1271485723', 09:00:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:26 INFO - '_': '/tools/buildbot/bin/python'} 09:00:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:26 INFO - Downloading/unpacking flake8 09:00:26 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 09:00:26 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 09:00:26 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 09:00:26 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 09:00:26 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 09:00:26 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 09:00:28 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:00:28 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:00:28 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 09:00:28 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 09:00:28 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 09:00:28 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 09:00:28 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 09:00:28 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 09:00:28 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:00:28 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:00:28 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 09:00:28 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 09:00:28 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 09:00:28 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 09:00:28 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 09:00:28 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 09:00:29 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:00:29 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 09:00:29 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 09:00:29 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 09:00:29 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 09:00:29 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 09:00:29 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 09:00:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:00:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:00:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:00:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:00:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:00:29 INFO - no previously-included directories found matching 'docs/_build' 09:00:29 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:00:29 INFO - Running setup.py install for pep8 09:00:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:00:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:00:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:00:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:00:29 INFO - no previously-included directories found matching 'docs/_build' 09:00:29 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:00:29 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:00:29 INFO - Cleaning up... 09:00:29 INFO - Return code: 0 09:00:29 INFO - Running post-action listener: _resource_record_post_action 09:00:29 INFO - Running post-action listener: _start_resource_monitoring 09:00:29 INFO - Starting resource monitoring. 09:00:29 INFO - ##### 09:00:29 INFO - ##### Running install step. 09:00:29 INFO - ##### 09:00:29 INFO - Running pre-action listener: _resource_record_pre_action 09:00:29 INFO - Running main action method: install 09:00:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:00:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:00:29 INFO - Reading from file tmpfile_stdout 09:00:29 INFO - Detecting whether we're running mozinstall >=1.0... 09:00:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:00:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:00:30 INFO - Reading from file tmpfile_stdout 09:00:30 INFO - Output received: 09:00:30 INFO - Usage: mozinstall [options] installer 09:00:30 INFO - Options: 09:00:30 INFO - -h, --help show this help message and exit 09:00:30 INFO - -d DEST, --destination=DEST 09:00:30 INFO - Directory to install application into. [default: 09:00:30 INFO - "/builds/slave/test"] 09:00:30 INFO - --app=APP Application being installed. [default: firefox] 09:00:30 INFO - mkdir: /builds/slave/test/build/application 09:00:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:00:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:00:45 INFO - Reading from file tmpfile_stdout 09:00:45 INFO - Output received: 09:00:45 INFO - /builds/slave/test/build/application/firefox/firefox 09:00:45 INFO - Running post-action listener: _resource_record_post_action 09:00:45 INFO - ##### 09:00:45 INFO - ##### Running run-tests step. 09:00:45 INFO - ##### 09:00:45 INFO - Running pre-action listener: _resource_record_pre_action 09:00:45 INFO - Running main action method: run_tests 09:00:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:00:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:00:45 INFO - Python 2.7.3 09:00:45 INFO - Return code: 0 09:00:45 INFO - grabbing minidump binary from tooltool 09:00:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 09:00:45 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 0xe06410>, '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 0xe07310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe077a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:00:45 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 09:00:45 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 09:00:45 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:00:45 INFO - Return code: 0 09:00:45 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:00:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:00:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:00:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:00:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:00:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:00:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:00:46 INFO - Return code: 0 09:00:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YKlNETTlQYWFAp1Pdi_NLg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--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 09:00:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/YKlNETTlQYWFAp1Pdi_NLg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-031 --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 09:00:46 INFO - Using env: {'DISPLAY': ':0', 09:00:46 INFO - 'HOME': '/home/cltbld', 09:00:46 INFO - 'LANG': 'en_US.UTF-8', 09:00:46 INFO - 'LANGUAGE': 'en_US:en', 09:00:46 INFO - 'LOGNAME': 'cltbld', 09:00:46 INFO - 'MAIL': '/var/mail/cltbld', 09:00:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:00:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:00:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:46 INFO - 'MOZ_NO_REMOTE': '1', 09:00:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:00:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:46 INFO - 'NO_EM_RESTART': '1', 09:00:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:46 INFO - 'PWD': '/builds/slave/test', 09:00:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:00:46 INFO - 'SHELL': '/bin/bash', 09:00:46 INFO - 'SHLVL': '1', 09:00:46 INFO - 'TERM': 'linux', 09:00:46 INFO - 'TMOUT': '86400', 09:00:46 INFO - 'USER': 'cltbld', 09:00:46 INFO - 'XDG_SESSION_COOKIE': 'db0483c52d987ecb441a263b000001ad-1461686160.610556-1271485723', 09:00:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:46 INFO - '_': '/tools/buildbot/bin/python'} 09:00:46 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YKlNETTlQYWFAp1Pdi_NLg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-031', '--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 09:00:47 INFO - mozversion application_buildid: 20160426084357 09:00:47 INFO - mozversion application_changeset: 8c55d8beba757967f6f5f7ff386499e8c6823c11 09:00:47 INFO - mozversion application_display_name: Nightly 09:00:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:00:47 INFO - mozversion application_name: Firefox 09:00:47 INFO - mozversion application_remotingname: firefox 09:00:47 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:00:47 INFO - mozversion application_vendor: Mozilla 09:00:47 INFO - mozversion application_version: 49.0a1 09:00:47 INFO - mozversion platform_buildid: 20160426084357 09:00:47 INFO - mozversion platform_changeset: 8c55d8beba757967f6f5f7ff386499e8c6823c11 09:00:47 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:00:47 INFO - mozversion platform_version: 49.0a1 09:00:47 INFO - using testdate: 1461686447 09:00:47 INFO - actual date: 1461686447 09:00:47 INFO - starting webserver on 'localhost:45200' 09:00:47 INFO - SUITE-START | Running 2 tests 09:00:47 INFO - TEST-START | dromaeo_css 09:00:47 INFO - operating with platform_type : linux_ 09:00:47 INFO - Initialising browser for dromaeo_css test... 09:00:47 INFO - TEST-INFO | started process 24627 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9kHKwG/profile http://localhost:45200/getInfo.html) 09:00:51 INFO - PROCESS | 24627 | __metrics Screen width/height:1600/1200 09:00:51 INFO - PROCESS | 24627 | colorDepth:24 09:00:51 INFO - PROCESS | 24627 | Browser inner width/height: 1024/697 09:00:51 INFO - PROCESS | 24627 | __metrics 09:00:52 ERROR - PROCESS | 24627 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:00:53 INFO - TEST-INFO | 24627: exit 0 09:00:53 INFO - Browser initialized. 09:00:53 INFO - Running cycle 1/1 for dromaeo_css test... 09:00:53 INFO - TEST-INFO | started process 24707 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9kHKwG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:01:05 INFO - PROCESS | 24707 | RSS: Main: 156188672 09:01:05 INFO - PROCESS | 24707 | 09:01:55 INFO - PROCESS | 24707 | 1461686515827 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] 09:04:07 INFO - PROCESS | 24707 | Cycle 1(1): loaded http://localhost:45200/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45200/tests/dromaeo/cssquery-ext.html) 09:04:08 INFO - PROCESS | 24707 | RSS: Main: 248582144 09:04:08 INFO - PROCESS | 24707 | 09:07:10 INFO - PROCESS | 24707 | Cycle 1(1): loaded http://localhost:45200/tests/dromaeo/cssquery-ext.html (next: http://localhost:45200/tests/dromaeo/cssquery-jquery.html) 09:07:11 INFO - PROCESS | 24707 | RSS: Main: 238858240 09:07:11 INFO - PROCESS | 24707 | 09:10:13 INFO - PROCESS | 24707 | Cycle 1(1): loaded http://localhost:45200/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45200/tests/dromaeo/cssquery-mootools.html) 09:10:14 INFO - PROCESS | 24707 | RSS: Main: 230318080 09:10:14 INFO - PROCESS | 24707 | 09:13:16 INFO - PROCESS | 24707 | Cycle 1(1): loaded http://localhost:45200/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45200/tests/dromaeo/cssquery-prototype.html) 09:13:16 INFO - PROCESS | 24707 | RSS: Main: 231944192 09:13:16 INFO - PROCESS | 24707 | 09:16:19 INFO - PROCESS | 24707 | Cycle 1(1): loaded http://localhost:45200/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45200/tests/dromaeo/cssquery-yui.html) 09:16:20 INFO - PROCESS | 24707 | RSS: Main: 209801216 09:16:20 INFO - PROCESS | 24707 | 09:19:23 INFO - PROCESS | 24707 | Cycle 1(1): loaded http://localhost:45200/tests/dromaeo/cssquery-yui.html (next: http://localhost:45200/tests/dromaeo/cssquery-dojo.html) 09:19:23 INFO - PROCESS | 24707 | RSS: Main: 277807104 09:19:23 INFO - PROCESS | 24707 | 09:19:23 INFO - PROCESS | 24707 | __start_tp_report 09:19:23 INFO - PROCESS | 24707 | _x_x_mozilla_page_load 09:19:23 INFO - PROCESS | 24707 | _x_x_mozilla_page_load_details 09:19:23 INFO - PROCESS | 24707 | |i|pagename|runs| 09:19:23 INFO - PROCESS | 24707 | |0;dojo.html;2558.6545816314797;2633.9604905926426;2634.9604755928676;2652.8408295502263;2653.2570880153553;4400.229959757045;4479.641628669703;4492.4833644130895;4513.277875539914;4518.70628409154;4488.147252022121;4517.932231016537;4543.5002149763495;4574.199515084863;4585.747783871886;4126.73176243544;4128.071183983609;4142.647874930634;4208.158368326332;4328.242557552431;4280.892977675552;4283.3146696528565;4305.870823875289;4309.525952145262;4336.653067754588;4081.9183616327805;4154.813033413504;4157.10622216225;4205.621494065518;4226.4716910386205;4146;4148.688848336356;4151.83392664293;4154.896127596834;4155.709100362976;4082.306007978621;4124.91750164998;4128.422020917074;4160.084781348099;4192.266353388175;4393.187260356813;4426.114777044604;4430.091831174602;4436.800343984529;4450.221211288044;4799.6400269979895;4804.711717296974;4806.663533552653;4810.254410566367;4845.6123510119105;4698.600618947394;4790.520947905216;4832.299316599108;4833.613310935117;4879.121758083544;4480.395146655225;4508.706934049276;4512.390827238347;4516.096780643885;4560.3843481129625;4839.2983017462975;4936.358273424432;4941.085899108676;4943.802247910044;4985.177445721415;4692.835750748707;4768.427788665383;4786.042791441726;4786.928196077061;4787.688800227974;4793.49668284832;4827.396575428072;4831.4685384607665;4834.154023045953;4876.29293752411;4779.713217206979;4779.832705855279;4786.6888652238185;4790.089882922303;4840.733759643183;4650;4674.135284972291;4714.198586240347;4743.739094349844;4770.76146192689;4185.016521117535;4192.664586833046;4195.727277726938;4236.809343579546;4238.321868501025;3590.9640903591153;3680.245549662313;3687.2994131115;3688.2254726507736;3713.4801127842125;4138.544760076389;4147.730397524151;4182.079942412664;4215.346621273708;4216.746995180299;4776.355192049098;4779.856604301876;4804.615630749532;4812.927806082912;4852.4662287148385;4035.9394609080887;4165.841486117143;4167.6874234432535;4169.91660166795;4191.476065491814;4370.431843860278;4409.492908315585;4414.448193975753;4449.154660614473;4464.508904020555;4536.274196128604;4537.705049171859;4551.726896386227;4625.676202665848;4631.8378856739855;4764.857054288377;4804.783784729626;4812.855614331435;4820.831270905502;4826.251930950708;4590;4595.218812801765;4599.402077729874;4606.286025666027;4648.93026604608;4774.7135171889795;4779.187538118459;4782.497837726988;4794.7842347093765;4831.178699621143;4613.5847773700525;4722.527747225387;4835.105505481497;4839.249916262985;4848.95151048485;4611.21609326422;4636.095961287517;4645.372874661877;4688.765561721969;4693.342931989592;4626.467956185082;4627.5141110184295;4631.235846085358;4635.907281854276;4678.461976872703;4553.453585569753;4558.58972692446;4611.861644150681;4697.436307642968;4699.953000469952;4717.1744944633865;4719.2921061839625;4740.075685241345;4740.170470167775;4779.737114458738;4644.117617652771;4644.2801365788355;4651.348811166372;4663.906721865475;4698.389209402756 09:19:23 INFO - PROCESS | 24707 | |1;ext.html;562.0240824832512;583.3000399520575;584.9883002339953;587.4419301663415;603.3935894426102;23717.525649487;28527.857360713195;28964.13107606775;29036.56445153319;29626.407471850605;31207.68792312076;31278.843605781938;31331.060068197934;31449.371012579733;31732;7151.14186297645;7400.296971787671;7403.555786652818;7404.1855395906405;7409.925900741005;7397.556146631219;7543.434242431812;7547.622618869062;7552.811179720496;7562.810929726746;7494.475386722932;7602.277783610548;7602.657880395368;7609.0869095708595;7618.123915749677;7490.5131166474;7563.697452101909;7593.240675932418;7598.544087354777;7600.923990760077;7386.224446433101;7506.962465187694;7508.061492313461;7509.924900750977;7514.473986820924;30658.84670576655;31104.533431998538;31134.688653113404;31212.37575248505;31226.063218103493;2822.8447435391045;3050.7254347108646;3134.2477805326557;3210.807351558914;3424.280901010766;24407.63388549173;24786.25641230766;25099.24702258917;25334.873325633253;25815.225543233733;3434.1414646338417;3443.139215196201;3458.014810083617;3497.3704733148024;3531.5762108547137;193494.9999999986;193897;194350;194642.99999999857;194905;41893.79053104715;42256.788716056224;42421.15157696829;42457;42461.57538424576;39499.2100158001;39966.60033399687;40047.39928901069;40280.395794063115;40785.79607101945;12786.10497265201;13004.154729942524;13085.149465284727;13091.607251782461;13110.410031548603;10201.540930658139;10300.072993430627;10304.536295866705;10304.845427318596;10310.123639490726;13452.798208026887;13464.394102265422;13501.324933753272;14161;14505.129692218501;5686.147077938343;5745.569082318842;5773.942260577424;5779.8555036124435;5842.2405087338375;753.7927070055669;822.8930239068883;831.2394159344133;888.4180861535705;925.9629614815467;23898.52202955931;24572.754272457405;24728.876355618107;24737.876310618332;24939;880.0935036911991;883.8099808541194;883.8320719063357;897.2911399993932;936.424099179;12861.228326300452;12943.676408089685;13099.279539625411;13113.344332783514;13152.276624785538;274157;275529.7129200937;277981;278374;278920;36596.63403365986;36608.26783464369;36660.81669591635;37074.62925370712;37089.443658345146;972.9221662267001;1024.7591815923402;1035.6271742172812;1094.0591091661165;1102.1072930925816;22076;23038.193663221708;23145.495542790337;23239.88380058089;23432.531349373257;30584.235394115327;31096;31149.065528034196;31193.220169495038;31296;154542;157644;158096.20951895165;158212.20893895457;158434.20782896012;18724.157412916724;20767.688484673043;20941.89529052406;21235.893820530797;22224.88887555552;31096.68903310938;31233.531497027107;31941.8402907984;32049.839750801097;32129;27021.324466888484;27389.31526711848;27610;27641.447171056065;28038.2990425241;28483;28542.001029963852;28791.712082878905;28837.711622883504;28848.278793030342;24675.506489870462;27287.317817054733;28122;28762.137135885958;30860.845695772277;25104.874475627505;25140.497190055732;25421.61867571951;27571.31071723223;27807;21551.89975305938;21608.567828643652;21779.891100544395;21858.89070554637;22263.332100037023 09:19:23 INFO - PROCESS | 24707 | |2;jquery.html;349.6713089695686;357.4905759293007;359.54517535317825;360.7618971478824;369.4882587616151;14982.101073935557;15514.146721930289;16064;16116.677666446663;16117.113558754278;13951;14129.434822607083;14793.112413255212;14813.111213327182;14823.407063717439;4750.833720819772;4998.800047998085;5116.616253780962;5151.150060240056;5152;4987.950120498785;5136.922946155792;5150.665206761567;5157.252198431225;5169.198774190007;4992.276119962603;5090.592752579803;5097.082525145473;5113.539781419671;5149.9485005149845;5090;5091.770870310845;5097.464766199532;5100.489951004888;5110.182370820669;4421.137878113769;4547.226971414834;4550.089982003579;4554.43069616298;4558.680892337537;18402.815971840246;18445.169967351503;18451.16969736365;18939.147738351807;19116.713249301272;1998.8001199879895;2127.351157896841;2338.8596684199;2469.444375015625;2508.1096210845253;12043.698907527294;12075.215111017844;12086.456109475095;12383.75232495345;12669.809952850623;1747.4408189379403;1788.7853457585043;1822.1891258390021;1841.0518582929801;1894.0719047666682;40783.388249176285;52302.47697523052;52357.476425236026;56960.715196424164;60213;8760.167784060504;8827.823443531093;8977.192052715287;9054.728358149267;9140;12570.560030399076;12659.873401266053;12691.428885700165;12722.109452338247;12749.107562470537;6714.328567143344;6732.124823772878;6831.863362732717;6853.657317134123;6906.171259448798;6341.841453963595;6365.968170159213;6486.902696459557;6495.610263384212;6501.252355979028;5122.257272695439;5729.398413166641;5821.476067153892;5994.490468310242;6116.816495505142;1548.3187397545043;1594.6810637872472;1597.936082556708;1631.6818220446899;1646.876484263685;760.7261385901071;775.1821827971544;784.8194915169429;791.7862177212121;809.3039985612369;14328.570142895729;14580.635484112981;14601.342939567745;15576.221188940508;15847.52457426279;768.235605572459;789.9368050555942;800.6757263308369;818.5088946632093;823.2014945502852;8414.000369429246;8865.911340886509;8995.820083598292;9286.210672092948;9372.95313523428;281340;288405;290745;291234;360756;59743.70128149331;62600.373996259455;63246.68376658087;63299.6835015822;64704;921.5069937583361;960.313375936195;961.6826447272383;963.7783309838776;989.6989499413892;13874.65313367174;14145.858541414455;14172.716545668824;14295;14636.634084147556;32438.35123297568;32715.34569308553;33104.83447582843;33115;33541.49687754637;43394.349084764384;43525.34711979386;45720.77139614281;45771.542284576724;45987.54012459832;7433.256674332523;7475.775726728207;7514.736984205527;7592.468527203043;7772;10554.10290125348;10728.249022568345;10917;10971.725706857073;10976.694894072334;9647;9815.509224538602;9964.900350996397;9993.450360229966;10132.44271565071;9731.951340243253;10056.29605927578;10183.18534517222;10286.125679317342;10310.381377117397;9795.216382689225;10062.949685251528;10086.8486972694;10120.84818727705;10563.313384629975;7223.638818059181;7608.771736847903;7696.538207707556;7755.844883102419;7902.802429939067;9962.651307204213;10031.498425078862;10204.4387558685;10252.077313041862;10381.429021403896 09:19:23 INFO - PROCESS | 24707 | |3;mootools.html;1091.1489038549935;1274.7769140400428;1289.9806502902459;1293.7153826158242;1525.8855585831066;6201;7198.208197098322;7380.409567234608;7391.039164908554;7399.741009064684;7090.787276381717;7223;7223.891641625367;7226.566406015642;7237.27627237276;1842.81571842816;1908.5896532245565;1908.9045547722628;2181.2474696229774;2195.747489038757;2181.2692747929464;2265.2184996176293;2266.3427605994243;2268.6483595042796;2270.19408110121;2125.553633736917;2135.882526461044;2145.3027765976044;2147.0231044874545;2185.322550009494;1815.8728888977778;2127.6276651585986;2135.9786402136015;2136.829053675702;2143.7641859395453;2157.169489746443;2179.923702670415;2180.193328468461;2180.5529866377515;2182.476205710634;7085;7146.642667866586;7153.320434558708;7153.6065516396575;7158.677859496283;4304.892377690551;4435.534268901751;4440.11197760446;4451.2655411857;4466.955330446686;2803.271149501124;2881.6686081100734;2929.5752115943283;2937.735603795669;2941.6470023597303;928.5635751196994;939.5490164720904;940.7271891151557;942.0861764088822;969.0987381734984;10134.949325253401;10137.898621013843;10152.390856548556;10154.543045562967;10166;4078.1028173801715;4175.791210439465;4215.199112168678;4221.409002739619;4235.0894557669635;8679.392442529088;8701.782455438664;8703.086175951561;8725.956370218108;8737.694180703646;4447.999200179934;4449.154660614473;4466.865994020184;4482.506924238396;4484.8430304939175;3525.0482369760534;3839.155385815116;3966.286068507696;3977.0455090778582;4073.7759423232005;3374.713149382281;3389.2035371687634;3445.913852153716;3452.223249768782;3469.479578063311;1124.9212555121226;1126.4649291586595;1134.6369161868122;1137.1300954769608;1137.4881303413497;712.868119397913;732.827785470414;741.0921621014322;743.7545609948704;759.1762937213075;4969.527894849984;5041.445441001487;5122.385313762298;5160.819371321985;5200.869978250574;379.1847527815219;381.6679488844723;389.20407766118353;398.36460844952256;412.95044594648834;3441.2601290722614;3523.894283171509;3585.8027808470265;3592.3354179476883;3601.3877640801124;8659.523726194993;9054.637814487478;9176.541172941326;9185.77035574116;9199.4940278284;9040.231580315747;9111.544422778834;9126.315526335551;9150.633974641072;9150.633974641072;6870.656467176621;6936.375726184668;6945.861082778417;6948.722051117999;6948.965255173692;5101.20931255656;5104.285400043923;5151.175811870083;5221.295125158055;5278.023565640369;8248.628811703607;8453.323734101381;8507.064222935349;8514.574271286287;8601.311895048257;8516.957415212884;8585.82828343442;8728.301735861243;8745.825083498421;8753.693620723245;5644.971775141098;6118.265808103056;6156.661383623765;6302.401271879165;6455.3221911700475;6780.69486873082;6781.287964763629;6801.591904485746;6824.931750682628;6849.794506164823;6014.398560143951;6194.845128871814;6220.098085777628;6251.9687401562705;6305.621662700252;6214.316425193316;6313.810585682437;6364.045393191059;6391.424771770563;6421.743130274736;5944.138099975393;5955.91066134017;5995;6009.188759517409;6009.489193418433;4050.0887300357185;4164.979175104105;4166.145940082337;4215.641670457922;4231.703780735319;4297;4413.801378938019;4499.190145773661;4537.1833070047705;4608.3778689876435 09:19:23 INFO - PROCESS | 24707 | |4;prototype.html;306.1046439165441;308.21559132009037;310.8585593554932;312.33472703142303;314.33518109199053;4673.742944138071;4728.4798672146035;4743.90512189756;4758.4765675775725;4772.284157376395;4664.97667511662;4669.276262179367;4673.509281525442;4687.648426368019;4690.366800481934;3511.6137224905306;3614.6746792788645;3619.402798538238;3730.3844865597143;3767.303048935942;3702.6300268900545;3724.14344700719;3733.2906747717984;3741.7754934703867;3748.8125593720206;3503.456964170545;3684.465752465891;3685.70514358852;3693.630636936312;3695.205530810875;3572.4462708280257;3667.7799332040163;3677.1358730698266;3685.649863262986;3690.4833323334756;3244.545763593099;3256.072019474462;3263.1515805890403;3263.689949454798;3267.950980735291;4493.573110554492;4505.346724724896;4507.864764057083;4520.4575450946095;4523.163214805271;3942.704297177723;4153.875383738493;4159.792010399498;4160.45914031174;4196.223698615766;4871;4888.120138375091;4894.4371397289415;4899.926501102486;4909.828156014522;3472.9305413891834;3475.5481787367735;3496.950914725572;3504.3516949364193;3506.1760486285702;4990.750462476862;5057.317262169597;5086.998670784255;5109.335786347789;5155.458676838953;3551.5027896094566;3566.4472006838982;3573.9821300893327;3587.264610754841;3590.694790942799;3613.6928361089076;3622.492851000862;3627.709783217389;3633.8364773585254;3645.2709458108484;4105.322621767435;4124.731892426983;4131.78112456826;4150.730202536825;4154.605312495308;4688.413948256468;4868.659193856396;4872.220444728897;4902.436219834764;4914.975425122851;1300.765862144804;1311.3770958794496;1314.3231235913513;1318.5187406596715;1322.9801552976712;845.3533047218882;889.7419748272928;899.5142622983647;908.6456282050005;911.9680811171577;4557.453105627346;4565.292379681155;4585.380973568593;4601.987562736193;4609.608183304456;5343.412224655285;5344.331958505187;5345.3852806926925;5350.5987050971335;5364.892702145935;4507.211238033331;4572.725636461823;4590.357349971006;4594.88512787183;4609.2394754865745;2816.718328167167;2858.628378310848;2863.599096126544;2869.8708558114936;2873.367859071001;5057.418396884332;5068.52805828009;5073.619478539051;5074.264231686385;5089.6691715038405;5276.498732620395;5443.8094666686475;5459.235707001023;5461.918071228934;5475.425080366584;5023.397192336943;5032.496750324938;5042.940982393665;5057.822976195742;5091.923621145612;5337.653052551572;5351.411344751997;5355.651882627617;5364.356277246756;5365.946340536545;4752.596029337545;5033.421156566895;5038.874028149179;5059;5230.241614965885;4848.8302909398;4867.756612169448;4868.513148685103;4879.804447910285;4880.534820277642;3626;3651.2697460508534;3664.5236119305387;3676.6139555346303;3677.705783537364;4865.829695960624;5074.086664400444;5106.514881086291;5157.447063703491;5163.535281824654;4932.28481870134;4941.579965702956;4945.307656928105;4955.1576232039915;4958.355413796184;4942.3327850741125;4955.1823949048;4975.427825799935;4977.776000079933;4986.80052797884;4748.382710247645;4900.460949295646;4900.975495122501;4906.6320025997475;4917.434495033117;4726.8109275628585;4812.8796780080775;4952.975235123801;4957.925631115609;4958.330625365529;4826.710397376169;4838.7338696372035;4844;4854.621235594604;4865.032330278598 09:19:23 INFO - PROCESS | 24707 | |5;yui.html;588.2441063233745;614.2628845385535;654.6595770199499;655.5574986883852;671.1509939925994;12375;12745.553905613306;12814.29521376324;12837.614871553846;12847.421866016028;10727.141828653708;10874.565017399314;10881.945590272057;10883.346999180036;10888.455577221128;498.41685228282995;519.8986197691452;579.9971000144993;605.1527860994621;634.1154090044397;596.4661627843078;609.8170548835354;619.5632079384023;650.3171669746771;652.6671397587241;645.3965542218018;650.7527139686912;672.878881801278;693.8716337477557;726.53138725522;525.5480286953236;641.1408712325483;659.2155335151155;659.5844617890746;659.6437923521293;479.23561918739557;479.3361194745303;479.913615549201;480.6179087625346;481.059528621544;11438.656840294805;11497;11502.884971150264;11503.769924601462;11506.827397589042;10887.455627218687;11377.94311028448;11484.36835974021;11940.522379104825;12134.150609457341;8815.809711878614;9120.042395548438;9159.129882661136;9272.443653380751;9447.338686291961;3237.7247933925646;3330.467125259947;3374.932501349984;3380.577427821522;3450.5341778859784;39877.80061099676;45492.090158196654;45877.54122458799;45933.77033114813;46022.309665355046;8886.955565222133;8950.104989501127;8964.013958464564;9429.90570094304;9463;14327.856721432861;14355.282235888164;14392.35234414454;14444.927775361057;14539.636509087146;6104.511639068864;6115.1744514490865;6262.937370626327;6268.435840774261;6419.967900160563;3617.366960781905;3629.219717760694;3703.759255648374;3719.5350581177354;3743.344914639927;2609.9478010440066;2658.3752818087733;2665.466906618684;2665.813393062467;2668.5196664600558;468.81013189658245;479.820067474697;484.39934481243006;493;497.16972655665126;487.5344046435645;579.8434422705845;594.2542109652359;600.579594284003;616.017452163798;7890.007278056453;8138.796530086795;8153.682814859893;8206.794830129174;8241.8351632967;490.2132078014806;562.7777028073888;580.6458060583041;588.2029849553793;593.7179839576166;3551.4317709166926;3783.564890037626;3790.2040571479747;3817.9427308590393;3840.6543411093135;53767.731161343945;54515.45484545183;55855.72072139613;55891.72054139703;55986.72006639941;45842.770786146524;47435.5256447438;47682.761586191846;48889.26666100011;49503.75248123737;43753.343699845165;43846.561534384884;44091.338629920574;44306.113877722266;44524;7401.259874012556;7510.737124200627;7626.237376262329;7649.732259370895;7681.231876812498;29755.851220743756;29920.102396928127;30520;30630.540541892336;30976;36924.630753692116;39577.80211098926;40280;41049.79475102606;41405.37891931563;9720.75698107553;9759.268054896052;9793.167580755715;10118.08937195656;10131.341462804894;11437.771244575228;11486.655400338002;11793.233439826385;11895.583654572049;12050.578229761917;10821.891781082088;10881.45592720345;11126.221164518405;11269.154813389192;11394.772104558027;10337.483125843828;10382.42896640692;10524;10659.946700266759;10799.460026998775;10425.53085111187;10655.200859935381;10756.784864302826;10959.50682219318;11260.09919206478;8755.868661969947;8941.418807777474;9124.406913550598;9437.716868493957;9493.335466517277;9171.174594286545;9194.908050919405;9240;9301.116393942564;9366.906330936603 09:19:23 INFO - PROCESS | 24707 | __end_tp_report 09:19:23 INFO - PROCESS | 24707 | __start_cc_report 09:19:23 INFO - PROCESS | 24707 | _x_x_mozilla_cycle_collect,876 09:19:23 INFO - PROCESS | 24707 | __end_cc_report 09:19:23 INFO - PROCESS | 24707 | __startTimestamp1461687563561__endTimestamp 09:19:23 INFO - PROCESS | 24707 | 09:19:23 INFO - PROCESS | 24707 | ------- Summary: start ------- 09:19:23 INFO - PROCESS | 24707 | Number of tests: 6 09:19:23 INFO - PROCESS | 24707 | 09:19:23 INFO - PROCESS | 24707 | [#0] dojo.html Cycles:165 Average:4469.62 Median:4599.40 stddev:436.12 (9.5%) stddev-sans-first:410.89 09:19:23 INFO - PROCESS | 24707 | Values: 2558.7 2634.0 2635.0 2652.8 2653.3 4400.2 4479.6 4492.5 4513.3 4518.7 4488.1 4517.9 4543.5 4574.2 4585.7 4126.7 4128.1 4142.6 4208.2 4328.2 4280.9 4283.3 4305.9 4309.5 4336.7 4081.9 4154.8 4157.1 4205.6 4226.5 4146.0 4148.7 4151.8 4154.9 4155.7 4082.3 4124.9 4128.4 4160.1 4192.3 4393.2 4426.1 4430.1 4436.8 4450.2 4799.6 4804.7 4806.7 4810.3 4845.6 4698.6 4790.5 4832.3 4833.6 4879.1 4480.4 4508.7 4512.4 4516.1 4560.4 4839.3 4936.4 4941.1 4943.8 4985.2 4692.8 4768.4 4786.0 4786.9 4787.7 4793.5 4827.4 4831.5 4834.2 4876.3 4779.7 4779.8 4786.7 4790.1 4840.7 4650.0 4674.1 4714.2 4743.7 4770.8 4185.0 4192.7 4195.7 4236.8 4238.3 3591.0 3680.2 3687.3 3688.2 3713.5 4138.5 4147.7 4182.1 4215.3 4216.7 4776.4 4779.9 4804.6 4812.9 4852.5 4035.9 4165.8 4167.7 4169.9 4191.5 4370.4 4409.5 4414.4 4449.2 4464.5 4536.3 4537.7 4551.7 4625.7 4631.8 4764.9 4804.8 4812.9 4820.8 4826.3 4590.0 4595.2 4599.4 4606.3 4648.9 4774.7 4779.2 4782.5 4794.8 4831.2 4613.6 4722.5 4835.1 4839.2 4849.0 4611.2 4636.1 4645.4 4688.8 4693.3 4626.5 4627.5 4631.2 4635.9 4678.5 4553.5 4558.6 4611.9 4697.4 4700.0 4717.2 4719.3 4740.1 4740.2 4779.7 4644.1 4644.3 4651.3 4663.9 4698.4 09:19:23 INFO - PROCESS | 24707 | 09:19:23 INFO - PROCESS | 24707 | [#1] ext.html Cycles:180 Average:34169.10 Median:30621.54 stddev:56273.91 (183.8%) stddev-sans-first:56375.19 09:19:23 INFO - PROCESS | 24707 | Values: 562.0 583.3 585.0 587.4 603.4 23717.5 28527.9 28964.1 29036.6 29626.4 31207.7 31278.8 31331.1 31449.4 31732.0 7151.1 7400.3 7403.6 7404.2 7409.9 7397.6 7543.4 7547.6 7552.8 7562.8 7494.5 7602.3 7602.7 7609.1 7618.1 7490.5 7563.7 7593.2 7598.5 7600.9 7386.2 7507.0 7508.1 7509.9 7514.5 30658.8 31104.5 31134.7 31212.4 31226.1 2822.8 3050.7 3134.2 3210.8 3424.3 24407.6 24786.3 25099.2 25334.9 25815.2 3434.1 3443.1 3458.0 3497.4 3531.6 193495.0 193897.0 194350.0 194643.0 194905.0 41893.8 42256.8 42421.2 42457.0 42461.6 39499.2 39966.6 40047.4 40280.4 40785.8 12786.1 13004.2 13085.1 13091.6 13110.4 10201.5 10300.1 10304.5 10304.8 10310.1 13452.8 13464.4 13501.3 14161.0 14505.1 5686.1 5745.6 5773.9 5779.9 5842.2 753.8 822.9 831.2 888.4 926.0 23898.5 24572.8 24728.9 24737.9 24939.0 880.1 883.8 883.8 897.3 936.4 12861.2 12943.7 13099.3 13113.3 13152.3 274157.0 275529.7 277981.0 278374.0 278920.0 36596.6 36608.3 36660.8 37074.6 37089.4 972.9 1024.8 1035.6 1094.1 1102.1 22076.0 23038.2 23145.5 23239.9 23432.5 30584.2 31096.0 31149.1 31193.2 31296.0 154542.0 157644.0 158096.2 158212.2 158434.2 18724.2 20767.7 20941.9 21235.9 22224.9 31096.7 31233.5 31941.8 32049.8 32129.0 27021.3 27389.3 27610.0 27641.4 28038.3 28483.0 28542.0 28791.7 28837.7 28848.3 24675.5 27287.3 28122.0 28762.1 30860.8 25104.9 25140.5 25421.6 27571.3 27807.0 21551.9 21608.6 21779.9 21858.9 22263.3 09:19:23 INFO - PROCESS | 24707 | 09:19:23 INFO - PROCESS | 24707 | [#2] jquery.html Cycles:180 Average:20428.89 Median:25139.73 stddev:50080.26 (199.2%) stddev-sans-first:50198.05 09:19:23 INFO - PROCESS | 24707 | Values: 349.7 357.5 359.5 360.8 369.5 14982.1 15514.1 16064.0 16116.7 16117.1 13951.0 14129.4 14793.1 14813.1 14823.4 4750.8 4998.8 5116.6 5151.2 5152.0 4988.0 5136.9 5150.7 5157.3 5169.2 4992.3 5090.6 5097.1 5113.5 5149.9 5090.0 5091.8 5097.5 5100.5 5110.2 4421.1 4547.2 4550.1 4554.4 4558.7 18402.8 18445.2 18451.2 18939.1 19116.7 1998.8 2127.4 2338.9 2469.4 2508.1 12043.7 12075.2 12086.5 12383.8 12669.8 1747.4 1788.8 1822.2 1841.1 1894.1 40783.4 52302.5 52357.5 56960.7 60213.0 8760.2 8827.8 8977.2 9054.7 9140.0 12570.6 12659.9 12691.4 12722.1 12749.1 6714.3 6732.1 6831.9 6853.7 6906.2 6341.8 6366.0 6486.9 6495.6 6501.3 5122.3 5729.4 5821.5 5994.5 6116.8 1548.3 1594.7 1597.9 1631.7 1646.9 760.7 775.2 784.8 791.8 809.3 14328.6 14580.6 14601.3 15576.2 15847.5 768.2 789.9 800.7 818.5 823.2 8414.0 8865.9 8995.8 9286.2 9373.0 281340.0 288405.0 290745.0 291234.0 360756.0 59743.7 62600.4 63246.7 63299.7 64704.0 921.5 960.3 961.7 963.8 989.7 13874.7 14145.9 14172.7 14295.0 14636.6 32438.4 32715.3 33104.8 33115.0 33541.5 43394.3 43525.3 45720.8 45771.5 45987.5 7433.3 7475.8 7514.7 7592.5 7772.0 10554.1 10728.2 10917.0 10971.7 10976.7 9647.0 9815.5 9964.9 9993.5 10132.4 9732.0 10056.3 10183.2 10286.1 10310.4 9795.2 10062.9 10086.8 10120.8 10563.3 7223.6 7608.8 7696.5 7755.8 7902.8 9962.7 10031.5 10204.4 10252.1 10381.4 09:19:23 INFO - PROCESS | 24707 | 09:19:23 INFO - PROCESS | 24707 | [#3] mootools.html Cycles:180 Average:4858.13 Median:5005.49 stddev:2713.30 (54.2%) stddev-sans-first:2706.13 09:19:23 INFO - PROCESS | 24707 | Values: 1091.1 1274.8 1290.0 1293.7 1525.9 6201.0 7198.2 7380.4 7391.0 7399.7 7090.8 7223.0 7223.9 7226.6 7237.3 1842.8 1908.6 1908.9 2181.2 2195.7 2181.3 2265.2 2266.3 2268.6 2270.2 2125.6 2135.9 2145.3 2147.0 2185.3 1815.9 2127.6 2136.0 2136.8 2143.8 2157.2 2179.9 2180.2 2180.6 2182.5 7085.0 7146.6 7153.3 7153.6 7158.7 4304.9 4435.5 4440.1 4451.3 4467.0 2803.3 2881.7 2929.6 2937.7 2941.6 928.6 939.5 940.7 942.1 969.1 10134.9 10137.9 10152.4 10154.5 10166.0 4078.1 4175.8 4215.2 4221.4 4235.1 8679.4 8701.8 8703.1 8726.0 8737.7 4448.0 4449.2 4466.9 4482.5 4484.8 3525.0 3839.2 3966.3 3977.0 4073.8 3374.7 3389.2 3445.9 3452.2 3469.5 1124.9 1126.5 1134.6 1137.1 1137.5 712.9 732.8 741.1 743.8 759.2 4969.5 5041.4 5122.4 5160.8 5200.9 379.2 381.7 389.2 398.4 413.0 3441.3 3523.9 3585.8 3592.3 3601.4 8659.5 9054.6 9176.5 9185.8 9199.5 9040.2 9111.5 9126.3 9150.6 9150.6 6870.7 6936.4 6945.9 6948.7 6949.0 5101.2 5104.3 5151.2 5221.3 5278.0 8248.6 8453.3 8507.1 8514.6 8601.3 8517.0 8585.8 8728.3 8745.8 8753.7 5645.0 6118.3 6156.7 6302.4 6455.3 6780.7 6781.3 6801.6 6824.9 6849.8 6014.4 6194.8 6220.1 6252.0 6305.6 6214.3 6313.8 6364.0 6391.4 6421.7 5944.1 5955.9 5995.0 6009.2 6009.5 4050.1 4165.0 4166.1 4215.6 4231.7 4297.0 4413.8 4499.2 4537.2 4608.4 09:19:23 INFO - PROCESS | 24707 | 09:19:23 INFO - PROCESS | 24707 | [#4] prototype.html Cycles:180 Average:4154.59 Median:4689.39 stddev:1209.18 (25.8%) stddev-sans-first:1177.57 09:19:23 INFO - PROCESS | 24707 | Values: 306.1 308.2 310.9 312.3 314.3 4673.7 4728.5 4743.9 4758.5 4772.3 4665.0 4669.3 4673.5 4687.6 4690.4 3511.6 3614.7 3619.4 3730.4 3767.3 3702.6 3724.1 3733.3 3741.8 3748.8 3503.5 3684.5 3685.7 3693.6 3695.2 3572.4 3667.8 3677.1 3685.6 3690.5 3244.5 3256.1 3263.2 3263.7 3268.0 4493.6 4505.3 4507.9 4520.5 4523.2 3942.7 4153.9 4159.8 4160.5 4196.2 4871.0 4888.1 4894.4 4899.9 4909.8 3472.9 3475.5 3497.0 3504.4 3506.2 4990.8 5057.3 5087.0 5109.3 5155.5 3551.5 3566.4 3574.0 3587.3 3590.7 3613.7 3622.5 3627.7 3633.8 3645.3 4105.3 4124.7 4131.8 4150.7 4154.6 4688.4 4868.7 4872.2 4902.4 4915.0 1300.8 1311.4 1314.3 1318.5 1323.0 845.4 889.7 899.5 908.6 912.0 4557.5 4565.3 4585.4 4602.0 4609.6 5343.4 5344.3 5345.4 5350.6 5364.9 4507.2 4572.7 4590.4 4594.9 4609.2 2816.7 2858.6 2863.6 2869.9 2873.4 5057.4 5068.5 5073.6 5074.3 5089.7 5276.5 5443.8 5459.2 5461.9 5475.4 5023.4 5032.5 5042.9 5057.8 5091.9 5337.7 5351.4 5355.7 5364.4 5365.9 4752.6 5033.4 5038.9 5059.0 5230.2 4848.8 4867.8 4868.5 4879.8 4880.5 3626.0 3651.3 3664.5 3676.6 3677.7 4865.8 5074.1 5106.5 5157.4 5163.5 4932.3 4941.6 4945.3 4955.2 4958.4 4942.3 4955.2 4975.4 4977.8 4986.8 4748.4 4900.5 4901.0 4906.6 4917.4 4726.8 4812.9 4953.0 4957.9 4958.3 4826.7 4838.7 4844.0 4854.6 4865.0 09:19:23 INFO - PROCESS | 24707 | 09:19:23 INFO - PROCESS | 24707 | [#5] yui.html Cycles:180 Average:12624.63 Median:24081.00 stddev:14900.78 (61.9%) stddev-sans-first:14915.17 09:19:23 INFO - PROCESS | 24707 | Values: 588.2 614.3 654.7 655.6 671.2 12375.0 12745.6 12814.3 12837.6 12847.4 10727.1 10874.6 10881.9 10883.3 10888.5 498.4 519.9 580.0 605.2 634.1 596.5 609.8 619.6 650.3 652.7 645.4 650.8 672.9 693.9 726.5 525.5 641.1 659.2 659.6 659.6 479.2 479.3 479.9 480.6 481.1 11438.7 11497.0 11502.9 11503.8 11506.8 10887.5 11377.9 11484.4 11940.5 12134.2 8815.8 9120.0 9159.1 9272.4 9447.3 3237.7 3330.5 3374.9 3380.6 3450.5 39877.8 45492.1 45877.5 45933.8 46022.3 8887.0 8950.1 8964.0 9429.9 9463.0 14327.9 14355.3 14392.4 14444.9 14539.6 6104.5 6115.2 6262.9 6268.4 6420.0 3617.4 3629.2 3703.8 3719.5 3743.3 2609.9 2658.4 2665.5 2665.8 2668.5 468.8 479.8 484.4 493.0 497.2 487.5 579.8 594.3 600.6 616.0 7890.0 8138.8 8153.7 8206.8 8241.8 490.2 562.8 580.6 588.2 593.7 3551.4 3783.6 3790.2 3817.9 3840.7 53767.7 54515.5 55855.7 55891.7 55986.7 45842.8 47435.5 47682.8 48889.3 49503.8 43753.3 43846.6 44091.3 44306.1 44524.0 7401.3 7510.7 7626.2 7649.7 7681.2 29755.9 29920.1 30520.0 30630.5 30976.0 36924.6 39577.8 40280.0 41049.8 41405.4 9720.8 9759.3 9793.2 10118.1 10131.3 11437.8 11486.7 11793.2 11895.6 12050.6 10821.9 10881.5 11126.2 11269.2 11394.8 10337.5 10382.4 10524.0 10659.9 10799.5 10425.5 10655.2 10756.8 10959.5 11260.1 8755.9 8941.4 9124.4 9437.7 9493.3 9171.2 9194.9 9240.0 9301.1 9366.9 09:19:23 INFO - PROCESS | 24707 | -------- Summary: end -------- 09:19:23 INFO - PROCESS | 24707 | 09:19:24 INFO - TEST-INFO | 24707: exit 0 09:19:24 INFO - TEST-OK | dromaeo_css | took 1117305ms 09:19:24 INFO - TEST-START | kraken 09:19:24 INFO - operating with platform_type : linux_ 09:19:24 INFO - Initialising browser for kraken test... 09:19:24 INFO - TEST-INFO | started process 24888 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpz6Ngg0/profile http://localhost:45200/getInfo.html) 09:19:28 INFO - PROCESS | 24888 | __metrics Screen width/height:1600/1200 09:19:28 INFO - PROCESS | 24888 | colorDepth:24 09:19:28 INFO - PROCESS | 24888 | Browser inner width/height: 1024/697 09:19:28 INFO - PROCESS | 24888 | __metrics 09:19:29 ERROR - PROCESS | 24888 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:19:30 INFO - TEST-INFO | 24888: exit 0 09:19:30 INFO - Browser initialized. 09:19:30 INFO - Running cycle 1/1 for kraken test... 09:19:30 INFO - TEST-INFO | started process 24968 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpz6Ngg0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:19:42 INFO - PROCESS | 24968 | RSS: Main: 156712960 09:19:42 INFO - PROCESS | 24968 | 09:19:44 INFO - PROCESS | 24968 | Cycle 1(1): loaded http://localhost:45200/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45200/tests/kraken/driver.html?testName=audio-beat-detection) 09:19:45 INFO - PROCESS | 24968 | RSS: Main: 210714624 09:19:45 INFO - PROCESS | 24968 | 09:19:48 INFO - PROCESS | 24968 | Cycle 1(1): loaded http://localhost:45200/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45200/tests/kraken/driver.html?testName=audio-dft) 09:19:49 INFO - PROCESS | 24968 | RSS: Main: 196423680 09:19:49 INFO - PROCESS | 24968 | 09:19:52 INFO - PROCESS | 24968 | Cycle 1(1): loaded http://localhost:45200/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45200/tests/kraken/driver.html?testName=audio-fft) 09:19:52 INFO - PROCESS | 24968 | RSS: Main: 266919936 09:19:52 INFO - PROCESS | 24968 | 09:19:55 INFO - PROCESS | 24968 | Cycle 1(1): loaded http://localhost:45200/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45200/tests/kraken/driver.html?testName=audio-oscillator) 09:19:55 INFO - PROCESS | 24968 | RSS: Main: 175194112 09:19:55 INFO - PROCESS | 24968 | 09:19:57 INFO - PROCESS | 24968 | Cycle 1(1): loaded http://localhost:45200/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45200/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:19:58 INFO - PROCESS | 24968 | RSS: Main: 179904512 09:19:58 INFO - PROCESS | 24968 | 09:20:01 INFO - PROCESS | 24968 | Cycle 1(1): loaded http://localhost:45200/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45200/tests/kraken/driver.html?testName=imaging-darkroom) 09:20:02 INFO - PROCESS | 24968 | RSS: Main: 215232512 09:20:02 INFO - PROCESS | 24968 | 09:20:05 INFO - PROCESS | 24968 | Cycle 1(1): loaded http://localhost:45200/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45200/tests/kraken/driver.html?testName=imaging-desaturate) 09:20:06 INFO - PROCESS | 24968 | RSS: Main: 216379392 09:20:06 INFO - PROCESS | 24968 | 09:20:09 INFO - PROCESS | 24968 | Cycle 1(1): loaded http://localhost:45200/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45200/tests/kraken/driver.html?testName=json-parse-financial) 09:20:09 INFO - PROCESS | 24968 | RSS: Main: 216952832 09:20:09 INFO - PROCESS | 24968 | 09:20:11 INFO - PROCESS | 24968 | Cycle 1(1): loaded http://localhost:45200/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45200/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:20:12 INFO - PROCESS | 24968 | RSS: Main: 202874880 09:20:12 INFO - PROCESS | 24968 | 09:20:14 INFO - PROCESS | 24968 | Cycle 1(1): loaded http://localhost:45200/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45200/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:20:14 INFO - PROCESS | 24968 | RSS: Main: 204734464 09:20:14 INFO - PROCESS | 24968 | 09:20:16 INFO - PROCESS | 24968 | Cycle 1(1): loaded http://localhost:45200/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45200/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:20:17 INFO - PROCESS | 24968 | RSS: Main: 217501696 09:20:17 INFO - PROCESS | 24968 | 09:20:20 INFO - PROCESS | 24968 | Cycle 1(1): loaded http://localhost:45200/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45200/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:20:20 INFO - PROCESS | 24968 | RSS: Main: 226578432 09:20:20 INFO - PROCESS | 24968 | 09:20:24 INFO - PROCESS | 24968 | Cycle 1(1): loaded http://localhost:45200/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45200/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:20:24 INFO - PROCESS | 24968 | RSS: Main: 223465472 09:20:24 INFO - PROCESS | 24968 | 09:20:26 INFO - PROCESS | 24968 | Cycle 1(1): loaded http://localhost:45200/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45200/tests/kraken/driver.html?testName=ai-astar) 09:20:27 INFO - PROCESS | 24968 | RSS: Main: 287399936 09:20:27 INFO - PROCESS | 24968 | 09:20:27 INFO - PROCESS | 24968 | __start_tp_report 09:20:27 INFO - PROCESS | 24968 | _x_x_mozilla_page_load 09:20:27 INFO - PROCESS | 24968 | _x_x_mozilla_page_load_details 09:20:27 INFO - PROCESS | 24968 | |i|pagename|runs| 09:20:27 INFO - PROCESS | 24968 | |0;ai-astar;112;114;108;113;114;115;105;113;113;116 09:20:27 INFO - PROCESS | 24968 | |1;audio-beat-detection;172;131;162;185;137;152;158;133;185;132 09:20:27 INFO - PROCESS | 24968 | |2;audio-dft;162;161;163;161;163;161;163;163;163;162 09:20:27 INFO - PROCESS | 24968 | |3;audio-fft;77;74;138;108;75;72;73;114;109;76 09:20:27 INFO - PROCESS | 24968 | |4;audio-oscillator;95;95;148;93;94;94;149;95;94;150 09:20:27 INFO - PROCESS | 24968 | |5;imaging-gaussian-blur;117;117;116;117;117;116;117;119;116;117 09:20:27 INFO - PROCESS | 24968 | |6;imaging-darkroom;146;145;146;146;145;146;146;146;146;147 09:20:27 INFO - PROCESS | 24968 | |7;imaging-desaturate;94;95;95;95;95;95;95;95;95;95 09:20:27 INFO - PROCESS | 24968 | |8;json-parse-financial;66;68;65;65;66;65;66;81;64;62 09:20:27 INFO - PROCESS | 24968 | |9;json-stringify-tinderbox;65;65;65;65;65;66;65;66;66;66 09:20:27 INFO - PROCESS | 24968 | |10;stanford-crypto-aes;86;86;79;79;81;81;80;81;80;81 09:20:27 INFO - PROCESS | 24968 | |11;stanford-crypto-ccm;141;142;133;137;132;131;133;135;166;145 09:20:27 INFO - PROCESS | 24968 | |12;stanford-crypto-pbkdf2;221;221;226;217;217;223;219;226;215;218 09:20:27 INFO - PROCESS | 24968 | |13;stanford-crypto-sha256-iterative;74;77;75;74;73;78;75;73;77;74 09:20:27 INFO - PROCESS | 24968 | __end_tp_report 09:20:27 INFO - PROCESS | 24968 | __start_cc_report 09:20:27 INFO - PROCESS | 24968 | _x_x_mozilla_cycle_collect,1924 09:20:27 INFO - PROCESS | 24968 | __end_cc_report 09:20:27 INFO - PROCESS | 24968 | __startTimestamp1461687627014__endTimestamp 09:20:27 INFO - PROCESS | 24968 | 09:20:27 INFO - PROCESS | 24968 | ------- Summary: start ------- 09:20:27 INFO - PROCESS | 24968 | Number of tests: 14 09:20:27 INFO - PROCESS | 24968 | 09:20:27 INFO - PROCESS | 24968 | [#0] ai-astar Cycles:10 Average:112.30 Median:113.50 stddev:3.33 (2.9%) stddev-sans-first:3.54 09:20:27 INFO - PROCESS | 24968 | Values: 112.0 114.0 108.0 113.0 114.0 115.0 105.0 113.0 113.0 116.0 09:20:27 INFO - PROCESS | 24968 | 09:20:27 INFO - PROCESS | 24968 | [#1] audio-beat-detection Cycles:10 Average:154.70 Median:160.00 stddev:21.26 (13.3%) stddev-sans-first:21.61 09:20:27 INFO - PROCESS | 24968 | Values: 172.0 131.0 162.0 185.0 137.0 152.0 158.0 133.0 185.0 132.0 09:20:27 INFO - PROCESS | 24968 | 09:20:27 INFO - PROCESS | 24968 | [#2] audio-dft Cycles:10 Average:162.20 Median:163.00 stddev:0.92 (0.6%) stddev-sans-first:0.97 09:20:27 INFO - PROCESS | 24968 | Values: 162.0 161.0 163.0 161.0 163.0 161.0 163.0 163.0 163.0 162.0 09:20:27 INFO - PROCESS | 24968 | 09:20:27 INFO - PROCESS | 24968 | [#3] audio-fft Cycles:10 Average:91.60 Median:73.50 stddev:23.57 (32.1%) stddev-sans-first:24.40 09:20:27 INFO - PROCESS | 24968 | Values: 77.0 74.0 138.0 108.0 75.0 72.0 73.0 114.0 109.0 76.0 09:20:27 INFO - PROCESS | 24968 | 09:20:27 INFO - PROCESS | 24968 | [#4] audio-oscillator Cycles:10 Average:110.70 Median:94.00 stddev:26.44 (28.1%) stddev-sans-first:27.43 09:20:27 INFO - PROCESS | 24968 | Values: 95.0 95.0 148.0 93.0 94.0 94.0 149.0 95.0 94.0 150.0 09:20:27 INFO - PROCESS | 24968 | 09:20:27 INFO - PROCESS | 24968 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.88 (0.7%) stddev-sans-first:0.93 09:20:27 INFO - PROCESS | 24968 | Values: 117.0 117.0 116.0 117.0 117.0 116.0 117.0 119.0 116.0 117.0 09:20:27 INFO - PROCESS | 24968 | 09:20:27 INFO - PROCESS | 24968 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 09:20:27 INFO - PROCESS | 24968 | Values: 146.0 145.0 146.0 146.0 145.0 146.0 146.0 146.0 146.0 147.0 09:20:27 INFO - PROCESS | 24968 | 09:20:27 INFO - PROCESS | 24968 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.00 09:20:27 INFO - PROCESS | 24968 | Values: 94.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 09:20:27 INFO - PROCESS | 24968 | 09:20:27 INFO - PROCESS | 24968 | [#8] json-parse-financial Cycles:10 Average:66.80 Median:66.00 stddev:5.22 (7.9%) stddev-sans-first:5.53 09:20:27 INFO - PROCESS | 24968 | Values: 66.0 68.0 65.0 65.0 66.0 65.0 66.0 81.0 64.0 62.0 09:20:27 INFO - PROCESS | 24968 | 09:20:27 INFO - PROCESS | 24968 | [#9] json-stringify-tinderbox Cycles:10 Average:65.40 Median:65.50 stddev:0.52 (0.8%) stddev-sans-first:0.53 09:20:27 INFO - PROCESS | 24968 | Values: 65.0 65.0 65.0 65.0 65.0 66.0 65.0 66.0 66.0 66.0 09:20:27 INFO - PROCESS | 24968 | 09:20:27 INFO - PROCESS | 24968 | [#10] stanford-crypto-aes Cycles:10 Average:81.40 Median:81.00 stddev:2.55 (3.1%) stddev-sans-first:2.09 09:20:27 INFO - PROCESS | 24968 | Values: 86.0 86.0 79.0 79.0 81.0 81.0 80.0 81.0 80.0 81.0 09:20:27 INFO - PROCESS | 24968 | 09:20:27 INFO - PROCESS | 24968 | [#11] stanford-crypto-ccm Cycles:10 Average:139.50 Median:139.00 stddev:10.44 (7.5%) stddev-sans-first:11.06 09:20:27 INFO - PROCESS | 24968 | Values: 141.0 142.0 133.0 137.0 132.0 131.0 133.0 135.0 166.0 145.0 09:20:27 INFO - PROCESS | 24968 | 09:20:27 INFO - PROCESS | 24968 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:220.30 Median:221.00 stddev:3.80 (1.7%) stddev-sans-first:4.02 09:20:27 INFO - PROCESS | 24968 | Values: 221.0 221.0 226.0 217.0 217.0 223.0 219.0 226.0 215.0 218.0 09:20:27 INFO - PROCESS | 24968 | 09:20:27 INFO - PROCESS | 24968 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.00 Median:75.00 stddev:1.76 (2.4%) stddev-sans-first:1.83 09:20:27 INFO - PROCESS | 24968 | Values: 74.0 77.0 75.0 74.0 73.0 78.0 75.0 73.0 77.0 74.0 09:20:27 INFO - PROCESS | 24968 | -------- Summary: end -------- 09:20:27 INFO - PROCESS | 24968 | 09:20:28 INFO - TEST-INFO | 24968: exit 0 09:20:28 INFO - TEST-OK | kraken | took 63804ms 09:20:28 INFO - SUITE-END | took 1181s 09:20:28 INFO - Completed test suite (00:19:41) 09:20:28 INFO - javascript benchmark 09:20:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2558.6545816314797, 2633.9604905926426, 2634.9604755928676, 2652.8408295502263, 2653.2570880153553, 4400.229959757045, 4479.641628669703, 4492.4833644130895, 4513.277875539914, 4518.70628409154, 4488.147252022121, 4517.932231016537, 4543.5002149763495, 4574.199515084863, 4585.747783871886, 4126.73176243544, 4128.071183983609, 4142.647874930634, 4208.158368326332, 4328.242557552431, 4280.892977675552, 4283.3146696528565, 4305.870823875289, 4309.525952145262, 4336.653067754588, 4081.9183616327805, 4154.813033413504, 4157.10622216225, 4205.621494065518, 4226.4716910386205, 4146.0, 4148.688848336356, 4151.83392664293, 4154.896127596834, 4155.709100362976, 4082.306007978621, 4124.91750164998, 4128.422020917074, 4160.084781348099, 4192.266353388175, 4393.187260356813, 4426.114777044604, 4430.091831174602, 4436.800343984529, 4450.221211288044, 4799.6400269979895, 4804.711717296974, 4806.663533552653, 4810.254410566367, 4845.6123510119105, 4698.600618947394, 4790.520947905216, 4832.299316599108, 4833.613310935117, 4879.121758083544, 4480.395146655225, 4508.706934049276, 4512.390827238347, 4516.096780643885, 4560.3843481129625, 4839.2983017462975, 4936.358273424432, 4941.085899108676, 4943.802247910044, 4985.177445721415, 4692.835750748707, 4768.427788665383, 4786.042791441726, 4786.928196077061, 4787.688800227974, 4793.49668284832, 4827.396575428072, 4831.4685384607665, 4834.154023045953, 4876.29293752411, 4779.713217206979, 4779.832705855279, 4786.6888652238185, 4790.089882922303, 4840.733759643183, 4650.0, 4674.135284972291, 4714.198586240347, 4743.739094349844, 4770.76146192689, 4185.016521117535, 4192.664586833046, 4195.727277726938, 4236.809343579546, 4238.321868501025, 3590.9640903591153, 3680.245549662313, 3687.2994131115, 3688.2254726507736, 3713.4801127842125, 4138.544760076389, 4147.730397524151, 4182.079942412664, 4215.346621273708, 4216.746995180299, 4776.355192049098, 4779.856604301876, 4804.615630749532, 4812.927806082912, 4852.4662287148385, 4035.9394609080887, 4165.841486117143, 4167.6874234432535, 4169.91660166795, 4191.476065491814, 4370.431843860278, 4409.492908315585, 4414.448193975753, 4449.154660614473, 4464.508904020555, 4536.274196128604, 4537.705049171859, 4551.726896386227, 4625.676202665848, 4631.8378856739855, 4764.857054288377, 4804.783784729626, 4812.855614331435, 4820.831270905502, 4826.251930950708, 4590.0, 4595.218812801765, 4599.402077729874, 4606.286025666027, 4648.93026604608, 4774.7135171889795, 4779.187538118459, 4782.497837726988, 4794.7842347093765, 4831.178699621143, 4613.5847773700525, 4722.527747225387, 4835.105505481497, 4839.249916262985, 4848.95151048485, 4611.21609326422, 4636.095961287517, 4645.372874661877, 4688.765561721969, 4693.342931989592, 4626.467956185082, 4627.5141110184295, 4631.235846085358, 4635.907281854276, 4678.461976872703, 4553.453585569753, 4558.58972692446, 4611.861644150681, 4697.436307642968, 4699.953000469952, 4717.1744944633865, 4719.2921061839625, 4740.075685241345, 4740.170470167775, 4779.737114458738, 4644.117617652771, 4644.2801365788355, 4651.348811166372, 4663.906721865475, 4698.389209402756], "value": 4443.920638795382, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [562.0240824832512, 583.3000399520575, 584.9883002339953, 587.4419301663415, 603.3935894426102, 23717.525649487, 28527.857360713195, 28964.13107606775, 29036.56445153319, 29626.407471850605, 31207.68792312076, 31278.843605781938, 31331.060068197934, 31449.371012579733, 31732.0, 7151.14186297645, 7400.296971787671, 7403.555786652818, 7404.1855395906405, 7409.925900741005, 7397.556146631219, 7543.434242431812, 7547.622618869062, 7552.811179720496, 7562.810929726746, 7494.475386722932, 7602.277783610548, 7602.657880395368, 7609.0869095708595, 7618.123915749677, 7490.5131166474, 7563.697452101909, 7593.240675932418, 7598.544087354777, 7600.923990760077, 7386.224446433101, 7506.962465187694, 7508.061492313461, 7509.924900750977, 7514.473986820924, 30658.84670576655, 31104.533431998538, 31134.688653113404, 31212.37575248505, 31226.063218103493, 2822.8447435391045, 3050.7254347108646, 3134.2477805326557, 3210.807351558914, 3424.280901010766, 24407.63388549173, 24786.25641230766, 25099.24702258917, 25334.873325633253, 25815.225543233733, 3434.1414646338417, 3443.139215196201, 3458.014810083617, 3497.3704733148024, 3531.5762108547137, 193494.9999999986, 193897.0, 194350.0, 194642.99999999857, 194905.0, 41893.79053104715, 42256.788716056224, 42421.15157696829, 42457.0, 42461.57538424576, 39499.2100158001, 39966.60033399687, 40047.39928901069, 40280.395794063115, 40785.79607101945, 12786.10497265201, 13004.154729942524, 13085.149465284727, 13091.607251782461, 13110.410031548603, 10201.540930658139, 10300.072993430627, 10304.536295866705, 10304.845427318596, 10310.123639490726, 13452.798208026887, 13464.394102265422, 13501.324933753272, 14161.0, 14505.129692218501, 5686.147077938343, 5745.569082318842, 5773.942260577424, 5779.8555036124435, 5842.2405087338375, 753.7927070055669, 822.8930239068883, 831.2394159344133, 888.4180861535705, 925.9629614815467, 23898.52202955931, 24572.754272457405, 24728.876355618107, 24737.876310618332, 24939.0, 880.0935036911991, 883.8099808541194, 883.8320719063357, 897.2911399993932, 936.424099179, 12861.228326300452, 12943.676408089685, 13099.279539625411, 13113.344332783514, 13152.276624785538, 274157.0, 275529.7129200937, 277981.0, 278374.0, 278920.0, 36596.63403365986, 36608.26783464369, 36660.81669591635, 37074.62925370712, 37089.443658345146, 972.9221662267001, 1024.7591815923402, 1035.6271742172812, 1094.0591091661165, 1102.1072930925816, 22076.0, 23038.193663221708, 23145.495542790337, 23239.88380058089, 23432.531349373257, 30584.235394115327, 31096.0, 31149.065528034196, 31193.220169495038, 31296.0, 154542.0, 157644.0, 158096.20951895165, 158212.20893895457, 158434.20782896012, 18724.157412916724, 20767.688484673043, 20941.89529052406, 21235.893820530797, 22224.88887555552, 31096.68903310938, 31233.531497027107, 31941.8402907984, 32049.839750801097, 32129.0, 27021.324466888484, 27389.31526711848, 27610.0, 27641.447171056065, 28038.2990425241, 28483.0, 28542.001029963852, 28791.712082878905, 28837.711622883504, 28848.278793030342, 24675.506489870462, 27287.317817054733, 28122.0, 28762.137135885958, 30860.845695772277, 25104.874475627505, 25140.497190055732, 25421.61867571951, 27571.31071723223, 27807.0, 21551.89975305938, 21608.567828643652, 21779.891100544395, 21858.89070554637, 22263.332100037023], "value": 14626.546364984435, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.6713089695686, 357.4905759293007, 359.54517535317825, 360.7618971478824, 369.4882587616151, 14982.101073935557, 15514.146721930289, 16064.0, 16116.677666446663, 16117.113558754278, 13951.0, 14129.434822607083, 14793.112413255212, 14813.111213327182, 14823.407063717439, 4750.833720819772, 4998.800047998085, 5116.616253780962, 5151.150060240056, 5152.0, 4987.950120498785, 5136.922946155792, 5150.665206761567, 5157.252198431225, 5169.198774190007, 4992.276119962603, 5090.592752579803, 5097.082525145473, 5113.539781419671, 5149.9485005149845, 5090.0, 5091.770870310845, 5097.464766199532, 5100.489951004888, 5110.182370820669, 4421.137878113769, 4547.226971414834, 4550.089982003579, 4554.43069616298, 4558.680892337537, 18402.815971840246, 18445.169967351503, 18451.16969736365, 18939.147738351807, 19116.713249301272, 1998.8001199879895, 2127.351157896841, 2338.8596684199, 2469.444375015625, 2508.1096210845253, 12043.698907527294, 12075.215111017844, 12086.456109475095, 12383.75232495345, 12669.809952850623, 1747.4408189379403, 1788.7853457585043, 1822.1891258390021, 1841.0518582929801, 1894.0719047666682, 40783.388249176285, 52302.47697523052, 52357.476425236026, 56960.715196424164, 60213.0, 8760.167784060504, 8827.823443531093, 8977.192052715287, 9054.728358149267, 9140.0, 12570.560030399076, 12659.873401266053, 12691.428885700165, 12722.109452338247, 12749.107562470537, 6714.328567143344, 6732.124823772878, 6831.863362732717, 6853.657317134123, 6906.171259448798, 6341.841453963595, 6365.968170159213, 6486.902696459557, 6495.610263384212, 6501.252355979028, 5122.257272695439, 5729.398413166641, 5821.476067153892, 5994.490468310242, 6116.816495505142, 1548.3187397545043, 1594.6810637872472, 1597.936082556708, 1631.6818220446899, 1646.876484263685, 760.7261385901071, 775.1821827971544, 784.8194915169429, 791.7862177212121, 809.3039985612369, 14328.570142895729, 14580.635484112981, 14601.342939567745, 15576.221188940508, 15847.52457426279, 768.235605572459, 789.9368050555942, 800.6757263308369, 818.5088946632093, 823.2014945502852, 8414.000369429246, 8865.911340886509, 8995.820083598292, 9286.210672092948, 9372.95313523428, 281340.0, 288405.0, 290745.0, 291234.0, 360756.0, 59743.70128149331, 62600.373996259455, 63246.68376658087, 63299.6835015822, 64704.0, 921.5069937583361, 960.313375936195, 961.6826447272383, 963.7783309838776, 989.6989499413892, 13874.65313367174, 14145.858541414455, 14172.716545668824, 14295.0, 14636.634084147556, 32438.35123297568, 32715.34569308553, 33104.83447582843, 33115.0, 33541.49687754637, 43394.349084764384, 43525.34711979386, 45720.77139614281, 45771.542284576724, 45987.54012459832, 7433.256674332523, 7475.775726728207, 7514.736984205527, 7592.468527203043, 7772.0, 10554.10290125348, 10728.249022568345, 10917.0, 10971.725706857073, 10976.694894072334, 9647.0, 9815.509224538602, 9964.900350996397, 9993.450360229966, 10132.44271565071, 9731.951340243253, 10056.29605927578, 10183.18534517222, 10286.125679317342, 10310.381377117397, 9795.216382689225, 10062.949685251528, 10086.8486972694, 10120.84818727705, 10563.313384629975, 7223.638818059181, 7608.771736847903, 7696.538207707556, 7755.844883102419, 7902.802429939067, 9962.651307204213, 10031.498425078862, 10204.4387558685, 10252.077313041862, 10381.429021403896], "value": 7782.085307610877, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1091.1489038549935, 1274.7769140400428, 1289.9806502902459, 1293.7153826158242, 1525.8855585831066, 6201.0, 7198.208197098322, 7380.409567234608, 7391.039164908554, 7399.741009064684, 7090.787276381717, 7223.0, 7223.891641625367, 7226.566406015642, 7237.27627237276, 1842.81571842816, 1908.5896532245565, 1908.9045547722628, 2181.2474696229774, 2195.747489038757, 2181.2692747929464, 2265.2184996176293, 2266.3427605994243, 2268.6483595042796, 2270.19408110121, 2125.553633736917, 2135.882526461044, 2145.3027765976044, 2147.0231044874545, 2185.322550009494, 1815.8728888977778, 2127.6276651585986, 2135.9786402136015, 2136.829053675702, 2143.7641859395453, 2157.169489746443, 2179.923702670415, 2180.193328468461, 2180.5529866377515, 2182.476205710634, 7085.0, 7146.642667866586, 7153.320434558708, 7153.6065516396575, 7158.677859496283, 4304.892377690551, 4435.534268901751, 4440.11197760446, 4451.2655411857, 4466.955330446686, 2803.271149501124, 2881.6686081100734, 2929.5752115943283, 2937.735603795669, 2941.6470023597303, 928.5635751196994, 939.5490164720904, 940.7271891151557, 942.0861764088822, 969.0987381734984, 10134.949325253401, 10137.898621013843, 10152.390856548556, 10154.543045562967, 10166.0, 4078.1028173801715, 4175.791210439465, 4215.199112168678, 4221.409002739619, 4235.0894557669635, 8679.392442529088, 8701.782455438664, 8703.086175951561, 8725.956370218108, 8737.694180703646, 4447.999200179934, 4449.154660614473, 4466.865994020184, 4482.506924238396, 4484.8430304939175, 3525.0482369760534, 3839.155385815116, 3966.286068507696, 3977.0455090778582, 4073.7759423232005, 3374.713149382281, 3389.2035371687634, 3445.913852153716, 3452.223249768782, 3469.479578063311, 1124.9212555121226, 1126.4649291586595, 1134.6369161868122, 1137.1300954769608, 1137.4881303413497, 712.868119397913, 732.827785470414, 741.0921621014322, 743.7545609948704, 759.1762937213075, 4969.527894849984, 5041.445441001487, 5122.385313762298, 5160.819371321985, 5200.869978250574, 379.1847527815219, 381.6679488844723, 389.20407766118353, 398.36460844952256, 412.95044594648834, 3441.2601290722614, 3523.894283171509, 3585.8027808470265, 3592.3354179476883, 3601.3877640801124, 8659.523726194993, 9054.637814487478, 9176.541172941326, 9185.77035574116, 9199.4940278284, 9040.231580315747, 9111.544422778834, 9126.315526335551, 9150.633974641072, 9150.633974641072, 6870.656467176621, 6936.375726184668, 6945.861082778417, 6948.722051117999, 6948.965255173692, 5101.20931255656, 5104.285400043923, 5151.175811870083, 5221.295125158055, 5278.023565640369, 8248.628811703607, 8453.323734101381, 8507.064222935349, 8514.574271286287, 8601.311895048257, 8516.957415212884, 8585.82828343442, 8728.301735861243, 8745.825083498421, 8753.693620723245, 5644.971775141098, 6118.265808103056, 6156.661383623765, 6302.401271879165, 6455.3221911700475, 6780.69486873082, 6781.287964763629, 6801.591904485746, 6824.931750682628, 6849.794506164823, 6014.398560143951, 6194.845128871814, 6220.098085777628, 6251.9687401562705, 6305.621662700252, 6214.316425193316, 6313.810585682437, 6364.045393191059, 6391.424771770563, 6421.743130274736, 5944.138099975393, 5955.91066134017, 5995.0, 6009.188759517409, 6009.489193418433, 4050.0887300357185, 4164.979175104105, 4166.145940082337, 4215.641670457922, 4231.703780735319, 4297.0, 4413.801378938019, 4499.190145773661, 4537.1833070047705, 4608.3778689876435], "value": 3859.224302144723, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.1046439165441, 308.21559132009037, 310.8585593554932, 312.33472703142303, 314.33518109199053, 4673.742944138071, 4728.4798672146035, 4743.90512189756, 4758.4765675775725, 4772.284157376395, 4664.97667511662, 4669.276262179367, 4673.509281525442, 4687.648426368019, 4690.366800481934, 3511.6137224905306, 3614.6746792788645, 3619.402798538238, 3730.3844865597143, 3767.303048935942, 3702.6300268900545, 3724.14344700719, 3733.2906747717984, 3741.7754934703867, 3748.8125593720206, 3503.456964170545, 3684.465752465891, 3685.70514358852, 3693.630636936312, 3695.205530810875, 3572.4462708280257, 3667.7799332040163, 3677.1358730698266, 3685.649863262986, 3690.4833323334756, 3244.545763593099, 3256.072019474462, 3263.1515805890403, 3263.689949454798, 3267.950980735291, 4493.573110554492, 4505.346724724896, 4507.864764057083, 4520.4575450946095, 4523.163214805271, 3942.704297177723, 4153.875383738493, 4159.792010399498, 4160.45914031174, 4196.223698615766, 4871.0, 4888.120138375091, 4894.4371397289415, 4899.926501102486, 4909.828156014522, 3472.9305413891834, 3475.5481787367735, 3496.950914725572, 3504.3516949364193, 3506.1760486285702, 4990.750462476862, 5057.317262169597, 5086.998670784255, 5109.335786347789, 5155.458676838953, 3551.5027896094566, 3566.4472006838982, 3573.9821300893327, 3587.264610754841, 3590.694790942799, 3613.6928361089076, 3622.492851000862, 3627.709783217389, 3633.8364773585254, 3645.2709458108484, 4105.322621767435, 4124.731892426983, 4131.78112456826, 4150.730202536825, 4154.605312495308, 4688.413948256468, 4868.659193856396, 4872.220444728897, 4902.436219834764, 4914.975425122851, 1300.765862144804, 1311.3770958794496, 1314.3231235913513, 1318.5187406596715, 1322.9801552976712, 845.3533047218882, 889.7419748272928, 899.5142622983647, 908.6456282050005, 911.9680811171577, 4557.453105627346, 4565.292379681155, 4585.380973568593, 4601.987562736193, 4609.608183304456, 5343.412224655285, 5344.331958505187, 5345.3852806926925, 5350.5987050971335, 5364.892702145935, 4507.211238033331, 4572.725636461823, 4590.357349971006, 4594.88512787183, 4609.2394754865745, 2816.718328167167, 2858.628378310848, 2863.599096126544, 2869.8708558114936, 2873.367859071001, 5057.418396884332, 5068.52805828009, 5073.619478539051, 5074.264231686385, 5089.6691715038405, 5276.498732620395, 5443.8094666686475, 5459.235707001023, 5461.918071228934, 5475.425080366584, 5023.397192336943, 5032.496750324938, 5042.940982393665, 5057.822976195742, 5091.923621145612, 5337.653052551572, 5351.411344751997, 5355.651882627617, 5364.356277246756, 5365.946340536545, 4752.596029337545, 5033.421156566895, 5038.874028149179, 5059.0, 5230.241614965885, 4848.8302909398, 4867.756612169448, 4868.513148685103, 4879.804447910285, 4880.534820277642, 3626.0, 3651.2697460508534, 3664.5236119305387, 3676.6139555346303, 3677.705783537364, 4865.829695960624, 5074.086664400444, 5106.514881086291, 5157.447063703491, 5163.535281824654, 4932.28481870134, 4941.579965702956, 4945.307656928105, 4955.1576232039915, 4958.355413796184, 4942.3327850741125, 4955.1823949048, 4975.427825799935, 4977.776000079933, 4986.80052797884, 4748.382710247645, 4900.460949295646, 4900.975495122501, 4906.6320025997475, 4917.434495033117, 4726.8109275628585, 4812.8796780080775, 4952.975235123801, 4957.925631115609, 4958.330625365529, 4826.710397376169, 4838.7338696372035, 4844.0, 4854.621235594604, 4865.032330278598], "value": 3779.8766730198968, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [588.2441063233745, 614.2628845385535, 654.6595770199499, 655.5574986883852, 671.1509939925994, 12375.0, 12745.553905613306, 12814.29521376324, 12837.614871553846, 12847.421866016028, 10727.141828653708, 10874.565017399314, 10881.945590272057, 10883.346999180036, 10888.455577221128, 498.41685228282995, 519.8986197691452, 579.9971000144993, 605.1527860994621, 634.1154090044397, 596.4661627843078, 609.8170548835354, 619.5632079384023, 650.3171669746771, 652.6671397587241, 645.3965542218018, 650.7527139686912, 672.878881801278, 693.8716337477557, 726.53138725522, 525.5480286953236, 641.1408712325483, 659.2155335151155, 659.5844617890746, 659.6437923521293, 479.23561918739557, 479.3361194745303, 479.913615549201, 480.6179087625346, 481.059528621544, 11438.656840294805, 11497.0, 11502.884971150264, 11503.769924601462, 11506.827397589042, 10887.455627218687, 11377.94311028448, 11484.36835974021, 11940.522379104825, 12134.150609457341, 8815.809711878614, 9120.042395548438, 9159.129882661136, 9272.443653380751, 9447.338686291961, 3237.7247933925646, 3330.467125259947, 3374.932501349984, 3380.577427821522, 3450.5341778859784, 39877.80061099676, 45492.090158196654, 45877.54122458799, 45933.77033114813, 46022.309665355046, 8886.955565222133, 8950.104989501127, 8964.013958464564, 9429.90570094304, 9463.0, 14327.856721432861, 14355.282235888164, 14392.35234414454, 14444.927775361057, 14539.636509087146, 6104.511639068864, 6115.1744514490865, 6262.937370626327, 6268.435840774261, 6419.967900160563, 3617.366960781905, 3629.219717760694, 3703.759255648374, 3719.5350581177354, 3743.344914639927, 2609.9478010440066, 2658.3752818087733, 2665.466906618684, 2665.813393062467, 2668.5196664600558, 468.81013189658245, 479.820067474697, 484.39934481243006, 493.0, 497.16972655665126, 487.5344046435645, 579.8434422705845, 594.2542109652359, 600.579594284003, 616.017452163798, 7890.007278056453, 8138.796530086795, 8153.682814859893, 8206.794830129174, 8241.8351632967, 490.2132078014806, 562.7777028073888, 580.6458060583041, 588.2029849553793, 593.7179839576166, 3551.4317709166926, 3783.564890037626, 3790.2040571479747, 3817.9427308590393, 3840.6543411093135, 53767.731161343945, 54515.45484545183, 55855.72072139613, 55891.72054139703, 55986.72006639941, 45842.770786146524, 47435.5256447438, 47682.761586191846, 48889.26666100011, 49503.75248123737, 43753.343699845165, 43846.561534384884, 44091.338629920574, 44306.113877722266, 44524.0, 7401.259874012556, 7510.737124200627, 7626.237376262329, 7649.732259370895, 7681.231876812498, 29755.851220743756, 29920.102396928127, 30520.0, 30630.540541892336, 30976.0, 36924.630753692116, 39577.80211098926, 40280.0, 41049.79475102606, 41405.37891931563, 9720.75698107553, 9759.268054896052, 9793.167580755715, 10118.08937195656, 10131.341462804894, 11437.771244575228, 11486.655400338002, 11793.233439826385, 11895.583654572049, 12050.578229761917, 10821.891781082088, 10881.45592720345, 11126.221164518405, 11269.154813389192, 11394.772104558027, 10337.483125843828, 10382.42896640692, 10524.0, 10659.946700266759, 10799.460026998775, 10425.53085111187, 10655.200859935381, 10756.784864302826, 10959.50682219318, 11260.09919206478, 8755.868661969947, 8941.418807777474, 9124.406913550598, 9437.716868493957, 9493.335466517277, 9171.174594286545, 9194.908050919405, 9240.0, 9301.116393942564, 9366.906330936603], "value": 5545.071455961836, "unit": "score"}], "name": "dromaeo_css", "value": 5870.237289734406, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 114.0, 108.0, 113.0, 114.0, 115.0, 105.0, 113.0, 113.0, 116.0], "value": 112.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 131.0, 162.0, 185.0, 137.0, 152.0, 158.0, 133.0, 185.0, 132.0], "value": 154.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 163.0, 161.0, 163.0, 161.0, 163.0, 163.0, 163.0, 162.0], "value": 162.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 74.0, 138.0, 108.0, 75.0, 72.0, 73.0, 114.0, 109.0, 76.0], "value": 91.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 148.0, 93.0, 94.0, 94.0, 149.0, 95.0, 94.0, 150.0], "value": 110.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 117.0, 116.0, 117.0, 119.0, 116.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 146.0, 146.0, 145.0, 146.0, 146.0, 146.0, 146.0, 147.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 65.0, 65.0, 66.0, 65.0, 66.0, 81.0, 64.0, 62.0], "value": 66.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 65.0, 65.0, 66.0, 65.0, 66.0, 66.0, 66.0], "value": 65.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 79.0, 79.0, 81.0, 81.0, 80.0, 81.0, 80.0, 81.0], "value": 81.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 142.0, 133.0, 137.0, 132.0, 131.0, 133.0, 135.0, 166.0, 145.0], "value": 139.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [221.0, 221.0, 226.0, 217.0, 217.0, 223.0, 219.0, 226.0, 215.0, 218.0], "value": 220.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 77.0, 75.0, 74.0, 73.0, 78.0, 75.0, 73.0, 77.0, 74.0], "value": 75.0, "unit": "score"}], "name": "kraken", "value": 1637.6000000000001, "alertThreshold": 2.0}]} 09:20:28 INFO - Return code: 0 09:20:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:20:28 ERROR - # TBPL SUCCESS # 09:20:28 INFO - Running post-action listener: _resource_record_post_action 09:20:28 INFO - Running post-run listener: _resource_record_post_run 09:20:29 INFO - Total resource usage - Wall time: 1198s; CPU: 13.0%; Read bytes: 0; Write bytes: 519024640; Read time: 0; Write time: 507964 09:20:29 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 234643456; Read time: 0; Write time: 265644 09:20:29 INFO - run-tests - Wall time: 1182s; CPU: 13.0%; Read bytes: 0; Write bytes: 284381184; Read time: 0; Write time: 242320 09:20:29 INFO - Running post-run listener: _upload_blobber_files 09:20:29 INFO - Blob upload gear active. 09:20:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:20:29 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:20:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:20:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:20:29 INFO - (blobuploader) - INFO - Open directory for files ... 09:20:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:20:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:20:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:20:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:20:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:20:30 INFO - (blobuploader) - INFO - Done attempting. 09:20:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:20:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:20:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:20:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:20:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:20:31 INFO - (blobuploader) - INFO - Done attempting. 09:20:31 INFO - (blobuploader) - INFO - Iteration through files over. 09:20:31 INFO - Return code: 0 09:20:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:20:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:20:31 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/e91f105683482fc19125e876b360fa1af92737744843704ada4a448615295488e072ee27529150a6ee489b7dfa5f3c85de2ed6c86a3405412a022a1ebf32fbf2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/4cc4b4cea5f3381f7a8083af6868e6951915c51456afa6098b9453b350ed0793170ffab3d0790a324b2329bc4361dda3eea834b62cd3499be3cea13e0c56c65c"} 09:20:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:20:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:20:31 INFO - Contents: 09:20:31 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/e91f105683482fc19125e876b360fa1af92737744843704ada4a448615295488e072ee27529150a6ee489b7dfa5f3c85de2ed6c86a3405412a022a1ebf32fbf2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/4cc4b4cea5f3381f7a8083af6868e6951915c51456afa6098b9453b350ed0793170ffab3d0790a324b2329bc4361dda3eea834b62cd3499be3cea13e0c56c65c"} 09:20:31 INFO - Running post-run listener: copy_logs_to_upload_dir 09:20:31 INFO - Copying logs to upload dir... 09:20:31 INFO - mkdir: /builds/slave/test/build/upload/logs 09:20:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1249.269868 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 49 secs) (at 2016-04-26 09:20:30.960219) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-26 09:20:30.964100) ========= 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=db0483c52d987ecb441a263b000001ad-1461686160.610556-1271485723 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/e91f105683482fc19125e876b360fa1af92737744843704ada4a448615295488e072ee27529150a6ee489b7dfa5f3c85de2ed6c86a3405412a022a1ebf32fbf2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/4cc4b4cea5f3381f7a8083af6868e6951915c51456afa6098b9453b350ed0793170ffab3d0790a324b2329bc4361dda3eea834b62cd3499be3cea13e0c56c65c"} build_url:https://queue.taskcluster.net/v1/task/YKlNETTlQYWFAp1Pdi_NLg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011182 build_url: 'https://queue.taskcluster.net/v1/task/YKlNETTlQYWFAp1Pdi_NLg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/e91f105683482fc19125e876b360fa1af92737744843704ada4a448615295488e072ee27529150a6ee489b7dfa5f3c85de2ed6c86a3405412a022a1ebf32fbf2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/4cc4b4cea5f3381f7a8083af6868e6951915c51456afa6098b9453b350ed0793170ffab3d0790a324b2329bc4361dda3eea834b62cd3499be3cea13e0c56c65c"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-26 09:20:30.988062) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 09:20:30.988363) ========= 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=db0483c52d987ecb441a263b000001ad-1461686160.610556-1271485723 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004439 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 09:20:31.073470) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-26 09:20:31.073828) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-26 09:20:31.074262) ========= ========= Total master_lag: 0.18 =========