builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-054 starttime: 1461686382.96 results: success (0) buildid: 20160426084357 builduid: f8ee86fbc5814290997e348edec02a2b revision: 8c55d8beba757967f6f5f7ff386499e8c6823c11 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-26 08:59:42.960951) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-26 08:59:42.961404) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-26 08:59:42.961673) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-26 08:59:42.992883) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 08:59:42.993155) ========= 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=cbe409c6de289db6ac707de3000001d4-1461685951.312872-1003828145 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-04-26 08:59:43-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 224M=0s 2016-04-26 08:59:43 (224 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.085853 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 08:59:43.097349) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 08:59:43.097701) ========= 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=cbe409c6de289db6ac707de3000001d4-1461685951.312872-1003828145 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016534 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 08:59:43.151128) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 08:59:43.151437) ========= 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=cbe409c6de289db6ac707de3000001d4-1461685951.312872-1003828145 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-04-26 08:59:43,284 truncating revision to first 12 chars 2016-04-26 08:59:43,284 Setting DEBUG logging. 2016-04-26 08:59:43,284 attempt 1/10 2016-04-26 08:59:43,284 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:43,544 unpacking tar archive at: fx-team-8c55d8beba75/testing/mozharness/ program finished with exit code 0 elapsedTime=0.532420 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 08:59:43.701529) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-26 08:59:43.701831) ========= script_repo_revision: 8c55d8beba757967f6f5f7ff386499e8c6823c11 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-26 08:59:43.702221) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-26 08:59:43.702485) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-26 08:59:43.715657) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-04-26 08:59:43.715927) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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=cbe409c6de289db6ac707de3000001d4-1461685951.312872-1003828145 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:59:43 INFO - MultiFileLogger online at 20160426 08:59:43 in /builds/slave/test 08:59:43 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --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:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:59:43 INFO - {'append_to_log': False, 08:59:43 INFO - 'base_work_dir': '/builds/slave/test', 08:59:43 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 08:59:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:59:43 INFO - 'branch': 'Fx-Team-Non-PGO', 08:59:43 INFO - 'buildbot_json_path': 'buildprops.json', 08:59:43 INFO - 'config_files': ('talos/linux_config.py',), 08:59:43 INFO - 'default_actions': ('clobber', 08:59:43 INFO - 'read-buildbot-config', 08:59:43 INFO - 'download-and-extract', 08:59:43 INFO - 'populate-webroot', 08:59:43 INFO - 'create-virtualenv', 08:59:43 INFO - 'install', 08:59:43 INFO - 'run-tests'), 08:59:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:59:43 INFO - 'download_minidump_stackwalk': True, 08:59:43 INFO - 'download_symbols': 'ondemand', 08:59:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:59:43 INFO - 'tooltool.py': '/tools/tooltool.py', 08:59:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:59:43 INFO - '/tools/misc-python/virtualenv.py')}, 08:59:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:59:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:59:43 INFO - 'installer_path': 'installer.exe', 08:59:43 INFO - 'log_level': 'info', 08:59:43 INFO - 'log_name': 'talos', 08:59:43 INFO - 'log_to_console': True, 08:59:43 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:59:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:59:43 INFO - 'opt_config_files': (), 08:59:43 INFO - 'pip_index': False, 08:59:43 INFO - 'sps_profile': False, 08:59:43 INFO - 'sps_profile_interval': 0, 08:59:43 INFO - 'suite': 'dromaeojs-e10s', 08:59:43 INFO - 'system_bits': '32', 08:59:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:59:43 INFO - 'title': 'talos-linux64-ix-054', 08:59:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:59:43 INFO - 'use_talos_json': True, 08:59:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:59:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:59:43 INFO - 'work_dir': 'build'} 08:59:43 INFO - ##### 08:59:43 INFO - ##### Running clobber 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: clobber 08:59:43 INFO - rmtree: /builds/slave/test/build 08:59:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:59:44 INFO - Running post-action listener: _resource_record_post_action 08:59:44 INFO - ##### 08:59:44 INFO - ##### Running read-buildbot-config step. 08:59:44 INFO - ##### 08:59:44 INFO - Running pre-action listener: _resource_record_pre_action 08:59:44 INFO - Running main action method: read_buildbot_config 08:59:44 INFO - Using buildbot properties: 08:59:44 INFO - { 08:59:44 INFO - "project": "", 08:59:44 INFO - "product": "firefox", 08:59:44 INFO - "script_repo_revision": "production", 08:59:44 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 08:59:44 INFO - "repository": "", 08:59:44 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs-e10s", 08:59:44 INFO - "buildid": "20160426084357", 08:59:44 INFO - "pgo_build": "False", 08:59:44 INFO - "basedir": "/builds/slave/test", 08:59:44 INFO - "buildnumber": 1214, 08:59:44 INFO - "slavename": "talos-linux64-ix-054", 08:59:44 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 08:59:44 INFO - "platform": "ubuntu64_hw", 08:59:44 INFO - "scheduler": "tests-fx-team-linux64-talos", 08:59:44 INFO - "branch": "fx-team", 08:59:44 INFO - "repo_path": "integration/fx-team", 08:59:44 INFO - "revision": "8c55d8beba757967f6f5f7ff386499e8c6823c11", 08:59:44 INFO - "stage_platform": "linux64", 08:59:44 INFO - "builduid": "f8ee86fbc5814290997e348edec02a2b", 08:59:44 INFO - "slavebuilddir": "test" 08:59:44 INFO - } 08:59:44 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:44 INFO - Running post-action listener: _resource_record_post_action 08:59:44 INFO - ##### 08:59:44 INFO - ##### Running download-and-extract step. 08:59:44 INFO - ##### 08:59:44 INFO - Running pre-action listener: _resource_record_pre_action 08:59:44 INFO - Running main action method: download_and_extract 08:59:44 INFO - mkdir: /builds/slave/test/build/tests 08:59:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:59:44 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:44 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:44 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:44 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:44 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:48 INFO - Downloaded 1361 bytes. 08:59:48 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 08:59:48 INFO - Using the following test package requirements: 08:59:48 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 08:59:48 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:59:48 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 08:59:48 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:59:48 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 08:59:48 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:59:48 INFO - u'jsshell-linux-x86_64.zip'], 08:59:48 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:59:48 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 08:59:48 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 08:59:48 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:59:48 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 08:59:48 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:59:48 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 08:59:48 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:59:48 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 08:59:48 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 08:59:48 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 08:59:48 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:59:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:59:48 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:48 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:48 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:48 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:48 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:52 INFO - Downloaded 22291517 bytes. 08:59:52 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:52 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:54 INFO - Return code: 0 08:59:54 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:54 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:54 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:54 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:54 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:54 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:54 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:56 INFO - Downloaded 22291517 bytes. 08:59:56 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:56 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:58 INFO - Return code: 0 08:59:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:59:58 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 08:59:58 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 08:59:58 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 08:59:58 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 08:59:58 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 08:59:59 INFO - Downloaded 10840145 bytes. 08:59:59 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'] 08:59:59 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 08:59:59 INFO - Return code: 0 08:59:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:59 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 08:59:59 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 08:59:59 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 08:59:59 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 08:59:59 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:01 INFO - Downloaded 54818399 bytes. 09:00:01 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:01 INFO - mkdir: /builds/slave/test/properties 09:00:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:00:01 INFO - Writing to file /builds/slave/test/properties/build_url 09:00:01 INFO - Contents: 09:00:01 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:02 INFO - Running post-action listener: _resource_record_post_action 09:00:02 INFO - Running post-action listener: set_extra_try_arguments 09:00:02 INFO - ##### 09:00:02 INFO - ##### Running populate-webroot step. 09:00:02 INFO - ##### 09:00:02 INFO - Running pre-action listener: _resource_record_pre_action 09:00:02 INFO - Running main action method: populate_webroot 09:00:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:00:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:00:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:00:02 INFO - '--tppagecycles', 09:00:02 INFO - '7'], 09:00:02 INFO - 'tests': ['tsvgm']}}, 09:00:02 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:00:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:00:02 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:00:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:00:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:00:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:00:02 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:02 INFO - 'talos_options': ['--disable-e10s'], 09:00:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:00:02 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:00:02 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:02 INFO - 'talos_options': ['--disable-e10s'], 09:00:02 INFO - 'tests': ['damp', 'tps']}, 09:00:02 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:02 INFO - 'tests': ['damp', 'tps']}, 09:00:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:00:02 INFO - 'tests': ['dromaeo_dom']}, 09:00:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:00:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:00:02 INFO - 'tests': ['a11yr', 09:00:02 INFO - 'ts_paint', 09:00:02 INFO - 'tpaint', 09:00:02 INFO - 'sessionrestore', 09:00:02 INFO - 'sessionrestore_no_auto_restore', 09:00:02 INFO - 'tabpaint']}, 09:00:02 INFO - 'other-e10s': {'tests': ['a11yr', 09:00:02 INFO - 'ts_paint', 09:00:02 INFO - 'tpaint', 09:00:02 INFO - 'sessionrestore', 09:00:02 INFO - 'sessionrestore_no_auto_restore', 09:00:02 INFO - 'tabpaint']}, 09:00:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:00:02 INFO - 'ts_paint', 09:00:02 INFO - 'tpaint', 09:00:02 INFO - 'sessionrestore', 09:00:02 INFO - 'sessionrestore_no_auto_restore', 09:00:02 INFO - 'tabpaint']}, 09:00:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:00:02 INFO - 'ts_paint', 09:00:02 INFO - 'tpaint', 09:00:02 INFO - 'sessionrestore', 09:00:02 INFO - 'sessionrestore_no_auto_restore', 09:00:02 INFO - 'tabpaint']}, 09:00:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:00:02 INFO - 'tests': ['a11yr', 09:00:02 INFO - 'ts_paint', 09:00:02 INFO - 'tpaint', 09:00:02 INFO - 'sessionrestore', 09:00:02 INFO - 'sessionrestore_no_auto_restore', 09:00:02 INFO - 'tabpaint']}, 09:00:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:00:02 INFO - 'tests': ['a11yr', 09:00:02 INFO - 'ts_paint', 09:00:02 INFO - 'tpaint', 09:00:02 INFO - 'sessionrestore', 09:00:02 INFO - 'sessionrestore_no_auto_restore', 09:00:02 INFO - 'tabpaint']}, 09:00:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:00:02 INFO - 'tests': ['tsvgx', 09:00:02 INFO - 'tsvgr_opacity', 09:00:02 INFO - 'tart', 09:00:02 INFO - 'tscrollx', 09:00:02 INFO - 'cart']}, 09:00:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:00:02 INFO - 'tsvgr_opacity', 09:00:02 INFO - 'tart', 09:00:02 INFO - 'tscrollx', 09:00:02 INFO - 'cart']}, 09:00:02 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:02 INFO - 'talos_options': ['--disable-e10s'], 09:00:02 INFO - 'tests': ['tp5o']}, 09:00:02 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:02 INFO - 'tests': ['tp5o']}, 09:00:02 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:02 INFO - 'talos_options': ['--disable-e10s', 09:00:02 INFO - '--xperf_path', 09:00:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:00:02 INFO - 'tests': ['tp5n']}, 09:00:02 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:00:02 INFO - 'talos_options': ['--xperf_path', 09:00:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:00:02 INFO - 'tests': ['tp5n']}}, 09:00:02 INFO - 'talos.zip': {'path': '', 09:00:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:00:02 INFO - Running post-action listener: _resource_record_post_action 09:00:02 INFO - ##### 09:00:02 INFO - ##### Running create-virtualenv step. 09:00:02 INFO - ##### 09:00:02 INFO - Running pre-action listener: _resource_record_pre_action 09:00:02 INFO - Running main action method: create_virtualenv 09:00:02 INFO - Creating virtualenv /builds/slave/test/build/venv 09:00:02 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:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:00:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:00:02 INFO - Using real prefix '/usr' 09:00:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:00:02 INFO - Installing distribute.............................................................................................................................................................................................done. 09:00:04 INFO - Installing pip.................done. 09:00:04 INFO - Return code: 0 09:00:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:00:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f13fb23af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a265a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab5410>, '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 0x1ab6310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ab67a0>, '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': 'cbe409c6de289db6ac707de3000001d4-1461685951.312872-1003828145', '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:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:00:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:00:04 INFO - Using env: {'DISPLAY': ':0', 09:00:04 INFO - 'HOME': '/home/cltbld', 09:00:04 INFO - 'LANG': 'en_US.UTF-8', 09:00:04 INFO - 'LANGUAGE': 'en_US:en', 09:00:04 INFO - 'LOGNAME': 'cltbld', 09:00:04 INFO - 'MAIL': '/var/mail/cltbld', 09:00:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:04 INFO - 'MOZ_NO_REMOTE': '1', 09:00:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:04 INFO - 'NO_EM_RESTART': '1', 09:00:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:04 INFO - 'PWD': '/builds/slave/test', 09:00:04 INFO - 'SHELL': '/bin/bash', 09:00:04 INFO - 'SHLVL': '1', 09:00:04 INFO - 'TERM': 'linux', 09:00:04 INFO - 'TMOUT': '86400', 09:00:04 INFO - 'USER': 'cltbld', 09:00:04 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1461685951.312872-1003828145', 09:00:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:04 INFO - '_': '/tools/buildbot/bin/python'} 09:00:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:05 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:05 INFO - Cleaning up... 09:00:05 INFO - Return code: 0 09:00:05 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:00:05 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:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:05 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:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:05 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 0x7f13fb23af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a265a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab5410>, '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 0x1ab6310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ab67a0>, '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': 'cbe409c6de289db6ac707de3000001d4-1461685951.312872-1003828145', '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:05 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:05 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:05 INFO - Using env: {'DISPLAY': ':0', 09:00:05 INFO - 'HOME': '/home/cltbld', 09:00:05 INFO - 'LANG': 'en_US.UTF-8', 09:00:05 INFO - 'LANGUAGE': 'en_US:en', 09:00:05 INFO - 'LOGNAME': 'cltbld', 09:00:05 INFO - 'MAIL': '/var/mail/cltbld', 09:00:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:05 INFO - 'MOZ_NO_REMOTE': '1', 09:00:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:05 INFO - 'NO_EM_RESTART': '1', 09:00:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:05 INFO - 'PWD': '/builds/slave/test', 09:00:05 INFO - 'SHELL': '/bin/bash', 09:00:05 INFO - 'SHLVL': '1', 09:00:05 INFO - 'TERM': 'linux', 09:00:05 INFO - 'TMOUT': '86400', 09:00:05 INFO - 'USER': 'cltbld', 09:00:05 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1461685951.312872-1003828145', 09:00:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:05 INFO - '_': '/tools/buildbot/bin/python'} 09:00:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:05 INFO - Downloading/unpacking psutil>=0.7.1 09:00:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:00:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:00:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:00:07 INFO - Installing collected packages: psutil 09:00:07 INFO - Running setup.py install for psutil 09:00:07 INFO - building 'psutil._psutil_linux' extension 09:00:07 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:07 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:07 INFO - building 'psutil._psutil_posix' extension 09:00:07 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:07 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:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:00:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:00:07 INFO - Successfully installed psutil 09:00:07 INFO - Cleaning up... 09:00:07 INFO - Return code: 0 09:00:07 INFO - Installing mozsystemmonitor==0.0.0 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', '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 0x7f13fb23af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a265a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab5410>, '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 0x1ab6310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ab67a0>, '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': 'cbe409c6de289db6ac707de3000001d4-1461685951.312872-1003828145', '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', 'mozsystemmonitor==0.0.0'] 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 mozsystemmonitor==0.0.0 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': 'cbe409c6de289db6ac707de3000001d4-1461685951.312872-1003828145', 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 - Downloading/unpacking mozsystemmonitor==0.0.0 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:09 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:00:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:00:09 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:09 INFO - Installing collected packages: mozsystemmonitor 09:00:09 INFO - Running setup.py install for mozsystemmonitor 09:00:10 INFO - Successfully installed mozsystemmonitor 09:00:10 INFO - Cleaning up... 09:00:10 INFO - Return code: 0 09:00:10 INFO - Installing blobuploader==1.2.4 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', '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 0x7f13fb23af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a265a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab5410>, '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 0x1ab6310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ab67a0>, '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': 'cbe409c6de289db6ac707de3000001d4-1461685951.312872-1003828145', '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', 'blobuploader==1.2.4'] 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 blobuploader==1.2.4 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': 'cbe409c6de289db6ac707de3000001d4-1461685951.312872-1003828145', 09:00:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:10 INFO - '_': '/tools/buildbot/bin/python'} 09:00:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:10 INFO - Downloading/unpacking blobuploader==1.2.4 09:00:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:12 INFO - Downloading blobuploader-1.2.4.tar.gz 09:00:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:00:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:00:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:00:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:00:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:00:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:00:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:00:12 INFO - Downloading docopt-0.6.1.tar.gz 09:00:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:00:12 INFO - Installing collected packages: blobuploader, requests, docopt 09:00:12 INFO - Running setup.py install for blobuploader 09:00:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:00:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:00:13 INFO - Running setup.py install for requests 09:00:13 INFO - Running setup.py install for docopt 09:00:13 INFO - Successfully installed blobuploader requests docopt 09:00:13 INFO - Cleaning up... 09:00:13 INFO - Return code: 0 09:00:13 INFO - Installing None 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', '--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 0x7f13fb23af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a265a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab5410>, '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 0x1ab6310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ab67a0>, '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': 'cbe409c6de289db6ac707de3000001d4-1461685951.312872-1003828145', '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', '--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:13 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: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': 'cbe409c6de289db6ac707de3000001d4-1461685951.312872-1003828145', 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 - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:00:13 INFO - Running setup.py (path:/tmp/pip-q5TTVO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:00:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:00:13 INFO - Running setup.py (path:/tmp/pip-DH6YuH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:00:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:00:13 INFO - Running setup.py (path:/tmp/pip-G_mH9O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:00:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:00:13 INFO - Running setup.py (path:/tmp/pip-EAJZiM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:00:14 INFO - Running setup.py (path:/tmp/pip-WtQjB0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:00:14 INFO - Running setup.py (path:/tmp/pip-L6At56-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:00:14 INFO - Running setup.py (path:/tmp/pip-QKStnh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:00:14 INFO - Running setup.py (path:/tmp/pip-dZlzl0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:00:14 INFO - Running setup.py (path:/tmp/pip-et8fLe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:00:14 INFO - Running setup.py (path:/tmp/pip-hbXwCe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:00:14 INFO - Running setup.py (path:/tmp/pip-_rS0Oq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:00:14 INFO - Running setup.py (path:/tmp/pip-kl_5N8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:00:14 INFO - Running setup.py (path:/tmp/pip-I1_XL_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:00:14 INFO - Running setup.py (path:/tmp/pip-oedTjU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:14 INFO - Running setup.py (path:/tmp/pip-5chu4p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:00:14 INFO - Running setup.py (path:/tmp/pip-FnnFIw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:00:14 INFO - Running setup.py (path:/tmp/pip-GASTdc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:00:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:00:14 INFO - Running setup.py install for manifestparser 09:00:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:00:15 INFO - Running setup.py install for mozcrash 09:00:15 INFO - Running setup.py install for mozdebug 09:00:15 INFO - Running setup.py install for mozdevice 09:00:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:00:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:00:15 INFO - Running setup.py install for mozfile 09:00:15 INFO - Running setup.py install for mozhttpd 09:00:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:00:15 INFO - Running setup.py install for mozinfo 09:00:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:00:15 INFO - Running setup.py install for mozInstall 09:00:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:00:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:00:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:00:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:00:15 INFO - Running setup.py install for mozleak 09:00:15 INFO - Running setup.py install for mozlog 09:00:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:00:16 INFO - Running setup.py install for moznetwork 09:00:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:00:16 INFO - Running setup.py install for mozprocess 09:00:16 INFO - Running setup.py install for mozprofile 09:00:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:00:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:00:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:00:16 INFO - Running setup.py install for mozrunner 09:00:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:00:16 INFO - Running setup.py install for mozscreenshot 09:00:16 INFO - Running setup.py install for moztest 09:00:16 INFO - Running setup.py install for mozversion 09:00:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:00:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 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', '--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 0x7f13fb23af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a265a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab5410>, '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 0x1ab6310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ab67a0>, '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': 'cbe409c6de289db6ac707de3000001d4-1461685951.312872-1003828145', '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', '--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 --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': 'cbe409c6de289db6ac707de3000001d4-1461685951.312872-1003828145', 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-ywrqhf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:00:17 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:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:00:17 INFO - Running setup.py (path:/tmp/pip-caN0G0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:00:17 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:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:00:17 INFO - Running setup.py (path:/tmp/pip-nOR2sN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:00:17 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:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:00:17 INFO - Running setup.py (path:/tmp/pip-FAUznn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:00:17 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:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:00:17 INFO - Running setup.py (path:/tmp/pip-pJ7isi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:00:17 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:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:00:17 INFO - Running setup.py (path:/tmp/pip-UIul4u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:00:17 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:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:00:17 INFO - Running setup.py (path:/tmp/pip-0rTxK3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:00:17 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:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:00:17 INFO - Running setup.py (path:/tmp/pip-mop2sX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:00:17 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:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:00:17 INFO - Running setup.py (path:/tmp/pip-syMs1d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:00:17 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:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:00:17 INFO - Running setup.py (path:/tmp/pip-SqC3ct-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:00:17 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:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:00:17 INFO - Running setup.py (path:/tmp/pip-anlyai-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:00:17 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:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:00:17 INFO - Running setup.py (path:/tmp/pip-HCVzgn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:00:17 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:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:00:17 INFO - Running setup.py (path:/tmp/pip-nSlza2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:00:17 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:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:00:17 INFO - Running setup.py (path:/tmp/pip-8GPF93-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:00:18 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:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:18 INFO - Running setup.py (path:/tmp/pip-ngAJ9s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:00:18 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:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:00:18 INFO - Running setup.py (path:/tmp/pip-Rid_5z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:00:18 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:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:00:18 INFO - Running setup.py (path:/tmp/pip-xjvJGF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:00:18 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:18 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:18 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:18 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:18 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:18 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:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:00:18 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:18 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:18 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:18 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:18 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:18 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:20 INFO - Downloading blessings-1.6.tar.gz 09:00:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:00:20 INFO - Installing collected packages: blessings 09:00:20 INFO - Running setup.py install for blessings 09:00:20 INFO - Successfully installed blessings 09:00:20 INFO - Cleaning up... 09:00:20 INFO - Return code: 0 09:00:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:00:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:00:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:00:20 INFO - Reading from file tmpfile_stdout 09:00:20 INFO - Current package versions: 09:00:20 INFO - argparse == 1.2.1 09:00:20 INFO - blessings == 1.6 09:00:20 INFO - blobuploader == 1.2.4 09:00:20 INFO - docopt == 0.6.1 09:00:20 INFO - manifestparser == 1.1 09:00:20 INFO - mozInstall == 1.12 09:00:20 INFO - mozcrash == 0.17 09:00:20 INFO - mozdebug == 0.1 09:00:20 INFO - mozdevice == 0.48 09:00:20 INFO - mozfile == 1.2 09:00:20 INFO - mozhttpd == 0.7 09:00:20 INFO - mozinfo == 0.9 09:00:20 INFO - mozleak == 0.1 09:00:20 INFO - mozlog == 3.1 09:00:20 INFO - moznetwork == 0.27 09:00:20 INFO - mozprocess == 0.22 09:00:20 INFO - mozprofile == 0.28 09:00:20 INFO - mozrunner == 6.11 09:00:20 INFO - mozscreenshot == 0.1 09:00:20 INFO - mozsystemmonitor == 0.0 09:00:20 INFO - moztest == 0.7 09:00:20 INFO - mozversion == 1.4 09:00:20 INFO - psutil == 3.1.1 09:00:20 INFO - requests == 1.2.3 09:00:20 INFO - wsgiref == 0.1.2 09:00:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:00:20 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:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:20 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:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:20 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 0x7f13fb23af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a265a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab5410>, '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 0x1ab6310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ab67a0>, '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': 'cbe409c6de289db6ac707de3000001d4-1461685951.312872-1003828145', '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:20 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:20 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:20 INFO - Using env: {'DISPLAY': ':0', 09:00:20 INFO - 'HOME': '/home/cltbld', 09:00:20 INFO - 'LANG': 'en_US.UTF-8', 09:00:20 INFO - 'LANGUAGE': 'en_US:en', 09:00:20 INFO - 'LOGNAME': 'cltbld', 09:00:20 INFO - 'MAIL': '/var/mail/cltbld', 09:00:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:20 INFO - 'MOZ_NO_REMOTE': '1', 09:00:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:20 INFO - 'NO_EM_RESTART': '1', 09:00:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:20 INFO - 'PWD': '/builds/slave/test', 09:00:20 INFO - 'SHELL': '/bin/bash', 09:00:20 INFO - 'SHLVL': '1', 09:00:20 INFO - 'TERM': 'linux', 09:00:20 INFO - 'TMOUT': '86400', 09:00:20 INFO - 'USER': 'cltbld', 09:00:20 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1461685951.312872-1003828145', 09:00:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:20 INFO - '_': '/tools/buildbot/bin/python'} 09:00:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:20 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:20 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:20 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:20 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:20 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:20 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:20 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:20 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:20 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:20 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:20 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:20 INFO - Cleaning up... 09:00:20 INFO - Return code: 0 09:00:20 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:00:20 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:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:00:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:20 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:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:00:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:00:20 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 0x7f13fb23af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a265a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab5410>, '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 0x1ab6310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ab67a0>, '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': 'cbe409c6de289db6ac707de3000001d4-1461685951.312872-1003828145', '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:20 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:20 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:20 INFO - Using env: {'DISPLAY': ':0', 09:00:20 INFO - 'HOME': '/home/cltbld', 09:00:20 INFO - 'LANG': 'en_US.UTF-8', 09:00:20 INFO - 'LANGUAGE': 'en_US:en', 09:00:20 INFO - 'LOGNAME': 'cltbld', 09:00:20 INFO - 'MAIL': '/var/mail/cltbld', 09:00:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:20 INFO - 'MOZ_NO_REMOTE': '1', 09:00:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:20 INFO - 'NO_EM_RESTART': '1', 09:00:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:20 INFO - 'PWD': '/builds/slave/test', 09:00:20 INFO - 'SHELL': '/bin/bash', 09:00:20 INFO - 'SHLVL': '1', 09:00:20 INFO - 'TERM': 'linux', 09:00:20 INFO - 'TMOUT': '86400', 09:00:20 INFO - 'USER': 'cltbld', 09:00:20 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1461685951.312872-1003828145', 09:00:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:20 INFO - '_': '/tools/buildbot/bin/python'} 09:00:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:00:20 INFO - Downloading/unpacking jsonschema 09:00:20 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:20 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:20 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:20 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:20 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:20 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:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:00:22 INFO - Downloading/unpacking functools32 (from jsonschema) 09:00:22 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:22 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:22 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:22 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:22 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:22 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 functools32-3.2.3-2.tar.gz 09:00:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:00:23 INFO - warning: no files found matching '*.txt' 09:00:23 INFO - no previously-included directories found matching 'build' 09:00:23 INFO - no previously-included directories found matching 'dist' 09:00:23 INFO - no previously-included directories found matching '.git*' 09:00:23 INFO - Installing collected packages: jsonschema, functools32 09:00:23 INFO - Running setup.py install for functools32 09:00:23 INFO - warning: no files found matching '*.txt' 09:00:23 INFO - no previously-included directories found matching 'build' 09:00:23 INFO - no previously-included directories found matching 'dist' 09:00:23 INFO - no previously-included directories found matching '.git*' 09:00:23 INFO - Successfully installed jsonschema functools32 09:00:23 INFO - Cleaning up... 09:00:23 INFO - Return code: 0 09:00:23 INFO - Installing flake8 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f13fb23af10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1a265a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1ab5410>, '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 0x1ab6310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ab67a0>, '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': 'cbe409c6de289db6ac707de3000001d4-1461685951.312872-1003828145', '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', 'flake8'] 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 flake8 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': 'cbe409c6de289db6ac707de3000001d4-1461685951.312872-1003828145', 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 - Downloading/unpacking flake8 09:00:23 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:23 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 - 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:23 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:23 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:23 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 flake8-2.5.0-py2.py3-none-any.whl 09:00:25 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:00:25 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:25 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 - 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:25 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:25 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:25 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 mccabe-0.3.1-py2.py3-none-any.whl 09:00:25 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:00:25 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:25 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 - 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:25 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:25 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:25 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/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:00:25 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:25 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 - 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:25 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:25 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:25 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 - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:00:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:00:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:00:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:00:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:00:26 INFO - no previously-included directories found matching 'docs/_build' 09:00:26 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:00:26 INFO - Running setup.py install for pep8 09:00:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:00:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:00:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:00:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:00:26 INFO - no previously-included directories found matching 'docs/_build' 09:00:26 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:00:26 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:00:26 INFO - Cleaning up... 09:00:26 INFO - Return code: 0 09:00:26 INFO - Running post-action listener: _resource_record_post_action 09:00:26 INFO - Running post-action listener: _start_resource_monitoring 09:00:26 INFO - Starting resource monitoring. 09:00:26 INFO - ##### 09:00:26 INFO - ##### Running install step. 09:00:26 INFO - ##### 09:00:26 INFO - Running pre-action listener: _resource_record_pre_action 09:00:26 INFO - Running main action method: install 09:00:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:00:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:00:26 INFO - Reading from file tmpfile_stdout 09:00:26 INFO - Detecting whether we're running mozinstall >=1.0... 09:00:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:00:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:00:26 INFO - Reading from file tmpfile_stdout 09:00:26 INFO - Output received: 09:00:26 INFO - Usage: mozinstall [options] installer 09:00:26 INFO - Options: 09:00:26 INFO - -h, --help show this help message and exit 09:00:26 INFO - -d DEST, --destination=DEST 09:00:26 INFO - Directory to install application into. [default: 09:00:26 INFO - "/builds/slave/test"] 09:00:26 INFO - --app=APP Application being installed. [default: firefox] 09:00:26 INFO - mkdir: /builds/slave/test/build/application 09:00:26 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:26 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:42 INFO - Reading from file tmpfile_stdout 09:00:42 INFO - Output received: 09:00:42 INFO - /builds/slave/test/build/application/firefox/firefox 09:00:42 INFO - Running post-action listener: _resource_record_post_action 09:00:42 INFO - ##### 09:00:42 INFO - ##### Running run-tests step. 09:00:42 INFO - ##### 09:00:42 INFO - Running pre-action listener: _resource_record_pre_action 09:00:42 INFO - Running main action method: run_tests 09:00:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:00:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:00:42 INFO - Python 2.7.3 09:00:42 INFO - Return code: 0 09:00:42 INFO - grabbing minidump binary from tooltool 09:00:42 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:42 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 0x1ab5410>, '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 0x1ab6310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1ab67a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:00:42 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:42 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:42 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:00:42 INFO - Return code: 0 09:00:42 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:00:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:00:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:00:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:00:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:00:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:00:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:00:43 INFO - Return code: 0 09:00:43 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-e10s', '--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-054', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:00:43 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-e10s --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-054 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:00:43 INFO - Using env: {'DISPLAY': ':0', 09:00:43 INFO - 'HOME': '/home/cltbld', 09:00:43 INFO - 'LANG': 'en_US.UTF-8', 09:00:43 INFO - 'LANGUAGE': 'en_US:en', 09:00:43 INFO - 'LOGNAME': 'cltbld', 09:00:43 INFO - 'MAIL': '/var/mail/cltbld', 09:00:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:00:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:00:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:00:43 INFO - 'MOZ_NO_REMOTE': '1', 09:00:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:00:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:00:43 INFO - 'NO_EM_RESTART': '1', 09:00:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:00:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:00:43 INFO - 'PWD': '/builds/slave/test', 09:00:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:00:43 INFO - 'SHELL': '/bin/bash', 09:00:43 INFO - 'SHLVL': '1', 09:00:43 INFO - 'TERM': 'linux', 09:00:43 INFO - 'TMOUT': '86400', 09:00:43 INFO - 'USER': 'cltbld', 09:00:43 INFO - 'XDG_SESSION_COOKIE': 'cbe409c6de289db6ac707de3000001d4-1461685951.312872-1003828145', 09:00:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:00:43 INFO - '_': '/tools/buildbot/bin/python'} 09:00:43 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-e10s', '--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-054', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:00:43 INFO - mozversion application_buildid: 20160426084357 09:00:43 INFO - mozversion application_changeset: 8c55d8beba757967f6f5f7ff386499e8c6823c11 09:00:43 INFO - mozversion application_display_name: Nightly 09:00:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:00:43 INFO - mozversion application_name: Firefox 09:00:43 INFO - mozversion application_remotingname: firefox 09:00:43 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:00:43 INFO - mozversion application_vendor: Mozilla 09:00:43 INFO - mozversion application_version: 49.0a1 09:00:43 INFO - mozversion platform_buildid: 20160426084357 09:00:43 INFO - mozversion platform_changeset: 8c55d8beba757967f6f5f7ff386499e8c6823c11 09:00:43 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:00:43 INFO - mozversion platform_version: 49.0a1 09:00:43 INFO - using testdate: 1461686443 09:00:43 INFO - actual date: 1461686443 09:00:43 INFO - starting webserver on 'localhost:59529' 09:00:43 INFO - SUITE-START | Running 2 tests 09:00:43 INFO - TEST-START | dromaeo_css 09:00:43 INFO - operating with platform_type : linux_ 09:00:43 INFO - Initialising browser for dromaeo_css test... 09:00:43 INFO - TEST-INFO | started process 9317 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpD5AJZr/profile http://localhost:59529/getInfo.html) 09:00:47 INFO - PROCESS | 9317 | 09:00:47 INFO - PROCESS | 9317 | (process:9386): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 09:00:48 INFO - PROCESS | 9317 | __metrics Screen width/height:1600/1200 09:00:48 INFO - PROCESS | 9317 | colorDepth:24 09:00:48 INFO - PROCESS | 9317 | Browser inner width/height: 1024/697 09:00:48 INFO - PROCESS | 9317 | __metrics 09:00:49 ERROR - PROCESS | 9317 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 09:00:50 INFO - TEST-INFO | 9317: exit 0 09:00:50 INFO - Browser initialized. 09:00:50 INFO - Running cycle 1/1 for dromaeo_css test... 09:00:50 INFO - TEST-INFO | started process 9437 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpD5AJZr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:00:52 INFO - PROCESS | 9437 | 09:00:52 INFO - PROCESS | 9437 | (process:9499): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 09:01:02 INFO - PROCESS | 9437 | RSS: Main: 166133760 09:01:02 INFO - PROCESS | 9437 | 09:01:52 INFO - PROCESS | 9437 | 1461686512104 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:03 INFO - PROCESS | 9437 | Cycle 1(1): loaded http://localhost:59529/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59529/tests/dromaeo/cssquery-ext.html) 09:04:04 INFO - PROCESS | 9437 | RSS: Main: 168378368 09:04:04 INFO - PROCESS | 9437 | 09:07:06 INFO - PROCESS | 9437 | Cycle 1(1): loaded http://localhost:59529/tests/dromaeo/cssquery-ext.html (next: http://localhost:59529/tests/dromaeo/cssquery-jquery.html) 09:07:06 INFO - PROCESS | 9437 | RSS: Main: 167800832 09:07:06 INFO - PROCESS | 9437 | 09:10:08 INFO - PROCESS | 9437 | Cycle 1(1): loaded http://localhost:59529/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59529/tests/dromaeo/cssquery-mootools.html) 09:10:09 INFO - PROCESS | 9437 | RSS: Main: 168280064 09:10:09 INFO - PROCESS | 9437 | 09:13:11 INFO - PROCESS | 9437 | Cycle 1(1): loaded http://localhost:59529/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59529/tests/dromaeo/cssquery-prototype.html) 09:13:11 INFO - PROCESS | 9437 | RSS: Main: 166211584 09:13:11 INFO - PROCESS | 9437 | 09:16:14 INFO - PROCESS | 9437 | Cycle 1(1): loaded http://localhost:59529/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59529/tests/dromaeo/cssquery-yui.html) 09:16:15 INFO - PROCESS | 9437 | RSS: Main: 167075840 09:16:15 INFO - PROCESS | 9437 | 09:19:17 INFO - PROCESS | 9437 | Cycle 1(1): loaded http://localhost:59529/tests/dromaeo/cssquery-yui.html (next: http://localhost:59529/tests/dromaeo/cssquery-dojo.html) 09:19:17 INFO - PROCESS | 9437 | RSS: Main: 169086976 09:19:17 INFO - PROCESS | 9437 | 09:19:17 INFO - PROCESS | 9437 | __start_tp_report 09:19:17 INFO - PROCESS | 9437 | _x_x_mozilla_page_load 09:19:17 INFO - PROCESS | 9437 | _x_x_mozilla_page_load_details 09:19:17 INFO - PROCESS | 9437 | |i|pagename|runs| 09:19:17 INFO - PROCESS | 9437 | |0;dojo.html;2577.098015694507;2642.775364094052;2657.9335516612077;2699.6355492008574;2701.0951331304022;4565.269556870897;4577.404937358147;4600.700954437959;4608.746518941465;4638.3274425208365;4558;4571.817127314912;4612.262038073909;4663.160631086403;4687.343771871941;4191.664666826662;4267.829286828523;4269.701120921537;4291.806868690916;4328.718633288826;531.5380020652112;4206.642435392995;4255.957440425587;4268.274393353137;4301.483821941356;4230.02709376842;4251.3622956556455;4259.382389553529;4267.338562522814;4283.421738065353;4127.050778320973;4242.745435273893;4275.209086319041;4295.334223195409;4338.457692788402;4191;4217.936730949007;4265;4270.679699022555;4272;4494.1461122386645;4497.572730590621;4539.931901021488;4540.477845047829;4598.172328980782;4914.410270767495;4970.6520543561965;4982.9252561211615;4995.275685025687;4999.100161970844;4982.676126051795;5000.374953130859;5002.399712034579;5019.498050194988;5046.772895219688;4614.146382919137;4655.511171326996;4660.836870709577;4672.345871577982;4709.952900470951;4945.406551213878;4994.650374473825;5103.719295438712;5109.770060347293;5118.411382690963;4858.7570621468785;4864.197407427735;4890.095332363453;4900.975495122501;4924.630652701062;4963.925541116886;4964.60283177345;4968.850934471971;4989.376327959005;5017.899642007213;4779.187538118528;4903.26451032348;4984.576311013604;4986.376702912136;5009.5741861941415;4710.128626204095;4838.806447742121;4851.805927762921;4860.854174374775;4967;4284.614384705392;4304.827806887752;4305.07440900208;4334;4351.43431353922;3682.1531047859144;3717.368047431943;3772.641599048086;3773.981130094332;3792.7724336539895;4344.891377715582;4359.1717573661535;4374.365716971021;4396.626286765661;4407.977960110179;4886.828960986349;4936.062148191832;4965.155923493015;4981.277714731344;5011.649184557046;4218.325067989107;4298.226319262562;4346.130773845243;4346.847860324873;4373.737575745402;4513.887152821206;4527.185106680829;4530.411046563926;4553.134904368159;4563.406757121553;4703.529647035338;4723.373068696766;4727.479977202505;4733.810647574059;4778.116048530964;4841.806327746851;4920.36035315421;4987.775550100327;4995;5017.899642007213;4651.930221046755;4670.416197975253;4682.367880336247;4700;4757.333973243818;4901.5588597026435;4904.6566740327835;4914.508549145057;4922.532359425849;4974.676646017997;4825.6139508838505;4908.214685650279;4972.950270497248;4981.4769449207315;5021.72380519075;4822.397200349957;4824.421069471686;4837.975810120927;4840.951590484191;4883.951160488492;4600;4758.191107511662;4820.1805693031565;4824.782884770124;4885.511448855228;4627.005193883331;4665.930011049769;4719.150552900511;4814.7833347498745;4846.757662116951;4895.216765317532;4895.216765317532;4896.926546101882;4902.362692850049;4931.605471562339;4669.18289299378;4786.473487916257;4786.641001924939;4786.808527658994;4787.40157480315 09:19:17 INFO - PROCESS | 9437 | |1;ext.html;563.6082922368953;585.212888664746;587.9941200587992;608.0544752909226;617.4905702795195;29166.12501624952;30082.699173008263;30240.848795756046;30431.391372172544;30638.080857574252;30407.391852162942;30864.228394290098;30917.22706932328;30946;30974.845125774395;7327.926720732791;7501.4748967572295;7508.662110205027;7509.211532789061;7511.248875112499;7348.301911318443;7481.177070522238;7482.326590606845;7501.774946751606;7574.280443357872;7661.578613176273;7766;7771.378289736807;7788.961055194716;7798.883016754753;7515.361194298491;7554.735584254552;7560.130584982742;7566;7606.201348858345;7424.146223184349;7492.100947886234;7518.210587888302;7528.58592777397;7562.205968373351;31229.843850780602;31884.6811531884;32164;32628.510572341434;32922.6707732922;2223.9332820015425;2667.213172114231;2667.2398366465463;2959.7632189424794;3047.360054388581;20835.1665933364;20838.5832283355;20924.476888077766;24612.753872461406;25085.749142508525;3312.271300313927;3318.785955167635;3352.396568617648;3388.9830550847337;3436.347094052122;173969;174313;174717.1264143684;175072;175173;25880.352991174994;29111.088891110718;29606.407871842443;30191.245218869703;30208.395832083235;28752.281192969924;28767.42465150685;28881;28989;29197.416051678847;12043.698907527207;12366.258024518558;12396;12402.13185077036;12417.875821241852;10083.899161008296;10116.54475548602;10153.593856245816;10183.7454063649;10199.490025498695;7449.404047676173;7462.477626566197;7556.508826926342;7589.658465369072;7601.695932162652;2907.7819163562817;2979.5381715834083;2983.761299096067;2987.521996480553;3025.7125573070525;736.4100012439358;759.517706256531;809.3080416244144;842.30509829391;878.6792820620556;23863;24264.51470970571;24392.390190245387;24805.379865503506;24953.750462495507;898.1961144775389;929.990700093004;939.0140352629847;962.0571839597234;994.5325696922434;12294.938525307316;12470.251784892935;12838.229706217657;12907.806382904264;12975.675608109685;274056;278162;278176;278211;281181;43853;44035.33946990798;44405.33391999122;44532.77733611376;44835;1078.805814953316;1098.3310555350574;1099.769048499808;1106.4578356605366;1113.1484414422973;23403.76596234016;24224.152154674503;24316.878415607807;24370.147044853344;24523.877380612983;32823.34353312968;33420.331593368486;33648.66351336553;33666.49500257547;34892.30215395627;248244;250993;251546;253226;253548;31379.529307059955;31687;31694.573744182027;31975.520367194047;32002.83998579992;34185.316293674485;35125.824370877985;35454.29091418105;35558.822205888806;35566.822165889;29361.85319073391;30781.23046923845;30942.845285773426;30958.22604434907;31137.688623113478;30047;30305.848470757504;30426.391472170875;30819.53770693486;30898.845505773228;24831;31122.377552449278;31351.84324078365;31727;32234.194145145622;28702.712972870006;30163.698363016967;30561.694383055885;31331.060068197992;32255.35489290154;25003.374915627253;25595.872020639777;25859.612105818804;25986.22041338763;26068.43589384643 09:19:17 INFO - PROCESS | 9437 | |2;jquery.html;352.55049811490346;357.3424898187335;364.3769154745385;371.29454037329054;372.7614326830829;13924.582262532127;14259.215743134124;14760.778588321156;14792.18642974636;14881.851181488182;13307.733845323111;13345.53290634828;14469.059511131769;14523.12861228327;14581.19803410812;4821.783019764111;4920.114379411704;4975.9004819903585;4989.301497790312;5226.294450249225;5116.258142569325;5141;5153.433122356538;5164.354455693038;5177.068127737006;5041;5337.092694241988;5348.090824559815;5357.491038351351;5374.355077390699;5109.846704598868;5299.8410047698635;5306.761195746181;5308.283381743454;5311.256424100629;5150.227465880148;5237.633365664405;5244.265802787575;5262.736863156827;5280.894382112375;10737.785244295148;11208.887911120948;11357;17565.560860978578;17933.461996140137;1791.668541319847;1876.8498520118358;1935.7144275248716;1950.2783969931074;2170.9565808683897;8519.081222637595;8584.74245772628;8852.557372131432;8991.280697544245;9161.862572061358;1695.983040169595;1798.390445494633;1809.493341864267;1814.5917168637084;1841.0426578179272;51381.22928156081;54027.72986135083;55524;57543.13685294724;59691.40308596902;9037.457752534869;9190.264778817678;9482.857757133648;9500.762480937894;9586.233101351874;12786.168899021533;12882.355882205853;12891.16207446513;12922.353882305848;12932;6677.232118306457;6790.660466976631;6808.9659551701925;6855.931440685629;6933.514653974174;6628.668566571652;6700.765473208512;6788.185417749803;6798.184217893884;6826.419754320839;4172.395002724588;4212.810423530948;4235.407043013981;4311.698181127354;4357.281048627005;1778.6175972166047;1809.8190180981796;1814.8820326678724;1828.3052440072954;1840.3742727472456;716.9032180655649;737.4395459450856;774.6823802241054;822.9218224268685;858.7552547524051;14775.63060923464;15072.472463463726;15130.621734456727;15296;15587.06477611347;806.3186607316808;809.1503920883049;810.7040930060604;817.5912043978011;830;8579.184977427134;8583.484990900566;9251.352405331561;9298.395604285699;9417.387869788441;243247;249330;249938;250202;369001;49167.50832491629;65297;67274.66362668155;67379.32620673829;69654.65172674206;925.1765928323797;930.6277489004452;967.0957654592837;971.8930917599058;978.7699890525579;14748.778768318698;14772;14781.630459238606;14994.625134371729;15056.171910545025;31687.05618537436;32725.50911736278;33599;33599.496007560396;33714.662853371156;45224.32163517484;45429.31856022229;45715.31427028531;45875.082498350515;45965.77017114893;7397.593132377771;7473.140588832136;7617.619119044137;7781.610919454118;7797.571133587707;10707.143428525544;11430.28545718149;11468.31190128595;11474.196806223485;11522.355061819604;9434.150926416656;9984.700458986243;10011.149052330633;10146.340487868265;10361.63734269297;9551.856722149034;9989.650362237287;10017;10113.59545618167;10338;10541.420221887869;10621.097206737513;10630.202734795075;10802.919708029134;10863.565457381615;7671.232876712284;7685.154632990479;7690.461667683432;7773.028371453568;7829.256220659028;9528.428294302365;10366.844497332697;10581.735456613646;10582.841257380991;10826.188035897496 09:19:17 INFO - PROCESS | 9437 | |3;mootools.html;1267.4043199696143;1289.8645642207568;1304.4477072283983;1518.7873697682326;1533.2103966457269;6345.23857137143;7208.603526806023;7244.601546914918;7430.294122058409;7523.510971786837;7297.2702729727;7321.194668586464;7379.483436159471;7423.22056184101;7424.777256682295;2187.048633844279;2212.6127927612683;2216.5345277491747;2221.31139346803;2223.5108276179217;2168.1652563762987;2169.4359466538654;2234.497238121426;2237.630790919496;2241.316398498457;2173.4892300309416;2197.604431202383;2201.8018378345946;2206.7682893296214;2249.010435408415;2166.2959538150085;2174.978250217501;2176.7714389988982;2181.814545763612;2187.9343619691435;2133.4666333416644;2133.9253126140666;2161.2003558683386;2165.8483906126576;2171.826253899684;7445.8883116753295;7476.439267054939;7478.626068696543;7489.550626962399;7509.699612015513;4500.8649740507835;4657.301404789308;4660.18446771817;4667.486576476585;4678.228092364756;2825.872835722397;2840.4887120318335;2942.926426839325;2964.3626620276527;2971.747401470878;888.5335199020501;924.1867156901883;946.9621215151454;947.1049857884302;961.8653388525612;9685.806283874354;9945.453000085065;9949.950250248774;9962.053604907522;9972.252081093948;4020.3768415895584;4051.493563304587;4161;4162.188373267184;4180.435641188401;7929.841403171904;7936.246056624612;7942.404319675932;7943.364530837519;7954.840903181904;4403.691741578059;4459.264221403497;4482.8655140345845;4483.686141970096;4497.955020449819;3473.9826300868335;3935.5670876203594;4081.4490043844294;4119.485064366954;4132.793360331972;3248.3016151527536;3274.1323549259464;3317.303366293057;3345.2974875275977;3405.250844814137;1020.260311274335;1027.8869324374314;1029.552144816992;1046.8325067989085;1049.9370037797758;668.3650531994624;802.2659266770839;803.883436901646;810.3638643664694;816.4856140631483;4957.3059771632;5118.129917913963;5171.663841850268;5219.060569097599;5251.186066159751;798.1818635898251;835.4736515994884;853.9274161696201;855.4225897519274;858.1289990659485;3514.8242587870504;3519.419295816213;3666.816659167031;3699.8520059197867;3731.600649756341;9248.491332976617;9370.718878433658;9511.096445837635;9512.38169518979;9577.808443831085;9435.858462123073;9438.669646562337;9467.242620590336;9469.526523673789;9481.715548533555;6483;6714.731410743613;6759.121314229119;6901.482388820858;6902.275261097613;5021.623378246719;5043.9747801260755;5074.644774865724;5168.819091331786;5232.659877107976;8383.538905360263;8426.957865210634;8645.178708022728;8695.782605434915;8743.737687869374;8559.95720021396;8658.956705216433;8706.47761134334;8829.337799664921;8870.911290887008;5688.63023903445;6416.550841441054;6608.173978252718;6721.521265321621;6727.024581435567;7042.718291268497;7056.788296351117;7098.29017098286;7102.751403701052;7108.040414543971;6514.120593719787;6645.335466453316;6744.831379215559;6745.5952642841585;6808.557443766139;6537.05212744159;6613.702383392855;6645.036469711768;6734.966325168343;6803.353681400259;6144.354842741626;6192.783252586138;6193.256809182927;6217.533684973555;6253.0620406937505;4329.826806927687;4365.083332500083;4390.516484393695;4441.755703436342;4578.839740609063;4578.816847326069;4587.747673877835;4603.723776573416;4614.492405835288;4657.208274593395 09:19:17 INFO - PROCESS | 9437 | |4;prototype.html;306.7193517931093;309.11901081916557;309.9581556489875;310.4334589374392;320.59444802325055;4853.956399374134;4890.559849613534;4901.852944411669;4904.975475122628;4910.631702622304;4825.300331451937;4828.879278018043;4837.226043832988;4850.68470549414;4878.609711223103;3643.3259846928336;3728.4966529518574;3851.4607954886337;3865.8840234793;3874.767513949172;3837.6546110850018;3877.0501227199375;3880.611938806125;3881.10734531058;3884.2037382336553;3844.384898416254;3850.4031875059272;3858.5176852893387;3859.864904728335;3868.0523271798306;3594.514740510024;3822.9235415291537;3849.2493963677093;3858.594847541024;3866.76799392036;3299.6040475143136;3302.091924720697;3321.368939901411;3322.318924620471;3323.202431416443;4696.365990591261;4714.858554243378;4722.276369125245;4745.454272758642;4771.093492236499;4318.5465526119615;4320.697551171419;4322.956770432287;4331.870043898688;4338.891527711801;5026.145555255579;5059.696418214919;5107.412647545506;5122.154844450662;5140.537351638371;3627.6916462100753;3632;3645.052286405527;3647.598764135943;3652.506911566931;5334.813281535166;5398.271233383522;5409.972950135263;5426;5432.728363581845;3643.3441980443245;3712.4988126603103;3750.2124553843996;3756.3050835595504;3771.07608635886;3781.0736369589627;3784.243151369737;3789.5263092113487;3800.6959443245028;3809.8095095245126;4260.360945858146;4290.334998075304;4290.635295999875;4335.588119128678;4344.370066340363;4985.750712464434;5057.69653820772;5127.9230811537855;5138.768755406016;5145;1371.7805151175764;1377.4145987955078;1379.5585412668052;1389.2428626398435;1392.0464481829777;856.1566856646199;888.4846788862574;904.3443503459939;931.3108299858056;933.5518950903604;4431.955680443219;4580;4590.449146102489;4596.127420362273;4626.6298696104905;5229.450907654717;5279.736013199325;5310.849106112031;5317.760700768475;5324.840254792362;4436.489803672554;4530.682852200314;4584.404027476407;4592.173408786383;4594.874255807348;2963.6443626764926;3030.257586891226;3031.0755219658217;3033.877465337795;3040.9847950760104;5315.335583052119;5332.173513105475;5336.386315573681;5341.919871201935;5347.839564813062;5649.293838270216;5760.654360738369;5768.048272035067;5778.561755755319;5837.970810145922;5309.681419114865;5325.520703136736;5340.652857564246;5351.304330437096;5359.571234301168;5670.60305778608;5677.801276955287;5691.800786972436;5697.857553561194;5710.48672101885;5440.455954404528;5528.226048353315;5554.555635549065;5564.38791732901;5567.916481252865;5257.842264732064;5295;5298.788048478018;5339.199120131856;5344.225087362233;3972.8013599320498;4015.0966032642423;4033.3748269018347;4039.8384064638262;4048.9190216196102;5402.189671549299;5518.558515318685;5574.9442505574425;5585.441455854382;5591.748371323219;5414.945850541444;5433.347998240079;5434.2120392542065;5434.8586796773125;5446.972765136149;5173.456787037458;5366.892662146814;5399.865003374947;5403.8108666196495;5431.538319242909;5350;5365.785368585213;5369.221462887937;5373;5383.7308134593895;4895.951040489549;5024;5147.974260128675;5163.870903227449;5483.945160548344;4970.826021089245;5010.196329321514;5023.648344615842;5053.924191137209;5075.187969924795 09:19:17 INFO - PROCESS | 9437 | |5;yui.html;586.6773274698917;648.0732552449997;652.0805664013742;658.1378394303463;671.7816709569385;12204.81692774608;12308.876911230886;12339.568115115973;12344.753104937896;12405.441755121019;11154.442277886094;11189.72025699358;11843.881561184386;12222.899939005487;12270;509.1217649554526;547.8712502561895;550.1994597860104;593.4866340615358;642.9324920883311;541.204429488651;541.8049502179213;542.4927692607405;543.8776275338037;546.2761840561272;558.9441055894418;562.6764610349065;564.4130104691117;564.8813749112692;565.1635579342576;553.2586334312041;555.2005112637809;556.1685280505629;559.158466507905;559.4601209832531;404.7510780869773;406.4817357868711;407.3665450224899;408.3649924367607;408.79764516564194;10979.231453798237;11004.669859904216;11019.118470522339;11104.833427498594;11138.66584002473;10995.230333876632;11374.260673056226;11424.428778561121;11480.712982175512;11751.647450576496;9633.903660963371;9635.566399511968;9810.166135878459;9883.209343252522;9901;3305.1076209423563;3305.735541156702;3327.2014716468116;3332.3002169544425;3341.248219150696;43654.345184822574;43806;43862.12275754499;44332.33501497448;44671.32993005108;9145.359824812333;9312.48781317034;9395.154436100647;9624.711258662252;9730.172935300438;15291.235438228045;15332.15673137988;15355.61610959735;15417.229138543253;15482.458113965957;5678.432156784371;6095.817125486243;6124.846878827976;6183.721732522047;6272.059191121369;3551.7691350062164;3623.130448692295;3651.6896063834342;3692.593814680383;3696.2237930034457;2537.6574162488196;2598.2205338398403;2616.476704659076;2620.489004644076;2623.357277467033;440.4651232158969;446.9486009108929;447.67767207610467;456.6301295950291;457.72993933579346;468.08722990169304;561.7893290016244;564.7232855900661;585.844751140948;590.2032256453753;7990.360771138295;8019.719309824128;8092.3930705197345;8106.675732970615;8184.304334131665;577.3389469057905;585.2107603268885;588.5674029588249;589.513651237722;610.5909040942579;3758.8684396046556;3759.5824905735653;3764.962350376461;3789.981050094787;3844.058205739612;52384.21423678648;52598;53536.732316338945;53661.731691341294;53673.463265366845;46783.29825052627;47512.28731569029;48063.75968120137;48114.759426203345;48424.51575484271;42402.15195696131;43719.56280437155;43772.3434148488;43793.343099853526;44293.33559996603;7257.165425976081;7372.262773722585;7541.5475071495885;7563.546187228784;7754;29494.705052949197;30538.236544086576;30809.8459507701;31067.84466077655;31281.374372511968;37100.628993710794;39548;39638.40542391924;39674;40452.59547404606;9008.279337653103;9552.761180970532;9555.378900371454;9662.227021838096;9757.365771224848;9874.506274686093;10252;10274.33216840903;10280.331778434378;10280.742981425525;9225.169734723908;9497.762555936157;9608.519574021131;9644.324897257125;9670.226381889292;9327.81344373095;9422.246220302497;9534.284928630243;9604.423734575948;9639.71080867575;10019.649312274327;10077.395356278646;10186.185105191715;10230.181585472996;10609.575616975235;8111.148329425326;8231.794205144919;8371.926794037632;8421.578921054046;8516.574171291535;8079;8110.148434414302;8153.7553873383895;8300.501969881827;8379.70671026511 09:19:17 INFO - PROCESS | 9437 | __end_tp_report 09:19:17 INFO - PROCESS | 9437 | __start_cc_report 09:19:17 INFO - PROCESS | 9437 | _x_x_mozilla_cycle_collect,357 09:19:17 INFO - PROCESS | 9437 | __end_cc_report 09:19:17 INFO - PROCESS | 9437 | __startTimestamp1461687557934__endTimestamp 09:19:17 INFO - PROCESS | 9437 | 09:19:17 INFO - PROCESS | 9437 | ------- Summary: start ------- 09:19:17 INFO - PROCESS | 9437 | Number of tests: 6 09:19:17 INFO - PROCESS | 9437 | 09:19:17 INFO - PROCESS | 9437 | [#0] dojo.html Cycles:165 Average:4570.00 Median:4709.95 stddev:560.16 (11.9%) stddev-sans-first:539.62 09:19:17 INFO - PROCESS | 9437 | Values: 2577.1 2642.8 2657.9 2699.6 2701.1 4565.3 4577.4 4600.7 4608.7 4638.3 4558.0 4571.8 4612.3 4663.2 4687.3 4191.7 4267.8 4269.7 4291.8 4328.7 531.5 4206.6 4256.0 4268.3 4301.5 4230.0 4251.4 4259.4 4267.3 4283.4 4127.1 4242.7 4275.2 4295.3 4338.5 4191.0 4217.9 4265.0 4270.7 4272.0 4494.1 4497.6 4539.9 4540.5 4598.2 4914.4 4970.7 4982.9 4995.3 4999.1 4982.7 5000.4 5002.4 5019.5 5046.8 4614.1 4655.5 4660.8 4672.3 4710.0 4945.4 4994.7 5103.7 5109.8 5118.4 4858.8 4864.2 4890.1 4901.0 4924.6 4963.9 4964.6 4968.9 4989.4 5017.9 4779.2 4903.3 4984.6 4986.4 5009.6 4710.1 4838.8 4851.8 4860.9 4967.0 4284.6 4304.8 4305.1 4334.0 4351.4 3682.2 3717.4 3772.6 3774.0 3792.8 4344.9 4359.2 4374.4 4396.6 4408.0 4886.8 4936.1 4965.2 4981.3 5011.6 4218.3 4298.2 4346.1 4346.8 4373.7 4513.9 4527.2 4530.4 4553.1 4563.4 4703.5 4723.4 4727.5 4733.8 4778.1 4841.8 4920.4 4987.8 4995.0 5017.9 4651.9 4670.4 4682.4 4700.0 4757.3 4901.6 4904.7 4914.5 4922.5 4974.7 4825.6 4908.2 4973.0 4981.5 5021.7 4822.4 4824.4 4838.0 4841.0 4884.0 4600.0 4758.2 4820.2 4824.8 4885.5 4627.0 4665.9 4719.2 4814.8 4846.8 4895.2 4895.2 4896.9 4902.4 4931.6 4669.2 4786.5 4786.6 4786.8 4787.4 09:19:17 INFO - PROCESS | 9437 | 09:19:17 INFO - PROCESS | 9437 | [#1] ext.html Cycles:180 Average:36439.67 Median:30841.88 stddev:62538.47 (202.8%) stddev-sans-first:62655.89 09:19:17 INFO - PROCESS | 9437 | Values: 563.6 585.2 588.0 608.1 617.5 29166.1 30082.7 30240.8 30431.4 30638.1 30407.4 30864.2 30917.2 30946.0 30974.8 7327.9 7501.5 7508.7 7509.2 7511.2 7348.3 7481.2 7482.3 7501.8 7574.3 7661.6 7766.0 7771.4 7789.0 7798.9 7515.4 7554.7 7560.1 7566.0 7606.2 7424.1 7492.1 7518.2 7528.6 7562.2 31229.8 31884.7 32164.0 32628.5 32922.7 2223.9 2667.2 2667.2 2959.8 3047.4 20835.2 20838.6 20924.5 24612.8 25085.7 3312.3 3318.8 3352.4 3389.0 3436.3 173969.0 174313.0 174717.1 175072.0 175173.0 25880.4 29111.1 29606.4 30191.2 30208.4 28752.3 28767.4 28881.0 28989.0 29197.4 12043.7 12366.3 12396.0 12402.1 12417.9 10083.9 10116.5 10153.6 10183.7 10199.5 7449.4 7462.5 7556.5 7589.7 7601.7 2907.8 2979.5 2983.8 2987.5 3025.7 736.4 759.5 809.3 842.3 878.7 23863.0 24264.5 24392.4 24805.4 24953.8 898.2 930.0 939.0 962.1 994.5 12294.9 12470.3 12838.2 12907.8 12975.7 274056.0 278162.0 278176.0 278211.0 281181.0 43853.0 44035.3 44405.3 44532.8 44835.0 1078.8 1098.3 1099.8 1106.5 1113.1 23403.8 24224.2 24316.9 24370.1 24523.9 32823.3 33420.3 33648.7 33666.5 34892.3 248244.0 250993.0 251546.0 253226.0 253548.0 31379.5 31687.0 31694.6 31975.5 32002.8 34185.3 35125.8 35454.3 35558.8 35566.8 29361.9 30781.2 30942.8 30958.2 31137.7 30047.0 30305.8 30426.4 30819.5 30898.8 24831.0 31122.4 31351.8 31727.0 32234.2 28702.7 30163.7 30561.7 31331.1 32255.4 25003.4 25595.9 25859.6 25986.2 26068.4 09:19:17 INFO - PROCESS | 9437 | 09:19:17 INFO - PROCESS | 9437 | [#2] jquery.html Cycles:180 Average:19523.37 Median:26994.65 stddev:45950.64 (170.2%) stddev-sans-first:46057.00 09:19:17 INFO - PROCESS | 9437 | Values: 352.6 357.3 364.4 371.3 372.8 13924.6 14259.2 14760.8 14792.2 14881.9 13307.7 13345.5 14469.1 14523.1 14581.2 4821.8 4920.1 4975.9 4989.3 5226.3 5116.3 5141.0 5153.4 5164.4 5177.1 5041.0 5337.1 5348.1 5357.5 5374.4 5109.8 5299.8 5306.8 5308.3 5311.3 5150.2 5237.6 5244.3 5262.7 5280.9 10737.8 11208.9 11357.0 17565.6 17933.5 1791.7 1876.8 1935.7 1950.3 2171.0 8519.1 8584.7 8852.6 8991.3 9161.9 1696.0 1798.4 1809.5 1814.6 1841.0 51381.2 54027.7 55524.0 57543.1 59691.4 9037.5 9190.3 9482.9 9500.8 9586.2 12786.2 12882.4 12891.2 12922.4 12932.0 6677.2 6790.7 6809.0 6855.9 6933.5 6628.7 6700.8 6788.2 6798.2 6826.4 4172.4 4212.8 4235.4 4311.7 4357.3 1778.6 1809.8 1814.9 1828.3 1840.4 716.9 737.4 774.7 822.9 858.8 14775.6 15072.5 15130.6 15296.0 15587.1 806.3 809.2 810.7 817.6 830.0 8579.2 8583.5 9251.4 9298.4 9417.4 243247.0 249330.0 249938.0 250202.0 369001.0 49167.5 65297.0 67274.7 67379.3 69654.7 925.2 930.6 967.1 971.9 978.8 14748.8 14772.0 14781.6 14994.6 15056.2 31687.1 32725.5 33599.0 33599.5 33714.7 45224.3 45429.3 45715.3 45875.1 45965.8 7397.6 7473.1 7617.6 7781.6 7797.6 10707.1 11430.3 11468.3 11474.2 11522.4 9434.2 9984.7 10011.1 10146.3 10361.6 9551.9 9989.7 10017.0 10113.6 10338.0 10541.4 10621.1 10630.2 10802.9 10863.6 7671.2 7685.2 7690.5 7773.0 7829.3 9528.4 10366.8 10581.7 10582.8 10826.2 09:19:17 INFO - PROCESS | 9437 | 09:19:17 INFO - PROCESS | 9437 | [#3] mootools.html Cycles:180 Average:4947.33 Median:5916.49 stddev:2716.59 (45.9%) stddev-sans-first:2710.13 09:19:17 INFO - PROCESS | 9437 | Values: 1267.4 1289.9 1304.4 1518.8 1533.2 6345.2 7208.6 7244.6 7430.3 7523.5 7297.3 7321.2 7379.5 7423.2 7424.8 2187.0 2212.6 2216.5 2221.3 2223.5 2168.2 2169.4 2234.5 2237.6 2241.3 2173.5 2197.6 2201.8 2206.8 2249.0 2166.3 2175.0 2176.8 2181.8 2187.9 2133.5 2133.9 2161.2 2165.8 2171.8 7445.9 7476.4 7478.6 7489.6 7509.7 4500.9 4657.3 4660.2 4667.5 4678.2 2825.9 2840.5 2942.9 2964.4 2971.7 888.5 924.2 947.0 947.1 961.9 9685.8 9945.5 9950.0 9962.1 9972.3 4020.4 4051.5 4161.0 4162.2 4180.4 7929.8 7936.2 7942.4 7943.4 7954.8 4403.7 4459.3 4482.9 4483.7 4498.0 3474.0 3935.6 4081.4 4119.5 4132.8 3248.3 3274.1 3317.3 3345.3 3405.3 1020.3 1027.9 1029.6 1046.8 1049.9 668.4 802.3 803.9 810.4 816.5 4957.3 5118.1 5171.7 5219.1 5251.2 798.2 835.5 853.9 855.4 858.1 3514.8 3519.4 3666.8 3699.9 3731.6 9248.5 9370.7 9511.1 9512.4 9577.8 9435.9 9438.7 9467.2 9469.5 9481.7 6483.0 6714.7 6759.1 6901.5 6902.3 5021.6 5044.0 5074.6 5168.8 5232.7 8383.5 8427.0 8645.2 8695.8 8743.7 8560.0 8659.0 8706.5 8829.3 8870.9 5688.6 6416.6 6608.2 6721.5 6727.0 7042.7 7056.8 7098.3 7102.8 7108.0 6514.1 6645.3 6744.8 6745.6 6808.6 6537.1 6613.7 6645.0 6735.0 6803.4 6144.4 6192.8 6193.3 6217.5 6253.1 4329.8 4365.1 4390.5 4441.8 4578.8 4578.8 4587.7 4603.7 4614.5 4657.2 09:19:17 INFO - PROCESS | 9437 | 09:19:17 INFO - PROCESS | 9437 | [#4] prototype.html Cycles:180 Average:4370.47 Median:4884.58 stddev:1293.92 (26.5%) stddev-sans-first:1261.09 09:19:17 INFO - PROCESS | 9437 | Values: 306.7 309.1 310.0 310.4 320.6 4854.0 4890.6 4901.9 4905.0 4910.6 4825.3 4828.9 4837.2 4850.7 4878.6 3643.3 3728.5 3851.5 3865.9 3874.8 3837.7 3877.1 3880.6 3881.1 3884.2 3844.4 3850.4 3858.5 3859.9 3868.1 3594.5 3822.9 3849.2 3858.6 3866.8 3299.6 3302.1 3321.4 3322.3 3323.2 4696.4 4714.9 4722.3 4745.5 4771.1 4318.5 4320.7 4323.0 4331.9 4338.9 5026.1 5059.7 5107.4 5122.2 5140.5 3627.7 3632.0 3645.1 3647.6 3652.5 5334.8 5398.3 5410.0 5426.0 5432.7 3643.3 3712.5 3750.2 3756.3 3771.1 3781.1 3784.2 3789.5 3800.7 3809.8 4260.4 4290.3 4290.6 4335.6 4344.4 4985.8 5057.7 5127.9 5138.8 5145.0 1371.8 1377.4 1379.6 1389.2 1392.0 856.2 888.5 904.3 931.3 933.6 4432.0 4580.0 4590.4 4596.1 4626.6 5229.5 5279.7 5310.8 5317.8 5324.8 4436.5 4530.7 4584.4 4592.2 4594.9 2963.6 3030.3 3031.1 3033.9 3041.0 5315.3 5332.2 5336.4 5341.9 5347.8 5649.3 5760.7 5768.0 5778.6 5838.0 5309.7 5325.5 5340.7 5351.3 5359.6 5670.6 5677.8 5691.8 5697.9 5710.5 5440.5 5528.2 5554.6 5564.4 5567.9 5257.8 5295.0 5298.8 5339.2 5344.2 3972.8 4015.1 4033.4 4039.8 4048.9 5402.2 5518.6 5574.9 5585.4 5591.7 5414.9 5433.3 5434.2 5434.9 5447.0 5173.5 5366.9 5399.9 5403.8 5431.5 5350.0 5365.8 5369.2 5373.0 5383.7 4896.0 5024.0 5148.0 5163.9 5483.9 4970.8 5010.2 5023.6 5053.9 5075.2 09:19:17 INFO - PROCESS | 9437 | 09:19:17 INFO - PROCESS | 9437 | [#5] yui.html Cycles:180 Average:12361.14 Median:26446.67 stddev:14687.56 (55.5%) stddev-sans-first:14702.15 09:19:17 INFO - PROCESS | 9437 | Values: 586.7 648.1 652.1 658.1 671.8 12204.8 12308.9 12339.6 12344.8 12405.4 11154.4 11189.7 11843.9 12222.9 12270.0 509.1 547.9 550.2 593.5 642.9 541.2 541.8 542.5 543.9 546.3 558.9 562.7 564.4 564.9 565.2 553.3 555.2 556.2 559.2 559.5 404.8 406.5 407.4 408.4 408.8 10979.2 11004.7 11019.1 11104.8 11138.7 10995.2 11374.3 11424.4 11480.7 11751.6 9633.9 9635.6 9810.2 9883.2 9901.0 3305.1 3305.7 3327.2 3332.3 3341.2 43654.3 43806.0 43862.1 44332.3 44671.3 9145.4 9312.5 9395.2 9624.7 9730.2 15291.2 15332.2 15355.6 15417.2 15482.5 5678.4 6095.8 6124.8 6183.7 6272.1 3551.8 3623.1 3651.7 3692.6 3696.2 2537.7 2598.2 2616.5 2620.5 2623.4 440.5 446.9 447.7 456.6 457.7 468.1 561.8 564.7 585.8 590.2 7990.4 8019.7 8092.4 8106.7 8184.3 577.3 585.2 588.6 589.5 610.6 3758.9 3759.6 3765.0 3790.0 3844.1 52384.2 52598.0 53536.7 53661.7 53673.5 46783.3 47512.3 48063.8 48114.8 48424.5 42402.2 43719.6 43772.3 43793.3 44293.3 7257.2 7372.3 7541.5 7563.5 7754.0 29494.7 30538.2 30809.8 31067.8 31281.4 37100.6 39548.0 39638.4 39674.0 40452.6 9008.3 9552.8 9555.4 9662.2 9757.4 9874.5 10252.0 10274.3 10280.3 10280.7 9225.2 9497.8 9608.5 9644.3 9670.2 9327.8 9422.2 9534.3 9604.4 9639.7 10019.6 10077.4 10186.2 10230.2 10609.6 8111.1 8231.8 8371.9 8421.6 8516.6 8079.0 8110.1 8153.8 8300.5 8379.7 09:19:17 INFO - PROCESS | 9437 | -------- Summary: end -------- 09:19:17 INFO - PROCESS | 9437 | 09:19:18 INFO - TEST-INFO | 9437: exit 0 09:19:18 INFO - TEST-OK | dromaeo_css | took 1115036ms 09:19:18 INFO - TEST-START | kraken 09:19:18 INFO - operating with platform_type : linux_ 09:19:18 INFO - Initialising browser for kraken test... 09:19:18 INFO - TEST-INFO | started process 9673 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkOd6gi/profile http://localhost:59529/getInfo.html) 09:19:22 INFO - PROCESS | 9673 | 09:19:22 INFO - PROCESS | 9673 | (process:9739): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 09:19:23 INFO - PROCESS | 9673 | __metrics Screen width/height:1600/1200 09:19:23 INFO - PROCESS | 9673 | colorDepth:24 09:19:23 INFO - PROCESS | 9673 | Browser inner width/height: 1024/697 09:19:23 INFO - PROCESS | 9673 | __metrics 09:19:24 ERROR - PROCESS | 9673 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 09:19:25 INFO - TEST-INFO | 9673: exit 0 09:19:25 INFO - Browser initialized. 09:19:25 INFO - Running cycle 1/1 for kraken test... 09:19:25 INFO - TEST-INFO | started process 9790 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkOd6gi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:19:27 INFO - PROCESS | 9790 | 09:19:27 INFO - PROCESS | 9790 | (process:9849): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 09:19:37 INFO - PROCESS | 9790 | RSS: Main: 166219776 09:19:37 INFO - PROCESS | 9790 | 09:19:39 INFO - PROCESS | 9790 | Cycle 1(1): loaded http://localhost:59529/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59529/tests/kraken/driver.html?testName=audio-beat-detection) 09:19:40 INFO - PROCESS | 9790 | RSS: Main: 169451520 09:19:40 INFO - PROCESS | 9790 | 09:19:43 INFO - PROCESS | 9790 | Cycle 1(1): loaded http://localhost:59529/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59529/tests/kraken/driver.html?testName=audio-dft) 09:19:44 INFO - PROCESS | 9790 | RSS: Main: 172728320 09:19:44 INFO - PROCESS | 9790 | 09:19:47 INFO - PROCESS | 9790 | Cycle 1(1): loaded http://localhost:59529/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59529/tests/kraken/driver.html?testName=audio-fft) 09:19:47 INFO - PROCESS | 9790 | RSS: Main: 164491264 09:19:47 INFO - PROCESS | 9790 | 09:19:49 INFO - PROCESS | 9790 | Cycle 1(1): loaded http://localhost:59529/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59529/tests/kraken/driver.html?testName=audio-oscillator) 09:19:50 INFO - PROCESS | 9790 | RSS: Main: 164978688 09:19:50 INFO - PROCESS | 9790 | 09:19:52 INFO - PROCESS | 9790 | Cycle 1(1): loaded http://localhost:59529/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59529/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:19:53 INFO - PROCESS | 9790 | RSS: Main: 165642240 09:19:53 INFO - PROCESS | 9790 | 09:19:56 INFO - PROCESS | 9790 | Cycle 1(1): loaded http://localhost:59529/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59529/tests/kraken/driver.html?testName=imaging-darkroom) 09:19:57 INFO - PROCESS | 9790 | RSS: Main: 165974016 09:19:57 INFO - PROCESS | 9790 | 09:20:00 INFO - PROCESS | 9790 | Cycle 1(1): loaded http://localhost:59529/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59529/tests/kraken/driver.html?testName=imaging-desaturate) 09:20:01 INFO - PROCESS | 9790 | RSS: Main: 164818944 09:20:01 INFO - PROCESS | 9790 | 09:20:04 INFO - PROCESS | 9790 | Cycle 1(1): loaded http://localhost:59529/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59529/tests/kraken/driver.html?testName=json-parse-financial) 09:20:05 INFO - PROCESS | 9790 | RSS: Main: 164720640 09:20:05 INFO - PROCESS | 9790 | 09:20:06 INFO - PROCESS | 9790 | Cycle 1(1): loaded http://localhost:59529/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59529/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:20:07 INFO - PROCESS | 9790 | RSS: Main: 165769216 09:20:07 INFO - PROCESS | 9790 | 09:20:09 INFO - PROCESS | 9790 | Cycle 1(1): loaded http://localhost:59529/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59529/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:20:09 INFO - PROCESS | 9790 | RSS: Main: 165310464 09:20:09 INFO - PROCESS | 9790 | 09:20:12 INFO - PROCESS | 9790 | Cycle 1(1): loaded http://localhost:59529/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59529/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:20:12 INFO - PROCESS | 9790 | RSS: Main: 165445632 09:20:12 INFO - PROCESS | 9790 | 09:20:15 INFO - PROCESS | 9790 | Cycle 1(1): loaded http://localhost:59529/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59529/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:20:16 INFO - PROCESS | 9790 | RSS: Main: 165474304 09:20:16 INFO - PROCESS | 9790 | 09:20:19 INFO - PROCESS | 9790 | Cycle 1(1): loaded http://localhost:59529/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59529/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:20:20 INFO - PROCESS | 9790 | RSS: Main: 163360768 09:20:20 INFO - PROCESS | 9790 | 09:20:22 INFO - PROCESS | 9790 | Cycle 1(1): loaded http://localhost:59529/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59529/tests/kraken/driver.html?testName=ai-astar) 09:20:22 INFO - PROCESS | 9790 | RSS: Main: 165437440 09:20:22 INFO - PROCESS | 9790 | 09:20:22 INFO - PROCESS | 9790 | __start_tp_report 09:20:22 INFO - PROCESS | 9790 | _x_x_mozilla_page_load 09:20:22 INFO - PROCESS | 9790 | _x_x_mozilla_page_load_details 09:20:22 INFO - PROCESS | 9790 | |i|pagename|runs| 09:20:22 INFO - PROCESS | 9790 | |0;ai-astar;115;110;115;112;113;128;115;115;114;113 09:20:22 INFO - PROCESS | 9790 | |1;audio-beat-detection;152;132;156;184;140;167;159;133;198;133 09:20:22 INFO - PROCESS | 9790 | |2;audio-dft;162;162;166;170;162;162;163;163;165;162 09:20:22 INFO - PROCESS | 9790 | |3;audio-fft;77;82;74;153;76;77;74;76;160;74 09:20:22 INFO - PROCESS | 9790 | |4;audio-oscillator;94;94;142;94;94;95;136;95;94;147 09:20:22 INFO - PROCESS | 9790 | |5;imaging-gaussian-blur;118;117;117;118;119;118;117;117;117;117 09:20:22 INFO - PROCESS | 9790 | |6;imaging-darkroom;148;146;147;148;148;147;147;146;148;146 09:20:22 INFO - PROCESS | 9790 | |7;imaging-desaturate;97;96;96;96;96;96;96;96;98;95 09:20:22 INFO - PROCESS | 9790 | |8;json-parse-financial;65;65;67;66;65;67;68;66;66;74 09:20:22 INFO - PROCESS | 9790 | |9;json-stringify-tinderbox;65;65;67;66;64;66;65;65;64;65 09:20:22 INFO - PROCESS | 9790 | |10;stanford-crypto-aes;90;80;78;79;79;80;80;91;117;80 09:20:22 INFO - PROCESS | 9790 | |11;stanford-crypto-ccm;138;143;142;145;141;134;134;135;137;137 09:20:22 INFO - PROCESS | 9790 | |12;stanford-crypto-pbkdf2;224;234;221;223;217;217;224;222;227;219 09:20:22 INFO - PROCESS | 9790 | |13;stanford-crypto-sha256-iterative;75;75;78;75;76;75;77;76;77;77 09:20:22 INFO - PROCESS | 9790 | __end_tp_report 09:20:22 INFO - PROCESS | 9790 | __start_cc_report 09:20:22 INFO - PROCESS | 9790 | _x_x_mozilla_cycle_collect,917 09:20:22 INFO - PROCESS | 9790 | __end_cc_report 09:20:22 INFO - PROCESS | 9790 | __startTimestamp1461687622437__endTimestamp 09:20:22 INFO - PROCESS | 9790 | 09:20:22 INFO - PROCESS | 9790 | ------- Summary: start ------- 09:20:22 INFO - PROCESS | 9790 | Number of tests: 14 09:20:22 INFO - PROCESS | 9790 | 09:20:22 INFO - PROCESS | 9790 | [#0] ai-astar Cycles:10 Average:115.00 Median:115.00 stddev:4.85 (4.2%) stddev-sans-first:5.15 09:20:22 INFO - PROCESS | 9790 | Values: 115.0 110.0 115.0 112.0 113.0 128.0 115.0 115.0 114.0 113.0 09:20:22 INFO - PROCESS | 9790 | 09:20:22 INFO - PROCESS | 9790 | [#1] audio-beat-detection Cycles:10 Average:155.40 Median:157.50 stddev:22.56 (14.3%) stddev-sans-first:23.89 09:20:22 INFO - PROCESS | 9790 | Values: 152.0 132.0 156.0 184.0 140.0 167.0 159.0 133.0 198.0 133.0 09:20:22 INFO - PROCESS | 9790 | 09:20:22 INFO - PROCESS | 9790 | [#2] audio-dft Cycles:10 Average:163.70 Median:163.00 stddev:2.63 (1.6%) stddev-sans-first:2.71 09:20:22 INFO - PROCESS | 9790 | Values: 162.0 162.0 166.0 170.0 162.0 162.0 163.0 163.0 165.0 162.0 09:20:22 INFO - PROCESS | 9790 | 09:20:22 INFO - PROCESS | 9790 | [#3] audio-fft Cycles:10 Average:92.30 Median:76.00 stddev:33.96 (44.7%) stddev-sans-first:35.56 09:20:22 INFO - PROCESS | 9790 | Values: 77.0 82.0 74.0 153.0 76.0 77.0 74.0 76.0 160.0 74.0 09:20:22 INFO - PROCESS | 9790 | 09:20:22 INFO - PROCESS | 9790 | [#4] audio-oscillator Cycles:10 Average:108.50 Median:94.00 stddev:23.04 (24.5%) stddev-sans-first:23.83 09:20:22 INFO - PROCESS | 9790 | Values: 94.0 94.0 142.0 94.0 94.0 95.0 136.0 95.0 94.0 147.0 09:20:22 INFO - PROCESS | 9790 | 09:20:22 INFO - PROCESS | 9790 | [#5] imaging-gaussian-blur Cycles:10 Average:117.50 Median:117.50 stddev:0.71 (0.6%) stddev-sans-first:0.73 09:20:22 INFO - PROCESS | 9790 | Values: 118.0 117.0 117.0 118.0 119.0 118.0 117.0 117.0 117.0 117.0 09:20:22 INFO - PROCESS | 9790 | 09:20:22 INFO - PROCESS | 9790 | [#6] imaging-darkroom Cycles:10 Average:147.10 Median:147.50 stddev:0.88 (0.6%) stddev-sans-first:0.87 09:20:22 INFO - PROCESS | 9790 | Values: 148.0 146.0 147.0 148.0 148.0 147.0 147.0 146.0 148.0 146.0 09:20:22 INFO - PROCESS | 9790 | 09:20:22 INFO - PROCESS | 9790 | [#7] imaging-desaturate Cycles:10 Average:96.20 Median:96.00 stddev:0.79 (0.8%) stddev-sans-first:0.78 09:20:22 INFO - PROCESS | 9790 | Values: 97.0 96.0 96.0 96.0 96.0 96.0 96.0 96.0 98.0 95.0 09:20:22 INFO - PROCESS | 9790 | 09:20:22 INFO - PROCESS | 9790 | [#8] json-parse-financial Cycles:10 Average:66.90 Median:66.50 stddev:2.69 (4.0%) stddev-sans-first:2.76 09:20:22 INFO - PROCESS | 9790 | Values: 65.0 65.0 67.0 66.0 65.0 67.0 68.0 66.0 66.0 74.0 09:20:22 INFO - PROCESS | 9790 | 09:20:22 INFO - PROCESS | 9790 | [#9] json-stringify-tinderbox Cycles:10 Average:65.20 Median:65.00 stddev:0.92 (1.4%) stddev-sans-first:0.97 09:20:22 INFO - PROCESS | 9790 | Values: 65.0 65.0 67.0 66.0 64.0 66.0 65.0 65.0 64.0 65.0 09:20:22 INFO - PROCESS | 9790 | 09:20:22 INFO - PROCESS | 9790 | [#10] stanford-crypto-aes Cycles:10 Average:85.40 Median:80.00 stddev:12.04 (15.0%) stddev-sans-first:12.65 09:20:22 INFO - PROCESS | 9790 | Values: 90.0 80.0 78.0 79.0 79.0 80.0 80.0 91.0 117.0 80.0 09:20:22 INFO - PROCESS | 9790 | 09:20:22 INFO - PROCESS | 9790 | [#11] stanford-crypto-ccm Cycles:10 Average:138.60 Median:139.50 stddev:3.92 (2.8%) stddev-sans-first:4.15 09:20:22 INFO - PROCESS | 9790 | Values: 138.0 143.0 142.0 145.0 141.0 134.0 134.0 135.0 137.0 137.0 09:20:22 INFO - PROCESS | 9790 | 09:20:22 INFO - PROCESS | 9790 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:222.80 Median:223.50 stddev:5.07 (2.3%) stddev-sans-first:5.36 09:20:22 INFO - PROCESS | 9790 | Values: 224.0 234.0 221.0 223.0 217.0 217.0 224.0 222.0 227.0 219.0 09:20:22 INFO - PROCESS | 9790 | 09:20:22 INFO - PROCESS | 9790 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.10 Median:76.50 stddev:1.10 (1.4%) stddev-sans-first:1.09 09:20:22 INFO - PROCESS | 9790 | Values: 75.0 75.0 78.0 75.0 76.0 75.0 77.0 76.0 77.0 77.0 09:20:22 INFO - PROCESS | 9790 | -------- Summary: end -------- 09:20:22 INFO - PROCESS | 9790 | 09:20:23 INFO - TEST-INFO | 9790: exit 0 09:20:23 INFO - TEST-OK | kraken | took 64997ms 09:20:23 INFO - SUITE-END | took 1180s 09:20:24 INFO - Completed test suite (00:19:40) 09:20:24 INFO - javascript benchmark 09:20:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2577.098015694507, 2642.775364094052, 2657.9335516612077, 2699.6355492008574, 2701.0951331304022, 4565.269556870897, 4577.404937358147, 4600.700954437959, 4608.746518941465, 4638.3274425208365, 4558.0, 4571.817127314912, 4612.262038073909, 4663.160631086403, 4687.343771871941, 4191.664666826662, 4267.829286828523, 4269.701120921537, 4291.806868690916, 4328.718633288826, 531.5380020652112, 4206.642435392995, 4255.957440425587, 4268.274393353137, 4301.483821941356, 4230.02709376842, 4251.3622956556455, 4259.382389553529, 4267.338562522814, 4283.421738065353, 4127.050778320973, 4242.745435273893, 4275.209086319041, 4295.334223195409, 4338.457692788402, 4191.0, 4217.936730949007, 4265.0, 4270.679699022555, 4272.0, 4494.1461122386645, 4497.572730590621, 4539.931901021488, 4540.477845047829, 4598.172328980782, 4914.410270767495, 4970.6520543561965, 4982.9252561211615, 4995.275685025687, 4999.100161970844, 4982.676126051795, 5000.374953130859, 5002.399712034579, 5019.498050194988, 5046.772895219688, 4614.146382919137, 4655.511171326996, 4660.836870709577, 4672.345871577982, 4709.952900470951, 4945.406551213878, 4994.650374473825, 5103.719295438712, 5109.770060347293, 5118.411382690963, 4858.7570621468785, 4864.197407427735, 4890.095332363453, 4900.975495122501, 4924.630652701062, 4963.925541116886, 4964.60283177345, 4968.850934471971, 4989.376327959005, 5017.899642007213, 4779.187538118528, 4903.26451032348, 4984.576311013604, 4986.376702912136, 5009.5741861941415, 4710.128626204095, 4838.806447742121, 4851.805927762921, 4860.854174374775, 4967.0, 4284.614384705392, 4304.827806887752, 4305.07440900208, 4334.0, 4351.43431353922, 3682.1531047859144, 3717.368047431943, 3772.641599048086, 3773.981130094332, 3792.7724336539895, 4344.891377715582, 4359.1717573661535, 4374.365716971021, 4396.626286765661, 4407.977960110179, 4886.828960986349, 4936.062148191832, 4965.155923493015, 4981.277714731344, 5011.649184557046, 4218.325067989107, 4298.226319262562, 4346.130773845243, 4346.847860324873, 4373.737575745402, 4513.887152821206, 4527.185106680829, 4530.411046563926, 4553.134904368159, 4563.406757121553, 4703.529647035338, 4723.373068696766, 4727.479977202505, 4733.810647574059, 4778.116048530964, 4841.806327746851, 4920.36035315421, 4987.775550100327, 4995.0, 5017.899642007213, 4651.930221046755, 4670.416197975253, 4682.367880336247, 4700.0, 4757.333973243818, 4901.5588597026435, 4904.6566740327835, 4914.508549145057, 4922.532359425849, 4974.676646017997, 4825.6139508838505, 4908.214685650279, 4972.950270497248, 4981.4769449207315, 5021.72380519075, 4822.397200349957, 4824.421069471686, 4837.975810120927, 4840.951590484191, 4883.951160488492, 4600.0, 4758.191107511662, 4820.1805693031565, 4824.782884770124, 4885.511448855228, 4627.005193883331, 4665.930011049769, 4719.150552900511, 4814.7833347498745, 4846.757662116951, 4895.216765317532, 4895.216765317532, 4896.926546101882, 4902.362692850049, 4931.605471562339, 4669.18289299378, 4786.473487916257, 4786.641001924939, 4786.808527658994, 4787.40157480315], "value": 4537.850780454062, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [563.6082922368953, 585.212888664746, 587.9941200587992, 608.0544752909226, 617.4905702795195, 29166.12501624952, 30082.699173008263, 30240.848795756046, 30431.391372172544, 30638.080857574252, 30407.391852162942, 30864.228394290098, 30917.22706932328, 30946.0, 30974.845125774395, 7327.926720732791, 7501.4748967572295, 7508.662110205027, 7509.211532789061, 7511.248875112499, 7348.301911318443, 7481.177070522238, 7482.326590606845, 7501.774946751606, 7574.280443357872, 7661.578613176273, 7766.0, 7771.378289736807, 7788.961055194716, 7798.883016754753, 7515.361194298491, 7554.735584254552, 7560.130584982742, 7566.0, 7606.201348858345, 7424.146223184349, 7492.100947886234, 7518.210587888302, 7528.58592777397, 7562.205968373351, 31229.843850780602, 31884.6811531884, 32164.0, 32628.510572341434, 32922.6707732922, 2223.9332820015425, 2667.213172114231, 2667.2398366465463, 2959.7632189424794, 3047.360054388581, 20835.1665933364, 20838.5832283355, 20924.476888077766, 24612.753872461406, 25085.749142508525, 3312.271300313927, 3318.785955167635, 3352.396568617648, 3388.9830550847337, 3436.347094052122, 173969.0, 174313.0, 174717.1264143684, 175072.0, 175173.0, 25880.352991174994, 29111.088891110718, 29606.407871842443, 30191.245218869703, 30208.395832083235, 28752.281192969924, 28767.42465150685, 28881.0, 28989.0, 29197.416051678847, 12043.698907527207, 12366.258024518558, 12396.0, 12402.13185077036, 12417.875821241852, 10083.899161008296, 10116.54475548602, 10153.593856245816, 10183.7454063649, 10199.490025498695, 7449.404047676173, 7462.477626566197, 7556.508826926342, 7589.658465369072, 7601.695932162652, 2907.7819163562817, 2979.5381715834083, 2983.761299096067, 2987.521996480553, 3025.7125573070525, 736.4100012439358, 759.517706256531, 809.3080416244144, 842.30509829391, 878.6792820620556, 23863.0, 24264.51470970571, 24392.390190245387, 24805.379865503506, 24953.750462495507, 898.1961144775389, 929.990700093004, 939.0140352629847, 962.0571839597234, 994.5325696922434, 12294.938525307316, 12470.251784892935, 12838.229706217657, 12907.806382904264, 12975.675608109685, 274056.0, 278162.0, 278176.0, 278211.0, 281181.0, 43853.0, 44035.33946990798, 44405.33391999122, 44532.77733611376, 44835.0, 1078.805814953316, 1098.3310555350574, 1099.769048499808, 1106.4578356605366, 1113.1484414422973, 23403.76596234016, 24224.152154674503, 24316.878415607807, 24370.147044853344, 24523.877380612983, 32823.34353312968, 33420.331593368486, 33648.66351336553, 33666.49500257547, 34892.30215395627, 248244.0, 250993.0, 251546.0, 253226.0, 253548.0, 31379.529307059955, 31687.0, 31694.573744182027, 31975.520367194047, 32002.83998579992, 34185.316293674485, 35125.824370877985, 35454.29091418105, 35558.822205888806, 35566.822165889, 29361.85319073391, 30781.23046923845, 30942.845285773426, 30958.22604434907, 31137.688623113478, 30047.0, 30305.848470757504, 30426.391472170875, 30819.53770693486, 30898.845505773228, 24831.0, 31122.377552449278, 31351.84324078365, 31727.0, 32234.194145145622, 28702.712972870006, 30163.698363016967, 30561.694383055885, 31331.060068197992, 32255.35489290154, 25003.374915627253, 25595.872020639777, 25859.612105818804, 25986.22041338763, 26068.43589384643], "value": 14470.220140010146, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.55049811490346, 357.3424898187335, 364.3769154745385, 371.29454037329054, 372.7614326830829, 13924.582262532127, 14259.215743134124, 14760.778588321156, 14792.18642974636, 14881.851181488182, 13307.733845323111, 13345.53290634828, 14469.059511131769, 14523.12861228327, 14581.19803410812, 4821.783019764111, 4920.114379411704, 4975.9004819903585, 4989.301497790312, 5226.294450249225, 5116.258142569325, 5141.0, 5153.433122356538, 5164.354455693038, 5177.068127737006, 5041.0, 5337.092694241988, 5348.090824559815, 5357.491038351351, 5374.355077390699, 5109.846704598868, 5299.8410047698635, 5306.761195746181, 5308.283381743454, 5311.256424100629, 5150.227465880148, 5237.633365664405, 5244.265802787575, 5262.736863156827, 5280.894382112375, 10737.785244295148, 11208.887911120948, 11357.0, 17565.560860978578, 17933.461996140137, 1791.668541319847, 1876.8498520118358, 1935.7144275248716, 1950.2783969931074, 2170.9565808683897, 8519.081222637595, 8584.74245772628, 8852.557372131432, 8991.280697544245, 9161.862572061358, 1695.983040169595, 1798.390445494633, 1809.493341864267, 1814.5917168637084, 1841.0426578179272, 51381.22928156081, 54027.72986135083, 55524.0, 57543.13685294724, 59691.40308596902, 9037.457752534869, 9190.264778817678, 9482.857757133648, 9500.762480937894, 9586.233101351874, 12786.168899021533, 12882.355882205853, 12891.16207446513, 12922.353882305848, 12932.0, 6677.232118306457, 6790.660466976631, 6808.9659551701925, 6855.931440685629, 6933.514653974174, 6628.668566571652, 6700.765473208512, 6788.185417749803, 6798.184217893884, 6826.419754320839, 4172.395002724588, 4212.810423530948, 4235.407043013981, 4311.698181127354, 4357.281048627005, 1778.6175972166047, 1809.8190180981796, 1814.8820326678724, 1828.3052440072954, 1840.3742727472456, 716.9032180655649, 737.4395459450856, 774.6823802241054, 822.9218224268685, 858.7552547524051, 14775.63060923464, 15072.472463463726, 15130.621734456727, 15296.0, 15587.06477611347, 806.3186607316808, 809.1503920883049, 810.7040930060604, 817.5912043978011, 830.0, 8579.184977427134, 8583.484990900566, 9251.352405331561, 9298.395604285699, 9417.387869788441, 243247.0, 249330.0, 249938.0, 250202.0, 369001.0, 49167.50832491629, 65297.0, 67274.66362668155, 67379.32620673829, 69654.65172674206, 925.1765928323797, 930.6277489004452, 967.0957654592837, 971.8930917599058, 978.7699890525579, 14748.778768318698, 14772.0, 14781.630459238606, 14994.625134371729, 15056.171910545025, 31687.05618537436, 32725.50911736278, 33599.0, 33599.496007560396, 33714.662853371156, 45224.32163517484, 45429.31856022229, 45715.31427028531, 45875.082498350515, 45965.77017114893, 7397.593132377771, 7473.140588832136, 7617.619119044137, 7781.610919454118, 7797.571133587707, 10707.143428525544, 11430.28545718149, 11468.31190128595, 11474.196806223485, 11522.355061819604, 9434.150926416656, 9984.700458986243, 10011.149052330633, 10146.340487868265, 10361.63734269297, 9551.856722149034, 9989.650362237287, 10017.0, 10113.59545618167, 10338.0, 10541.420221887869, 10621.097206737513, 10630.202734795075, 10802.919708029134, 10863.565457381615, 7671.232876712284, 7685.154632990479, 7690.461667683432, 7773.028371453568, 7829.256220659028, 9528.428294302365, 10366.844497332697, 10581.735456613646, 10582.841257380991, 10826.188035897496], "value": 7658.935056785389, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1267.4043199696143, 1289.8645642207568, 1304.4477072283983, 1518.7873697682326, 1533.2103966457269, 6345.23857137143, 7208.603526806023, 7244.601546914918, 7430.294122058409, 7523.510971786837, 7297.2702729727, 7321.194668586464, 7379.483436159471, 7423.22056184101, 7424.777256682295, 2187.048633844279, 2212.6127927612683, 2216.5345277491747, 2221.31139346803, 2223.5108276179217, 2168.1652563762987, 2169.4359466538654, 2234.497238121426, 2237.630790919496, 2241.316398498457, 2173.4892300309416, 2197.604431202383, 2201.8018378345946, 2206.7682893296214, 2249.010435408415, 2166.2959538150085, 2174.978250217501, 2176.7714389988982, 2181.814545763612, 2187.9343619691435, 2133.4666333416644, 2133.9253126140666, 2161.2003558683386, 2165.8483906126576, 2171.826253899684, 7445.8883116753295, 7476.439267054939, 7478.626068696543, 7489.550626962399, 7509.699612015513, 4500.8649740507835, 4657.301404789308, 4660.18446771817, 4667.486576476585, 4678.228092364756, 2825.872835722397, 2840.4887120318335, 2942.926426839325, 2964.3626620276527, 2971.747401470878, 888.5335199020501, 924.1867156901883, 946.9621215151454, 947.1049857884302, 961.8653388525612, 9685.806283874354, 9945.453000085065, 9949.950250248774, 9962.053604907522, 9972.252081093948, 4020.3768415895584, 4051.493563304587, 4161.0, 4162.188373267184, 4180.435641188401, 7929.841403171904, 7936.246056624612, 7942.404319675932, 7943.364530837519, 7954.840903181904, 4403.691741578059, 4459.264221403497, 4482.8655140345845, 4483.686141970096, 4497.955020449819, 3473.9826300868335, 3935.5670876203594, 4081.4490043844294, 4119.485064366954, 4132.793360331972, 3248.3016151527536, 3274.1323549259464, 3317.303366293057, 3345.2974875275977, 3405.250844814137, 1020.260311274335, 1027.8869324374314, 1029.552144816992, 1046.8325067989085, 1049.9370037797758, 668.3650531994624, 802.2659266770839, 803.883436901646, 810.3638643664694, 816.4856140631483, 4957.3059771632, 5118.129917913963, 5171.663841850268, 5219.060569097599, 5251.186066159751, 798.1818635898251, 835.4736515994884, 853.9274161696201, 855.4225897519274, 858.1289990659485, 3514.8242587870504, 3519.419295816213, 3666.816659167031, 3699.8520059197867, 3731.600649756341, 9248.491332976617, 9370.718878433658, 9511.096445837635, 9512.38169518979, 9577.808443831085, 9435.858462123073, 9438.669646562337, 9467.242620590336, 9469.526523673789, 9481.715548533555, 6483.0, 6714.731410743613, 6759.121314229119, 6901.482388820858, 6902.275261097613, 5021.623378246719, 5043.9747801260755, 5074.644774865724, 5168.819091331786, 5232.659877107976, 8383.538905360263, 8426.957865210634, 8645.178708022728, 8695.782605434915, 8743.737687869374, 8559.95720021396, 8658.956705216433, 8706.47761134334, 8829.337799664921, 8870.911290887008, 5688.63023903445, 6416.550841441054, 6608.173978252718, 6721.521265321621, 6727.024581435567, 7042.718291268497, 7056.788296351117, 7098.29017098286, 7102.751403701052, 7108.040414543971, 6514.120593719787, 6645.335466453316, 6744.831379215559, 6745.5952642841585, 6808.557443766139, 6537.05212744159, 6613.702383392855, 6645.036469711768, 6734.966325168343, 6803.353681400259, 6144.354842741626, 6192.783252586138, 6193.256809182927, 6217.533684973555, 6253.0620406937505, 4329.826806927687, 4365.083332500083, 4390.516484393695, 4441.755703436342, 4578.839740609063, 4578.816847326069, 4587.747673877835, 4603.723776573416, 4614.492405835288, 4657.208274593395], "value": 4004.92444205946, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.7193517931093, 309.11901081916557, 309.9581556489875, 310.4334589374392, 320.59444802325055, 4853.956399374134, 4890.559849613534, 4901.852944411669, 4904.975475122628, 4910.631702622304, 4825.300331451937, 4828.879278018043, 4837.226043832988, 4850.68470549414, 4878.609711223103, 3643.3259846928336, 3728.4966529518574, 3851.4607954886337, 3865.8840234793, 3874.767513949172, 3837.6546110850018, 3877.0501227199375, 3880.611938806125, 3881.10734531058, 3884.2037382336553, 3844.384898416254, 3850.4031875059272, 3858.5176852893387, 3859.864904728335, 3868.0523271798306, 3594.514740510024, 3822.9235415291537, 3849.2493963677093, 3858.594847541024, 3866.76799392036, 3299.6040475143136, 3302.091924720697, 3321.368939901411, 3322.318924620471, 3323.202431416443, 4696.365990591261, 4714.858554243378, 4722.276369125245, 4745.454272758642, 4771.093492236499, 4318.5465526119615, 4320.697551171419, 4322.956770432287, 4331.870043898688, 4338.891527711801, 5026.145555255579, 5059.696418214919, 5107.412647545506, 5122.154844450662, 5140.537351638371, 3627.6916462100753, 3632.0, 3645.052286405527, 3647.598764135943, 3652.506911566931, 5334.813281535166, 5398.271233383522, 5409.972950135263, 5426.0, 5432.728363581845, 3643.3441980443245, 3712.4988126603103, 3750.2124553843996, 3756.3050835595504, 3771.07608635886, 3781.0736369589627, 3784.243151369737, 3789.5263092113487, 3800.6959443245028, 3809.8095095245126, 4260.360945858146, 4290.334998075304, 4290.635295999875, 4335.588119128678, 4344.370066340363, 4985.750712464434, 5057.69653820772, 5127.9230811537855, 5138.768755406016, 5145.0, 1371.7805151175764, 1377.4145987955078, 1379.5585412668052, 1389.2428626398435, 1392.0464481829777, 856.1566856646199, 888.4846788862574, 904.3443503459939, 931.3108299858056, 933.5518950903604, 4431.955680443219, 4580.0, 4590.449146102489, 4596.127420362273, 4626.6298696104905, 5229.450907654717, 5279.736013199325, 5310.849106112031, 5317.760700768475, 5324.840254792362, 4436.489803672554, 4530.682852200314, 4584.404027476407, 4592.173408786383, 4594.874255807348, 2963.6443626764926, 3030.257586891226, 3031.0755219658217, 3033.877465337795, 3040.9847950760104, 5315.335583052119, 5332.173513105475, 5336.386315573681, 5341.919871201935, 5347.839564813062, 5649.293838270216, 5760.654360738369, 5768.048272035067, 5778.561755755319, 5837.970810145922, 5309.681419114865, 5325.520703136736, 5340.652857564246, 5351.304330437096, 5359.571234301168, 5670.60305778608, 5677.801276955287, 5691.800786972436, 5697.857553561194, 5710.48672101885, 5440.455954404528, 5528.226048353315, 5554.555635549065, 5564.38791732901, 5567.916481252865, 5257.842264732064, 5295.0, 5298.788048478018, 5339.199120131856, 5344.225087362233, 3972.8013599320498, 4015.0966032642423, 4033.3748269018347, 4039.8384064638262, 4048.9190216196102, 5402.189671549299, 5518.558515318685, 5574.9442505574425, 5585.441455854382, 5591.748371323219, 5414.945850541444, 5433.347998240079, 5434.2120392542065, 5434.8586796773125, 5446.972765136149, 5173.456787037458, 5366.892662146814, 5399.865003374947, 5403.8108666196495, 5431.538319242909, 5350.0, 5365.785368585213, 5369.221462887937, 5373.0, 5383.7308134593895, 4895.951040489549, 5024.0, 5147.974260128675, 5163.870903227449, 5483.945160548344, 4970.826021089245, 5010.196329321514, 5023.648344615842, 5053.924191137209, 5075.187969924795], "value": 3964.0674840845118, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [586.6773274698917, 648.0732552449997, 652.0805664013742, 658.1378394303463, 671.7816709569385, 12204.81692774608, 12308.876911230886, 12339.568115115973, 12344.753104937896, 12405.441755121019, 11154.442277886094, 11189.72025699358, 11843.881561184386, 12222.899939005487, 12270.0, 509.1217649554526, 547.8712502561895, 550.1994597860104, 593.4866340615358, 642.9324920883311, 541.204429488651, 541.8049502179213, 542.4927692607405, 543.8776275338037, 546.2761840561272, 558.9441055894418, 562.6764610349065, 564.4130104691117, 564.8813749112692, 565.1635579342576, 553.2586334312041, 555.2005112637809, 556.1685280505629, 559.158466507905, 559.4601209832531, 404.7510780869773, 406.4817357868711, 407.3665450224899, 408.3649924367607, 408.79764516564194, 10979.231453798237, 11004.669859904216, 11019.118470522339, 11104.833427498594, 11138.66584002473, 10995.230333876632, 11374.260673056226, 11424.428778561121, 11480.712982175512, 11751.647450576496, 9633.903660963371, 9635.566399511968, 9810.166135878459, 9883.209343252522, 9901.0, 3305.1076209423563, 3305.735541156702, 3327.2014716468116, 3332.3002169544425, 3341.248219150696, 43654.345184822574, 43806.0, 43862.12275754499, 44332.33501497448, 44671.32993005108, 9145.359824812333, 9312.48781317034, 9395.154436100647, 9624.711258662252, 9730.172935300438, 15291.235438228045, 15332.15673137988, 15355.61610959735, 15417.229138543253, 15482.458113965957, 5678.432156784371, 6095.817125486243, 6124.846878827976, 6183.721732522047, 6272.059191121369, 3551.7691350062164, 3623.130448692295, 3651.6896063834342, 3692.593814680383, 3696.2237930034457, 2537.6574162488196, 2598.2205338398403, 2616.476704659076, 2620.489004644076, 2623.357277467033, 440.4651232158969, 446.9486009108929, 447.67767207610467, 456.6301295950291, 457.72993933579346, 468.08722990169304, 561.7893290016244, 564.7232855900661, 585.844751140948, 590.2032256453753, 7990.360771138295, 8019.719309824128, 8092.3930705197345, 8106.675732970615, 8184.304334131665, 577.3389469057905, 585.2107603268885, 588.5674029588249, 589.513651237722, 610.5909040942579, 3758.8684396046556, 3759.5824905735653, 3764.962350376461, 3789.981050094787, 3844.058205739612, 52384.21423678648, 52598.0, 53536.732316338945, 53661.731691341294, 53673.463265366845, 46783.29825052627, 47512.28731569029, 48063.75968120137, 48114.759426203345, 48424.51575484271, 42402.15195696131, 43719.56280437155, 43772.3434148488, 43793.343099853526, 44293.33559996603, 7257.165425976081, 7372.262773722585, 7541.5475071495885, 7563.546187228784, 7754.0, 29494.705052949197, 30538.236544086576, 30809.8459507701, 31067.84466077655, 31281.374372511968, 37100.628993710794, 39548.0, 39638.40542391924, 39674.0, 40452.59547404606, 9008.279337653103, 9552.761180970532, 9555.378900371454, 9662.227021838096, 9757.365771224848, 9874.506274686093, 10252.0, 10274.33216840903, 10280.331778434378, 10280.742981425525, 9225.169734723908, 9497.762555936157, 9608.519574021131, 9644.324897257125, 9670.226381889292, 9327.81344373095, 9422.246220302497, 9534.284928630243, 9604.423734575948, 9639.71080867575, 10019.649312274327, 10077.395356278646, 10186.185105191715, 10230.181585472996, 10609.575616975235, 8111.148329425326, 8231.794205144919, 8371.926794037632, 8421.578921054046, 8516.574171291535, 8079.0, 8110.148434414302, 8153.7553873383895, 8300.501969881827, 8379.70671026511], "value": 5338.861123640504, "unit": "score"}], "name": "dromaeo_css", "value": 5910.439379095252, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 110.0, 115.0, 112.0, 113.0, 128.0, 115.0, 115.0, 114.0, 113.0], "value": 115.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 132.0, 156.0, 184.0, 140.0, 167.0, 159.0, 133.0, 198.0, 133.0], "value": 155.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 166.0, 170.0, 162.0, 162.0, 163.0, 163.0, 165.0, 162.0], "value": 163.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 82.0, 74.0, 153.0, 76.0, 77.0, 74.0, 76.0, 160.0, 74.0], "value": 92.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 142.0, 94.0, 94.0, 95.0, 136.0, 95.0, 94.0, 147.0], "value": 108.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 117.0, 118.0, 119.0, 118.0, 117.0, 117.0, 117.0, 117.0], "value": 117.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 146.0, 147.0, 148.0, 148.0, 147.0, 147.0, 146.0, 148.0, 146.0], "value": 147.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 98.0, 95.0], "value": 96.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 67.0, 66.0, 65.0, 67.0, 68.0, 66.0, 66.0, 74.0], "value": 66.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 67.0, 66.0, 64.0, 66.0, 65.0, 65.0, 64.0, 65.0], "value": 65.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 80.0, 78.0, 79.0, 79.0, 80.0, 80.0, 91.0, 117.0, 80.0], "value": 85.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 143.0, 142.0, 145.0, 141.0, 134.0, 134.0, 135.0, 137.0, 137.0], "value": 138.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [224.0, 234.0, 221.0, 223.0, 217.0, 217.0, 224.0, 222.0, 227.0, 219.0], "value": 222.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 78.0, 75.0, 76.0, 75.0, 77.0, 76.0, 77.0, 77.0], "value": 76.1, "unit": "score"}], "name": "kraken", "value": 1650.7, "alertThreshold": 2.0}]} 09:20:24 INFO - Return code: 0 09:20:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:20:24 ERROR - # TBPL SUCCESS # 09:20:24 INFO - Running post-action listener: _resource_record_post_action 09:20:24 INFO - Running post-run listener: _resource_record_post_run 09:20:25 INFO - Total resource usage - Wall time: 1197s; CPU: 13.0%; Read bytes: 4096; Write bytes: 531521536; Read time: 16; Write time: 579388 09:20:25 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 234553344; Read time: 0; Write time: 301776 09:20:25 INFO - run-tests - Wall time: 1182s; CPU: 13.0%; Read bytes: 4096; Write bytes: 296968192; Read time: 16; Write time: 277612 09:20:25 INFO - Running post-run listener: _upload_blobber_files 09:20:25 INFO - Blob upload gear active. 09:20:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:20:25 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:25 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:25 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:25 INFO - (blobuploader) - INFO - Open directory for files ... 09:20:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:20:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:20:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:20:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:20:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:20:26 INFO - (blobuploader) - INFO - Done attempting. 09:20:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:20:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:20:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:20:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:20:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:20:27 INFO - (blobuploader) - INFO - Done attempting. 09:20:27 INFO - (blobuploader) - INFO - Iteration through files over. 09:20:27 INFO - Return code: 0 09:20:27 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:20:27 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:20:27 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/bb986c4947c7a34b9991381e221580c55544f0415fac8f71c4fe14e4dd6afcc8fb1b2b48dae9ca400e082fa913982ae25f4ce8f921bbb1917a3e1e609d226fe5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/9f8ede8567ebe52ed41135e0d039ecc418124e75085c8e08666c5a66b1d6778eec87531314786c26bff8f6bc298d0da59e1459ddf9d87fe6e74bc56850f92e8e"} 09:20:27 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:20:27 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:20:27 INFO - Contents: 09:20:27 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/bb986c4947c7a34b9991381e221580c55544f0415fac8f71c4fe14e4dd6afcc8fb1b2b48dae9ca400e082fa913982ae25f4ce8f921bbb1917a3e1e609d226fe5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/9f8ede8567ebe52ed41135e0d039ecc418124e75085c8e08666c5a66b1d6778eec87531314786c26bff8f6bc298d0da59e1459ddf9d87fe6e74bc56850f92e8e"} 09:20:27 INFO - Running post-run listener: copy_logs_to_upload_dir 09:20:27 INFO - Copying logs to upload dir... 09:20:27 INFO - mkdir: /builds/slave/test/build/upload/logs 09:20:27 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1243.606517 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-04-26 09:20:27.331243) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-26 09:20:27.334364) ========= 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=cbe409c6de289db6ac707de3000001d4-1461685951.312872-1003828145 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/bb986c4947c7a34b9991381e221580c55544f0415fac8f71c4fe14e4dd6afcc8fb1b2b48dae9ca400e082fa913982ae25f4ce8f921bbb1917a3e1e609d226fe5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/9f8ede8567ebe52ed41135e0d039ecc418124e75085c8e08666c5a66b1d6778eec87531314786c26bff8f6bc298d0da59e1459ddf9d87fe6e74bc56850f92e8e"} 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.011169 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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/bb986c4947c7a34b9991381e221580c55544f0415fac8f71c4fe14e4dd6afcc8fb1b2b48dae9ca400e082fa913982ae25f4ce8f921bbb1917a3e1e609d226fe5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/9f8ede8567ebe52ed41135e0d039ecc418124e75085c8e08666c5a66b1d6778eec87531314786c26bff8f6bc298d0da59e1459ddf9d87fe6e74bc56850f92e8e"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-26 09:20:27.359298) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 09:20:27.359638) ========= 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=cbe409c6de289db6ac707de3000001d4-1461685951.312872-1003828145 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004506 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 09:20:27.443211) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-26 09:20:27.443530) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-26 09:20:27.443833) ========= ========= Total master_lag: 0.17 =========