builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-026 starttime: 1462795698.74 results: success (0) buildid: 20160509042143 builduid: 1b31992cf06444a89046370bf69c67af revision: 27bbfef7a520f127d7dc0e880de7adf4548dca8b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-09 05:08:18.741494) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-09 05:08:18.741919) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-09 05:08:18.742190) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-09 05:08:18.758928) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 05:08:18.759197) ========= 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=41bf3e9aed84707956354da8000001c5-1462795698.338535-401650172 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-09 05:08:19-- 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% 70.6M=0s 2016-05-09 05:08:19 (70.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.091198 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 05:08:18.863237) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 05:08:18.863628) ========= 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=41bf3e9aed84707956354da8000001c5-1462795698.338535-401650172 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018242 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 05:08:18.913413) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-09 05:08:18.913768) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 27bbfef7a520f127d7dc0e880de7adf4548dca8b --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 27bbfef7a520f127d7dc0e880de7adf4548dca8b --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=41bf3e9aed84707956354da8000001c5-1462795698.338535-401650172 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-09 05:08:19,283 truncating revision to first 12 chars 2016-05-09 05:08:19,283 Setting DEBUG logging. 2016-05-09 05:08:19,283 attempt 1/10 2016-05-09 05:08:19,283 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/27bbfef7a520?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-09 05:08:20,489 unpacking tar archive at: fx-team-27bbfef7a520/testing/mozharness/ program finished with exit code 0 elapsedTime=1.482003 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-09 05:08:20.409415) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-09 05:08:20.409765) ========= script_repo_revision: 27bbfef7a520f127d7dc0e880de7adf4548dca8b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-09 05:08:20.410149) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-09 05:08:20.410414) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-09 05:08:20.418854) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2016-05-09 05:08:20.419142) ========= /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=41bf3e9aed84707956354da8000001c5-1462795698.338535-401650172 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 05:08:20 INFO - MultiFileLogger online at 20160509 05:08:20 in /builds/slave/test 05:08:20 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 05:08:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:08:20 INFO - {'append_to_log': False, 05:08:20 INFO - 'base_work_dir': '/builds/slave/test', 05:08:20 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 05:08:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:08:20 INFO - 'branch': 'Fx-Team-Non-PGO', 05:08:20 INFO - 'buildbot_json_path': 'buildprops.json', 05:08:20 INFO - 'config_files': ('talos/linux_config.py',), 05:08:20 INFO - 'default_actions': ('clobber', 05:08:20 INFO - 'read-buildbot-config', 05:08:20 INFO - 'download-and-extract', 05:08:20 INFO - 'populate-webroot', 05:08:20 INFO - 'create-virtualenv', 05:08:20 INFO - 'install', 05:08:20 INFO - 'run-tests'), 05:08:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:08:20 INFO - 'download_minidump_stackwalk': True, 05:08:20 INFO - 'download_symbols': 'ondemand', 05:08:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:08:20 INFO - 'tooltool.py': '/tools/tooltool.py', 05:08:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:08:20 INFO - '/tools/misc-python/virtualenv.py')}, 05:08:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:08:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:08:20 INFO - 'installer_path': 'installer.exe', 05:08:20 INFO - 'log_level': 'info', 05:08:20 INFO - 'log_name': 'talos', 05:08:20 INFO - 'log_to_console': True, 05:08:20 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 05:08:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 05:08:20 INFO - 'opt_config_files': (), 05:08:20 INFO - 'pip_index': False, 05:08:20 INFO - 'sps_profile': False, 05:08:20 INFO - 'sps_profile_interval': 0, 05:08:20 INFO - 'suite': 'dromaeojs-e10s', 05:08:20 INFO - 'system_bits': '32', 05:08:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:08:20 INFO - 'title': 'talos-linux64-ix-026', 05:08:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:08:20 INFO - 'use_talos_json': True, 05:08:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:08:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:08:20 INFO - 'work_dir': 'build'} 05:08:20 INFO - ##### 05:08:20 INFO - ##### Running clobber step. 05:08:20 INFO - ##### 05:08:20 INFO - Running pre-action listener: _resource_record_pre_action 05:08:20 INFO - Running main action method: clobber 05:08:20 INFO - rmtree: /builds/slave/test/build 05:08:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:08:21 INFO - Running post-action listener: _resource_record_post_action 05:08:21 INFO - ##### 05:08:21 INFO - ##### Finished clobber step (success) 05:08:21 INFO - ##### 05:08:21 INFO - ##### 05:08:21 INFO - ##### Running read-buildbot-config step. 05:08:21 INFO - ##### 05:08:21 INFO - Running pre-action listener: _resource_record_pre_action 05:08:21 INFO - Running main action method: read_buildbot_config 05:08:21 INFO - Using buildbot properties: 05:08:21 INFO - { 05:08:21 INFO - "project": "", 05:08:21 INFO - "product": "firefox", 05:08:21 INFO - "script_repo_revision": "production", 05:08:21 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 05:08:21 INFO - "repository": "", 05:08:21 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs-e10s", 05:08:21 INFO - "buildid": "20160509042143", 05:08:21 INFO - "pgo_build": "False", 05:08:21 INFO - "basedir": "/builds/slave/test", 05:08:21 INFO - "buildnumber": 1266, 05:08:21 INFO - "slavename": "talos-linux64-ix-026", 05:08:21 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 05:08:21 INFO - "platform": "ubuntu64_hw", 05:08:21 INFO - "scheduler": "tests-fx-team-linux64-talos", 05:08:21 INFO - "branch": "fx-team", 05:08:21 INFO - "repo_path": "integration/fx-team", 05:08:21 INFO - "revision": "27bbfef7a520f127d7dc0e880de7adf4548dca8b", 05:08:21 INFO - "stage_platform": "linux64", 05:08:21 INFO - "builduid": "1b31992cf06444a89046370bf69c67af", 05:08:21 INFO - "slavebuilddir": "test" 05:08:21 INFO - } 05:08:21 INFO - Found installer url https://queue.taskcluster.net/v1/task/ec9URdT1StCIucoQLQHEBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 05:08:21 INFO - Running post-action listener: _resource_record_post_action 05:08:21 INFO - ##### 05:08:21 INFO - ##### Finished read-buildbot-config step (success) 05:08:21 INFO - ##### 05:08:21 INFO - ##### 05:08:21 INFO - ##### Running download-and-extract step. 05:08:21 INFO - ##### 05:08:21 INFO - Running pre-action listener: _resource_record_pre_action 05:08:21 INFO - Running main action method: download_and_extract 05:08:21 INFO - mkdir: /builds/slave/test/build/tests 05:08:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:21 INFO - https://queue.taskcluster.net/v1/task/ec9URdT1StCIucoQLQHEBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 05:08:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ec9URdT1StCIucoQLQHEBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 05:08:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ec9URdT1StCIucoQLQHEBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 05:08:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ec9URdT1StCIucoQLQHEBA/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 05:08:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ec9URdT1StCIucoQLQHEBA/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 05:08:22 INFO - Downloaded 1361 bytes. 05:08:22 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 05:08:22 INFO - Using the following test package requirements: 05:08:22 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 05:08:22 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:08:22 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 05:08:22 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:08:22 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 05:08:22 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:08:22 INFO - u'jsshell-linux-x86_64.zip'], 05:08:22 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:08:22 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 05:08:22 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 05:08:22 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:08:22 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 05:08:22 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:08:22 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 05:08:22 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:08:22 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 05:08:22 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 05:08:22 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 05:08:22 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 05:08:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:22 INFO - https://queue.taskcluster.net/v1/task/ec9URdT1StCIucoQLQHEBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:08:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ec9URdT1StCIucoQLQHEBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 05:08:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ec9URdT1StCIucoQLQHEBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 05:08:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ec9URdT1StCIucoQLQHEBA/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 05:08:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ec9URdT1StCIucoQLQHEBA/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 05:08:23 INFO - Downloaded 22322863 bytes. 05:08:23 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'] 05:08:23 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 05:08:24 INFO - Return code: 0 05:08:24 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 05:08:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:24 INFO - https://queue.taskcluster.net/v1/task/ec9URdT1StCIucoQLQHEBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 05:08:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ec9URdT1StCIucoQLQHEBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 05:08:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ec9URdT1StCIucoQLQHEBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 05:08:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ec9URdT1StCIucoQLQHEBA/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 05:08:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ec9URdT1StCIucoQLQHEBA/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 05:08:25 INFO - Downloaded 22322863 bytes. 05:08:25 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'] 05:08:25 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 05:08:26 INFO - Return code: 0 05:08:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:26 INFO - https://queue.taskcluster.net/v1/task/ec9URdT1StCIucoQLQHEBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 05:08:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ec9URdT1StCIucoQLQHEBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 05:08:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ec9URdT1StCIucoQLQHEBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 05:08:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ec9URdT1StCIucoQLQHEBA/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 05:08:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ec9URdT1StCIucoQLQHEBA/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 05:08:27 INFO - Downloaded 10840152 bytes. 05:08:27 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'] 05:08:27 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 05:08:28 INFO - Return code: 0 05:08:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:28 INFO - https://queue.taskcluster.net/v1/task/ec9URdT1StCIucoQLQHEBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 05:08:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ec9URdT1StCIucoQLQHEBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 05:08:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ec9URdT1StCIucoQLQHEBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 05:08:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ec9URdT1StCIucoQLQHEBA/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 05:08:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ec9URdT1StCIucoQLQHEBA/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 05:08:29 INFO - Downloaded 54858774 bytes. 05:08:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ec9URdT1StCIucoQLQHEBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 05:08:29 INFO - mkdir: /builds/slave/test/properties 05:08:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:08:29 INFO - Writing to file /builds/slave/test/properties/build_url 05:08:29 INFO - Contents: 05:08:29 INFO - build_url:https://queue.taskcluster.net/v1/task/ec9URdT1StCIucoQLQHEBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 05:08:30 INFO - Running post-action listener: _resource_record_post_action 05:08:30 INFO - Running post-action listener: set_extra_try_arguments 05:08:30 INFO - ##### 05:08:30 INFO - ##### Finished download-and-extract step (success) 05:08:30 INFO - ##### 05:08:30 INFO - ##### 05:08:30 INFO - ##### Running populate-webroot step. 05:08:30 INFO - ##### 05:08:30 INFO - Running pre-action listener: _resource_record_pre_action 05:08:30 INFO - Running main action method: populate_webroot 05:08:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:08:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:08:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:08:30 INFO - '--tppagecycles', 05:08:30 INFO - '7'], 05:08:30 INFO - 'tests': ['tsvgm']}}, 05:08:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:08:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:08:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:08:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:08:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:08:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:08:30 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:30 INFO - 'talos_options': ['--disable-e10s'], 05:08:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:08:30 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:08:30 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:30 INFO - 'talos_options': ['--disable-e10s'], 05:08:30 INFO - 'tests': ['damp', 'tps']}, 05:08:30 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:30 INFO - 'tests': ['damp', 'tps']}, 05:08:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:08:30 INFO - 'tests': ['dromaeo_dom']}, 05:08:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:08:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:08:30 INFO - 'tests': ['a11yr', 05:08:30 INFO - 'ts_paint', 05:08:30 INFO - 'tpaint', 05:08:30 INFO - 'sessionrestore', 05:08:30 INFO - 'sessionrestore_no_auto_restore', 05:08:30 INFO - 'tabpaint']}, 05:08:30 INFO - 'other-e10s': {'tests': ['a11yr', 05:08:30 INFO - 'ts_paint', 05:08:30 INFO - 'tpaint', 05:08:30 INFO - 'sessionrestore', 05:08:30 INFO - 'sessionrestore_no_auto_restore', 05:08:30 INFO - 'tabpaint']}, 05:08:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:08:30 INFO - 'ts_paint', 05:08:30 INFO - 'tpaint', 05:08:30 INFO - 'sessionrestore', 05:08:30 INFO - 'sessionrestore_no_auto_restore', 05:08:30 INFO - 'tabpaint']}, 05:08:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:08:30 INFO - 'ts_paint', 05:08:30 INFO - 'tpaint', 05:08:30 INFO - 'sessionrestore', 05:08:30 INFO - 'sessionrestore_no_auto_restore', 05:08:30 INFO - 'tabpaint']}, 05:08:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:08:30 INFO - 'tests': ['a11yr', 05:08:30 INFO - 'ts_paint', 05:08:30 INFO - 'tpaint', 05:08:30 INFO - 'sessionrestore', 05:08:30 INFO - 'sessionrestore_no_auto_restore', 05:08:30 INFO - 'tabpaint']}, 05:08:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:08:30 INFO - 'tests': ['a11yr', 05:08:30 INFO - 'ts_paint', 05:08:30 INFO - 'tpaint', 05:08:30 INFO - 'sessionrestore', 05:08:30 INFO - 'sessionrestore_no_auto_restore', 05:08:30 INFO - 'tabpaint']}, 05:08:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:08:30 INFO - 'tests': ['tsvgx', 05:08:30 INFO - 'tsvgr_opacity', 05:08:30 INFO - 'tart', 05:08:30 INFO - 'tscrollx', 05:08:30 INFO - 'cart']}, 05:08:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:08:30 INFO - 'tsvgr_opacity', 05:08:30 INFO - 'tart', 05:08:30 INFO - 'tscrollx', 05:08:30 INFO - 'cart']}, 05:08:30 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:30 INFO - 'talos_options': ['--disable-e10s'], 05:08:30 INFO - 'tests': ['tp5o']}, 05:08:30 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:30 INFO - 'tests': ['tp5o']}, 05:08:30 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:30 INFO - 'talos_options': ['--disable-e10s', 05:08:30 INFO - '--xperf_path', 05:08:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:08:30 INFO - 'tests': ['tp5n']}, 05:08:30 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:08:30 INFO - 'talos_options': ['--xperf_path', 05:08:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:08:30 INFO - 'tests': ['tp5n']}}, 05:08:30 INFO - 'talos.zip': {'path': '', 05:08:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:08:30 INFO - Running post-action listener: _resource_record_post_action 05:08:30 INFO - ##### 05:08:30 INFO - ##### Finished populate-webroot step (success) 05:08:30 INFO - ##### 05:08:30 INFO - ##### 05:08:30 INFO - ##### Running create-virtualenv step. 05:08:30 INFO - ##### 05:08:30 INFO - Running pre-action listener: _resource_record_pre_action 05:08:30 INFO - Running main action method: create_virtualenv 05:08:30 INFO - Creating virtualenv /builds/slave/test/build/venv 05:08:30 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:08:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:08:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:08:30 INFO - Using real prefix '/usr' 05:08:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:08:30 INFO - Installing distribute.............................................................................................................................................................................................done. 05:08:32 INFO - Installing pip.................done. 05:08:32 INFO - Return code: 0 05:08:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:08:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:08:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:08:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f5c74327f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22bc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2387770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24169c0>, '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 0x24178c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2417d50>, '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': '41bf3e9aed84707956354da8000001c5-1462795698.338535-401650172', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:08:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:08:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:08:32 INFO - Using env: {'DISPLAY': ':0', 05:08:32 INFO - 'HOME': '/home/cltbld', 05:08:32 INFO - 'LANG': 'en_US.UTF-8', 05:08:32 INFO - 'LANGUAGE': 'en_US:en', 05:08:32 INFO - 'LOGNAME': 'cltbld', 05:08:32 INFO - 'MAIL': '/var/mail/cltbld', 05:08:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:08:32 INFO - 'MOZ_NO_REMOTE': '1', 05:08:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:08:32 INFO - 'NO_EM_RESTART': '1', 05:08:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:08:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:08:32 INFO - 'PWD': '/builds/slave/test', 05:08:32 INFO - 'SHELL': '/bin/bash', 05:08:32 INFO - 'SHLVL': '1', 05:08:32 INFO - 'TERM': 'linux', 05:08:32 INFO - 'TMOUT': '86400', 05:08:32 INFO - 'USER': 'cltbld', 05:08:32 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1462795698.338535-401650172', 05:08:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:08:32 INFO - '_': '/tools/buildbot/bin/python'} 05:08:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:08:32 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:08:32 INFO - Cleaning up... 05:08:32 INFO - Return code: 0 05:08:32 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:08:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:08:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:08:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f5c74327f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22bc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2387770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24169c0>, '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 0x24178c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2417d50>, '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': '41bf3e9aed84707956354da8000001c5-1462795698.338535-401650172', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:08:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 05:08:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 05:08:32 INFO - Using env: {'DISPLAY': ':0', 05:08:32 INFO - 'HOME': '/home/cltbld', 05:08:32 INFO - 'LANG': 'en_US.UTF-8', 05:08:32 INFO - 'LANGUAGE': 'en_US:en', 05:08:32 INFO - 'LOGNAME': 'cltbld', 05:08:32 INFO - 'MAIL': '/var/mail/cltbld', 05:08:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:08:32 INFO - 'MOZ_NO_REMOTE': '1', 05:08:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:08:32 INFO - 'NO_EM_RESTART': '1', 05:08:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:08:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:08:32 INFO - 'PWD': '/builds/slave/test', 05:08:32 INFO - 'SHELL': '/bin/bash', 05:08:32 INFO - 'SHLVL': '1', 05:08:32 INFO - 'TERM': 'linux', 05:08:32 INFO - 'TMOUT': '86400', 05:08:32 INFO - 'USER': 'cltbld', 05:08:32 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1462795698.338535-401650172', 05:08:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:08:32 INFO - '_': '/tools/buildbot/bin/python'} 05:08:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:08:33 INFO - Downloading/unpacking psutil>=0.7.1 05:08:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:08:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:08:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:08:35 INFO - Installing collected packages: psutil 05:08:35 INFO - Running setup.py install for psutil 05:08:35 INFO - building 'psutil._psutil_linux' extension 05:08:35 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 05:08:35 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 05:08:35 INFO - building 'psutil._psutil_posix' extension 05:08:35 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 05:08:35 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 05:08:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:08:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:08:35 INFO - Successfully installed psutil 05:08:35 INFO - Cleaning up... 05:08:35 INFO - Return code: 0 05:08:35 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:08:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:08:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:08:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f5c74327f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22bc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2387770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24169c0>, '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 0x24178c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2417d50>, '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': '41bf3e9aed84707956354da8000001c5-1462795698.338535-401650172', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:08:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 05:08:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 05:08:35 INFO - Using env: {'DISPLAY': ':0', 05:08:35 INFO - 'HOME': '/home/cltbld', 05:08:35 INFO - 'LANG': 'en_US.UTF-8', 05:08:35 INFO - 'LANGUAGE': 'en_US:en', 05:08:35 INFO - 'LOGNAME': 'cltbld', 05:08:35 INFO - 'MAIL': '/var/mail/cltbld', 05:08:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:08:35 INFO - 'MOZ_NO_REMOTE': '1', 05:08:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:08:35 INFO - 'NO_EM_RESTART': '1', 05:08:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:08:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:08:35 INFO - 'PWD': '/builds/slave/test', 05:08:35 INFO - 'SHELL': '/bin/bash', 05:08:35 INFO - 'SHLVL': '1', 05:08:35 INFO - 'TERM': 'linux', 05:08:35 INFO - 'TMOUT': '86400', 05:08:35 INFO - 'USER': 'cltbld', 05:08:35 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1462795698.338535-401650172', 05:08:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:08:35 INFO - '_': '/tools/buildbot/bin/python'} 05:08:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:08:35 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:08:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:37 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:08:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:08:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:08:37 INFO - Installing collected packages: mozsystemmonitor 05:08:37 INFO - Running setup.py install for mozsystemmonitor 05:08:37 INFO - Successfully installed mozsystemmonitor 05:08:37 INFO - Cleaning up... 05:08:37 INFO - Return code: 0 05:08:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:08:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:08:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:08:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f5c74327f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22bc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2387770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24169c0>, '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 0x24178c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2417d50>, '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': '41bf3e9aed84707956354da8000001c5-1462795698.338535-401650172', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:08:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:08:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:08:37 INFO - Using env: {'DISPLAY': ':0', 05:08:37 INFO - 'HOME': '/home/cltbld', 05:08:37 INFO - 'LANG': 'en_US.UTF-8', 05:08:37 INFO - 'LANGUAGE': 'en_US:en', 05:08:37 INFO - 'LOGNAME': 'cltbld', 05:08:37 INFO - 'MAIL': '/var/mail/cltbld', 05:08:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:08:37 INFO - 'MOZ_NO_REMOTE': '1', 05:08:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:08:37 INFO - 'NO_EM_RESTART': '1', 05:08:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:08:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:08:37 INFO - 'PWD': '/builds/slave/test', 05:08:37 INFO - 'SHELL': '/bin/bash', 05:08:37 INFO - 'SHLVL': '1', 05:08:37 INFO - 'TERM': 'linux', 05:08:37 INFO - 'TMOUT': '86400', 05:08:37 INFO - 'USER': 'cltbld', 05:08:37 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1462795698.338535-401650172', 05:08:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:08:37 INFO - '_': '/tools/buildbot/bin/python'} 05:08:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:08:38 INFO - Downloading/unpacking blobuploader==1.2.4 05:08:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:40 INFO - Downloading blobuploader-1.2.4.tar.gz 05:08:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:08:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:08:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:08:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:08:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:40 INFO - Downloading docopt-0.6.1.tar.gz 05:08:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:08:40 INFO - Installing collected packages: blobuploader, requests, docopt 05:08:40 INFO - Running setup.py install for blobuploader 05:08:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:08:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:08:40 INFO - Running setup.py install for requests 05:08:41 INFO - Running setup.py install for docopt 05:08:41 INFO - Successfully installed blobuploader requests docopt 05:08:41 INFO - Cleaning up... 05:08:41 INFO - Return code: 0 05:08:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:08:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:08:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:08:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f5c74327f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22bc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2387770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24169c0>, '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 0x24178c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2417d50>, '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': '41bf3e9aed84707956354da8000001c5-1462795698.338535-401650172', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:08:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:08:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:08:41 INFO - Using env: {'DISPLAY': ':0', 05:08:41 INFO - 'HOME': '/home/cltbld', 05:08:41 INFO - 'LANG': 'en_US.UTF-8', 05:08:41 INFO - 'LANGUAGE': 'en_US:en', 05:08:41 INFO - 'LOGNAME': 'cltbld', 05:08:41 INFO - 'MAIL': '/var/mail/cltbld', 05:08:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:08:41 INFO - 'MOZ_NO_REMOTE': '1', 05:08:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:08:41 INFO - 'NO_EM_RESTART': '1', 05:08:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:08:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:08:41 INFO - 'PWD': '/builds/slave/test', 05:08:41 INFO - 'SHELL': '/bin/bash', 05:08:41 INFO - 'SHLVL': '1', 05:08:41 INFO - 'TERM': 'linux', 05:08:41 INFO - 'TMOUT': '86400', 05:08:41 INFO - 'USER': 'cltbld', 05:08:41 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1462795698.338535-401650172', 05:08:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:08:41 INFO - '_': '/tools/buildbot/bin/python'} 05:08:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:08:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:08:41 INFO - Running setup.py (path:/tmp/pip-1dl5U9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:08:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:08:41 INFO - Running setup.py (path:/tmp/pip-KvsSAk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:08:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:08:41 INFO - Running setup.py (path:/tmp/pip-scRmow-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:08:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:08:41 INFO - Running setup.py (path:/tmp/pip-O7pDed-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:08:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:08:41 INFO - Running setup.py (path:/tmp/pip-YO1lDH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:08:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:08:41 INFO - Running setup.py (path:/tmp/pip-98PpL2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:08:42 INFO - Running setup.py (path:/tmp/pip-w8bZZ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:08:42 INFO - Running setup.py (path:/tmp/pip-4aELtd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:08:42 INFO - Running setup.py (path:/tmp/pip-2GrcDO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:08:42 INFO - Running setup.py (path:/tmp/pip-ts_jfn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:08:42 INFO - Running setup.py (path:/tmp/pip-nAbzMM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:08:42 INFO - Running setup.py (path:/tmp/pip-QJreN6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:08:42 INFO - Running setup.py (path:/tmp/pip-pUIduY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:08:42 INFO - Running setup.py (path:/tmp/pip-hcOSuF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:08:42 INFO - Running setup.py (path:/tmp/pip-UKZ6nH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:08:42 INFO - Running setup.py (path:/tmp/pip-SxQXDY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:08:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:08:42 INFO - Running setup.py (path:/tmp/pip-h8D_WK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:08:42 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:08:42 INFO - Running setup.py install for manifestparser 05:08:42 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:08:42 INFO - Running setup.py install for mozcrash 05:08:43 INFO - Running setup.py install for mozdebug 05:08:43 INFO - Running setup.py install for mozdevice 05:08:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:08:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:08:43 INFO - Running setup.py install for mozfile 05:08:43 INFO - Running setup.py install for mozhttpd 05:08:43 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:08:43 INFO - Running setup.py install for mozinfo 05:08:43 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:08:43 INFO - Running setup.py install for mozInstall 05:08:43 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:08:43 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:08:43 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:08:43 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:08:43 INFO - Running setup.py install for mozleak 05:08:43 INFO - Running setup.py install for mozlog 05:08:43 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:08:43 INFO - Running setup.py install for moznetwork 05:08:43 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:08:43 INFO - Running setup.py install for mozprocess 05:08:44 INFO - Running setup.py install for mozprofile 05:08:44 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:08:44 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:08:44 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:08:44 INFO - Running setup.py install for mozrunner 05:08:44 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:08:44 INFO - Running setup.py install for mozscreenshot 05:08:44 INFO - Running setup.py install for moztest 05:08:44 INFO - Running setup.py install for mozversion 05:08:44 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:08:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:08:44 INFO - Cleaning up... 05:08:44 INFO - Return code: 0 05:08:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:08:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:08:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:08:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f5c74327f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22bc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2387770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24169c0>, '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 0x24178c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2417d50>, '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': '41bf3e9aed84707956354da8000001c5-1462795698.338535-401650172', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:08:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:08:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:08:44 INFO - Using env: {'DISPLAY': ':0', 05:08:44 INFO - 'HOME': '/home/cltbld', 05:08:44 INFO - 'LANG': 'en_US.UTF-8', 05:08:44 INFO - 'LANGUAGE': 'en_US:en', 05:08:44 INFO - 'LOGNAME': 'cltbld', 05:08:44 INFO - 'MAIL': '/var/mail/cltbld', 05:08:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:08:44 INFO - 'MOZ_NO_REMOTE': '1', 05:08:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:08:44 INFO - 'NO_EM_RESTART': '1', 05:08:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:08:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:08:44 INFO - 'PWD': '/builds/slave/test', 05:08:44 INFO - 'SHELL': '/bin/bash', 05:08:44 INFO - 'SHLVL': '1', 05:08:44 INFO - 'TERM': 'linux', 05:08:44 INFO - 'TMOUT': '86400', 05:08:44 INFO - 'USER': 'cltbld', 05:08:44 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1462795698.338535-401650172', 05:08:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:08:44 INFO - '_': '/tools/buildbot/bin/python'} 05:08:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:08:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:08:44 INFO - Running setup.py (path:/tmp/pip-1dMGjs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:08:44 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:08:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:08:44 INFO - Running setup.py (path:/tmp/pip-kYuRI3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:08:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:08:45 INFO - Running setup.py (path:/tmp/pip-rXKsa6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:08:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:08:45 INFO - Running setup.py (path:/tmp/pip-JjDE27-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:08:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:08:45 INFO - Running setup.py (path:/tmp/pip-jCsBOt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:08:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:08:45 INFO - Running setup.py (path:/tmp/pip-N8mh2e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:08:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:08:45 INFO - Running setup.py (path:/tmp/pip-CWNeOc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:08:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:08:45 INFO - Running setup.py (path:/tmp/pip-NDdhHD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:08:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:08:45 INFO - Running setup.py (path:/tmp/pip-ruOuOR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:08:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:08:45 INFO - Running setup.py (path:/tmp/pip-FGk3r2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:08:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:08:45 INFO - Running setup.py (path:/tmp/pip-oc4hjD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:08:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:08:45 INFO - Running setup.py (path:/tmp/pip-sMHOHm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:08:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:08:45 INFO - Running setup.py (path:/tmp/pip-WkVzym-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:08:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:08:45 INFO - Running setup.py (path:/tmp/pip-YDp6Bv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:08:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:08:45 INFO - Running setup.py (path:/tmp/pip-IXncrX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:08:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:08:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:08:45 INFO - Running setup.py (path:/tmp/pip-qhOfbq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:08:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:08:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:08:46 INFO - Running setup.py (path:/tmp/pip-SzhlaK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:08:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:08:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:08:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:08:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:08:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:08:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:08:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:08:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:47 INFO - Downloading blessings-1.6.tar.gz 05:08:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:08:48 INFO - Installing collected packages: blessings 05:08:48 INFO - Running setup.py install for blessings 05:08:48 INFO - Successfully installed blessings 05:08:48 INFO - Cleaning up... 05:08:48 INFO - Return code: 0 05:08:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:08:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:08:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:08:48 INFO - Reading from file tmpfile_stdout 05:08:48 INFO - Current package versions: 05:08:48 INFO - argparse == 1.2.1 05:08:48 INFO - blessings == 1.6 05:08:48 INFO - blobuploader == 1.2.4 05:08:48 INFO - docopt == 0.6.1 05:08:48 INFO - manifestparser == 1.1 05:08:48 INFO - mozInstall == 1.12 05:08:48 INFO - mozcrash == 0.17 05:08:48 INFO - mozdebug == 0.1 05:08:48 INFO - mozdevice == 0.48 05:08:48 INFO - mozfile == 1.2 05:08:48 INFO - mozhttpd == 0.7 05:08:48 INFO - mozinfo == 0.9 05:08:48 INFO - mozleak == 0.1 05:08:48 INFO - mozlog == 3.1 05:08:48 INFO - moznetwork == 0.27 05:08:48 INFO - mozprocess == 0.22 05:08:48 INFO - mozprofile == 0.28 05:08:48 INFO - mozrunner == 6.11 05:08:48 INFO - mozscreenshot == 0.1 05:08:48 INFO - mozsystemmonitor == 0.0 05:08:48 INFO - moztest == 0.7 05:08:48 INFO - mozversion == 1.4 05:08:48 INFO - psutil == 3.1.1 05:08:48 INFO - requests == 1.2.3 05:08:48 INFO - wsgiref == 0.1.2 05:08:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:08:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:08:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:08:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f5c74327f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22bc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2387770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24169c0>, '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 0x24178c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2417d50>, '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': '41bf3e9aed84707956354da8000001c5-1462795698.338535-401650172', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:08:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:08:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:08:48 INFO - Using env: {'DISPLAY': ':0', 05:08:48 INFO - 'HOME': '/home/cltbld', 05:08:48 INFO - 'LANG': 'en_US.UTF-8', 05:08:48 INFO - 'LANGUAGE': 'en_US:en', 05:08:48 INFO - 'LOGNAME': 'cltbld', 05:08:48 INFO - 'MAIL': '/var/mail/cltbld', 05:08:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:08:48 INFO - 'MOZ_NO_REMOTE': '1', 05:08:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:08:48 INFO - 'NO_EM_RESTART': '1', 05:08:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:08:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:08:48 INFO - 'PWD': '/builds/slave/test', 05:08:48 INFO - 'SHELL': '/bin/bash', 05:08:48 INFO - 'SHLVL': '1', 05:08:48 INFO - 'TERM': 'linux', 05:08:48 INFO - 'TMOUT': '86400', 05:08:48 INFO - 'USER': 'cltbld', 05:08:48 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1462795698.338535-401650172', 05:08:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:08:48 INFO - '_': '/tools/buildbot/bin/python'} 05:08:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:08:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:08:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:08:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:08:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:08:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:08:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:08:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:08:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:08:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:08:48 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:08:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:08:48 INFO - Cleaning up... 05:08:48 INFO - Return code: 0 05:08:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:08:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:08:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:08:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f5c74327f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22bc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2387770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24169c0>, '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 0x24178c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2417d50>, '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': '41bf3e9aed84707956354da8000001c5-1462795698.338535-401650172', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:08:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:08:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:08:48 INFO - Using env: {'DISPLAY': ':0', 05:08:48 INFO - 'HOME': '/home/cltbld', 05:08:48 INFO - 'LANG': 'en_US.UTF-8', 05:08:48 INFO - 'LANGUAGE': 'en_US:en', 05:08:48 INFO - 'LOGNAME': 'cltbld', 05:08:48 INFO - 'MAIL': '/var/mail/cltbld', 05:08:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:08:48 INFO - 'MOZ_NO_REMOTE': '1', 05:08:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:08:48 INFO - 'NO_EM_RESTART': '1', 05:08:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:08:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:08:48 INFO - 'PWD': '/builds/slave/test', 05:08:48 INFO - 'SHELL': '/bin/bash', 05:08:48 INFO - 'SHLVL': '1', 05:08:48 INFO - 'TERM': 'linux', 05:08:48 INFO - 'TMOUT': '86400', 05:08:48 INFO - 'USER': 'cltbld', 05:08:48 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1462795698.338535-401650172', 05:08:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:08:48 INFO - '_': '/tools/buildbot/bin/python'} 05:08:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:08:48 INFO - Downloading/unpacking jsonschema 05:08:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:08:50 INFO - Downloading/unpacking functools32 (from jsonschema) 05:08:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:50 INFO - Downloading functools32-3.2.3-2.tar.gz 05:08:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:08:51 INFO - warning: no files found matching '*.txt' 05:08:51 INFO - no previously-included directories found matching 'build' 05:08:51 INFO - no previously-included directories found matching 'dist' 05:08:51 INFO - no previously-included directories found matching '.git*' 05:08:51 INFO - Installing collected packages: jsonschema, functools32 05:08:51 INFO - Running setup.py install for functools32 05:08:51 INFO - warning: no files found matching '*.txt' 05:08:51 INFO - no previously-included directories found matching 'build' 05:08:51 INFO - no previously-included directories found matching 'dist' 05:08:51 INFO - no previously-included directories found matching '.git*' 05:08:51 INFO - Successfully installed jsonschema functools32 05:08:51 INFO - Cleaning up... 05:08:51 INFO - Return code: 0 05:08:51 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:08:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:08:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:08:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:08:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:08:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f5c74327f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22bc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2387770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24169c0>, '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 0x24178c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2417d50>, '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': '41bf3e9aed84707956354da8000001c5-1462795698.338535-401650172', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 05:08:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 05:08:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 05:08:51 INFO - Using env: {'DISPLAY': ':0', 05:08:51 INFO - 'HOME': '/home/cltbld', 05:08:51 INFO - 'LANG': 'en_US.UTF-8', 05:08:51 INFO - 'LANGUAGE': 'en_US:en', 05:08:51 INFO - 'LOGNAME': 'cltbld', 05:08:51 INFO - 'MAIL': '/var/mail/cltbld', 05:08:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:08:51 INFO - 'MOZ_NO_REMOTE': '1', 05:08:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:08:51 INFO - 'NO_EM_RESTART': '1', 05:08:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:08:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:08:51 INFO - 'PWD': '/builds/slave/test', 05:08:51 INFO - 'SHELL': '/bin/bash', 05:08:51 INFO - 'SHLVL': '1', 05:08:51 INFO - 'TERM': 'linux', 05:08:51 INFO - 'TMOUT': '86400', 05:08:51 INFO - 'USER': 'cltbld', 05:08:51 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1462795698.338535-401650172', 05:08:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:08:51 INFO - '_': '/tools/buildbot/bin/python'} 05:08:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:08:51 INFO - Downloading/unpacking flake8 05:08:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:53 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:08:53 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:08:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:53 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:08:53 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:08:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:53 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:08:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:08:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:08:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:08:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:08:54 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:08:54 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:08:54 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:08:54 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:08:54 INFO - no previously-included directories found matching 'docs/_build' 05:08:54 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:08:54 INFO - Running setup.py install for pep8 05:08:54 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:08:54 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:08:54 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:08:54 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:08:54 INFO - no previously-included directories found matching 'docs/_build' 05:08:54 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:08:54 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:08:54 INFO - Cleaning up... 05:08:54 INFO - Return code: 0 05:08:54 INFO - Running post-action listener: _resource_record_post_action 05:08:54 INFO - Running post-action listener: _start_resource_monitoring 05:08:54 INFO - Starting resource monitoring. 05:08:54 INFO - ##### 05:08:54 INFO - ##### Finished create-virtualenv step (success) 05:08:54 INFO - ##### 05:08:54 INFO - ##### 05:08:54 INFO - ##### Running install step. 05:08:54 INFO - ##### 05:08:54 INFO - Running pre-action listener: _resource_record_pre_action 05:08:54 INFO - Running main action method: install 05:08:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:08:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:08:54 INFO - Reading from file tmpfile_stdout 05:08:54 INFO - Detecting whether we're running mozinstall >=1.0... 05:08:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:08:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:08:54 INFO - Reading from file tmpfile_stdout 05:08:54 INFO - Output received: 05:08:54 INFO - Usage: mozinstall [options] installer 05:08:54 INFO - Options: 05:08:54 INFO - -h, --help show this help message and exit 05:08:54 INFO - -d DEST, --destination=DEST 05:08:54 INFO - Directory to install application into. [default: 05:08:54 INFO - "/builds/slave/test"] 05:08:54 INFO - --app=APP Application being installed. [default: firefox] 05:08:54 INFO - mkdir: /builds/slave/test/build/application 05:08:54 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'] 05:08:54 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 05:09:10 INFO - Reading from file tmpfile_stdout 05:09:10 INFO - Output received: 05:09:10 INFO - /builds/slave/test/build/application/firefox/firefox 05:09:10 INFO - Running post-action listener: _resource_record_post_action 05:09:10 INFO - ##### 05:09:10 INFO - ##### Finished install step (success) 05:09:10 INFO - ##### 05:09:10 INFO - ##### 05:09:10 INFO - ##### Running run-tests step. 05:09:10 INFO - ##### 05:09:10 INFO - Running pre-action listener: _resource_record_pre_action 05:09:10 INFO - Running main action method: run_tests 05:09:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:09:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:09:10 INFO - Python 2.7.3 05:09:10 INFO - Return code: 0 05:09:10 INFO - grabbing minidump binary from tooltool 05:09:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:09:10 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24169c0>, '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 0x24178c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2417d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:09:10 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:09:10 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 05:09:10 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:09:10 INFO - Return code: 0 05:09:10 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 05:09:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:09:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:09:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 05:09:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:09:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:09:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:09:11 INFO - Return code: 0 05:09:11 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/ec9URdT1StCIucoQLQHEBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-026', '--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 05:09:11 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/ec9URdT1StCIucoQLQHEBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-026 --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 05:09:11 INFO - Using env: {'DISPLAY': ':0', 05:09:11 INFO - 'HOME': '/home/cltbld', 05:09:11 INFO - 'LANG': 'en_US.UTF-8', 05:09:11 INFO - 'LANGUAGE': 'en_US:en', 05:09:11 INFO - 'LOGNAME': 'cltbld', 05:09:11 INFO - 'MAIL': '/var/mail/cltbld', 05:09:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:09:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 05:09:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:09:11 INFO - 'MOZ_NO_REMOTE': '1', 05:09:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:09:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 05:09:11 INFO - 'NO_EM_RESTART': '1', 05:09:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 05:09:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:09:11 INFO - 'PWD': '/builds/slave/test', 05:09:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:09:11 INFO - 'SHELL': '/bin/bash', 05:09:11 INFO - 'SHLVL': '1', 05:09:11 INFO - 'TERM': 'linux', 05:09:11 INFO - 'TMOUT': '86400', 05:09:11 INFO - 'USER': 'cltbld', 05:09:11 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1462795698.338535-401650172', 05:09:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:09:11 INFO - '_': '/tools/buildbot/bin/python'} 05:09:11 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/ec9URdT1StCIucoQLQHEBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-026', '--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 05:09:11 INFO - mozversion application_buildid: 20160509042143 05:09:11 INFO - mozversion application_changeset: 27bbfef7a520f127d7dc0e880de7adf4548dca8b 05:09:11 INFO - mozversion application_display_name: Nightly 05:09:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:09:11 INFO - mozversion application_name: Firefox 05:09:11 INFO - mozversion application_remotingname: firefox 05:09:11 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:09:11 INFO - mozversion application_vendor: Mozilla 05:09:11 INFO - mozversion application_version: 49.0a1 05:09:11 INFO - mozversion platform_buildid: 20160509042143 05:09:11 INFO - mozversion platform_changeset: 27bbfef7a520f127d7dc0e880de7adf4548dca8b 05:09:11 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:09:11 INFO - mozversion platform_version: 49.0a1 05:09:11 INFO - using testdate: 1462795751 05:09:11 INFO - actual date: 1462795751 05:09:11 INFO - starting webserver on 'localhost:35609' 05:09:11 INFO - SUITE-START | Running 2 tests 05:09:11 INFO - TEST-START | dromaeo_css 05:09:11 INFO - operating with platform_type : linux_ 05:09:11 INFO - Initialising browser for dromaeo_css test... 05:09:11 INFO - TEST-INFO | started process 25579 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTr5dFG/profile http://localhost:35609/getInfo.html) 05:09:15 INFO - PROCESS | 25579 | 05:09:15 INFO - PROCESS | 25579 | (process:25644): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 05:09:16 INFO - PROCESS | 25579 | __metrics Screen width/height:1600/1200 05:09:16 INFO - PROCESS | 25579 | colorDepth:24 05:09:16 INFO - PROCESS | 25579 | Browser inner width/height: 1024/697 05:09:16 INFO - PROCESS | 25579 | __metrics 05:09:16 ERROR - PROCESS | 25579 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2937: TypeError: this.tabs is undefined 05:09:17 INFO - TEST-INFO | 25579: exit 0 05:09:17 INFO - Browser initialized. 05:09:17 INFO - Running cycle 1/1 for dromaeo_css test... 05:09:17 INFO - TEST-INFO | started process 25695 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTr5dFG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:09:19 INFO - PROCESS | 25695 | 05:09:19 INFO - PROCESS | 25695 | (process:25756): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 05:09:29 INFO - PROCESS | 25695 | RSS: Main: 164200448 05:09:29 INFO - PROCESS | 25695 | 05:10:19 INFO - PROCESS | 25695 | 1462795819325 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:12:31 INFO - PROCESS | 25695 | Cycle 1(1): loaded http://localhost:35609/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35609/tests/dromaeo/cssquery-ext.html) 05:12:32 INFO - PROCESS | 25695 | RSS: Main: 167219200 05:12:32 INFO - PROCESS | 25695 | 05:15:34 INFO - PROCESS | 25695 | Cycle 1(1): loaded http://localhost:35609/tests/dromaeo/cssquery-ext.html (next: http://localhost:35609/tests/dromaeo/cssquery-jquery.html) 05:15:35 INFO - PROCESS | 25695 | RSS: Main: 166912000 05:15:35 INFO - PROCESS | 25695 | 05:18:37 INFO - PROCESS | 25695 | Cycle 1(1): loaded http://localhost:35609/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35609/tests/dromaeo/cssquery-mootools.html) 05:18:37 INFO - PROCESS | 25695 | RSS: Main: 168103936 05:18:37 INFO - PROCESS | 25695 | 05:21:39 INFO - PROCESS | 25695 | Cycle 1(1): loaded http://localhost:35609/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35609/tests/dromaeo/cssquery-prototype.html) 05:21:40 INFO - PROCESS | 25695 | RSS: Main: 165654528 05:21:40 INFO - PROCESS | 25695 | 05:24:42 INFO - PROCESS | 25695 | Cycle 1(1): loaded http://localhost:35609/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35609/tests/dromaeo/cssquery-yui.html) 05:24:43 INFO - PROCESS | 25695 | RSS: Main: 167260160 05:24:43 INFO - PROCESS | 25695 | 05:27:46 INFO - PROCESS | 25695 | Cycle 1(1): loaded http://localhost:35609/tests/dromaeo/cssquery-yui.html (next: http://localhost:35609/tests/dromaeo/cssquery-dojo.html) 05:27:46 INFO - PROCESS | 25695 | RSS: Main: 168853504 05:27:46 INFO - PROCESS | 25695 | 05:27:46 INFO - PROCESS | 25695 | __start_tp_report 05:27:46 INFO - PROCESS | 25695 | _x_x_mozilla_page_load 05:27:46 INFO - PROCESS | 25695 | _x_x_mozilla_page_load_details 05:27:46 INFO - PROCESS | 25695 | |i|pagename|runs| 05:27:46 INFO - PROCESS | 25695 | |0;dojo.html;2648.3379155211196;2699.85150816705;2701.13563659629;2705.431859309545;2707.0660622085384;4724;4734.573888350048;4767.8808029799275;4841.055994081155;4888.73111978841;4680.602148817355;4728.834490792823;4757.333973243749;4759.309900064489;4790.137775200463;4348.260795664744;4407.559244075583;4477.440319960005;4478.596926276635;4505.414296141497;4397.31841564558;4509.391232183646;4509.458864936212;4514.6614003949835;4526.547345265463;4322.092360604277;4370.016746232104;4370.519242883281;4372.256716358211;4455.643548516111;4410.051838854662;4414.867553973386;4421.712588681726;4484.573965473307;4487.483939346984;4460.264056430685;4480.529544397857;4485.2599321111975;4485.551444855521;4525.502194758574;4697.295405689174;4755.738434386108;4767.833125840579;4769.308450274725;4775.617950563981;5202.661826981234;5204.375474943031;5210.53105220532;5237.450067742909;5258.55302299305;4913.975430122827;5259.605529585297;5275.129603615399;5277.02375060615;5282.022825777243;4839.782209800567;4860.1008813369635;4898.044881248194;4905.901881962341;4906.1414252505665;5321.73391330432;5335.86660333487;5363.946360536344;5367.1680889462195;5383.757730902119;4875.536824001714;5063.518965698253;5077;5131.7690703918415;5139.486051394905;5150.201718733602;5181.53366197044;5181.766820493087;5200.713960732196;5245.632805703564;4971.279164521125;5094.057599344133;5094.745262736922;5150.1502252127975;5156.20078887773;4975.601951843843;5077.086124497552;5138.871528211825;5142.948570514247;5159.638825282269;4403.867883963486;4428.7121337112985;4436.7559784211535;4459.0413061192;4481.462224533077;3840.731148819556;3843.135294558758;3859.880634615986;3874.2638898609175;3891.747036442622;4286.207051695446;4337.4795024596615;4343.869683909487;4392;4477.977610111929;5109.565686916654;5113.616478764107;5128;5131.538161565477;5174.197999310113;4380.5838445347645;4390.956090439055;4399.25212713833;4423.049044455457;4423.225935461282;4533.52397998212;4585.380973568593;4602.309653551927;4641.95358046422;4652.674312798072;4806.351142595705;4810.542998415145;4811.903761924742;4856.247281671346;4865.099956508057;5116.437191908962;5131.076406246912;5142.280080788618;5145.845624631267;5148.382194136728;4638.512956139557;4835.637327200544;4861.562459378673;4882;4903.190973489334;5082.644214904921;5099.6685215460875;5117.155669314521;5123.257127716534;5211.843644690665;5199.948000519947;5219.347581552306;5231.136862417658;5232.764525596282;5244.9737751310995;4843.854684359475;4873;4941.950580494149;4943.110240156806;4980.05378977993;4966.950330496648;4985.700857948535;4999.65002449825;5001.099802035668;5005.874853128701;4926.975365123151;4934.062528119789;4982;4992.126377883928;5009.298698182331;5073.746312684425;5111.872203194801;5122.590192784643;5135.691858488353;5194.4805519447755;5015.222640490584;5017.874553136201;5018.824341148043;5036.370453693288;5047.873803154804 05:27:46 INFO - PROCESS | 25695 | |1;ext.html;544.4827413956742;598.2850493660078;602.4698265526337;617.7035023188869;627.786552572126;36528.45207321889;40238.79880600593;40310.59689403105;40584.39123413147;40663.390049149246;40055.39916901249;40802.38796418049;40907;40945.18109637805;41154.794226028906;7781.844363112735;7897.407694422913;7929.881051784228;7939.047314322261;7946.046474423078;7844.568548729846;7984.40116991228;7993.560354180518;8006.159353267911;8007;8007.31937785286;8044.758657240292;8049.597520124;8061.314788243007;8068.636911338975;7976.999999999971;7977.521348719096;7978.760637180894;8013.759587212393;8036.196380361917;7761.534307941542;7880.330171935392;7915.050193976702;7916.881246781303;7923;38402.23195536074;38411;38653.420198697044;38693.613063869285;38735;3526.876559320437;3623.2391197848483;3903.7072219583647;4026.798660066985;4027.81874815634;29812;30268.69731302681;30450.716196285146;30494.847525762452;31001.8449907749;3477.7565570410084;3514.859405623772;3589.569251689788;3593.106816437574;3613.6205698401814;243946;244853;246510;246876;248387;33809.492857607154;34855;52798.77609791207;53685.46314536805;53766.73116634392;33927.151821204665;34092;34172.658273417444;34216.82891585526;34303.82848085744;12246.938765306295;12313.876861231452;12380.257184568954;12391.504339826306;12458.937705311415;9898.406095634142;10023.097921187129;10035.498225088717;10066.848997265048;10087.646932357478;8288.917110828936;8392.664293428317;8398.538080405515;8441.620127094295;8442.19799119095;3264.4124057669374;3269.493228549579;3276.4921437177277;3302.3560405720655;3310.2551925816497;874.6894852327332;971.4754032822196;977.130353984954;1020.3010937507831;1036.5387402605768;28347.43305133927;28596;29260.365468812593;29330.85334573356;29449.263768405963;1013.716159475348;1062.3625824505389;1078.757279612081;1078.9514471848786;1086.9076128529005;15104.848951510345;15577.299021543831;15604.14177220241;15630.9218453907;15860.286287117107;118937.40531297289;119769;374420;374953;375015;50375;51577;51751.22373164406;52069.218961715604;52160.73919630377;1247.3638444393425;1252.2924547630741;1261.6025951825145;1266.170658218868;1266.4807428954084;27488.17535473939;28117.71882281151;28318;28846.855765721037;28916;43883.34174987442;44446.333304999804;44515.554844451144;44544.77727611341;44594;309047;314754;314791;316621;318449;25927.481450371266;26180.214593562225;26209.344766380993;26387.20838374852;26416.471670566865;44239.336409953234;44512.10975780401;45178.322325165806;48331.5166848327;48896;38076.619233808415;38263.2347353057;38266;38813.02967425837;40000.19999599933;37807.24385512329;38145.2370952585;38228.42657360197;38285.4257186148;38724.22551548897;35274.82362588273;37602;37805.81097094607;38519.22961540698;41464;32703.34593308073;33691.83154084214;35016.12459688528;35595.822020890766;37766.43350349692;25856;26114.347141321618;26247.737522624528;26270.60594091052;26273.21180364592 05:27:46 INFO - PROCESS | 25695 | |2;jquery.html;379.9487069245653;380.13708880840477;381.9446180303856;387.38405934564037;391.1219312643117;15865.679761625486;17334.826651733496;17704.82295177048;17976.91011544944;17988.190531426102;16859.494215173534;17090.743638845397;17381.565460863483;17863.55341116473;17954.730679039825;5099.796008159669;5266.894662106755;5283.339582552181;5314.76083576238;5316.946830531703;3665.7617254878346;5708;6055.061465472859;6065.848353791146;6146.231721034871;5064;6075.939240607581;6082.148499210114;6086.6043707159115;6095.177151084591;5301.496357845998;5337.039332920072;5355.732213389315;5380.085385484438;5385.596080293974;5052.9747351263;5400.864978375533;5424.701641409721;5447.536959358459;5456.6725996439945;12525.123241373107;12625.873741262561;12929;12939;13013.284269365182;1977.5451646121473;1997.3308941504613;1998.9200431982704;2105.3683894831643;2123.575284943018;9505.287103467268;9673.951630241874;10427.739306517322;10576.206784491194;13911.79132313016;1817.2185960037311;1834.284628994693;1854.45293638377;1866.346778627483;1899.6390685769798;69014.65492672555;70836;71593.64203179003;72160.27839721588;72422.27577724207;9994;10111.292209545261;10397.688069357931;10499.68500944973;10508.737281568023;14549.41802327895;14634;14640.560783176521;14655.706885862222;14665.413383464542;7077.009218709385;7258.056452661121;7278.443468976183;7323.963380183065;7349.338559529668;6438.54930154894;6489.740410383626;6527.836804079936;6537.477001839841;6642.103316052271;4458.130664520452;4479.283314669702;4504.166729155117;4521.570450807168;4662.230731929194;1694.5678851892687;1791.7491551182845;1842.9043694185586;1879.229515898475;1921.2795201799324;808.1554775259867;934.5841100710255;960.8894977077586;999.4802702594756;1023.5087158164123;16689.24898379576;16804.74792878108;17177.05526196046;17411.47765567035;17432;863.1023735315327;891.3092353426147;925.1950802801471;955.2883102088849;970.752458123174;9348.90651093494;9777.706668799947;9818.969008254173;9821.508924553744;9890.505474726235;423484;423893;426739;427820;434658;79247;80676;81710.59144704357;81720.59139704263;82250.58874705588;922.5156792683895;926.1849572376266;941.0589410589411;946.5740416812462;949.5204921514729;13140.737185256432;15310.464133755266;15312.846871531143;15355.616109596902;15648.139352335731;35519;35575;35903.28193436169;36618.8169059153;36734.8163259182;49704.75147624239;50155.49844501508;50622.493775061776;50779.746101270735;50923.490765091876;4900.828421220436;5056.924146137737;7435.367993720378;7590.278923502258;7667.386609071373;5505.421930697219;10343.793124137626;11148.163887708291;11372.31766094037;11538.596149135075;9952;9986.900130998598;9993.850092248476;10120.291579589359;10287.84568231492;9888.653897113567;10087.69736907894;10122.696319110439;10138.138258248131;10205.948970255102;10109.090181883666;10135.847962280719;10142.49287535635;10266.589336426758;10429.16566674649;7636.770896873103;7650.234976502528;7668.6165691716305;7712.421568382281;7820.452568320163;7736.226377362219;9491.85762213581;9887.406755594688;9964.50177491137;9970.351927124713 05:27:46 INFO - PROCESS | 25695 | |3;mootools.html;1467.0610809082186;1493.2459108150383;1638.516637591911;1768.089433941521;1814.8820326678765;6605.306442823507;7220.097487814023;7271.636418179096;7679.232076792325;7714.961425192873;7396.815079623012;7424.443166762488;7429.814254643637;7495.475316727831;7498.100227972651;2207.580559693657;2244.8316376271764;2247.955040899181;2250.246167533878;2254.785795349439;2159.3521943416913;2174.0868835089223;2239;2240.5518896220738;2242.607543679858;2207.415035015722;2211.2481756202887;2215.9113635454564;2221.0227499900075;2232.9888350558226;2085.5620319732875;2091.895405229732;2091.9267825626107;2104.8000439958255;2109.7573779015456;2135.8077773000505;2159.66525188596;2178.531615702616;2186.7485239197376;2189.386971647941;7514.849703005964;7567.167611562723;7572.356349710281;7575.886361704524;7592.202818704011;4399.582039706254;4477.328400739882;4480.171168333836;4485.932711009337;4491.393661855673;2994.1317018064733;3022.879084836604;3103.953440698391;3123.203583086321;3147.8740850365957;965.1699538396973;1008.1380419741089;1008.3344992305117;1023.2223510132278;1030.011189258313;9875.456849873253;9876.90123098767;9894.30739848211;9922.801543969152;9944.353617014944;4180.665546756252;4196.853110141131;4256.36154576816;4322.978385108055;4383.07955329378;8650.307975361957;8657.610407531676;8680.739577812676;8682.479051256818;8685.43544669607;4509.549045095464;4523.163214805271;4549.909001819945;4556.2937744649635;4578.519255478193;3524.1189702574356;3962.7424217425773;4066.4510291110914;4081.918361632751;4130.173965206971;3617.276544691072;3649.908752281161;3693.279810436939;3734.663880250736;3784.2242340320167;1177.140687298277;1188.6790566547156;1195.5337418406655;1196.300164403816;1201.8377519034993;713.0694443750955;788.5426452657445;902.0843843498853;914.346242436661;926.092429419173;4997.625178111656;5048.116579598555;5068.771905264272;5072.6195535335;5183.637145399785;902.3187493442409;902.4314681750586;909.7088931541975;921.074320308086;936.9297302702324;3541.29174165168;3570;3627.147620309225;3671.394219953731;3829.7702137871815;8848.690295839615;9023.142801433853;9078.86381704275;9081.409708368936;9125.634974600942;8842.381033327605;8996.145366190201;9002.279817614575;9005.594748236344;9023.368364214442;6805.863882722417;7064;7193.676284567207;7235.746748863765;7274.090738657668;5081.110805609078;5111.156659151198;5200.271961925409;5245.3181086460045;5256.31667883188;7945.761627151194;8064.435489515678;8309.086000539815;8329.791755206168;8331.500109993178;8257.545834979133;8459.111793261865;8485.745427637181;8508.957455212685;8763.203543273487;6159.291681456511;6823.761168359084;7059.964700176466;7084.185318688211;7230.204677485367;7188.748393806191;7211.819704507219;7244.782656520313;7349.522281051714;7356.043714317105;6513.413792758674;6582.80251592453;6674.26583075871;6695.665216739241;6776.288489708707;6604.306547812411;6637.834054148491;6669.0329902164885;6736.090627765189;6744.460443164437;5972.910406343995;6044.576879618385;6046.365131661289;6046.7278972445465;6094.969525152346;4469.888252793706;4537.410136682342;4649.3026046093355;4683.718976861399;4693.22561777303;4526.728396296233;4535.183666939983;4558.5897269245925;4627.259638457831;4635.768211589339 05:27:46 INFO - PROCESS | 25695 | |4;prototype.html;304.60249374566183;316.43200455182944;318.9505626627874;321.19380355308175;321.53217069164367;4904.435989861158;4974.602031837453;5053.014662140884;5054.721990290533;5084.822031228903;4968.701877887329;4979.825706100287;4994.42564105128;5031.195008798593;5036.722980236085;3926.391409331558;3946.2502124596376;3948.9012774680646;3961.861334853281;3979.6219359160978;3683.5395575553057;3975.1652153047894;3978.269452788032;3995.3407687731633;4000.5599384067727;3878.825452854628;3881.359575670025;3881.592432794558;3882.456456096149;3884.9028774280587;3863.806809659534;3875.147467557133;3878.3794592865147;3890.0663840678317;3913.6477717005464;3402.625711171769;3406.3017081498233;3427.5715535558056;3427.9657203428146;3428.365752335826;4582.433837051262;4703.811847526094;4744.14605371033;4791.856244312677;4828.275758636232;4373.934390984138;4376.3654270130655;4376.409184760048;4392.3411488277015;4429.579189976947;5226.503482169188;5256.316678831766;5258.50044245796;5271.973640131813;5304.283921670563;3609.819509024538;3706.1475860552223;3746.456763769238;3756.887293381203;3760.3231418344963;5435.130379139318;5461.644993075437;5473.233747275345;5502.2296878437055;5515.503604675599;3761.5298087739034;3775.3581891078034;3779.4519794629623;3815.637514436124;3816.961830381716;3875.1862108957434;3893.04620368006;3899.1421887184774;3906.667933225651;3911.2177564487215;4316.935245971313;4402.581754733295;4411.360352748833;4411.845585404495;4429.180601588717;5223.4776522347465;5247.1866860636665;5280.155175171954;5295.808443100349;5303.337082864642;1227.2023184929867;1250.206119114369;1275.6428200103985;1287.929163895976;1291.6254286257067;863.0635760200253;870.4081224767219;883.5008220355479;885.1679421343916;891.4918496456958;4339.893327201544;4347.717398369159;4351.9564804352185;4354.303311470149;4360.672949528798;5125.2568377585785;5143.562797162208;5149.716765577854;5168.431472538018;5178.533931946067;4244.732416658448;4362.934555981663;4366.388705581221;4376.474823021258;4387.2103021455805;2683.9329016774736;2789.5257806173;2803.0049332486674;2811.9156425307274;2824.632797736322;4893.95106048942;4913.336699545587;4938.481459446778;4939.5554400104165;4969.304297398295;5114.156164232934;5223.007628550563;5299.205119232069;5315.335583052119;5362.9888791703115;4813.4945830687975;4852.223644216979;4867.780949857265;4904.975475122602;4931.16170251051;5171.431142574239;5183.5593974512585;5192.117340052276;5207.2449494823795;5212.582993360598;5037.471065538066;5107.799667078308;5150.201718733602;5164.8192313268855;5403.351597808131;5086.83809381019;5123.154679477844;5136.8202112925865;5155.917257375843;5160.200168973815;3724.366857634263;3791.8862434127022;3811.0662887592716;3818.427235914635;3826.1773718650625;5198.844034678966;5435.266239057677;5455.890882182255;5491.28613280271;5498.917516237179;5251.291075704731;5269.499397557226;5276.786339142096;5291.973540132429;5300.655457395257;5282.8943421132135;5291.153415453509;5294.338207724035;5306.920396193983;5317.574594032545;5068.670536415121;5071.822486212965;5165.173572228426;5176.844694659167;5183.377994640668;5281.419043905091;5292.788288468572;5301.985245606645;5336.226247194213;5352.785888564414;5144.511271429208;5237.711925844115;5237.869053273546;5255.159174532209;5258.1849813279005 05:27:46 INFO - PROCESS | 25695 | |5;yui.html;732.4250463386243;761.1437133225122;780.1963977103583;803.5982008995502;823.0164952881307;11015.944920275397;11682.941585292061;11726.706832329197;11732.882671173285;11759.70600734982;11101;12290.815637765418;12521.561745338915;12527.498900043987;12543.686407839808;862.5169904853286;874.7681864305964;876.2551830943679;878.038547790172;880.7974165941805;871.8866547348836;875.6191056890241;875.8729984152294;882.841088604051;883.152173913041;903.2638399704252;903.3495882964255;904.1275169461468;910.4901255297021;911.8905731312219;893.6336102198132;896.256107430834;900.2033200617501;904.3036861616552;907.4147175072075;661.2858113237692;666.8099591616366;667.4426853577269;668.2014992084461;668.7258224128133;10793.190510711727;10793.83809242862;10837.783244335147;10858.34849909008;10871.67384978452;9601.327907046509;10026.949865250626;10428.217883658755;10524;10849;8796.384253102286;9044.186023257938;9214.631414743402;9272.165505104505;9322.627094916195;3264.657210992859;3356.1441832332844;3357.513160591725;3366.074329559366;3441.587009558869;34851.477227841606;39313.21373572541;40567.59432405668;40707.98230044243;41077.79461102675;10036.498175091216;10354.275200735876;10390.844137337946;10725.14198864089;10751.677449676523;18288.17703203359;18337.908310458362;18357.265709371477;18550.907245463957;18591;6513.218413790375;6646.368594983565;6737.157855268091;6773.559718618274;7035.33164349386;3491.965080349215;3653.2819263665297;3654.1595433050015;3674.926501470009;3687.575928768226;2851.900183493568;2953.926151846221;2957.1276473440385;2971.806832555877;2974.866131024109;524.0880867290887;532.1432493685164;535.0529562674045;541.2098336428858;542.3925203771801;587.952963762898;616.7286393986632;623.3388020924277;630.7729217481703;635.6535688050024;7495.812604684927;7765.844683106307;7785.532868027936;7798.103218129874;8100.270975612223;624.5503237668871;633.5628416392766;647.3105195439898;652.5138771615083;652.6834485274638;3725.7578257413184;3786.6024067473068;3818.293615681108;3862.594427585063;3886.7279290449965;46184.5381546187;47936.7603161982;48252;48312.27531587029;48902;46380.955713635514;46400.303995440096;46889.765551172706;47763.76118119387;48366.5163348369;35968.82015589958;42174.78912605417;43015.56984430116;43338;44284.55715442869;7830.647620857189;7922.9304043955635;7970.40221983342;8201.917980820115;8338.666453341799;23465.648015279443;23592.646110308702;28417.43165136644;28590.285242869093;29296.41407171887;32848;36064.63935360718;36708.26583468262;36730.26539469249;36859;10769.62306319275;11201.551937922392;11273.661790146309;11277.548898044315;11329.206955513364;11279;11466.827997579876;11611.5355385787;11769.529218831492;11991.100667449802;10725.89274107249;11037.448127593427;11557.30656160633;11568.421578920852;11768.882311176778;10953.452327383759;10972.835407469054;11037.227394082338;11040.558377664804;11060.834087488854;10624.893751062391;10974.670759877217;11013.118950483782;11232.550697972316;11646.417679116179;8499.447535910145;8961.596728147118;9141.886151232105;9374.343795934219;9426.858597120912;9222.262219022596;9532.666356677482;9535.189508891544;9551.761205969906;9609.183219426428 05:27:46 INFO - PROCESS | 25695 | __end_tp_report 05:27:46 INFO - PROCESS | 25695 | __start_cc_report 05:27:46 INFO - PROCESS | 25695 | _x_x_mozilla_cycle_collect,355 05:27:46 INFO - PROCESS | 25695 | __end_cc_report 05:27:46 INFO - PROCESS | 25695 | __startTimestamp1462796866334__endTimestamp 05:27:46 INFO - PROCESS | 25695 | 05:27:46 INFO - PROCESS | 25695 | ------- Summary: start ------- 05:27:46 INFO - PROCESS | 25695 | Number of tests: 6 05:27:46 INFO - PROCESS | 25695 | 05:27:46 INFO - PROCESS | 25695 | [#0] dojo.html Cycles:165 Average:4767.02 Median:4882.00 stddev:503.37 (10.3%) stddev-sans-first:476.68 05:27:46 INFO - PROCESS | 25695 | Values: 2648.3 2699.9 2701.1 2705.4 2707.1 4724.0 4734.6 4767.9 4841.1 4888.7 4680.6 4728.8 4757.3 4759.3 4790.1 4348.3 4407.6 4477.4 4478.6 4505.4 4397.3 4509.4 4509.5 4514.7 4526.5 4322.1 4370.0 4370.5 4372.3 4455.6 4410.1 4414.9 4421.7 4484.6 4487.5 4460.3 4480.5 4485.3 4485.6 4525.5 4697.3 4755.7 4767.8 4769.3 4775.6 5202.7 5204.4 5210.5 5237.5 5258.6 4914.0 5259.6 5275.1 5277.0 5282.0 4839.8 4860.1 4898.0 4905.9 4906.1 5321.7 5335.9 5363.9 5367.2 5383.8 4875.5 5063.5 5077.0 5131.8 5139.5 5150.2 5181.5 5181.8 5200.7 5245.6 4971.3 5094.1 5094.7 5150.2 5156.2 4975.6 5077.1 5138.9 5142.9 5159.6 4403.9 4428.7 4436.8 4459.0 4481.5 3840.7 3843.1 3859.9 3874.3 3891.7 4286.2 4337.5 4343.9 4392.0 4478.0 5109.6 5113.6 5128.0 5131.5 5174.2 4380.6 4391.0 4399.3 4423.0 4423.2 4533.5 4585.4 4602.3 4642.0 4652.7 4806.4 4810.5 4811.9 4856.2 4865.1 5116.4 5131.1 5142.3 5145.8 5148.4 4638.5 4835.6 4861.6 4882.0 4903.2 5082.6 5099.7 5117.2 5123.3 5211.8 5199.9 5219.3 5231.1 5232.8 5245.0 4843.9 4873.0 4942.0 4943.1 4980.1 4967.0 4985.7 4999.7 5001.1 5005.9 4927.0 4934.1 4982.0 4992.1 5009.3 5073.7 5111.9 5122.6 5135.7 5194.5 5015.2 5017.9 5018.8 5036.4 5047.9 05:27:46 INFO - PROCESS | 25695 | 05:27:46 INFO - PROCESS | 25695 | [#1] ext.html Cycles:180 Average:43484.10 Median:206308.50 stddev:76023.51 (36.8%) stddev-sans-first:76168.41 05:27:46 INFO - PROCESS | 25695 | Values: 544.5 598.3 602.5 617.7 627.8 36528.5 40238.8 40310.6 40584.4 40663.4 40055.4 40802.4 40907.0 40945.2 41154.8 7781.8 7897.4 7929.9 7939.0 7946.0 7844.6 7984.4 7993.6 8006.2 8007.0 8007.3 8044.8 8049.6 8061.3 8068.6 7977.0 7977.5 7978.8 8013.8 8036.2 7761.5 7880.3 7915.1 7916.9 7923.0 38402.2 38411.0 38653.4 38693.6 38735.0 3526.9 3623.2 3903.7 4026.8 4027.8 29812.0 30268.7 30450.7 30494.8 31001.8 3477.8 3514.9 3589.6 3593.1 3613.6 243946.0 244853.0 246510.0 246876.0 248387.0 33809.5 34855.0 52798.8 53685.5 53766.7 33927.2 34092.0 34172.7 34216.8 34303.8 12246.9 12313.9 12380.3 12391.5 12458.9 9898.4 10023.1 10035.5 10066.8 10087.6 8288.9 8392.7 8398.5 8441.6 8442.2 3264.4 3269.5 3276.5 3302.4 3310.3 874.7 971.5 977.1 1020.3 1036.5 28347.4 28596.0 29260.4 29330.9 29449.3 1013.7 1062.4 1078.8 1079.0 1086.9 15104.8 15577.3 15604.1 15630.9 15860.3 118937.4 119769.0 374420.0 374953.0 375015.0 50375.0 51577.0 51751.2 52069.2 52160.7 1247.4 1252.3 1261.6 1266.2 1266.5 27488.2 28117.7 28318.0 28846.9 28916.0 43883.3 44446.3 44515.6 44544.8 44594.0 309047.0 314754.0 314791.0 316621.0 318449.0 25927.5 26180.2 26209.3 26387.2 26416.5 44239.3 44512.1 45178.3 48331.5 48896.0 38076.6 38263.2 38266.0 38813.0 40000.2 37807.2 38145.2 38228.4 38285.4 38724.2 35274.8 37602.0 37805.8 38519.2 41464.0 32703.3 33691.8 35016.1 35595.8 37766.4 25856.0 26114.3 26247.7 26270.6 26273.2 05:27:46 INFO - PROCESS | 25695 | 05:27:46 INFO - PROCESS | 25695 | [#2] jquery.html Cycles:180 Average:25274.62 Median:49930.12 stddev:70478.68 (141.2%) stddev-sans-first:70651.60 05:27:46 INFO - PROCESS | 25695 | Values: 379.9 380.1 381.9 387.4 391.1 15865.7 17334.8 17704.8 17976.9 17988.2 16859.5 17090.7 17381.6 17863.6 17954.7 5099.8 5266.9 5283.3 5314.8 5316.9 3665.8 5708.0 6055.1 6065.8 6146.2 5064.0 6075.9 6082.1 6086.6 6095.2 5301.5 5337.0 5355.7 5380.1 5385.6 5053.0 5400.9 5424.7 5447.5 5456.7 12525.1 12625.9 12929.0 12939.0 13013.3 1977.5 1997.3 1998.9 2105.4 2123.6 9505.3 9674.0 10427.7 10576.2 13911.8 1817.2 1834.3 1854.5 1866.3 1899.6 69014.7 70836.0 71593.6 72160.3 72422.3 9994.0 10111.3 10397.7 10499.7 10508.7 14549.4 14634.0 14640.6 14655.7 14665.4 7077.0 7258.1 7278.4 7324.0 7349.3 6438.5 6489.7 6527.8 6537.5 6642.1 4458.1 4479.3 4504.2 4521.6 4662.2 1694.6 1791.7 1842.9 1879.2 1921.3 808.2 934.6 960.9 999.5 1023.5 16689.2 16804.7 17177.1 17411.5 17432.0 863.1 891.3 925.2 955.3 970.8 9348.9 9777.7 9819.0 9821.5 9890.5 423484.0 423893.0 426739.0 427820.0 434658.0 79247.0 80676.0 81710.6 81720.6 82250.6 922.5 926.2 941.1 946.6 949.5 13140.7 15310.5 15312.8 15355.6 15648.1 35519.0 35575.0 35903.3 36618.8 36734.8 49704.8 50155.5 50622.5 50779.7 50923.5 4900.8 5056.9 7435.4 7590.3 7667.4 5505.4 10343.8 11148.2 11372.3 11538.6 9952.0 9986.9 9993.9 10120.3 10287.8 9888.7 10087.7 10122.7 10138.1 10205.9 10109.1 10135.8 10142.5 10266.6 10429.2 7636.8 7650.2 7668.6 7712.4 7820.5 7736.2 9491.9 9887.4 9964.5 9970.4 05:27:46 INFO - PROCESS | 25695 | 05:27:46 INFO - PROCESS | 25695 | [#3] mootools.html Cycles:180 Average:4995.33 Median:5191.95 stddev:2669.42 (51.4%) stddev-sans-first:2663.74 05:27:46 INFO - PROCESS | 25695 | Values: 1467.1 1493.2 1638.5 1768.1 1814.9 6605.3 7220.1 7271.6 7679.2 7715.0 7396.8 7424.4 7429.8 7495.5 7498.1 2207.6 2244.8 2248.0 2250.2 2254.8 2159.4 2174.1 2239.0 2240.6 2242.6 2207.4 2211.2 2215.9 2221.0 2233.0 2085.6 2091.9 2091.9 2104.8 2109.8 2135.8 2159.7 2178.5 2186.7 2189.4 7514.8 7567.2 7572.4 7575.9 7592.2 4399.6 4477.3 4480.2 4485.9 4491.4 2994.1 3022.9 3104.0 3123.2 3147.9 965.2 1008.1 1008.3 1023.2 1030.0 9875.5 9876.9 9894.3 9922.8 9944.4 4180.7 4196.9 4256.4 4323.0 4383.1 8650.3 8657.6 8680.7 8682.5 8685.4 4509.5 4523.2 4549.9 4556.3 4578.5 3524.1 3962.7 4066.5 4081.9 4130.2 3617.3 3649.9 3693.3 3734.7 3784.2 1177.1 1188.7 1195.5 1196.3 1201.8 713.1 788.5 902.1 914.3 926.1 4997.6 5048.1 5068.8 5072.6 5183.6 902.3 902.4 909.7 921.1 936.9 3541.3 3570.0 3627.1 3671.4 3829.8 8848.7 9023.1 9078.9 9081.4 9125.6 8842.4 8996.1 9002.3 9005.6 9023.4 6805.9 7064.0 7193.7 7235.7 7274.1 5081.1 5111.2 5200.3 5245.3 5256.3 7945.8 8064.4 8309.1 8329.8 8331.5 8257.5 8459.1 8485.7 8509.0 8763.2 6159.3 6823.8 7060.0 7084.2 7230.2 7188.7 7211.8 7244.8 7349.5 7356.0 6513.4 6582.8 6674.3 6695.7 6776.3 6604.3 6637.8 6669.0 6736.1 6744.5 5972.9 6044.6 6046.4 6046.7 6095.0 4469.9 4537.4 4649.3 4683.7 4693.2 4526.7 4535.2 4558.6 4627.3 4635.8 05:27:46 INFO - PROCESS | 25695 | 05:27:46 INFO - PROCESS | 25695 | [#4] prototype.html Cycles:180 Average:4309.19 Median:4909.16 stddev:1255.68 (25.6%) stddev-sans-first:1222.69 05:27:46 INFO - PROCESS | 25695 | Values: 304.6 316.4 319.0 321.2 321.5 4904.4 4974.6 5053.0 5054.7 5084.8 4968.7 4979.8 4994.4 5031.2 5036.7 3926.4 3946.3 3948.9 3961.9 3979.6 3683.5 3975.2 3978.3 3995.3 4000.6 3878.8 3881.4 3881.6 3882.5 3884.9 3863.8 3875.1 3878.4 3890.1 3913.6 3402.6 3406.3 3427.6 3428.0 3428.4 4582.4 4703.8 4744.1 4791.9 4828.3 4373.9 4376.4 4376.4 4392.3 4429.6 5226.5 5256.3 5258.5 5272.0 5304.3 3609.8 3706.1 3746.5 3756.9 3760.3 5435.1 5461.6 5473.2 5502.2 5515.5 3761.5 3775.4 3779.5 3815.6 3817.0 3875.2 3893.0 3899.1 3906.7 3911.2 4316.9 4402.6 4411.4 4411.8 4429.2 5223.5 5247.2 5280.2 5295.8 5303.3 1227.2 1250.2 1275.6 1287.9 1291.6 863.1 870.4 883.5 885.2 891.5 4339.9 4347.7 4352.0 4354.3 4360.7 5125.3 5143.6 5149.7 5168.4 5178.5 4244.7 4362.9 4366.4 4376.5 4387.2 2683.9 2789.5 2803.0 2811.9 2824.6 4894.0 4913.3 4938.5 4939.6 4969.3 5114.2 5223.0 5299.2 5315.3 5363.0 4813.5 4852.2 4867.8 4905.0 4931.2 5171.4 5183.6 5192.1 5207.2 5212.6 5037.5 5107.8 5150.2 5164.8 5403.4 5086.8 5123.2 5136.8 5155.9 5160.2 3724.4 3791.9 3811.1 3818.4 3826.2 5198.8 5435.3 5455.9 5491.3 5498.9 5251.3 5269.5 5276.8 5292.0 5300.7 5282.9 5291.2 5294.3 5306.9 5317.6 5068.7 5071.8 5165.2 5176.8 5183.4 5281.4 5292.8 5302.0 5336.2 5352.8 5144.5 5237.7 5237.9 5255.2 5258.2 05:27:46 INFO - PROCESS | 25695 | 05:27:46 INFO - PROCESS | 25695 | [#5] yui.html Cycles:180 Average:12208.24 Median:42595.18 stddev:13508.11 (31.7%) stddev-sans-first:13518.51 05:27:46 INFO - PROCESS | 25695 | Values: 732.4 761.1 780.2 803.6 823.0 11015.9 11682.9 11726.7 11732.9 11759.7 11101.0 12290.8 12521.6 12527.5 12543.7 862.5 874.8 876.3 878.0 880.8 871.9 875.6 875.9 882.8 883.2 903.3 903.3 904.1 910.5 911.9 893.6 896.3 900.2 904.3 907.4 661.3 666.8 667.4 668.2 668.7 10793.2 10793.8 10837.8 10858.3 10871.7 9601.3 10026.9 10428.2 10524.0 10849.0 8796.4 9044.2 9214.6 9272.2 9322.6 3264.7 3356.1 3357.5 3366.1 3441.6 34851.5 39313.2 40567.6 40708.0 41077.8 10036.5 10354.3 10390.8 10725.1 10751.7 18288.2 18337.9 18357.3 18550.9 18591.0 6513.2 6646.4 6737.2 6773.6 7035.3 3492.0 3653.3 3654.2 3674.9 3687.6 2851.9 2953.9 2957.1 2971.8 2974.9 524.1 532.1 535.1 541.2 542.4 588.0 616.7 623.3 630.8 635.7 7495.8 7765.8 7785.5 7798.1 8100.3 624.6 633.6 647.3 652.5 652.7 3725.8 3786.6 3818.3 3862.6 3886.7 46184.5 47936.8 48252.0 48312.3 48902.0 46381.0 46400.3 46889.8 47763.8 48366.5 35968.8 42174.8 43015.6 43338.0 44284.6 7830.6 7922.9 7970.4 8201.9 8338.7 23465.6 23592.6 28417.4 28590.3 29296.4 32848.0 36064.6 36708.3 36730.3 36859.0 10769.6 11201.6 11273.7 11277.5 11329.2 11279.0 11466.8 11611.5 11769.5 11991.1 10725.9 11037.4 11557.3 11568.4 11768.9 10953.5 10972.8 11037.2 11040.6 11060.8 10624.9 10974.7 11013.1 11232.6 11646.4 8499.4 8961.6 9141.9 9374.3 9426.9 9222.3 9532.7 9535.2 9551.8 9609.2 05:27:46 INFO - PROCESS | 25695 | -------- Summary: end -------- 05:27:46 INFO - PROCESS | 25695 | 05:27:47 INFO - TEST-INFO | 25695: exit 0 05:27:47 INFO - TEST-OK | dromaeo_css | took 1115442ms 05:27:47 INFO - TEST-START | kraken 05:27:47 INFO - operating with platform_type : linux_ 05:27:47 INFO - Initialising browser for kraken test... 05:27:47 INFO - TEST-INFO | started process 25930 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHyTJZq/profile http://localhost:35609/getInfo.html) 05:27:50 INFO - PROCESS | 25930 | 05:27:50 INFO - PROCESS | 25930 | (process:25996): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 05:27:51 INFO - PROCESS | 25930 | __metrics Screen width/height:1600/1200 05:27:51 INFO - PROCESS | 25930 | colorDepth:24 05:27:51 INFO - PROCESS | 25930 | Browser inner width/height: 1024/697 05:27:51 INFO - PROCESS | 25930 | __metrics 05:27:52 ERROR - PROCESS | 25930 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2937: TypeError: this.tabs is undefined 05:27:53 INFO - TEST-INFO | 25930: exit 0 05:27:53 INFO - Browser initialized. 05:27:53 INFO - Running cycle 1/1 for kraken test... 05:27:53 INFO - TEST-INFO | started process 26047 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHyTJZq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:27:55 INFO - PROCESS | 26047 | 05:27:55 INFO - PROCESS | 26047 | (process:26106): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 05:28:05 INFO - PROCESS | 26047 | RSS: Main: 163799040 05:28:05 INFO - PROCESS | 26047 | 05:28:07 INFO - PROCESS | 26047 | Cycle 1(1): loaded http://localhost:35609/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35609/tests/kraken/driver.html?testName=audio-beat-detection) 05:28:08 INFO - PROCESS | 26047 | RSS: Main: 167022592 05:28:08 INFO - PROCESS | 26047 | 05:28:11 INFO - PROCESS | 26047 | Cycle 1(1): loaded http://localhost:35609/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35609/tests/kraken/driver.html?testName=audio-dft) 05:28:12 INFO - PROCESS | 26047 | RSS: Main: 166899712 05:28:12 INFO - PROCESS | 26047 | 05:28:15 INFO - PROCESS | 26047 | Cycle 1(1): loaded http://localhost:35609/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35609/tests/kraken/driver.html?testName=audio-fft) 05:28:16 INFO - PROCESS | 26047 | RSS: Main: 164024320 05:28:16 INFO - PROCESS | 26047 | 05:28:18 INFO - PROCESS | 26047 | Cycle 1(1): loaded http://localhost:35609/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35609/tests/kraken/driver.html?testName=audio-oscillator) 05:28:18 INFO - PROCESS | 26047 | RSS: Main: 164429824 05:28:18 INFO - PROCESS | 26047 | 05:28:21 INFO - PROCESS | 26047 | Cycle 1(1): loaded http://localhost:35609/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35609/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:28:21 INFO - PROCESS | 26047 | RSS: Main: 163921920 05:28:21 INFO - PROCESS | 26047 | 05:28:25 INFO - PROCESS | 26047 | Cycle 1(1): loaded http://localhost:35609/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35609/tests/kraken/driver.html?testName=imaging-darkroom) 05:28:25 INFO - PROCESS | 26047 | RSS: Main: 163569664 05:28:25 INFO - PROCESS | 26047 | 05:28:29 INFO - PROCESS | 26047 | Cycle 1(1): loaded http://localhost:35609/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35609/tests/kraken/driver.html?testName=imaging-desaturate) 05:28:29 INFO - PROCESS | 26047 | RSS: Main: 162422784 05:28:29 INFO - PROCESS | 26047 | 05:28:32 INFO - PROCESS | 26047 | Cycle 1(1): loaded http://localhost:35609/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35609/tests/kraken/driver.html?testName=json-parse-financial) 05:28:33 INFO - PROCESS | 26047 | RSS: Main: 163119104 05:28:33 INFO - PROCESS | 26047 | 05:28:35 INFO - PROCESS | 26047 | Cycle 1(1): loaded http://localhost:35609/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35609/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:28:36 INFO - PROCESS | 26047 | RSS: Main: 163684352 05:28:36 INFO - PROCESS | 26047 | 05:28:37 INFO - PROCESS | 26047 | Cycle 1(1): loaded http://localhost:35609/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35609/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:28:38 INFO - PROCESS | 26047 | RSS: Main: 163246080 05:28:38 INFO - PROCESS | 26047 | 05:28:40 INFO - PROCESS | 26047 | Cycle 1(1): loaded http://localhost:35609/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35609/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:28:41 INFO - PROCESS | 26047 | RSS: Main: 164511744 05:28:41 INFO - PROCESS | 26047 | 05:28:44 INFO - PROCESS | 26047 | Cycle 1(1): loaded http://localhost:35609/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35609/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:28:45 INFO - PROCESS | 26047 | RSS: Main: 163246080 05:28:45 INFO - PROCESS | 26047 | 05:28:48 INFO - PROCESS | 26047 | Cycle 1(1): loaded http://localhost:35609/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35609/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:28:48 INFO - PROCESS | 26047 | RSS: Main: 163332096 05:28:48 INFO - PROCESS | 26047 | 05:28:50 INFO - PROCESS | 26047 | Cycle 1(1): loaded http://localhost:35609/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35609/tests/kraken/driver.html?testName=ai-astar) 05:28:50 INFO - PROCESS | 26047 | RSS: Main: 166592512 05:28:50 INFO - PROCESS | 26047 | 05:28:50 INFO - PROCESS | 26047 | __start_tp_report 05:28:50 INFO - PROCESS | 26047 | _x_x_mozilla_page_load 05:28:50 INFO - PROCESS | 26047 | _x_x_mozilla_page_load_details 05:28:50 INFO - PROCESS | 26047 | |i|pagename|runs| 05:28:50 INFO - PROCESS | 26047 | |0;ai-astar;113;115;108;116;116;116;111;114;118;116 05:28:50 INFO - PROCESS | 26047 | |1;audio-beat-detection;156;132;156;225;133;166;156;135;199;138 05:28:50 INFO - PROCESS | 26047 | |2;audio-dft;167;164;170;167;162;162;161;165;164;167 05:28:50 INFO - PROCESS | 26047 | |3;audio-fft;78;76;76;160;78;76;76;87;160;75 05:28:50 INFO - PROCESS | 26047 | |4;audio-oscillator;94;93;174;94;94;93;150;93;92;138 05:28:50 INFO - PROCESS | 26047 | |5;imaging-gaussian-blur;117;119;118;117;117;117;118;117;118;118 05:28:50 INFO - PROCESS | 26047 | |6;imaging-darkroom;152;147;147;147;148;147;146;147;148;147 05:28:50 INFO - PROCESS | 26047 | |7;imaging-desaturate;96;96;96;95;97;95;95;95;96;95 05:28:50 INFO - PROCESS | 26047 | |8;json-parse-financial;66;67;64;64;65;66;64;64;65;65 05:28:50 INFO - PROCESS | 26047 | |9;json-stringify-tinderbox;65;64;65;65;65;64;64;64;65;65 05:28:50 INFO - PROCESS | 26047 | |10;stanford-crypto-aes;83;86;82;81;88;85;85;83;138;84 05:28:50 INFO - PROCESS | 26047 | |11;stanford-crypto-ccm;152;137;134;134;135;133;137;143;154;136 05:28:50 INFO - PROCESS | 26047 | |12;stanford-crypto-pbkdf2;172;178;173;174;174;174;178;172;171;170 05:28:50 INFO - PROCESS | 26047 | |13;stanford-crypto-sha256-iterative;73;71;72;70;70;69;67;71;70;68 05:28:50 INFO - PROCESS | 26047 | __end_tp_report 05:28:50 INFO - PROCESS | 26047 | __start_cc_report 05:28:50 INFO - PROCESS | 26047 | _x_x_mozilla_cycle_collect,947 05:28:50 INFO - PROCESS | 26047 | __end_cc_report 05:28:50 INFO - PROCESS | 26047 | __startTimestamp1462796930767__endTimestamp 05:28:50 INFO - PROCESS | 26047 | 05:28:50 INFO - PROCESS | 26047 | ------- Summary: start ------- 05:28:50 INFO - PROCESS | 26047 | Number of tests: 14 05:28:50 INFO - PROCESS | 26047 | 05:28:50 INFO - PROCESS | 26047 | [#0] ai-astar Cycles:10 Average:114.30 Median:116.00 stddev:2.95 (2.5%) stddev-sans-first:3.09 05:28:50 INFO - PROCESS | 26047 | Values: 113.0 115.0 108.0 116.0 116.0 116.0 111.0 114.0 118.0 116.0 05:28:50 INFO - PROCESS | 26047 | 05:28:50 INFO - PROCESS | 26047 | [#1] audio-beat-detection Cycles:10 Average:159.60 Median:156.00 stddev:30.64 (19.6%) stddev-sans-first:32.47 05:28:50 INFO - PROCESS | 26047 | Values: 156.0 132.0 156.0 225.0 133.0 166.0 156.0 135.0 199.0 138.0 05:28:50 INFO - PROCESS | 26047 | 05:28:50 INFO - PROCESS | 26047 | [#2] audio-dft Cycles:10 Average:164.90 Median:166.00 stddev:2.85 (1.7%) stddev-sans-first:2.92 05:28:50 INFO - PROCESS | 26047 | Values: 167.0 164.0 170.0 167.0 162.0 162.0 161.0 165.0 164.0 167.0 05:28:50 INFO - PROCESS | 26047 | 05:28:50 INFO - PROCESS | 26047 | [#3] audio-fft Cycles:10 Average:94.20 Median:76.00 stddev:34.85 (45.9%) stddev-sans-first:36.47 05:28:50 INFO - PROCESS | 26047 | Values: 78.0 76.0 76.0 160.0 78.0 76.0 76.0 87.0 160.0 75.0 05:28:50 INFO - PROCESS | 26047 | 05:28:50 INFO - PROCESS | 26047 | [#4] audio-oscillator Cycles:10 Average:111.50 Median:93.00 stddev:30.58 (32.9%) stddev-sans-first:31.77 05:28:50 INFO - PROCESS | 26047 | Values: 94.0 93.0 174.0 94.0 94.0 93.0 150.0 93.0 92.0 138.0 05:28:50 INFO - PROCESS | 26047 | 05:28:50 INFO - PROCESS | 26047 | [#5] imaging-gaussian-blur Cycles:10 Average:117.60 Median:118.00 stddev:0.70 (0.6%) stddev-sans-first:0.71 05:28:50 INFO - PROCESS | 26047 | Values: 117.0 119.0 118.0 117.0 117.0 117.0 118.0 117.0 118.0 118.0 05:28:50 INFO - PROCESS | 26047 | 05:28:50 INFO - PROCESS | 26047 | [#6] imaging-darkroom Cycles:10 Average:147.60 Median:147.00 stddev:1.65 (1.1%) stddev-sans-first:0.60 05:28:50 INFO - PROCESS | 26047 | Values: 152.0 147.0 147.0 147.0 148.0 147.0 146.0 147.0 148.0 147.0 05:28:50 INFO - PROCESS | 26047 | 05:28:50 INFO - PROCESS | 26047 | [#7] imaging-desaturate Cycles:10 Average:95.60 Median:96.00 stddev:0.70 (0.7%) stddev-sans-first:0.73 05:28:50 INFO - PROCESS | 26047 | Values: 96.0 96.0 96.0 95.0 97.0 95.0 95.0 95.0 96.0 95.0 05:28:50 INFO - PROCESS | 26047 | 05:28:50 INFO - PROCESS | 26047 | [#8] json-parse-financial Cycles:10 Average:65.00 Median:65.00 stddev:1.05 (1.6%) stddev-sans-first:1.05 05:28:50 INFO - PROCESS | 26047 | Values: 66.0 67.0 64.0 64.0 65.0 66.0 64.0 64.0 65.0 65.0 05:28:50 INFO - PROCESS | 26047 | 05:28:50 INFO - PROCESS | 26047 | [#9] json-stringify-tinderbox Cycles:10 Average:64.60 Median:65.00 stddev:0.52 (0.8%) stddev-sans-first:0.53 05:28:50 INFO - PROCESS | 26047 | Values: 65.0 64.0 65.0 65.0 65.0 64.0 64.0 64.0 65.0 65.0 05:28:50 INFO - PROCESS | 26047 | 05:28:50 INFO - PROCESS | 26047 | [#10] stanford-crypto-aes Cycles:10 Average:89.50 Median:84.50 stddev:17.16 (20.3%) stddev-sans-first:18.04 05:28:50 INFO - PROCESS | 26047 | Values: 83.0 86.0 82.0 81.0 88.0 85.0 85.0 83.0 138.0 84.0 05:28:50 INFO - PROCESS | 26047 | 05:28:50 INFO - PROCESS | 26047 | [#11] stanford-crypto-ccm Cycles:10 Average:139.50 Median:137.00 stddev:7.65 (5.6%) stddev-sans-first:6.64 05:28:50 INFO - PROCESS | 26047 | Values: 152.0 137.0 134.0 134.0 135.0 133.0 137.0 143.0 154.0 136.0 05:28:50 INFO - PROCESS | 26047 | 05:28:50 INFO - PROCESS | 26047 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.60 Median:174.00 stddev:2.67 (1.5%) stddev-sans-first:2.77 05:28:50 INFO - PROCESS | 26047 | Values: 172.0 178.0 173.0 174.0 174.0 174.0 178.0 172.0 171.0 170.0 05:28:50 INFO - PROCESS | 26047 | 05:28:50 INFO - PROCESS | 26047 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.10 Median:70.50 stddev:1.79 (2.5%) stddev-sans-first:1.56 05:28:50 INFO - PROCESS | 26047 | Values: 73.0 71.0 72.0 70.0 70.0 69.0 67.0 71.0 70.0 68.0 05:28:50 INFO - PROCESS | 26047 | -------- Summary: end -------- 05:28:50 INFO - PROCESS | 26047 | 05:28:51 INFO - TEST-INFO | 26047: exit 0 05:28:51 INFO - TEST-OK | kraken | took 64893ms 05:28:51 INFO - SUITE-END | took 1180s 05:28:52 INFO - Completed test suite (00:19:40) 05:28:52 INFO - javascript benchmark 05:28:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2648.3379155211196, 2699.85150816705, 2701.13563659629, 2705.431859309545, 2707.0660622085384, 4724.0, 4734.573888350048, 4767.8808029799275, 4841.055994081155, 4888.73111978841, 4680.602148817355, 4728.834490792823, 4757.333973243749, 4759.309900064489, 4790.137775200463, 4348.260795664744, 4407.559244075583, 4477.440319960005, 4478.596926276635, 4505.414296141497, 4397.31841564558, 4509.391232183646, 4509.458864936212, 4514.6614003949835, 4526.547345265463, 4322.092360604277, 4370.016746232104, 4370.519242883281, 4372.256716358211, 4455.643548516111, 4410.051838854662, 4414.867553973386, 4421.712588681726, 4484.573965473307, 4487.483939346984, 4460.264056430685, 4480.529544397857, 4485.2599321111975, 4485.551444855521, 4525.502194758574, 4697.295405689174, 4755.738434386108, 4767.833125840579, 4769.308450274725, 4775.617950563981, 5202.661826981234, 5204.375474943031, 5210.53105220532, 5237.450067742909, 5258.55302299305, 4913.975430122827, 5259.605529585297, 5275.129603615399, 5277.02375060615, 5282.022825777243, 4839.782209800567, 4860.1008813369635, 4898.044881248194, 4905.901881962341, 4906.1414252505665, 5321.73391330432, 5335.86660333487, 5363.946360536344, 5367.1680889462195, 5383.757730902119, 4875.536824001714, 5063.518965698253, 5077.0, 5131.7690703918415, 5139.486051394905, 5150.201718733602, 5181.53366197044, 5181.766820493087, 5200.713960732196, 5245.632805703564, 4971.279164521125, 5094.057599344133, 5094.745262736922, 5150.1502252127975, 5156.20078887773, 4975.601951843843, 5077.086124497552, 5138.871528211825, 5142.948570514247, 5159.638825282269, 4403.867883963486, 4428.7121337112985, 4436.7559784211535, 4459.0413061192, 4481.462224533077, 3840.731148819556, 3843.135294558758, 3859.880634615986, 3874.2638898609175, 3891.747036442622, 4286.207051695446, 4337.4795024596615, 4343.869683909487, 4392.0, 4477.977610111929, 5109.565686916654, 5113.616478764107, 5128.0, 5131.538161565477, 5174.197999310113, 4380.5838445347645, 4390.956090439055, 4399.25212713833, 4423.049044455457, 4423.225935461282, 4533.52397998212, 4585.380973568593, 4602.309653551927, 4641.95358046422, 4652.674312798072, 4806.351142595705, 4810.542998415145, 4811.903761924742, 4856.247281671346, 4865.099956508057, 5116.437191908962, 5131.076406246912, 5142.280080788618, 5145.845624631267, 5148.382194136728, 4638.512956139557, 4835.637327200544, 4861.562459378673, 4882.0, 4903.190973489334, 5082.644214904921, 5099.6685215460875, 5117.155669314521, 5123.257127716534, 5211.843644690665, 5199.948000519947, 5219.347581552306, 5231.136862417658, 5232.764525596282, 5244.9737751310995, 4843.854684359475, 4873.0, 4941.950580494149, 4943.110240156806, 4980.05378977993, 4966.950330496648, 4985.700857948535, 4999.65002449825, 5001.099802035668, 5005.874853128701, 4926.975365123151, 4934.062528119789, 4982.0, 4992.126377883928, 5009.298698182331, 5073.746312684425, 5111.872203194801, 5122.590192784643, 5135.691858488353, 5194.4805519447755, 5015.222640490584, 5017.874553136201, 5018.824341148043, 5036.370453693288, 5047.873803154804], "value": 4734.835651851468, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [544.4827413956742, 598.2850493660078, 602.4698265526337, 617.7035023188869, 627.786552572126, 36528.45207321889, 40238.79880600593, 40310.59689403105, 40584.39123413147, 40663.390049149246, 40055.39916901249, 40802.38796418049, 40907.0, 40945.18109637805, 41154.794226028906, 7781.844363112735, 7897.407694422913, 7929.881051784228, 7939.047314322261, 7946.046474423078, 7844.568548729846, 7984.40116991228, 7993.560354180518, 8006.159353267911, 8007.0, 8007.31937785286, 8044.758657240292, 8049.597520124, 8061.314788243007, 8068.636911338975, 7976.999999999971, 7977.521348719096, 7978.760637180894, 8013.759587212393, 8036.196380361917, 7761.534307941542, 7880.330171935392, 7915.050193976702, 7916.881246781303, 7923.0, 38402.23195536074, 38411.0, 38653.420198697044, 38693.613063869285, 38735.0, 3526.876559320437, 3623.2391197848483, 3903.7072219583647, 4026.798660066985, 4027.81874815634, 29812.0, 30268.69731302681, 30450.716196285146, 30494.847525762452, 31001.8449907749, 3477.7565570410084, 3514.859405623772, 3589.569251689788, 3593.106816437574, 3613.6205698401814, 243946.0, 244853.0, 246510.0, 246876.0, 248387.0, 33809.492857607154, 34855.0, 52798.77609791207, 53685.46314536805, 53766.73116634392, 33927.151821204665, 34092.0, 34172.658273417444, 34216.82891585526, 34303.82848085744, 12246.938765306295, 12313.876861231452, 12380.257184568954, 12391.504339826306, 12458.937705311415, 9898.406095634142, 10023.097921187129, 10035.498225088717, 10066.848997265048, 10087.646932357478, 8288.917110828936, 8392.664293428317, 8398.538080405515, 8441.620127094295, 8442.19799119095, 3264.4124057669374, 3269.493228549579, 3276.4921437177277, 3302.3560405720655, 3310.2551925816497, 874.6894852327332, 971.4754032822196, 977.130353984954, 1020.3010937507831, 1036.5387402605768, 28347.43305133927, 28596.0, 29260.365468812593, 29330.85334573356, 29449.263768405963, 1013.716159475348, 1062.3625824505389, 1078.757279612081, 1078.9514471848786, 1086.9076128529005, 15104.848951510345, 15577.299021543831, 15604.14177220241, 15630.9218453907, 15860.286287117107, 118937.40531297289, 119769.0, 374420.0, 374953.0, 375015.0, 50375.0, 51577.0, 51751.22373164406, 52069.218961715604, 52160.73919630377, 1247.3638444393425, 1252.2924547630741, 1261.6025951825145, 1266.170658218868, 1266.4807428954084, 27488.17535473939, 28117.71882281151, 28318.0, 28846.855765721037, 28916.0, 43883.34174987442, 44446.333304999804, 44515.554844451144, 44544.77727611341, 44594.0, 309047.0, 314754.0, 314791.0, 316621.0, 318449.0, 25927.481450371266, 26180.214593562225, 26209.344766380993, 26387.20838374852, 26416.471670566865, 44239.336409953234, 44512.10975780401, 45178.322325165806, 48331.5166848327, 48896.0, 38076.619233808415, 38263.2347353057, 38266.0, 38813.02967425837, 40000.19999599933, 37807.24385512329, 38145.2370952585, 38228.42657360197, 38285.4257186148, 38724.22551548897, 35274.82362588273, 37602.0, 37805.81097094607, 38519.22961540698, 41464.0, 32703.34593308073, 33691.83154084214, 35016.12459688528, 35595.822020890766, 37766.43350349692, 25856.0, 26114.347141321618, 26247.737522624528, 26270.60594091052, 26273.21180364592], "value": 16814.928764757475, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [379.9487069245653, 380.13708880840477, 381.9446180303856, 387.38405934564037, 391.1219312643117, 15865.679761625486, 17334.826651733496, 17704.82295177048, 17976.91011544944, 17988.190531426102, 16859.494215173534, 17090.743638845397, 17381.565460863483, 17863.55341116473, 17954.730679039825, 5099.796008159669, 5266.894662106755, 5283.339582552181, 5314.76083576238, 5316.946830531703, 3665.7617254878346, 5708.0, 6055.061465472859, 6065.848353791146, 6146.231721034871, 5064.0, 6075.939240607581, 6082.148499210114, 6086.6043707159115, 6095.177151084591, 5301.496357845998, 5337.039332920072, 5355.732213389315, 5380.085385484438, 5385.596080293974, 5052.9747351263, 5400.864978375533, 5424.701641409721, 5447.536959358459, 5456.6725996439945, 12525.123241373107, 12625.873741262561, 12929.0, 12939.0, 13013.284269365182, 1977.5451646121473, 1997.3308941504613, 1998.9200431982704, 2105.3683894831643, 2123.575284943018, 9505.287103467268, 9673.951630241874, 10427.739306517322, 10576.206784491194, 13911.79132313016, 1817.2185960037311, 1834.284628994693, 1854.45293638377, 1866.346778627483, 1899.6390685769798, 69014.65492672555, 70836.0, 71593.64203179003, 72160.27839721588, 72422.27577724207, 9994.0, 10111.292209545261, 10397.688069357931, 10499.68500944973, 10508.737281568023, 14549.41802327895, 14634.0, 14640.560783176521, 14655.706885862222, 14665.413383464542, 7077.009218709385, 7258.056452661121, 7278.443468976183, 7323.963380183065, 7349.338559529668, 6438.54930154894, 6489.740410383626, 6527.836804079936, 6537.477001839841, 6642.103316052271, 4458.130664520452, 4479.283314669702, 4504.166729155117, 4521.570450807168, 4662.230731929194, 1694.5678851892687, 1791.7491551182845, 1842.9043694185586, 1879.229515898475, 1921.2795201799324, 808.1554775259867, 934.5841100710255, 960.8894977077586, 999.4802702594756, 1023.5087158164123, 16689.24898379576, 16804.74792878108, 17177.05526196046, 17411.47765567035, 17432.0, 863.1023735315327, 891.3092353426147, 925.1950802801471, 955.2883102088849, 970.752458123174, 9348.90651093494, 9777.706668799947, 9818.969008254173, 9821.508924553744, 9890.505474726235, 423484.0, 423893.0, 426739.0, 427820.0, 434658.0, 79247.0, 80676.0, 81710.59144704357, 81720.59139704263, 82250.58874705588, 922.5156792683895, 926.1849572376266, 941.0589410589411, 946.5740416812462, 949.5204921514729, 13140.737185256432, 15310.464133755266, 15312.846871531143, 15355.616109596902, 15648.139352335731, 35519.0, 35575.0, 35903.28193436169, 36618.8169059153, 36734.8163259182, 49704.75147624239, 50155.49844501508, 50622.493775061776, 50779.746101270735, 50923.490765091876, 4900.828421220436, 5056.924146137737, 7435.367993720378, 7590.278923502258, 7667.386609071373, 5505.421930697219, 10343.793124137626, 11148.163887708291, 11372.31766094037, 11538.596149135075, 9952.0, 9986.900130998598, 9993.850092248476, 10120.291579589359, 10287.84568231492, 9888.653897113567, 10087.69736907894, 10122.696319110439, 10138.138258248131, 10205.948970255102, 10109.090181883666, 10135.847962280719, 10142.49287535635, 10266.589336426758, 10429.16566674649, 7636.770896873103, 7650.234976502528, 7668.6165691716305, 7712.421568382281, 7820.452568320163, 7736.226377362219, 9491.85762213581, 9887.406755594688, 9964.50177491137, 9970.351927124713], "value": 8200.815950952703, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1467.0610809082186, 1493.2459108150383, 1638.516637591911, 1768.089433941521, 1814.8820326678765, 6605.306442823507, 7220.097487814023, 7271.636418179096, 7679.232076792325, 7714.961425192873, 7396.815079623012, 7424.443166762488, 7429.814254643637, 7495.475316727831, 7498.100227972651, 2207.580559693657, 2244.8316376271764, 2247.955040899181, 2250.246167533878, 2254.785795349439, 2159.3521943416913, 2174.0868835089223, 2239.0, 2240.5518896220738, 2242.607543679858, 2207.415035015722, 2211.2481756202887, 2215.9113635454564, 2221.0227499900075, 2232.9888350558226, 2085.5620319732875, 2091.895405229732, 2091.9267825626107, 2104.8000439958255, 2109.7573779015456, 2135.8077773000505, 2159.66525188596, 2178.531615702616, 2186.7485239197376, 2189.386971647941, 7514.849703005964, 7567.167611562723, 7572.356349710281, 7575.886361704524, 7592.202818704011, 4399.582039706254, 4477.328400739882, 4480.171168333836, 4485.932711009337, 4491.393661855673, 2994.1317018064733, 3022.879084836604, 3103.953440698391, 3123.203583086321, 3147.8740850365957, 965.1699538396973, 1008.1380419741089, 1008.3344992305117, 1023.2223510132278, 1030.011189258313, 9875.456849873253, 9876.90123098767, 9894.30739848211, 9922.801543969152, 9944.353617014944, 4180.665546756252, 4196.853110141131, 4256.36154576816, 4322.978385108055, 4383.07955329378, 8650.307975361957, 8657.610407531676, 8680.739577812676, 8682.479051256818, 8685.43544669607, 4509.549045095464, 4523.163214805271, 4549.909001819945, 4556.2937744649635, 4578.519255478193, 3524.1189702574356, 3962.7424217425773, 4066.4510291110914, 4081.918361632751, 4130.173965206971, 3617.276544691072, 3649.908752281161, 3693.279810436939, 3734.663880250736, 3784.2242340320167, 1177.140687298277, 1188.6790566547156, 1195.5337418406655, 1196.300164403816, 1201.8377519034993, 713.0694443750955, 788.5426452657445, 902.0843843498853, 914.346242436661, 926.092429419173, 4997.625178111656, 5048.116579598555, 5068.771905264272, 5072.6195535335, 5183.637145399785, 902.3187493442409, 902.4314681750586, 909.7088931541975, 921.074320308086, 936.9297302702324, 3541.29174165168, 3570.0, 3627.147620309225, 3671.394219953731, 3829.7702137871815, 8848.690295839615, 9023.142801433853, 9078.86381704275, 9081.409708368936, 9125.634974600942, 8842.381033327605, 8996.145366190201, 9002.279817614575, 9005.594748236344, 9023.368364214442, 6805.863882722417, 7064.0, 7193.676284567207, 7235.746748863765, 7274.090738657668, 5081.110805609078, 5111.156659151198, 5200.271961925409, 5245.3181086460045, 5256.31667883188, 7945.761627151194, 8064.435489515678, 8309.086000539815, 8329.791755206168, 8331.500109993178, 8257.545834979133, 8459.111793261865, 8485.745427637181, 8508.957455212685, 8763.203543273487, 6159.291681456511, 6823.761168359084, 7059.964700176466, 7084.185318688211, 7230.204677485367, 7188.748393806191, 7211.819704507219, 7244.782656520313, 7349.522281051714, 7356.043714317105, 6513.413792758674, 6582.80251592453, 6674.26583075871, 6695.665216739241, 6776.288489708707, 6604.306547812411, 6637.834054148491, 6669.0329902164885, 6736.090627765189, 6744.460443164437, 5972.910406343995, 6044.576879618385, 6046.365131661289, 6046.7278972445465, 6094.969525152346, 4469.888252793706, 4537.410136682342, 4649.3026046093355, 4683.718976861399, 4693.22561777303, 4526.728396296233, 4535.183666939983, 4558.5897269245925, 4627.259638457831, 4635.768211589339], "value": 4099.481312056178, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [304.60249374566183, 316.43200455182944, 318.9505626627874, 321.19380355308175, 321.53217069164367, 4904.435989861158, 4974.602031837453, 5053.014662140884, 5054.721990290533, 5084.822031228903, 4968.701877887329, 4979.825706100287, 4994.42564105128, 5031.195008798593, 5036.722980236085, 3926.391409331558, 3946.2502124596376, 3948.9012774680646, 3961.861334853281, 3979.6219359160978, 3683.5395575553057, 3975.1652153047894, 3978.269452788032, 3995.3407687731633, 4000.5599384067727, 3878.825452854628, 3881.359575670025, 3881.592432794558, 3882.456456096149, 3884.9028774280587, 3863.806809659534, 3875.147467557133, 3878.3794592865147, 3890.0663840678317, 3913.6477717005464, 3402.625711171769, 3406.3017081498233, 3427.5715535558056, 3427.9657203428146, 3428.365752335826, 4582.433837051262, 4703.811847526094, 4744.14605371033, 4791.856244312677, 4828.275758636232, 4373.934390984138, 4376.3654270130655, 4376.409184760048, 4392.3411488277015, 4429.579189976947, 5226.503482169188, 5256.316678831766, 5258.50044245796, 5271.973640131813, 5304.283921670563, 3609.819509024538, 3706.1475860552223, 3746.456763769238, 3756.887293381203, 3760.3231418344963, 5435.130379139318, 5461.644993075437, 5473.233747275345, 5502.2296878437055, 5515.503604675599, 3761.5298087739034, 3775.3581891078034, 3779.4519794629623, 3815.637514436124, 3816.961830381716, 3875.1862108957434, 3893.04620368006, 3899.1421887184774, 3906.667933225651, 3911.2177564487215, 4316.935245971313, 4402.581754733295, 4411.360352748833, 4411.845585404495, 4429.180601588717, 5223.4776522347465, 5247.1866860636665, 5280.155175171954, 5295.808443100349, 5303.337082864642, 1227.2023184929867, 1250.206119114369, 1275.6428200103985, 1287.929163895976, 1291.6254286257067, 863.0635760200253, 870.4081224767219, 883.5008220355479, 885.1679421343916, 891.4918496456958, 4339.893327201544, 4347.717398369159, 4351.9564804352185, 4354.303311470149, 4360.672949528798, 5125.2568377585785, 5143.562797162208, 5149.716765577854, 5168.431472538018, 5178.533931946067, 4244.732416658448, 4362.934555981663, 4366.388705581221, 4376.474823021258, 4387.2103021455805, 2683.9329016774736, 2789.5257806173, 2803.0049332486674, 2811.9156425307274, 2824.632797736322, 4893.95106048942, 4913.336699545587, 4938.481459446778, 4939.5554400104165, 4969.304297398295, 5114.156164232934, 5223.007628550563, 5299.205119232069, 5315.335583052119, 5362.9888791703115, 4813.4945830687975, 4852.223644216979, 4867.780949857265, 4904.975475122602, 4931.16170251051, 5171.431142574239, 5183.5593974512585, 5192.117340052276, 5207.2449494823795, 5212.582993360598, 5037.471065538066, 5107.799667078308, 5150.201718733602, 5164.8192313268855, 5403.351597808131, 5086.83809381019, 5123.154679477844, 5136.8202112925865, 5155.917257375843, 5160.200168973815, 3724.366857634263, 3791.8862434127022, 3811.0662887592716, 3818.427235914635, 3826.1773718650625, 5198.844034678966, 5435.266239057677, 5455.890882182255, 5491.28613280271, 5498.917516237179, 5251.291075704731, 5269.499397557226, 5276.786339142096, 5291.973540132429, 5300.655457395257, 5282.8943421132135, 5291.153415453509, 5294.338207724035, 5306.920396193983, 5317.574594032545, 5068.670536415121, 5071.822486212965, 5165.173572228426, 5176.844694659167, 5183.377994640668, 5281.419043905091, 5292.788288468572, 5301.985245606645, 5336.226247194213, 5352.785888564414, 5144.511271429208, 5237.711925844115, 5237.869053273546, 5255.159174532209, 5258.1849813279005], "value": 3910.492666757213, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [732.4250463386243, 761.1437133225122, 780.1963977103583, 803.5982008995502, 823.0164952881307, 11015.944920275397, 11682.941585292061, 11726.706832329197, 11732.882671173285, 11759.70600734982, 11101.0, 12290.815637765418, 12521.561745338915, 12527.498900043987, 12543.686407839808, 862.5169904853286, 874.7681864305964, 876.2551830943679, 878.038547790172, 880.7974165941805, 871.8866547348836, 875.6191056890241, 875.8729984152294, 882.841088604051, 883.152173913041, 903.2638399704252, 903.3495882964255, 904.1275169461468, 910.4901255297021, 911.8905731312219, 893.6336102198132, 896.256107430834, 900.2033200617501, 904.3036861616552, 907.4147175072075, 661.2858113237692, 666.8099591616366, 667.4426853577269, 668.2014992084461, 668.7258224128133, 10793.190510711727, 10793.83809242862, 10837.783244335147, 10858.34849909008, 10871.67384978452, 9601.327907046509, 10026.949865250626, 10428.217883658755, 10524.0, 10849.0, 8796.384253102286, 9044.186023257938, 9214.631414743402, 9272.165505104505, 9322.627094916195, 3264.657210992859, 3356.1441832332844, 3357.513160591725, 3366.074329559366, 3441.587009558869, 34851.477227841606, 39313.21373572541, 40567.59432405668, 40707.98230044243, 41077.79461102675, 10036.498175091216, 10354.275200735876, 10390.844137337946, 10725.14198864089, 10751.677449676523, 18288.17703203359, 18337.908310458362, 18357.265709371477, 18550.907245463957, 18591.0, 6513.218413790375, 6646.368594983565, 6737.157855268091, 6773.559718618274, 7035.33164349386, 3491.965080349215, 3653.2819263665297, 3654.1595433050015, 3674.926501470009, 3687.575928768226, 2851.900183493568, 2953.926151846221, 2957.1276473440385, 2971.806832555877, 2974.866131024109, 524.0880867290887, 532.1432493685164, 535.0529562674045, 541.2098336428858, 542.3925203771801, 587.952963762898, 616.7286393986632, 623.3388020924277, 630.7729217481703, 635.6535688050024, 7495.812604684927, 7765.844683106307, 7785.532868027936, 7798.103218129874, 8100.270975612223, 624.5503237668871, 633.5628416392766, 647.3105195439898, 652.5138771615083, 652.6834485274638, 3725.7578257413184, 3786.6024067473068, 3818.293615681108, 3862.594427585063, 3886.7279290449965, 46184.5381546187, 47936.7603161982, 48252.0, 48312.27531587029, 48902.0, 46380.955713635514, 46400.303995440096, 46889.765551172706, 47763.76118119387, 48366.5163348369, 35968.82015589958, 42174.78912605417, 43015.56984430116, 43338.0, 44284.55715442869, 7830.647620857189, 7922.9304043955635, 7970.40221983342, 8201.917980820115, 8338.666453341799, 23465.648015279443, 23592.646110308702, 28417.43165136644, 28590.285242869093, 29296.41407171887, 32848.0, 36064.63935360718, 36708.26583468262, 36730.26539469249, 36859.0, 10769.62306319275, 11201.551937922392, 11273.661790146309, 11277.548898044315, 11329.206955513364, 11279.0, 11466.827997579876, 11611.5355385787, 11769.529218831492, 11991.100667449802, 10725.89274107249, 11037.448127593427, 11557.30656160633, 11568.421578920852, 11768.882311176778, 10953.452327383759, 10972.835407469054, 11037.227394082338, 11040.558377664804, 11060.834087488854, 10624.893751062391, 10974.670759877217, 11013.118950483782, 11232.550697972316, 11646.417679116179, 8499.447535910145, 8961.596728147118, 9141.886151232105, 9374.343795934219, 9426.858597120912, 9222.262219022596, 9532.666356677482, 9535.189508891544, 9551.761205969906, 9609.183219426428], "value": 5914.530381979332, "unit": "score"}], "name": "dromaeo_css", "value": 6289.711349082573, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 115.0, 108.0, 116.0, 116.0, 116.0, 111.0, 114.0, 118.0, 116.0], "value": 114.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 132.0, 156.0, 225.0, 133.0, 166.0, 156.0, 135.0, 199.0, 138.0], "value": 159.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 164.0, 170.0, 167.0, 162.0, 162.0, 161.0, 165.0, 164.0, 167.0], "value": 164.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 76.0, 76.0, 160.0, 78.0, 76.0, 76.0, 87.0, 160.0, 75.0], "value": 94.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 174.0, 94.0, 94.0, 93.0, 150.0, 93.0, 92.0, 138.0], "value": 111.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 119.0, 118.0, 117.0, 117.0, 117.0, 118.0, 117.0, 118.0, 118.0], "value": 117.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 147.0, 147.0, 147.0, 148.0, 147.0, 146.0, 147.0, 148.0, 147.0], "value": 147.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 95.0, 97.0, 95.0, 95.0, 95.0, 96.0, 95.0], "value": 95.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 64.0, 64.0, 65.0, 66.0, 64.0, 64.0, 65.0, 65.0], "value": 65.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 65.0, 65.0, 65.0, 64.0, 64.0, 64.0, 65.0, 65.0], "value": 64.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 82.0, 81.0, 88.0, 85.0, 85.0, 83.0, 138.0, 84.0], "value": 89.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 137.0, 134.0, 134.0, 135.0, 133.0, 137.0, 143.0, 154.0, 136.0], "value": 139.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 178.0, 173.0, 174.0, 174.0, 174.0, 178.0, 172.0, 171.0, 170.0], "value": 173.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 71.0, 72.0, 70.0, 70.0, 69.0, 67.0, 71.0, 70.0, 68.0], "value": 70.1, "unit": "score"}], "name": "kraken", "value": 1607.6, "alertThreshold": 2.0}]} 05:28:52 INFO - Return code: 0 05:28:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:28:52 ERROR - # TBPL SUCCESS # 05:28:52 INFO - Running post-action listener: _resource_record_post_action 05:28:52 INFO - ##### 05:28:52 INFO - ##### Finished run-tests step (success) 05:28:52 INFO - ##### 05:28:52 INFO - Running post-run listener: _resource_record_post_run 05:28:53 INFO - Total resource usage - Wall time: 1198s; CPU: 13.0%; Read bytes: 4096; Write bytes: 531816448; Read time: 12; Write time: 436744 05:28:53 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 235073536; Read time: 0; Write time: 233640 05:28:53 INFO - run-tests - Wall time: 1182s; CPU: 13.0%; Read bytes: 4096; Write bytes: 296742912; Read time: 12; Write time: 203104 05:28:53 INFO - Running post-run listener: _upload_blobber_files 05:28:53 INFO - Blob upload gear active. 05:28:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:28:53 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:28:53 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'] 05:28:53 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 05:28:54 INFO - (blobuploader) - INFO - Open directory for files ... 05:28:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:28:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:28:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:28:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:28:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:28:54 INFO - (blobuploader) - INFO - Done attempting. 05:28:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:28:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:28:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:28:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:28:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:28:55 INFO - (blobuploader) - INFO - Done attempting. 05:28:55 INFO - (blobuploader) - INFO - Iteration through files over. 05:28:55 INFO - Return code: 0 05:28:55 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:28:55 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:28:55 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/6cc38e7589da9ad637b34e7d5342d9ee751db3a035eeacfa1f6902d8c4694ba4f71c0833899df51bb0c2147ea132fc35ed6ce98044ab45da6fbea7734648c299", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/423c98bfe9a66acc3855942f5794971b9357db9ecb000768517801c6d7eacb0d44b8029273fa0aa1b7a98f16f8f18225eec97cbe4a82fe60d159242054b19722"} 05:28:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:28:55 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:28:55 INFO - Contents: 05:28:55 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/6cc38e7589da9ad637b34e7d5342d9ee751db3a035eeacfa1f6902d8c4694ba4f71c0833899df51bb0c2147ea132fc35ed6ce98044ab45da6fbea7734648c299", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/423c98bfe9a66acc3855942f5794971b9357db9ecb000768517801c6d7eacb0d44b8029273fa0aa1b7a98f16f8f18225eec97cbe4a82fe60d159242054b19722"} 05:28:55 INFO - Running post-run listener: copy_logs_to_upload_dir 05:28:55 INFO - Copying logs to upload dir... 05:28:55 INFO - mkdir: /builds/slave/test/build/upload/logs 05:28:55 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1234.868399 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2016-05-09 05:28:55.302338) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-09 05:28:55.305835) ========= 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=41bf3e9aed84707956354da8000001c5-1462795698.338535-401650172 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/6cc38e7589da9ad637b34e7d5342d9ee751db3a035eeacfa1f6902d8c4694ba4f71c0833899df51bb0c2147ea132fc35ed6ce98044ab45da6fbea7734648c299", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/423c98bfe9a66acc3855942f5794971b9357db9ecb000768517801c6d7eacb0d44b8029273fa0aa1b7a98f16f8f18225eec97cbe4a82fe60d159242054b19722"} build_url:https://queue.taskcluster.net/v1/task/ec9URdT1StCIucoQLQHEBA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011246 build_url: 'https://queue.taskcluster.net/v1/task/ec9URdT1StCIucoQLQHEBA/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/6cc38e7589da9ad637b34e7d5342d9ee751db3a035eeacfa1f6902d8c4694ba4f71c0833899df51bb0c2147ea132fc35ed6ce98044ab45da6fbea7734648c299", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/423c98bfe9a66acc3855942f5794971b9357db9ecb000768517801c6d7eacb0d44b8029273fa0aa1b7a98f16f8f18225eec97cbe4a82fe60d159242054b19722"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-09 05:28:55.329690) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 05:28:55.329981) ========= 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=41bf3e9aed84707956354da8000001c5-1462795698.338535-401650172 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004425 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-09 05:28:55.415228) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-09 05:28:55.415556) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-09 05:28:55.415830) ========= ========= Total master_lag: 0.17 =========