builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-026 starttime: 1461756573.34 results: success (0) buildid: 20160427025603 builduid: 3a5b84d2cfaf4104a9d688815a3e42b2 revision: 488df90abd620538454e58ae0a75f13453f391b1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-27 04:29:33.339051) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-27 04:29:33.339513) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-27 04:29:33.339795) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-27 04:29:33.355732) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 04:29:33.356055) ========= 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-1461756572.954321-1283641448 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-04-27 04:29:33-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 50.6M=0s 2016-04-27 04:29:33 (50.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.111792 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 04:29:33.482730) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 04:29:33.483063) ========= 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-1461756572.954321-1283641448 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017321 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 04:29:33.532889) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-27 04:29:33.533264) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 488df90abd620538454e58ae0a75f13453f391b1 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 488df90abd620538454e58ae0a75f13453f391b1 --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-1461756572.954321-1283641448 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-04-27 04:29:33,782 truncating revision to first 12 chars 2016-04-27 04:29:33,782 Setting DEBUG logging. 2016-04-27 04:29:33,782 attempt 1/10 2016-04-27 04:29:33,782 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/488df90abd62?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-27 04:29:34,980 unpacking tar archive at: mozilla-central-488df90abd62/testing/mozharness/ program finished with exit code 0 elapsedTime=1.510482 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-27 04:29:35.058863) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-27 04:29:35.059433) ========= script_repo_revision: 488df90abd620538454e58ae0a75f13453f391b1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-27 04:29:35.060095) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-27 04:29:35.060564) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-27 04:29:35.071944) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 36 secs) (at 2016-04-27 04:29:35.072433) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-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', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-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-1461756572.954321-1283641448 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:29:35 INFO - MultiFileLogger online at 20160427 04:29:35 in /builds/slave/test 04:29:35 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 04:29:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:29:35 INFO - {'append_to_log': False, 04:29:35 INFO - 'base_work_dir': '/builds/slave/test', 04:29:35 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 04:29:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:29:35 INFO - 'branch': 'Firefox-Non-PGO', 04:29:35 INFO - 'buildbot_json_path': 'buildprops.json', 04:29:35 INFO - 'config_files': ('talos/linux_config.py',), 04:29:35 INFO - 'default_actions': ('clobber', 04:29:35 INFO - 'read-buildbot-config', 04:29:35 INFO - 'download-and-extract', 04:29:35 INFO - 'populate-webroot', 04:29:35 INFO - 'create-virtualenv', 04:29:35 INFO - 'install', 04:29:35 INFO - 'run-tests'), 04:29:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:29:35 INFO - 'download_minidump_stackwalk': True, 04:29:35 INFO - 'download_symbols': 'ondemand', 04:29:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:29:35 INFO - 'tooltool.py': '/tools/tooltool.py', 04:29:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:29:35 INFO - '/tools/misc-python/virtualenv.py')}, 04:29:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:29:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:29:35 INFO - 'installer_path': 'installer.exe', 04:29:35 INFO - 'log_level': 'info', 04:29:35 INFO - 'log_name': 'talos', 04:29:35 INFO - 'log_to_console': True, 04:29:35 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:29:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:29:35 INFO - 'opt_config_files': (), 04:29:35 INFO - 'pip_index': False, 04:29:35 INFO - 'sps_profile': False, 04:29:35 INFO - 'sps_profile_interval': 0, 04:29:35 INFO - 'suite': 'dromaeojs-e10s', 04:29:35 INFO - 'system_bits': '32', 04:29:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:29:35 INFO - 'title': 'talos-linux64-ix-026', 04:29:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:29:35 INFO - 'use_talos_json': True, 04:29:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:29:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:29:35 INFO - 'work_dir': 'build'} 04:29:35 INFO - ##### 04:29:35 INFO - ##### Running clobber step. 04:29:35 INFO - ##### 04:29:35 INFO - Running pre-action listener: _resource_record_pre_action 04:29:35 INFO - Running main action method: clobber 04:29:35 INFO - rmtree: /builds/slave/test/build 04:29:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:29:35 INFO - Running post-action listener: _resource_record_post_action 04:29:35 INFO - ##### 04:29:35 INFO - ##### Running read-buildbot-config step. 04:29:35 INFO - ##### 04:29:35 INFO - Running pre-action listener: _resource_record_pre_action 04:29:35 INFO - Running main action method: read_buildbot_config 04:29:35 INFO - Using buildbot properties: 04:29:35 INFO - { 04:29:35 INFO - "project": "", 04:29:35 INFO - "product": "firefox", 04:29:35 INFO - "script_repo_revision": "production", 04:29:35 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 04:29:35 INFO - "repository": "", 04:29:35 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 04:29:35 INFO - "buildid": "20160427025603", 04:29:35 INFO - "pgo_build": "False", 04:29:35 INFO - "basedir": "/builds/slave/test", 04:29:35 INFO - "buildnumber": 42, 04:29:35 INFO - "slavename": "talos-linux64-ix-026", 04:29:35 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 04:29:35 INFO - "platform": "ubuntu64_hw", 04:29:35 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 04:29:35 INFO - "branch": "mozilla-central", 04:29:35 INFO - "repo_path": "mozilla-central", 04:29:35 INFO - "revision": "488df90abd620538454e58ae0a75f13453f391b1", 04:29:35 INFO - "stage_platform": "linux64", 04:29:35 INFO - "builduid": "3a5b84d2cfaf4104a9d688815a3e42b2", 04:29:35 INFO - "slavebuilddir": "test" 04:29:35 INFO - } 04:29:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/RsZhMrEDSbqvuE8XqQnoYg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 04:29:35 INFO - Running post-action listener: _resource_record_post_action 04:29:35 INFO - ##### 04:29:35 INFO - ##### Running download-and-extract step. 04:29:35 INFO - ##### 04:29:35 INFO - Running pre-action listener: _resource_record_pre_action 04:29:35 INFO - Running main action method: download_and_extract 04:29:35 INFO - mkdir: /builds/slave/test/build/tests 04:29: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')]} 04:29:35 INFO - https://queue.taskcluster.net/v1/task/RsZhMrEDSbqvuE8XqQnoYg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 04:29:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RsZhMrEDSbqvuE8XqQnoYg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 04:29:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RsZhMrEDSbqvuE8XqQnoYg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 04:29:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RsZhMrEDSbqvuE8XqQnoYg/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 04:29:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RsZhMrEDSbqvuE8XqQnoYg/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 04:29:36 INFO - Downloaded 1361 bytes. 04:29:36 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 04:29:36 INFO - Using the following test package requirements: 04:29:36 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 04:29:36 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 04:29:36 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 04:29:36 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 04:29:36 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 04:29:36 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 04:29:36 INFO - u'jsshell-linux-x86_64.zip'], 04:29:36 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 04:29:36 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 04:29:36 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 04:29:36 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 04:29:36 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 04:29:36 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 04:29:36 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 04:29:36 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 04:29:36 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 04:29:36 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 04:29:36 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 04:29:36 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:29:36 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')]} 04:29:36 INFO - https://queue.taskcluster.net/v1/task/RsZhMrEDSbqvuE8XqQnoYg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:29:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RsZhMrEDSbqvuE8XqQnoYg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 04:29:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RsZhMrEDSbqvuE8XqQnoYg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 04:29:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RsZhMrEDSbqvuE8XqQnoYg/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 04:29:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RsZhMrEDSbqvuE8XqQnoYg/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 04:29:37 INFO - Downloaded 22291553 bytes. 04:29:37 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'] 04:29:37 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 04:29:38 INFO - Return code: 0 04:29:38 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 04:29:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:29:38 INFO - https://queue.taskcluster.net/v1/task/RsZhMrEDSbqvuE8XqQnoYg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:29:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RsZhMrEDSbqvuE8XqQnoYg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 04:29:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RsZhMrEDSbqvuE8XqQnoYg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 04:29:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RsZhMrEDSbqvuE8XqQnoYg/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 04:29:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RsZhMrEDSbqvuE8XqQnoYg/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 04:29:40 INFO - Downloaded 22291553 bytes. 04:29:40 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'] 04:29:40 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 04:29:41 INFO - Return code: 0 04:29: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')]} 04:29:41 INFO - https://queue.taskcluster.net/v1/task/RsZhMrEDSbqvuE8XqQnoYg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 04:29:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RsZhMrEDSbqvuE8XqQnoYg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 04:29:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RsZhMrEDSbqvuE8XqQnoYg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 04:29:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RsZhMrEDSbqvuE8XqQnoYg/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 04:29:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RsZhMrEDSbqvuE8XqQnoYg/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 04:30:13 WARNING - Socket error when accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RsZhMrEDSbqvuE8XqQnoYg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip: The read operation timed out 04:30:13 INFO - retry: attempt #1 caught exception: The read operation timed out 04:30:13 INFO - retry: Failed, sleeping 30 seconds before retrying 04:30:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RsZhMrEDSbqvuE8XqQnoYg/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 #2 04:30:44 INFO - Downloaded 10840145 bytes. 04:30:44 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'] 04:30:44 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 04:30:44 INFO - Return code: 0 04:30: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')]} 04:30:44 INFO - https://queue.taskcluster.net/v1/task/RsZhMrEDSbqvuE8XqQnoYg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 04:30:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RsZhMrEDSbqvuE8XqQnoYg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 04:30:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RsZhMrEDSbqvuE8XqQnoYg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 04:30:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RsZhMrEDSbqvuE8XqQnoYg/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 04:30:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/RsZhMrEDSbqvuE8XqQnoYg/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 04:30:45 INFO - Downloaded 54815609 bytes. 04:30:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RsZhMrEDSbqvuE8XqQnoYg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 04:30:45 INFO - mkdir: /builds/slave/test/properties 04:30:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:30:45 INFO - Writing to file /builds/slave/test/properties/build_url 04:30:45 INFO - Contents: 04:30:45 INFO - build_url:https://queue.taskcluster.net/v1/task/RsZhMrEDSbqvuE8XqQnoYg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 04:30:46 INFO - Running post-action listener: _resource_record_post_action 04:30:46 INFO - Running post-action listener: set_extra_try_arguments 04:30:46 INFO - ##### 04:30:46 INFO - ##### Running populate-webroot step. 04:30:46 INFO - ##### 04:30:46 INFO - Running pre-action listener: _resource_record_pre_action 04:30:46 INFO - Running main action method: populate_webroot 04:30:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:30:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:30:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:30:46 INFO - '--tppagecycles', 04:30:46 INFO - '7'], 04:30:46 INFO - 'tests': ['tsvgm']}}, 04:30:46 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:30:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:30:46 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:30:46 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:30:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:30:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:30:46 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:30:46 INFO - 'talos_options': ['--disable-e10s'], 04:30:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:30:46 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:30:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:30:46 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:30:46 INFO - 'talos_options': ['--disable-e10s'], 04:30:46 INFO - 'tests': ['damp', 'tps']}, 04:30:46 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:30:46 INFO - 'tests': ['damp', 'tps']}, 04:30:46 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:30:46 INFO - 'tests': ['dromaeo_dom']}, 04:30:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:30:46 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:30:46 INFO - 'tests': ['a11yr', 04:30:46 INFO - 'ts_paint', 04:30:46 INFO - 'tpaint', 04:30:46 INFO - 'sessionrestore', 04:30:46 INFO - 'sessionrestore_no_auto_restore', 04:30:46 INFO - 'tabpaint']}, 04:30:46 INFO - 'other-e10s': {'tests': ['a11yr', 04:30:46 INFO - 'ts_paint', 04:30:46 INFO - 'tpaint', 04:30:46 INFO - 'sessionrestore', 04:30:46 INFO - 'sessionrestore_no_auto_restore', 04:30:46 INFO - 'tabpaint']}, 04:30:46 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:30:46 INFO - 'ts_paint', 04:30:46 INFO - 'tpaint', 04:30:46 INFO - 'sessionrestore', 04:30:46 INFO - 'sessionrestore_no_auto_restore', 04:30:46 INFO - 'tabpaint']}, 04:30:46 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:30:46 INFO - 'ts_paint', 04:30:46 INFO - 'tpaint', 04:30:46 INFO - 'sessionrestore', 04:30:46 INFO - 'sessionrestore_no_auto_restore', 04:30:46 INFO - 'tabpaint']}, 04:30:46 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:30:46 INFO - 'tests': ['a11yr', 04:30:46 INFO - 'ts_paint', 04:30:46 INFO - 'tpaint', 04:30:46 INFO - 'sessionrestore', 04:30:46 INFO - 'sessionrestore_no_auto_restore', 04:30:46 INFO - 'tabpaint']}, 04:30:46 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:30:46 INFO - 'tests': ['a11yr', 04:30:46 INFO - 'ts_paint', 04:30:46 INFO - 'tpaint', 04:30:46 INFO - 'sessionrestore', 04:30:46 INFO - 'sessionrestore_no_auto_restore', 04:30:46 INFO - 'tabpaint']}, 04:30:46 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:30:46 INFO - 'tests': ['tsvgx', 04:30:46 INFO - 'tsvgr_opacity', 04:30:46 INFO - 'tart', 04:30:46 INFO - 'tscrollx', 04:30:46 INFO - 'cart']}, 04:30:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:30:46 INFO - 'tsvgr_opacity', 04:30:46 INFO - 'tart', 04:30:46 INFO - 'tscrollx', 04:30:46 INFO - 'cart']}, 04:30:46 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:30:46 INFO - 'talos_options': ['--disable-e10s'], 04:30:46 INFO - 'tests': ['tp5o']}, 04:30:46 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:30:46 INFO - 'tests': ['tp5o']}, 04:30:46 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:30:46 INFO - 'talos_options': ['--disable-e10s', 04:30:46 INFO - '--xperf_path', 04:30:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:30:46 INFO - 'tests': ['tp5n']}, 04:30:46 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:30:46 INFO - 'talos_options': ['--xperf_path', 04:30:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:30:46 INFO - 'tests': ['tp5n']}}, 04:30:46 INFO - 'talos.zip': {'path': '', 04:30:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:30:46 INFO - Running post-action listener: _resource_record_post_action 04:30:46 INFO - ##### 04:30:46 INFO - ##### Running create-virtualenv step. 04:30:46 INFO - ##### 04:30:46 INFO - Running pre-action listener: _resource_record_pre_action 04:30:46 INFO - Running main action method: create_virtualenv 04:30:46 INFO - Creating virtualenv /builds/slave/test/build/venv 04:30:46 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 04:30:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:30:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:30:46 INFO - Using real prefix '/usr' 04:30:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:30:47 INFO - Installing distribute.............................................................................................................................................................................................done. 04:30:49 INFO - Installing pip.................done. 04:30:49 INFO - Return code: 0 04:30:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:30:49 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')]} 04:30:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:49 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')]} 04:30:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:49 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 0x7fbed4eaff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf205a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfaf410>, '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 0xfb0310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfb07a0>, '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-1461756572.954321-1283641448', '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 04:30:49 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 04:30:49 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 04:30:49 INFO - Using env: {'DISPLAY': ':0', 04:30:49 INFO - 'HOME': '/home/cltbld', 04:30:49 INFO - 'LANG': 'en_US.UTF-8', 04:30:49 INFO - 'LANGUAGE': 'en_US:en', 04:30:49 INFO - 'LOGNAME': 'cltbld', 04:30:49 INFO - 'MAIL': '/var/mail/cltbld', 04:30:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:49 INFO - 'MOZ_NO_REMOTE': '1', 04:30:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:30:49 INFO - 'NO_EM_RESTART': '1', 04:30:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:30:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:49 INFO - 'PWD': '/builds/slave/test', 04:30:49 INFO - 'SHELL': '/bin/bash', 04:30:49 INFO - 'SHLVL': '1', 04:30:49 INFO - 'TERM': 'linux', 04:30:49 INFO - 'TMOUT': '86400', 04:30:49 INFO - 'USER': 'cltbld', 04:30:49 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1461756572.954321-1283641448', 04:30:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:49 INFO - '_': '/tools/buildbot/bin/python'} 04:30:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:49 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 04:30:49 INFO - Cleaning up... 04:30:49 INFO - Return code: 0 04:30:49 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 04:30:49 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')]} 04:30:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:49 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')]} 04:30:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:49 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 0x7fbed4eaff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf205a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfaf410>, '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 0xfb0310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfb07a0>, '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-1461756572.954321-1283641448', '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 04:30:49 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 04:30:49 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 04:30:49 INFO - Using env: {'DISPLAY': ':0', 04:30:49 INFO - 'HOME': '/home/cltbld', 04:30:49 INFO - 'LANG': 'en_US.UTF-8', 04:30:49 INFO - 'LANGUAGE': 'en_US:en', 04:30:49 INFO - 'LOGNAME': 'cltbld', 04:30:49 INFO - 'MAIL': '/var/mail/cltbld', 04:30:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:49 INFO - 'MOZ_NO_REMOTE': '1', 04:30:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:30:49 INFO - 'NO_EM_RESTART': '1', 04:30:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:30:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:49 INFO - 'PWD': '/builds/slave/test', 04:30:49 INFO - 'SHELL': '/bin/bash', 04:30:49 INFO - 'SHLVL': '1', 04:30:49 INFO - 'TERM': 'linux', 04:30:49 INFO - 'TMOUT': '86400', 04:30:49 INFO - 'USER': 'cltbld', 04:30:49 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1461756572.954321-1283641448', 04:30:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:49 INFO - '_': '/tools/buildbot/bin/python'} 04:30:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:49 INFO - Downloading/unpacking psutil>=0.7.1 04:30:49 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 04:30:49 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 04:30:49 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 04:30:49 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 04:30:49 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 04:30:49 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 04:30:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:30:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:30:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:30:51 INFO - Installing collected packages: psutil 04:30:51 INFO - Running setup.py install for psutil 04:30:51 INFO - building 'psutil._psutil_linux' extension 04:30:51 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 04:30:51 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 04:30:51 INFO - building 'psutil._psutil_posix' extension 04:30:51 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 04:30:51 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 04:30:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:30:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:30:51 INFO - Successfully installed psutil 04:30:51 INFO - Cleaning up... 04:30:52 INFO - Return code: 0 04:30:52 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 04:30:52 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')]} 04:30:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:52 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')]} 04:30:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:52 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 0x7fbed4eaff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf205a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfaf410>, '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 0xfb0310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfb07a0>, '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-1461756572.954321-1283641448', '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 04:30:52 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 04:30:52 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 04:30:52 INFO - Using env: {'DISPLAY': ':0', 04:30:52 INFO - 'HOME': '/home/cltbld', 04:30:52 INFO - 'LANG': 'en_US.UTF-8', 04:30:52 INFO - 'LANGUAGE': 'en_US:en', 04:30:52 INFO - 'LOGNAME': 'cltbld', 04:30:52 INFO - 'MAIL': '/var/mail/cltbld', 04:30:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:52 INFO - 'MOZ_NO_REMOTE': '1', 04:30:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:30:52 INFO - 'NO_EM_RESTART': '1', 04:30:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:30:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:52 INFO - 'PWD': '/builds/slave/test', 04:30:52 INFO - 'SHELL': '/bin/bash', 04:30:52 INFO - 'SHLVL': '1', 04:30:52 INFO - 'TERM': 'linux', 04:30:52 INFO - 'TMOUT': '86400', 04:30:52 INFO - 'USER': 'cltbld', 04:30:52 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1461756572.954321-1283641448', 04:30:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:52 INFO - '_': '/tools/buildbot/bin/python'} 04:30:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:52 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 04:30:52 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 04:30:52 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 04:30:52 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 04:30:52 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 04:30:52 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 04:30:52 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 04:30:54 INFO - Downloading mozsystemmonitor-0.0.tar.gz 04:30:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:30:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 04:30:54 INFO - Installing collected packages: mozsystemmonitor 04:30:54 INFO - Running setup.py install for mozsystemmonitor 04:30:54 INFO - Successfully installed mozsystemmonitor 04:30:54 INFO - Cleaning up... 04:30:54 INFO - Return code: 0 04:30:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:30:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:30:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:30:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:54 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 0x7fbed4eaff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf205a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfaf410>, '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 0xfb0310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfb07a0>, '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-1461756572.954321-1283641448', '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 04:30:54 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 04:30:54 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 04:30:54 INFO - Using env: {'DISPLAY': ':0', 04:30:54 INFO - 'HOME': '/home/cltbld', 04:30:54 INFO - 'LANG': 'en_US.UTF-8', 04:30:54 INFO - 'LANGUAGE': 'en_US:en', 04:30:54 INFO - 'LOGNAME': 'cltbld', 04:30:54 INFO - 'MAIL': '/var/mail/cltbld', 04:30:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:54 INFO - 'MOZ_NO_REMOTE': '1', 04:30:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:30:54 INFO - 'NO_EM_RESTART': '1', 04:30:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:30:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:54 INFO - 'PWD': '/builds/slave/test', 04:30:54 INFO - 'SHELL': '/bin/bash', 04:30:54 INFO - 'SHLVL': '1', 04:30:54 INFO - 'TERM': 'linux', 04:30:54 INFO - 'TMOUT': '86400', 04:30:54 INFO - 'USER': 'cltbld', 04:30:54 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1461756572.954321-1283641448', 04:30:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:54 INFO - '_': '/tools/buildbot/bin/python'} 04:30:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:54 INFO - Downloading/unpacking blobuploader==1.2.4 04:30:54 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 04:30:54 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 04:30:54 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 04:30:54 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 04:30:54 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 04:30:54 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 04:30:56 INFO - Downloading blobuploader-1.2.4.tar.gz 04:30:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:30:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:30:56 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 04:30:56 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 04:30:56 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 04:30:56 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 04:30:56 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 04:30:56 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 04:30:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:30:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:30:56 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 04:30:56 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 04:30:56 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 04:30:56 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 04:30:56 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 04:30:56 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 04:30:57 INFO - Downloading docopt-0.6.1.tar.gz 04:30:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:30:57 INFO - Installing collected packages: blobuploader, requests, docopt 04:30:57 INFO - Running setup.py install for blobuploader 04:30:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:30:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:30:57 INFO - Running setup.py install for requests 04:30:57 INFO - Running setup.py install for docopt 04:30:57 INFO - Successfully installed blobuploader requests docopt 04:30:57 INFO - Cleaning up... 04:30:57 INFO - Return code: 0 04:30:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:30:57 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')]} 04:30:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:57 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')]} 04:30:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:57 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 0x7fbed4eaff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf205a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfaf410>, '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 0xfb0310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfb07a0>, '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-1461756572.954321-1283641448', '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 04:30:57 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 04:30:57 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 04:30:57 INFO - Using env: {'DISPLAY': ':0', 04:30:57 INFO - 'HOME': '/home/cltbld', 04:30:57 INFO - 'LANG': 'en_US.UTF-8', 04:30:57 INFO - 'LANGUAGE': 'en_US:en', 04:30:57 INFO - 'LOGNAME': 'cltbld', 04:30:57 INFO - 'MAIL': '/var/mail/cltbld', 04:30:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:57 INFO - 'MOZ_NO_REMOTE': '1', 04:30:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:30:57 INFO - 'NO_EM_RESTART': '1', 04:30:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:30:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:57 INFO - 'PWD': '/builds/slave/test', 04:30:57 INFO - 'SHELL': '/bin/bash', 04:30:57 INFO - 'SHLVL': '1', 04:30:57 INFO - 'TERM': 'linux', 04:30:57 INFO - 'TMOUT': '86400', 04:30:57 INFO - 'USER': 'cltbld', 04:30:57 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1461756572.954321-1283641448', 04:30:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:57 INFO - '_': '/tools/buildbot/bin/python'} 04:30:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:30:58 INFO - Running setup.py (path:/tmp/pip-lUhtOz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:30:58 INFO - Running setup.py (path:/tmp/pip-5vYTCe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:30:58 INFO - Running setup.py (path:/tmp/pip-2af_2y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:30:58 INFO - Running setup.py (path:/tmp/pip-taSpFG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:30:58 INFO - Running setup.py (path:/tmp/pip-oCTs5y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:30:58 INFO - Running setup.py (path:/tmp/pip-cQqo0z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:30:58 INFO - Running setup.py (path:/tmp/pip-qLvFx7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:30:58 INFO - Running setup.py (path:/tmp/pip-U1jhIV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:30:58 INFO - Running setup.py (path:/tmp/pip-6m6mjy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:30:58 INFO - Running setup.py (path:/tmp/pip-k24Mal-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:30:58 INFO - Running setup.py (path:/tmp/pip-FyHmEO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:30:58 INFO - Running setup.py (path:/tmp/pip-yAj_yQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:30:58 INFO - Running setup.py (path:/tmp/pip-LA29X2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:30:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:30:58 INFO - Running setup.py (path:/tmp/pip-FSCVc8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:30:59 INFO - Running setup.py (path:/tmp/pip-U74ZDk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:30:59 INFO - Running setup.py (path:/tmp/pip-TBfIpy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:30:59 INFO - Running setup.py (path:/tmp/pip-Jjxnfu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:30:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:30:59 INFO - Running setup.py install for manifestparser 04:30:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:30:59 INFO - Running setup.py install for mozcrash 04:30:59 INFO - Running setup.py install for mozdebug 04:30:59 INFO - Running setup.py install for mozdevice 04:30:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:30:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:30:59 INFO - Running setup.py install for mozfile 04:30:59 INFO - Running setup.py install for mozhttpd 04:30:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:30:59 INFO - Running setup.py install for mozinfo 04:30:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:30:59 INFO - Running setup.py install for mozInstall 04:31:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:31:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:31:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:31:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:31:00 INFO - Running setup.py install for mozleak 04:31:00 INFO - Running setup.py install for mozlog 04:31:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:31:00 INFO - Running setup.py install for moznetwork 04:31:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:31:00 INFO - Running setup.py install for mozprocess 04:31:00 INFO - Running setup.py install for mozprofile 04:31:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:31:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:31:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:31:00 INFO - Running setup.py install for mozrunner 04:31:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:31:00 INFO - Running setup.py install for mozscreenshot 04:31:00 INFO - Running setup.py install for moztest 04:31:00 INFO - Running setup.py install for mozversion 04:31:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:31:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:31:01 INFO - Cleaning up... 04:31:01 INFO - Return code: 0 04:31:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:31:01 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')]} 04:31:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:31:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:01 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')]} 04:31:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:31:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:01 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 0x7fbed4eaff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf205a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfaf410>, '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 0xfb0310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfb07a0>, '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-1461756572.954321-1283641448', '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 04:31:01 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 04:31:01 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 04:31:01 INFO - Using env: {'DISPLAY': ':0', 04:31:01 INFO - 'HOME': '/home/cltbld', 04:31:01 INFO - 'LANG': 'en_US.UTF-8', 04:31:01 INFO - 'LANGUAGE': 'en_US:en', 04:31:01 INFO - 'LOGNAME': 'cltbld', 04:31:01 INFO - 'MAIL': '/var/mail/cltbld', 04:31:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:31:01 INFO - 'MOZ_NO_REMOTE': '1', 04:31:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:31:01 INFO - 'NO_EM_RESTART': '1', 04:31:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:31:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:31:01 INFO - 'PWD': '/builds/slave/test', 04:31:01 INFO - 'SHELL': '/bin/bash', 04:31:01 INFO - 'SHLVL': '1', 04:31:01 INFO - 'TERM': 'linux', 04:31:01 INFO - 'TMOUT': '86400', 04:31:01 INFO - 'USER': 'cltbld', 04:31:01 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1461756572.954321-1283641448', 04:31:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:31:01 INFO - '_': '/tools/buildbot/bin/python'} 04:31:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:31:01 INFO - Running setup.py (path:/tmp/pip-S1ifhh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:31:01 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)) 04:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:31:01 INFO - Running setup.py (path:/tmp/pip-9iwRDD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:31:01 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)) 04:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:31:01 INFO - Running setup.py (path:/tmp/pip-dQNrKV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:31:01 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)) 04:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:31:01 INFO - Running setup.py (path:/tmp/pip-_z2lvX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:31:01 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)) 04:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:31:01 INFO - Running setup.py (path:/tmp/pip-FGh3Yg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:31:01 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)) 04:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:31:01 INFO - Running setup.py (path:/tmp/pip-fkI68t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:31:01 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)) 04:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:31:01 INFO - Running setup.py (path:/tmp/pip-xCIrJ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:31:01 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)) 04:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:31:01 INFO - Running setup.py (path:/tmp/pip-yohL7N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:31:01 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)) 04:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:31:01 INFO - Running setup.py (path:/tmp/pip-KXsBBi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:31:01 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)) 04:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:31:01 INFO - Running setup.py (path:/tmp/pip-BC7NHL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:31:01 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)) 04:31:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:31:01 INFO - Running setup.py (path:/tmp/pip-b1wuVq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:31:02 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)) 04:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:31:02 INFO - Running setup.py (path:/tmp/pip-3FTMYY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:31:02 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)) 04:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:31:02 INFO - Running setup.py (path:/tmp/pip-To2GJ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:31:02 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)) 04:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:31:02 INFO - Running setup.py (path:/tmp/pip-xo88sB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:31:02 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)) 04:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:31:02 INFO - Running setup.py (path:/tmp/pip-8RUtO3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:31:02 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)) 04:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:31:02 INFO - Running setup.py (path:/tmp/pip-7I9Dk7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:31:02 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)) 04:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:31:02 INFO - Running setup.py (path:/tmp/pip-K0EZ9u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:31:02 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)) 04:31:02 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)) 04:31:02 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)) 04:31:02 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)) 04:31:02 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)) 04:31:02 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)) 04:31:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:31:02 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 04:31:02 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 04:31:02 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 04:31:02 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 04:31:02 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 04:31:02 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 04:31:04 INFO - Downloading blessings-1.6.tar.gz 04:31:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:31:04 INFO - Installing collected packages: blessings 04:31:04 INFO - Running setup.py install for blessings 04:31:04 INFO - Successfully installed blessings 04:31:04 INFO - Cleaning up... 04:31:04 INFO - Return code: 0 04:31:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:31:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:31:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:31:04 INFO - Reading from file tmpfile_stdout 04:31:04 INFO - Current package versions: 04:31:04 INFO - argparse == 1.2.1 04:31:04 INFO - blessings == 1.6 04:31:04 INFO - blobuploader == 1.2.4 04:31:04 INFO - docopt == 0.6.1 04:31:04 INFO - manifestparser == 1.1 04:31:04 INFO - mozInstall == 1.12 04:31:04 INFO - mozcrash == 0.17 04:31:04 INFO - mozdebug == 0.1 04:31:04 INFO - mozdevice == 0.48 04:31:04 INFO - mozfile == 1.2 04:31:04 INFO - mozhttpd == 0.7 04:31:04 INFO - mozinfo == 0.9 04:31:04 INFO - mozleak == 0.1 04:31:04 INFO - mozlog == 3.1 04:31:04 INFO - moznetwork == 0.27 04:31:04 INFO - mozprocess == 0.22 04:31:04 INFO - mozprofile == 0.28 04:31:04 INFO - mozrunner == 6.11 04:31:04 INFO - mozscreenshot == 0.1 04:31:04 INFO - mozsystemmonitor == 0.0 04:31:04 INFO - moztest == 0.7 04:31:04 INFO - mozversion == 1.4 04:31:04 INFO - psutil == 3.1.1 04:31:04 INFO - requests == 1.2.3 04:31:04 INFO - wsgiref == 0.1.2 04:31:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:31:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:31:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:31:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:31:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:31:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:04 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 0x7fbed4eaff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf205a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfaf410>, '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 0xfb0310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfb07a0>, '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-1461756572.954321-1283641448', '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 04:31:04 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 04:31:04 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 04:31:04 INFO - Using env: {'DISPLAY': ':0', 04:31:04 INFO - 'HOME': '/home/cltbld', 04:31:04 INFO - 'LANG': 'en_US.UTF-8', 04:31:04 INFO - 'LANGUAGE': 'en_US:en', 04:31:04 INFO - 'LOGNAME': 'cltbld', 04:31:04 INFO - 'MAIL': '/var/mail/cltbld', 04:31:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:31:04 INFO - 'MOZ_NO_REMOTE': '1', 04:31:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:31:04 INFO - 'NO_EM_RESTART': '1', 04:31:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:31:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:31:04 INFO - 'PWD': '/builds/slave/test', 04:31:04 INFO - 'SHELL': '/bin/bash', 04:31:04 INFO - 'SHLVL': '1', 04:31:04 INFO - 'TERM': 'linux', 04:31:04 INFO - 'TMOUT': '86400', 04:31:04 INFO - 'USER': 'cltbld', 04:31:04 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1461756572.954321-1283641448', 04:31:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:31:04 INFO - '_': '/tools/buildbot/bin/python'} 04:31:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:31:05 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)) 04:31:05 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)) 04:31:05 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)) 04:31:05 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)) 04:31:05 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)) 04:31:05 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)) 04:31:05 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)) 04:31:05 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)) 04:31:05 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)) 04:31:05 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)) 04:31:05 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)) 04:31:05 INFO - Cleaning up... 04:31:05 INFO - Return code: 0 04:31:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:31:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:31:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:31:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:31:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:31:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbed4eaff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf205a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfaf410>, '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 0xfb0310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfb07a0>, '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-1461756572.954321-1283641448', '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 04:31:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:31:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:31:05 INFO - Using env: {'DISPLAY': ':0', 04:31:05 INFO - 'HOME': '/home/cltbld', 04:31:05 INFO - 'LANG': 'en_US.UTF-8', 04:31:05 INFO - 'LANGUAGE': 'en_US:en', 04:31:05 INFO - 'LOGNAME': 'cltbld', 04:31:05 INFO - 'MAIL': '/var/mail/cltbld', 04:31:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:31:05 INFO - 'MOZ_NO_REMOTE': '1', 04:31:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:31:05 INFO - 'NO_EM_RESTART': '1', 04:31:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:31:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:31:05 INFO - 'PWD': '/builds/slave/test', 04:31:05 INFO - 'SHELL': '/bin/bash', 04:31:05 INFO - 'SHLVL': '1', 04:31:05 INFO - 'TERM': 'linux', 04:31:05 INFO - 'TMOUT': '86400', 04:31:05 INFO - 'USER': 'cltbld', 04:31:05 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1461756572.954321-1283641448', 04:31:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:31:05 INFO - '_': '/tools/buildbot/bin/python'} 04:31:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:31:05 INFO - Downloading/unpacking jsonschema 04:31:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:31:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:31:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:31:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:31:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:31:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:31:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:31:07 INFO - Downloading/unpacking functools32 (from jsonschema) 04:31:07 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 04:31:07 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 04:31:07 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 04:31:07 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 04:31:07 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 04:31:07 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 04:31:07 INFO - Downloading functools32-3.2.3-2.tar.gz 04:31:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:31:07 INFO - warning: no files found matching '*.txt' 04:31:07 INFO - no previously-included directories found matching 'build' 04:31:07 INFO - no previously-included directories found matching 'dist' 04:31:07 INFO - no previously-included directories found matching '.git*' 04:31:07 INFO - Installing collected packages: jsonschema, functools32 04:31:07 INFO - Running setup.py install for functools32 04:31:07 INFO - warning: no files found matching '*.txt' 04:31:07 INFO - no previously-included directories found matching 'build' 04:31:07 INFO - no previously-included directories found matching 'dist' 04:31:07 INFO - no previously-included directories found matching '.git*' 04:31:07 INFO - Successfully installed jsonschema functools32 04:31:07 INFO - Cleaning up... 04:31:07 INFO - Return code: 0 04:31:07 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:31:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:31:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:31:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:31:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:31:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbed4eaff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe558a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf205a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfaf410>, '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 0xfb0310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfb07a0>, '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-1461756572.954321-1283641448', '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 04:31:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 04:31:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 04:31:07 INFO - Using env: {'DISPLAY': ':0', 04:31:07 INFO - 'HOME': '/home/cltbld', 04:31:07 INFO - 'LANG': 'en_US.UTF-8', 04:31:07 INFO - 'LANGUAGE': 'en_US:en', 04:31:07 INFO - 'LOGNAME': 'cltbld', 04:31:07 INFO - 'MAIL': '/var/mail/cltbld', 04:31:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:31:07 INFO - 'MOZ_NO_REMOTE': '1', 04:31:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:31:07 INFO - 'NO_EM_RESTART': '1', 04:31:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:31:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:31:07 INFO - 'PWD': '/builds/slave/test', 04:31:07 INFO - 'SHELL': '/bin/bash', 04:31:07 INFO - 'SHLVL': '1', 04:31:07 INFO - 'TERM': 'linux', 04:31:07 INFO - 'TMOUT': '86400', 04:31:07 INFO - 'USER': 'cltbld', 04:31:07 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1461756572.954321-1283641448', 04:31:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:31:07 INFO - '_': '/tools/buildbot/bin/python'} 04:31:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:31:07 INFO - Downloading/unpacking flake8 04:31:07 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 04:31:07 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 04:31:07 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 04:31:07 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 04:31:07 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 04:31:07 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 04:31:09 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:31:09 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:31:09 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 04:31:09 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 04:31:09 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 04:31:09 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 04:31:09 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 04:31:09 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 04:31:09 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:31:09 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:31:09 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 04:31:09 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 04:31:09 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 04:31:09 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 04:31:09 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 04:31:09 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 04:31:10 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:31:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:31:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:31:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:31:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:31:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:31:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:31:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:31:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:31:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:31:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:31:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:31:10 INFO - no previously-included directories found matching 'docs/_build' 04:31:10 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:31:10 INFO - Running setup.py install for pep8 04:31:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:31:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:31:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:31:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:31:10 INFO - no previously-included directories found matching 'docs/_build' 04:31:10 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:31:10 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:31:10 INFO - Cleaning up... 04:31:10 INFO - Return code: 0 04:31:10 INFO - Running post-action listener: _resource_record_post_action 04:31:10 INFO - Running post-action listener: _start_resource_monitoring 04:31:10 INFO - Starting resource monitoring. 04:31:10 INFO - ##### 04:31:10 INFO - ##### Running install step. 04:31:10 INFO - ##### 04:31:10 INFO - Running pre-action listener: _resource_record_pre_action 04:31:10 INFO - Running main action method: install 04:31:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:31:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:31:10 INFO - Reading from file tmpfile_stdout 04:31:10 INFO - Detecting whether we're running mozinstall >=1.0... 04:31:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:31:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:31:11 INFO - Reading from file tmpfile_stdout 04:31:11 INFO - Output received: 04:31:11 INFO - Usage: mozinstall [options] installer 04:31:11 INFO - Options: 04:31:11 INFO - -h, --help show this help message and exit 04:31:11 INFO - -d DEST, --destination=DEST 04:31:11 INFO - Directory to install application into. [default: 04:31:11 INFO - "/builds/slave/test"] 04:31:11 INFO - --app=APP Application being installed. [default: firefox] 04:31:11 INFO - mkdir: /builds/slave/test/build/application 04:31:11 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'] 04:31:11 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 04:31:26 INFO - Reading from file tmpfile_stdout 04:31:26 INFO - Output received: 04:31:26 INFO - /builds/slave/test/build/application/firefox/firefox 04:31:26 INFO - Running post-action listener: _resource_record_post_action 04:31:26 INFO - ##### 04:31:26 INFO - ##### Running run-tests step. 04:31:26 INFO - ##### 04:31:26 INFO - Running pre-action listener: _resource_record_pre_action 04:31:26 INFO - Running main action method: run_tests 04:31:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:31:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:31:26 INFO - Python 2.7.3 04:31:26 INFO - Return code: 0 04:31:26 INFO - grabbing minidump binary from tooltool 04:31: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')]} 04:31:26 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 0xfaf410>, '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 0xfb0310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xfb07a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:31:26 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 04:31:26 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 04:31:26 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:31:26 INFO - Return code: 0 04:31:26 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:31:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:31:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:31:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:31:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:31:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:31:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:31:27 INFO - Return code: 0 04:31:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RsZhMrEDSbqvuE8XqQnoYg/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 04:31:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RsZhMrEDSbqvuE8XqQnoYg/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 04:31:27 INFO - Using env: {'DISPLAY': ':0', 04:31:27 INFO - 'HOME': '/home/cltbld', 04:31:27 INFO - 'LANG': 'en_US.UTF-8', 04:31:27 INFO - 'LANGUAGE': 'en_US:en', 04:31:27 INFO - 'LOGNAME': 'cltbld', 04:31:27 INFO - 'MAIL': '/var/mail/cltbld', 04:31:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:31:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:31:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:31:27 INFO - 'MOZ_NO_REMOTE': '1', 04:31:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:31:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:31:27 INFO - 'NO_EM_RESTART': '1', 04:31:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:31:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:31:27 INFO - 'PWD': '/builds/slave/test', 04:31:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:31:27 INFO - 'SHELL': '/bin/bash', 04:31:27 INFO - 'SHLVL': '1', 04:31:27 INFO - 'TERM': 'linux', 04:31:27 INFO - 'TMOUT': '86400', 04:31:27 INFO - 'USER': 'cltbld', 04:31:27 INFO - 'XDG_SESSION_COOKIE': '41bf3e9aed84707956354da8000001c5-1461756572.954321-1283641448', 04:31:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:31:27 INFO - '_': '/tools/buildbot/bin/python'} 04:31:27 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RsZhMrEDSbqvuE8XqQnoYg/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 04:31:28 INFO - mozversion application_buildid: 20160427025603 04:31:28 INFO - mozversion application_changeset: 488df90abd620538454e58ae0a75f13453f391b1 04:31:28 INFO - mozversion application_display_name: Nightly 04:31:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:31:28 INFO - mozversion application_name: Firefox 04:31:28 INFO - mozversion application_remotingname: firefox 04:31:28 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:31:28 INFO - mozversion application_vendor: Mozilla 04:31:28 INFO - mozversion application_version: 49.0a1 04:31:28 INFO - mozversion platform_buildid: 20160427025603 04:31:28 INFO - mozversion platform_changeset: 488df90abd620538454e58ae0a75f13453f391b1 04:31:28 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:31:28 INFO - mozversion platform_version: 49.0a1 04:31:28 INFO - using testdate: 1461756688 04:31:28 INFO - actual date: 1461756688 04:31:28 INFO - starting webserver on 'localhost:51468' 04:31:28 INFO - SUITE-START | Running 2 tests 04:31:28 INFO - TEST-START | dromaeo_css 04:31:28 INFO - operating with platform_type : linux_ 04:31:28 INFO - Initialising browser for dromaeo_css test... 04:31:28 INFO - TEST-INFO | started process 12896 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGIIeNc/profile http://localhost:51468/getInfo.html) 04:31:32 INFO - PROCESS | 12896 | 04:31:32 INFO - PROCESS | 12896 | (process:12966): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 04:31:32 INFO - PROCESS | 12896 | __metrics Screen width/height:1600/1200 04:31:32 INFO - PROCESS | 12896 | colorDepth:24 04:31:32 INFO - PROCESS | 12896 | Browser inner width/height: 1024/697 04:31:32 INFO - PROCESS | 12896 | __metrics 04:31:33 ERROR - PROCESS | 12896 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 04:31:34 INFO - TEST-INFO | 12896: exit 0 04:31:34 INFO - Browser initialized. 04:31:34 INFO - Running cycle 1/1 for dromaeo_css test... 04:31:34 INFO - TEST-INFO | started process 13017 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGIIeNc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:31:36 INFO - PROCESS | 13017 | 04:31:36 INFO - PROCESS | 13017 | (process:13078): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 04:31:46 INFO - PROCESS | 13017 | RSS: Main: 166449152 04:31:46 INFO - PROCESS | 13017 | 04:32:35 INFO - PROCESS | 13017 | 1461756755930 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] 04:34:47 INFO - PROCESS | 13017 | Cycle 1(1): loaded http://localhost:51468/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51468/tests/dromaeo/cssquery-ext.html) 04:34:48 INFO - PROCESS | 13017 | RSS: Main: 169873408 04:34:48 INFO - PROCESS | 13017 | 04:37:50 INFO - PROCESS | 13017 | Cycle 1(1): loaded http://localhost:51468/tests/dromaeo/cssquery-ext.html (next: http://localhost:51468/tests/dromaeo/cssquery-jquery.html) 04:37:51 INFO - PROCESS | 13017 | RSS: Main: 166903808 04:37:51 INFO - PROCESS | 13017 | 04:40:53 INFO - PROCESS | 13017 | Cycle 1(1): loaded http://localhost:51468/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51468/tests/dromaeo/cssquery-mootools.html) 04:40:54 INFO - PROCESS | 13017 | RSS: Main: 168038400 04:40:54 INFO - PROCESS | 13017 | 04:43:56 INFO - PROCESS | 13017 | Cycle 1(1): loaded http://localhost:51468/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51468/tests/dromaeo/cssquery-prototype.html) 04:43:56 INFO - PROCESS | 13017 | RSS: Main: 165609472 04:43:56 INFO - PROCESS | 13017 | 04:46:59 INFO - PROCESS | 13017 | Cycle 1(1): loaded http://localhost:51468/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51468/tests/dromaeo/cssquery-yui.html) 04:46:59 INFO - PROCESS | 13017 | RSS: Main: 166895616 04:46:59 INFO - PROCESS | 13017 | 04:50:02 INFO - PROCESS | 13017 | Cycle 1(1): loaded http://localhost:51468/tests/dromaeo/cssquery-yui.html (next: http://localhost:51468/tests/dromaeo/cssquery-dojo.html) 04:50:02 INFO - PROCESS | 13017 | RSS: Main: 168853504 04:50:02 INFO - PROCESS | 13017 | 04:50:02 INFO - PROCESS | 13017 | __start_tp_report 04:50:02 INFO - PROCESS | 13017 | _x_x_mozilla_page_load 04:50:02 INFO - PROCESS | 13017 | _x_x_mozilla_page_load_details 04:50:02 INFO - PROCESS | 13017 | |i|pagename|runs| 04:50:02 INFO - PROCESS | 13017 | |0;dojo.html;2606.1530002749105;2662.0948877381693;2688.7042425333198;2696.622472853801;2713.280980540157;4673.60274376678;4738.431388233408;4758.762061896909;4766.285057241415;4800.927986080212;4635.212013957627;4737.478877323491;4750.691205071668;4774.427068751755;4818.590419814312;4329.458817647786;4413.293872980324;4417.602415782579;4423.0711550574415;4423.447069116361;625.8451311674078;4054.755353628324;4319.179355922364;4339.609435150835;4370.519242883281;4320.762358070305;4321.351797230409;4322.0707547877355;4364.345348197796;4370.912581748347;4319.740815551077;4330.415393921811;4330.458692663417;4331.263685173528;4366.650667946588;4302.182585308813;4359.411479450297;4366.104948485553;4388.188185185719;4420.248557745159;4652.232381657022;4674.485806561275;4675.4155730533685;4680.4149481314835;4704.976475117637;4959.454460009396;5113.284140220372;5142.7685754140775;5150.716710580917;5160.92258616121;5149.9742501287255;5151.819686310999;5157.30376399185;5183.688978661255;5202;4741.668083234175;4772.045590881838;4780.856574302777;4793.616510679138;4811.735354555498;5151.9484805152215;5250.26496290512;5254.894902102013;5307.442718514578;5308.309919710413;4950.405951285797;4971.975140124277;4977.676451030671;5001.5498605124985;5015.04714104319;5060.898782024413;5080.898382032338;5082.567981721595;5083.61872859537;5135.7432128394175;4856.562909338177;5007.324011258506;5026.145555255615;5076.796928122908;5126.128558145049;5033;5038.0931432341795;5045.621578381636;5052.065367906949;5129.9743501283;4276.914461710749;4278.080212754273;4287.849925252601;4298.828046878152;4338.110687309094;3588.2823435313044;3623.148560088377;3680.42953342227;3735.4210097434943;3776.885818683495;4369.80335884886;4446.911061778746;4453.35426363182;4460.8661740147845;4495.595396414338;5087.109755792721;5098.1842905135;5112.9744351278005;5121.539061484485;5162.74186290684;4339.479262488521;4449.510553839075;4461.57615026572;4507.594316511464;4530.501644819067;4609.95390046102;4613.515580864028;4617.659211174713;4644.047970166108;4648.302754586772;4811.879703007453;4877.804887804448;4882.829100981448;4887.780049897692;4939.703617782872;5038.571721403575;5159.406668233051;5203.505666961784;5253.579713622978;5258;4791.880202994814;4803.303520989367;4814.398200224972;4824.879378015437;4862.17343051675;4945.876353091202;5039.647224694236;5041.596672266283;5045.344105266292;5092.414372347227;5103.617228707788;5121.436641969306;5125.051865404763;5142.8971420572125;5177.896442071213;4969.652124351405;5003.3495645565845;5005.599552035756;5018.322526459027;5073.213651883964;4862.9756851216935;4864.440589332272;4974.950250497449;5001.174806156943;5015.498450155102;4760.785764640669;4904.239842824368;4906.460289368238;4927.876803079952;4948.233023881304;4936.160852654985;4983.800647974041;4984.42679091909;5004.199328107486;5039;4854.466008738966;4903.092927808367;4913.582345500672;4954.083494553519;4970.428400733962 04:50:02 INFO - PROCESS | 13017 | |1;ext.html;564.1960206705446;591.8520369907523;608.1334098909055;609.3571282297178;629.8173529676394;33221.66778332216;34157.316853662946;34272;34300.31399372011;34328.82835585822;36927.26145477095;37361.252774944485;37456.25087498255;37538.8123059385;37633.623663763356;7673.92326076739;7841.451098423113;7841.451098423113;7844.921550784505;7862.37101031919;7671.041119860018;7709.730159444421;7783.027121609799;7783.727569535067;7799.0641123065025;7804.765857024298;7851.725189618364;7874.527528348317;7882.330001949841;7887.250711182429;7745.1093124290865;7752.224777522231;7762.534247945141;7785.260400261966;7787;7638.236176382317;7687.346575541086;7708.57602831844;7723.961380193119;7727.304542591138;25492.47045177295;34340.313193736234;34597.308053839035;34697.479537806954;34735.82632086849;2779.7637200837953;2928.941421171565;2931.3551018775834;2990.2524368907775;3013.7588992880737;25952.09167679141;26207.475850483075;26279;26352.73647263522;26673.599896001575;3233.757468189895;3341.4152523308326;3361.126107212118;3389.9491507627404;3423.5207071010077;191317;191695;192364;193424;194844;29849;49958.50041499611;50239.74880125649;50328.74835625799;50375.496245037815;31195.844020780205;31374.215644609067;31406.842965785483;31496.527552086736;31526;11410;11437.428128593703;11547.364894930859;11560.6531804046;11562.537498500134;9340;9362.204212642002;9381.671641492516;9423.623055077856;9442.95278523603;7705.499142555716;7813.765587032398;7819.530828150329;7853.450258481966;7946.165652606393;3107.502799552061;3124.203328151307;3129.8278594677054;3165.09794708507;3188.4101441233447;856.7686724584328;901.8241442918568;902.5803001604286;918.2699753695828;1020.8468729690458;26724.732752672615;27223.319417014336;27403.725962740515;27857.16428507148;27928.58107128395;919.9080091990747;1006.8892421833592;1026.2251999740297;1063.4576366053368;1148.6841118692425;14069.43722251119;14643.92678036603;14726.718389807444;14843.257837108102;14862.925685371503;266915;272479;272834;274714;275608;45586.316205256946;45614.54385456103;46090.07819843652;46403.76798115988;46488.767556162005;1182.39697754146;1215.489494412349;1231.7723946230049;1236.8701286365163;1239.6714870559128;25888;26632;27007.86496067507;27030.729692702822;27413;40137.598624013386;40189.59810401859;40311.59688403078;40502;40579.18841623092;234314;235123;235144;235822;236323;24643.753562464146;24787.380315492257;24865;25050.3737406559;25300.11449599255;39302.6069739299;39395.606043939195;39443.80278098591;39700.60299396969;40494;33806.49290260599;34599.23206143553;34699.30601388009;34720.82639586786;34911.65088349084;33660;33846.83076584601;34095.80664676725;34155.65844341624;34551.136221594665;31713.841430793622;32471;32512.674873250966;33954;34641;27642.447151056465;29121.85439072791;29221.707782921898;29232.56151157777;30759.384812303182;23918.282451526484;23946.640800388355;24186.51626967486;24747.752522474544;24845.378865528506 04:50:02 INFO - PROCESS | 13017 | |2;jquery.html;373.32055658701165;380.2490082087876;390.2039838728993;391.49692644951244;392.4211787613271;15129.924350378247;15770.211489425526;16661.416850410238;16689.666206675858;16722.080285584318;13066.216027038385;15231.54305370838;15753.212339383042;15771.447999320026;15827.683446331095;4634.721916685001;4781;4823.469418363977;4825.396825396825;4827.7586120694;3412.368711788327;6408.967955160241;6940.097787287646;6954.965225173867;6955.513114082017;6148.969255153718;6477.74089036438;6883.139607549057;6933;6941.409980151668;6210.472109870667;6396.680165991682;6399.168108145957;6421.454176395012;6879.071325371111;6620.834479138012;6633.834154146137;6661.9000714989315;6663.333666633346;6683.899741503881;20130.496737581532;21635.242766503095;21996.780032199633;22371.776282237293;22391;1967.4687834284664;2023;2025.43287879394;2070.3892351756267;2086.749590049204;13815.585532433943;14390.208538530376;14391.856081439157;14655.34050967709;14669.33987970544;1781.6971114910366;1949.0741897598668;1956.1979588368843;1962.901854907249;2035.4097311779567;68861;72359.63820180866;72790.2720972794;73021.63489182573;73544.63227683827;15350.692986140215;15710.764338534931;15757.763633545506;16183.514494565183;16292;27428.862855685595;27449;27757;27828.72171278261;28020.29949251285;8307.793672819425;8608.526531040729;8684.522351270742;8773.868391974125;8834.55827208637;7076.646167691595;7104.502684812117;7209.170945341353;7219.783406497813;7276.490645654753;3647.1976165243605;8045.879311810328;8267.875981860157;8303.294219991369;8333.416660833684;1791.6652094189913;1882.6140641168527;1893.3184053740642;1907.2561700936647;1914.7510823592843;834.3700506117842;848.5799529233069;852.5012867472474;881.0484676549312;949.639137127887;15538.922305388402;15721.449749258722;16064.27710753019;16187.757183642254;16223.918880405523;790.2808444315646;830.3274347778319;859.7420773767844;863.2834747159842;876.7720392697817;5853.795117170878;8874.112588741204;9015.368924175376;9068.274538036942;9187.26501879848;292165;297068;297893;298282;359525;67874.3212567868;70860.2913970864;71229.6438517804;71475;74456;994.0655783563427;995.318207028188;997.910188083076;1045.7594753206654;1047.3454091193005;14747;14978.251087445366;15018.20735965892;15075.718563921751;15250.771238431655;31869.521957170196;32469.837650811594;33194.834025830685;34169.31661366808;34270.828645856614;40770.38844417277;42635.786821065696;44103.33844992264;44545.331820022075;45324.32013519734;7508.174100848793;7517.548947063194;7581.241875812375;7670.156282808775;7734.961325193338;10364.896351036394;11247.268927519684;11294.887051129384;11341.546338146713;11469.426528673699;9433.962264150801;9746.074122958307;9879;10019.699409017741;10228.335158214692;4640.303954406866;7780.455368124403;8912.86630700553;9414.340996130206;9589.369807132665;9731.61073557078;9782.315237933277;9990.000999899952;10425;10433.26967112295;7423.962880185564;7499.9250007501405;7502.324790768857;7507.361874240751;7556.319931206218;8208.46644968075;9759.365641233297;9782.608695652094;9941.25440591973;9943.254255930979 04:50:02 INFO - PROCESS | 13017 | |3;mootools.html;1440.517426662068;1465.472429925227;1586.8016497937758;1741.9619045716502;1744.4766570028987;6464.385883341087;7371.115466144057;7389.445791565634;7640.770776876689;7644.61776911155;7390.408767298617;7394.075740532434;7442.144153422358;7465.701371945116;7482.40140788737;2172.728408948881;2200.680901269315;2202.0861342542826;2203.151786562177;2213.7896899794496;2174.9782502174935;2183.1049269799387;2183.1922188790168;2184.1809321504434;2190.3647942096773;2113.725215721954;2128;2132.5201829588295;2140.079765700748;2172.283146561631;2087.2277257414703;2090.1325949730845;2092.0899408757164;2092.9058192381303;2093.497560585464;2102.894855257231;2116.788321167871;2176.7823217678265;2208.2823082498253;2218.5230175512343;7438.330550250449;7592.544447333178;7595.924040759633;7605.885911711329;7607.049118860143;4513.480949690795;4617.076584683044;4651.069786042805;4668.253079507296;4694.201985662446;2815.845128517931;2970.287131088523;3001.1896787867154;3014.9849250753605;3020.7885448018646;897.1208215948342;933.3233405780823;934.8878134623889;957.7222605444482;960.6829746183743;10244.385336879812;10328.638497652546;10369.325993810378;10431.739206519822;10471.057604815604;4156.58434156588;4245.320748680197;4278.850240241639;4287.528371879091;4297.871063868089;8865.734027979172;8895;8900.910990890137;8923.910760892308;8925.866112008325;4617.607503362252;4619.884502887455;4653.557911998355;4660.790264438108;4668.976655116703;3568.678818906311;4158.334666453414;4205.3271476563605;4264.381664658669;4306.698531102856;3603.3694103532303;3621.9818900905325;3682.3924052531033;3698.389765688631;3714.8699795507027;1215.6292330839178;1222.9449674764658;1225.2525959164902;1225.8099994500867;1241.5039876948256;685.7702669605638;843.9324854011664;865.2256230673512;873.7990262239593;890.679355432044;5030.094582975026;5209.6874187548865;5271.815486457956;5412.83761487156;5430.864228394322;841.3773807382493;890.123228619809;900.0234745301422;907.603035262615;915.9375124855136;3508.1405055760993;3654.1778099927305;3726.105734623725;3740.682042026404;3790.1093243087853;9121.45271283712;9395.24838012971;9475.28935329853;9512.809743805085;9527.761805954906;9375.765605859771;9449.669261575811;9553.426794392359;9594.616215351447;9667.613295468103;6488.351164883568;6841.555298905555;6958.025876377311;7024.262452442522;7041.0846589944;5247.685138891678;5393.919091213556;5394.460553944731;5427.077396842467;5435.809746658848;8306.086330503518;8574.657013719381;8602.39783215169;8666.350023748118;8672.696455624024;8678.435901666371;8706.521141337287;8725.127487251224;8736.126387361213;8747.868781968404;6033.668148251712;6317.5893566918;6524.151860258136;6524.543281970216;6590.406863382319;7093.964530177316;7128.429725621834;7176.677049532888;7183.748568800067;7225.530340528059;6297.527685423704;6487.156669632917;6535.836604084936;6590.703418346282;6682.198136223684;6478.125453063964;6507.544471886922;6527.967360163168;6575.178102737158;6613.437857782143;6317.368263173646;6337.524685648503;6340.873182536231;6414.454771344302;6418.261899881573;4324.481062272547;4441.178381999341;4492.77536123186;4507.09858028389;4520.367148599133;4424.77876106187;4439.289713645801;4463.35281384204;4552.977235113935;4557.977210113928 04:50:02 INFO - PROCESS | 13017 | |4;prototype.html;296.70626080180614;300.31228486765303;304.95578141169545;304.9786514943955;307.6738657023555;4872.366592342995;4966.106100901836;4973.875653108674;4980.277859710344;5031.874203144923;4867.172580661288;4901.509849015097;4915.950840491603;4933.333999910011;4934.333864928233;3677.264547090579;3752.43713442983;3869.129445874684;3878.9806050969846;3888.747231429962;3859.5561510426237;3869.4582758413844;3874.9806250968704;3895.454636350913;3906.8437262509465;3856.633619806114;3868.3617203161443;3869.342211823997;3875.9418608720894;3881.204353107606;3625.3655610268097;3821.980890095532;3871.7870517121546;3874.411491288558;3875.748076375055;3344.615369232545;3351.631320554737;3354.5135955286346;3368.2421455172635;3368.6294507604143;4837;4839.596517010063;4840.128776820171;4842.83050093245;4861.975690121562;4371;4390.187815254157;4393.582609652078;4400.867973960786;4417.315316125973;5045.12427916088;5157.53582177602;5172.974135129338;5178.326817513736;5219.765110569995;3637.5816781070253;3650.543682039745;3654.3604869147794;3673.559172899269;3678.1724112074835;5392.4337944515655;5445.782168713246;5481.479259470344;5497.367802702701;5521.254630624854;3666.175110600094;3719.739618226752;3747.5877653458097;3753.9624603753614;3766.322062028806;3787.696984241254;3788;3803.9619603804163;3815.561210460777;3816.38937769961;4366.148601022834;4375.299952007664;4379;4386.627136693417;4412.227860124465;5067.670601410897;5199.376074871039;5242.1088414969545;5247.10799164143;5272.288241087481;1371.7805151175764;1373.5604606526015;1382.8755412012968;1383.481194552043;1385.9328317195702;848.3849209323191;892.1212605583495;911.2209061252667;928.9581968811419;930.4091901642371;4458.8439404620685;4581.954180458153;4589.839355622537;4622.838200662961;4636.258198688193;5364.3830959440165;5402.6758394496455;5416.458354164552;5454;5489.313835770528;4441;4565.406497155349;4608.216603177468;4622.329762184531;4630.143423466669;3056.8777248910237;3067.2791893904914;3080.3069309405205;3093.5359696045325;3100.5659207710937;5395.70323632204;5396.408059622421;5397.568194544427;5424.674519528841;5439.592030597721;5256.369235691742;5587.888242235133;5595.132754423071;5882.49998750111;5909.172715819789;5087.720175390389;5091.1854103343285;5108.361454818148;5121.4622464641425;5149.2791009258735;5431.701256430776;5446.972765136149;5460.5904557159165;5491.478309560585;5492.780288788404;5183.341330774116;5301.628885977944;5331.280277162534;5337.973310133424;5338.786448542015;5053.545180933734;5100.2349647553165;5113.488651135006;5134.383873935151;5147;3865.458835762939;3870.53553573573;3884.6115388460926;3910.8631197907935;3937.3306537888056;5274.525292723673;5330.813421530228;5361.758720857492;5395.541378982831;5396.865078373072;5188.636795424435;5195.558377537952;5221.869453263699;5231.267622532924;5243.764030618708;4980.825671101494;5175.378954525481;5213.218017297436;5235.738213089407;5249.501297376896;5121.769520371667;5143.031192733792;5145;5151.562117220078;5173.586113110867;4997.9500204997485;5105.2086926526445;5273.815416460406;5290.576753859759;5296.3379577552805;5126.7183204198955;5157.329547158995;5165.4834516549545;5188.351456067991;5189.948100518946 04:50:02 INFO - PROCESS | 13017 | |5;yui.html;610.109240508857;720.3264947274299;726.1576571177435;733.3546479098393;766.1266156581498;10865.40240286784;11883.405829708512;12011.339376334297;12451.564195253168;13444.596662100153;13126.934365328183;13311.800322995163;13345.53290634828;13371.26458044807;13391.330433478312;798.5767543202112;810.4286478032973;810.9052778748671;811.7767613906163;813.3005615170978;796.0606484348466;801.6232370785722;802.3741481644298;803.1927912448011;807.7818988873;843.1020962674762;848.1857416879802;848.9405741598091;850.0521918061348;851.6423102297019;830.7341650671788;843.4686147726954;845.0746432656263;845.4335595151264;847.9703210387669;605.8333958161497;612.0727098445858;612.7793994162098;614.4623454477332;615.0681717198448;12363.876361236453;12539.373031348396;12555.372231388394;12594.81107783384;12612.936935315356;11915;12028.45871935765;12183.147179697426;12925.289109098996;13017.349132543335;9916.90083099167;10003.149732272766;10202.846957295647;10212.131968782662;10425.113865321457;3241.6758324167395;3254.544363789071;3294.3246634439884;3322.352141332441;3327.8502467389026;44689;44881;45072.098558028985;45124.54875451236;46127.76936115298;9657.9034209657;9747.853782193273;9809.117179453884;9887.258455615858;10003.149732272694;15401.922990384975;16032.11823349727;16851.831481685273;16875.493735187963;16943.813933024816;7055.89416158758;7102.325279098477;7105.92894071063;7124.92875071253;7234.602096884617;3822.9426558601645;3952.940705889414;3953;3956.1098752780968;3988.3219852625125;2785.8467784271656;2795.2452837734104;2820.661520617498;2821.238265668258;2823.449427361686;518.225253246399;523.6805548615341;536.9194620806832;542.8534295740128;546.5572885962304;533.47186285577;612.9532165190453;616.1681729665006;629.3769168523221;640.5964242527272;7452;7456.105267367842;7620.542767433972;7687.730929417556;7864.41016923733;440.00558737253397;442.6082916618751;501.30569161710565;516.0144124721763;534.8208350202725;3275.4922986937063;3305.074579117851;3345.381104495676;3445.328161008579;3460.2214501736908;54555.72722136364;54819.45180548223;54870;55032.449675502736;55555.44444555583;47479.5252047482;48474.27288590674;48743;49476;50065.24902126471;44242.77878610586;44282.99999999935;44570.55429445729;44957.55042449599;45201.54798451973;7202.89195662076;7224.494285400182;7385.150707668472;7522.548647081193;7526.736564220226;30007.399852003273;30718.539221911244;30788;31086.53370199494;31191.84404077965;36684.81657591802;40517;41218.1756364865;41467.1706565873;41641.79179104085;10117.089461948166;10164.898351016394;10170.593176272867;10184.949075254577;10285.640002599874;10327.380357178594;10433.895661043596;11027.062699670618;11086.833697494705;11108.722281943015;9864.950675246864;9921.305508614616;10146.086852183338;10159.187265018929;10291.43397113136;9729.173020293072;9871.605135794487;9923.950380248052;9974.351667141613;10539.789204216026;10234.53944572511;10340.069393754296;10383.48082595852;10430.68707938763;10464.424456654653;8827.470351778657;8837.072107428627;8888.60001299956;8895.510746908982;9031.683891063782;8560.871586926076;8937.46375217489;8989.190972812477;9051.14014168653;9331.486768227813 04:50:02 INFO - PROCESS | 13017 | __end_tp_report 04:50:02 INFO - PROCESS | 13017 | __start_cc_report 04:50:02 INFO - PROCESS | 13017 | _x_x_mozilla_cycle_collect,373 04:50:02 INFO - PROCESS | 13017 | __end_cc_report 04:50:02 INFO - PROCESS | 13017 | __startTimestamp1461757802419__endTimestamp 04:50:02 INFO - PROCESS | 13017 | 04:50:02 INFO - PROCESS | 13017 | ------- Summary: start ------- 04:50:02 INFO - PROCESS | 13017 | Number of tests: 6 04:50:02 INFO - PROCESS | 13017 | 04:50:02 INFO - PROCESS | 13017 | [#0] dojo.html Cycles:165 Average:4688.35 Median:4862.17 stddev:596.92 (12.3%) stddev-sans-first:575.97 04:50:02 INFO - PROCESS | 13017 | Values: 2606.2 2662.1 2688.7 2696.6 2713.3 4673.6 4738.4 4758.8 4766.3 4800.9 4635.2 4737.5 4750.7 4774.4 4818.6 4329.5 4413.3 4417.6 4423.1 4423.4 625.8 4054.8 4319.2 4339.6 4370.5 4320.8 4321.4 4322.1 4364.3 4370.9 4319.7 4330.4 4330.5 4331.3 4366.7 4302.2 4359.4 4366.1 4388.2 4420.2 4652.2 4674.5 4675.4 4680.4 4705.0 4959.5 5113.3 5142.8 5150.7 5160.9 5150.0 5151.8 5157.3 5183.7 5202.0 4741.7 4772.0 4780.9 4793.6 4811.7 5151.9 5250.3 5254.9 5307.4 5308.3 4950.4 4972.0 4977.7 5001.5 5015.0 5060.9 5080.9 5082.6 5083.6 5135.7 4856.6 5007.3 5026.1 5076.8 5126.1 5033.0 5038.1 5045.6 5052.1 5130.0 4276.9 4278.1 4287.8 4298.8 4338.1 3588.3 3623.1 3680.4 3735.4 3776.9 4369.8 4446.9 4453.4 4460.9 4495.6 5087.1 5098.2 5113.0 5121.5 5162.7 4339.5 4449.5 4461.6 4507.6 4530.5 4610.0 4613.5 4617.7 4644.0 4648.3 4811.9 4877.8 4882.8 4887.8 4939.7 5038.6 5159.4 5203.5 5253.6 5258.0 4791.9 4803.3 4814.4 4824.9 4862.2 4945.9 5039.6 5041.6 5045.3 5092.4 5103.6 5121.4 5125.1 5142.9 5177.9 4969.7 5003.3 5005.6 5018.3 5073.2 4863.0 4864.4 4975.0 5001.2 5015.5 4760.8 4904.2 4906.5 4927.9 4948.2 4936.2 4983.8 4984.4 5004.2 5039.0 4854.5 4903.1 4913.6 4954.1 4970.4 04:50:02 INFO - PROCESS | 13017 | 04:50:02 INFO - PROCESS | 13017 | [#1] ext.html Cycles:180 Average:37901.78 Median:33826.66 stddev:61577.47 (182.0%) stddev-sans-first:61686.40 04:50:02 INFO - PROCESS | 13017 | Values: 564.2 591.9 608.1 609.4 629.8 33221.7 34157.3 34272.0 34300.3 34328.8 36927.3 37361.3 37456.3 37538.8 37633.6 7673.9 7841.5 7841.5 7844.9 7862.4 7671.0 7709.7 7783.0 7783.7 7799.1 7804.8 7851.7 7874.5 7882.3 7887.3 7745.1 7752.2 7762.5 7785.3 7787.0 7638.2 7687.3 7708.6 7724.0 7727.3 25492.5 34340.3 34597.3 34697.5 34735.8 2779.8 2928.9 2931.4 2990.3 3013.8 25952.1 26207.5 26279.0 26352.7 26673.6 3233.8 3341.4 3361.1 3389.9 3423.5 191317.0 191695.0 192364.0 193424.0 194844.0 29849.0 49958.5 50239.7 50328.7 50375.5 31195.8 31374.2 31406.8 31496.5 31526.0 11410.0 11437.4 11547.4 11560.7 11562.5 9340.0 9362.2 9381.7 9423.6 9443.0 7705.5 7813.8 7819.5 7853.5 7946.2 3107.5 3124.2 3129.8 3165.1 3188.4 856.8 901.8 902.6 918.3 1020.8 26724.7 27223.3 27403.7 27857.2 27928.6 919.9 1006.9 1026.2 1063.5 1148.7 14069.4 14643.9 14726.7 14843.3 14862.9 266915.0 272479.0 272834.0 274714.0 275608.0 45586.3 45614.5 46090.1 46403.8 46488.8 1182.4 1215.5 1231.8 1236.9 1239.7 25888.0 26632.0 27007.9 27030.7 27413.0 40137.6 40189.6 40311.6 40502.0 40579.2 234314.0 235123.0 235144.0 235822.0 236323.0 24643.8 24787.4 24865.0 25050.4 25300.1 39302.6 39395.6 39443.8 39700.6 40494.0 33806.5 34599.2 34699.3 34720.8 34911.7 33660.0 33846.8 34095.8 34155.7 34551.1 31713.8 32471.0 32512.7 33954.0 34641.0 27642.4 29121.9 29221.7 29232.6 30759.4 23918.3 23946.6 24186.5 24747.8 24845.4 04:50:02 INFO - PROCESS | 13017 | 04:50:02 INFO - PROCESS | 13017 | [#2] jquery.html Cycles:180 Average:22385.17 Median:4802.23 stddev:51534.00 (1073.1%) stddev-sans-first:51652.07 04:50:02 INFO - PROCESS | 13017 | Values: 373.3 380.2 390.2 391.5 392.4 15129.9 15770.2 16661.4 16689.7 16722.1 13066.2 15231.5 15753.2 15771.4 15827.7 4634.7 4781.0 4823.5 4825.4 4827.8 3412.4 6409.0 6940.1 6955.0 6955.5 6149.0 6477.7 6883.1 6933.0 6941.4 6210.5 6396.7 6399.2 6421.5 6879.1 6620.8 6633.8 6661.9 6663.3 6683.9 20130.5 21635.2 21996.8 22371.8 22391.0 1967.5 2023.0 2025.4 2070.4 2086.7 13815.6 14390.2 14391.9 14655.3 14669.3 1781.7 1949.1 1956.2 1962.9 2035.4 68861.0 72359.6 72790.3 73021.6 73544.6 15350.7 15710.8 15757.8 16183.5 16292.0 27428.9 27449.0 27757.0 27828.7 28020.3 8307.8 8608.5 8684.5 8773.9 8834.6 7076.6 7104.5 7209.2 7219.8 7276.5 3647.2 8045.9 8267.9 8303.3 8333.4 1791.7 1882.6 1893.3 1907.3 1914.8 834.4 848.6 852.5 881.0 949.6 15538.9 15721.4 16064.3 16187.8 16223.9 790.3 830.3 859.7 863.3 876.8 5853.8 8874.1 9015.4 9068.3 9187.3 292165.0 297068.0 297893.0 298282.0 359525.0 67874.3 70860.3 71229.6 71475.0 74456.0 994.1 995.3 997.9 1045.8 1047.3 14747.0 14978.3 15018.2 15075.7 15250.8 31869.5 32469.8 33194.8 34169.3 34270.8 40770.4 42635.8 44103.3 44545.3 45324.3 7508.2 7517.5 7581.2 7670.2 7735.0 10364.9 11247.3 11294.9 11341.5 11469.4 9434.0 9746.1 9879.0 10019.7 10228.3 4640.3 7780.5 8912.9 9414.3 9589.4 9731.6 9782.3 9990.0 10425.0 10433.3 7424.0 7499.9 7502.3 7507.4 7556.3 8208.5 9759.4 9782.6 9941.3 9943.3 04:50:02 INFO - PROCESS | 13017 | 04:50:02 INFO - PROCESS | 13017 | [#3] mootools.html Cycles:180 Average:5040.04 Median:5403.65 stddev:2759.47 (51.1%) stddev-sans-first:2753.96 04:50:02 INFO - PROCESS | 13017 | Values: 1440.5 1465.5 1586.8 1742.0 1744.5 6464.4 7371.1 7389.4 7640.8 7644.6 7390.4 7394.1 7442.1 7465.7 7482.4 2172.7 2200.7 2202.1 2203.2 2213.8 2175.0 2183.1 2183.2 2184.2 2190.4 2113.7 2128.0 2132.5 2140.1 2172.3 2087.2 2090.1 2092.1 2092.9 2093.5 2102.9 2116.8 2176.8 2208.3 2218.5 7438.3 7592.5 7595.9 7605.9 7607.0 4513.5 4617.1 4651.1 4668.3 4694.2 2815.8 2970.3 3001.2 3015.0 3020.8 897.1 933.3 934.9 957.7 960.7 10244.4 10328.6 10369.3 10431.7 10471.1 4156.6 4245.3 4278.9 4287.5 4297.9 8865.7 8895.0 8900.9 8923.9 8925.9 4617.6 4619.9 4653.6 4660.8 4669.0 3568.7 4158.3 4205.3 4264.4 4306.7 3603.4 3622.0 3682.4 3698.4 3714.9 1215.6 1222.9 1225.3 1225.8 1241.5 685.8 843.9 865.2 873.8 890.7 5030.1 5209.7 5271.8 5412.8 5430.9 841.4 890.1 900.0 907.6 915.9 3508.1 3654.2 3726.1 3740.7 3790.1 9121.5 9395.2 9475.3 9512.8 9527.8 9375.8 9449.7 9553.4 9594.6 9667.6 6488.4 6841.6 6958.0 7024.3 7041.1 5247.7 5393.9 5394.5 5427.1 5435.8 8306.1 8574.7 8602.4 8666.4 8672.7 8678.4 8706.5 8725.1 8736.1 8747.9 6033.7 6317.6 6524.2 6524.5 6590.4 7094.0 7128.4 7176.7 7183.7 7225.5 6297.5 6487.2 6535.8 6590.7 6682.2 6478.1 6507.5 6528.0 6575.2 6613.4 6317.4 6337.5 6340.9 6414.5 6418.3 4324.5 4441.2 4492.8 4507.1 4520.4 4424.8 4439.3 4463.4 4553.0 4558.0 04:50:02 INFO - PROCESS | 13017 | 04:50:02 INFO - PROCESS | 13017 | [#4] prototype.html Cycles:180 Average:4352.09 Median:4950.22 stddev:1268.32 (25.6%) stddev-sans-first:1234.81 04:50:02 INFO - PROCESS | 13017 | Values: 296.7 300.3 305.0 305.0 307.7 4872.4 4966.1 4973.9 4980.3 5031.9 4867.2 4901.5 4916.0 4933.3 4934.3 3677.3 3752.4 3869.1 3879.0 3888.7 3859.6 3869.5 3875.0 3895.5 3906.8 3856.6 3868.4 3869.3 3875.9 3881.2 3625.4 3822.0 3871.8 3874.4 3875.7 3344.6 3351.6 3354.5 3368.2 3368.6 4837.0 4839.6 4840.1 4842.8 4862.0 4371.0 4390.2 4393.6 4400.9 4417.3 5045.1 5157.5 5173.0 5178.3 5219.8 3637.6 3650.5 3654.4 3673.6 3678.2 5392.4 5445.8 5481.5 5497.4 5521.3 3666.2 3719.7 3747.6 3754.0 3766.3 3787.7 3788.0 3804.0 3815.6 3816.4 4366.1 4375.3 4379.0 4386.6 4412.2 5067.7 5199.4 5242.1 5247.1 5272.3 1371.8 1373.6 1382.9 1383.5 1385.9 848.4 892.1 911.2 929.0 930.4 4458.8 4582.0 4589.8 4622.8 4636.3 5364.4 5402.7 5416.5 5454.0 5489.3 4441.0 4565.4 4608.2 4622.3 4630.1 3056.9 3067.3 3080.3 3093.5 3100.6 5395.7 5396.4 5397.6 5424.7 5439.6 5256.4 5587.9 5595.1 5882.5 5909.2 5087.7 5091.2 5108.4 5121.5 5149.3 5431.7 5447.0 5460.6 5491.5 5492.8 5183.3 5301.6 5331.3 5338.0 5338.8 5053.5 5100.2 5113.5 5134.4 5147.0 3865.5 3870.5 3884.6 3910.9 3937.3 5274.5 5330.8 5361.8 5395.5 5396.9 5188.6 5195.6 5221.9 5231.3 5243.8 4980.8 5175.4 5213.2 5235.7 5249.5 5121.8 5143.0 5145.0 5151.6 5173.6 4998.0 5105.2 5273.8 5290.6 5296.3 5126.7 5157.3 5165.5 5188.4 5189.9 04:50:02 INFO - PROCESS | 13017 | 04:50:02 INFO - PROCESS | 13017 | [#5] yui.html Cycles:180 Average:12848.92 Median:46803.65 stddev:15026.14 (32.1%) stddev-sans-first:15040.18 04:50:02 INFO - PROCESS | 13017 | Values: 610.1 720.3 726.2 733.4 766.1 10865.4 11883.4 12011.3 12451.6 13444.6 13126.9 13311.8 13345.5 13371.3 13391.3 798.6 810.4 810.9 811.8 813.3 796.1 801.6 802.4 803.2 807.8 843.1 848.2 848.9 850.1 851.6 830.7 843.5 845.1 845.4 848.0 605.8 612.1 612.8 614.5 615.1 12363.9 12539.4 12555.4 12594.8 12612.9 11915.0 12028.5 12183.1 12925.3 13017.3 9916.9 10003.1 10202.8 10212.1 10425.1 3241.7 3254.5 3294.3 3322.4 3327.9 44689.0 44881.0 45072.1 45124.5 46127.8 9657.9 9747.9 9809.1 9887.3 10003.1 15401.9 16032.1 16851.8 16875.5 16943.8 7055.9 7102.3 7105.9 7124.9 7234.6 3822.9 3952.9 3953.0 3956.1 3988.3 2785.8 2795.2 2820.7 2821.2 2823.4 518.2 523.7 536.9 542.9 546.6 533.5 613.0 616.2 629.4 640.6 7452.0 7456.1 7620.5 7687.7 7864.4 440.0 442.6 501.3 516.0 534.8 3275.5 3305.1 3345.4 3445.3 3460.2 54555.7 54819.5 54870.0 55032.4 55555.4 47479.5 48474.3 48743.0 49476.0 50065.2 44242.8 44283.0 44570.6 44957.6 45201.5 7202.9 7224.5 7385.2 7522.5 7526.7 30007.4 30718.5 30788.0 31086.5 31191.8 36684.8 40517.0 41218.2 41467.2 41641.8 10117.1 10164.9 10170.6 10184.9 10285.6 10327.4 10433.9 11027.1 11086.8 11108.7 9865.0 9921.3 10146.1 10159.2 10291.4 9729.2 9871.6 9924.0 9974.4 10539.8 10234.5 10340.1 10383.5 10430.7 10464.4 8827.5 8837.1 8888.6 8895.5 9031.7 8560.9 8937.5 8989.2 9051.1 9331.5 04:50:02 INFO - PROCESS | 13017 | -------- Summary: end -------- 04:50:02 INFO - PROCESS | 13017 | 04:50:03 INFO - TEST-INFO | 13017: exit 0 04:50:03 INFO - TEST-OK | dromaeo_css | took 1115216ms 04:50:03 INFO - TEST-START | kraken 04:50:03 INFO - operating with platform_type : linux_ 04:50:03 INFO - Initialising browser for kraken test... 04:50:03 INFO - TEST-INFO | started process 13250 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpt5AYEw/profile http://localhost:51468/getInfo.html) 04:50:07 INFO - PROCESS | 13250 | 04:50:07 INFO - PROCESS | 13250 | (process:13317): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 04:50:07 INFO - PROCESS | 13250 | __metrics Screen width/height:1600/1200 04:50:07 INFO - PROCESS | 13250 | colorDepth:24 04:50:07 INFO - PROCESS | 13250 | Browser inner width/height: 1024/697 04:50:07 INFO - PROCESS | 13250 | __metrics 04:50:08 ERROR - PROCESS | 13250 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 04:50:09 INFO - TEST-INFO | 13250: exit 0 04:50:09 INFO - Browser initialized. 04:50:09 INFO - Running cycle 1/1 for kraken test... 04:50:09 INFO - TEST-INFO | started process 13368 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpt5AYEw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:50:11 INFO - PROCESS | 13368 | 04:50:11 INFO - PROCESS | 13368 | (process:13429): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 04:50:21 INFO - PROCESS | 13368 | RSS: Main: 166461440 04:50:21 INFO - PROCESS | 13368 | 04:50:24 INFO - PROCESS | 13368 | Cycle 1(1): loaded http://localhost:51468/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51468/tests/kraken/driver.html?testName=audio-beat-detection) 04:50:25 INFO - PROCESS | 13368 | RSS: Main: 169693184 04:50:25 INFO - PROCESS | 13368 | 04:50:27 INFO - PROCESS | 13368 | Cycle 1(1): loaded http://localhost:51468/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51468/tests/kraken/driver.html?testName=audio-dft) 04:50:28 INFO - PROCESS | 13368 | RSS: Main: 173207552 04:50:28 INFO - PROCESS | 13368 | 04:50:31 INFO - PROCESS | 13368 | Cycle 1(1): loaded http://localhost:51468/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51468/tests/kraken/driver.html?testName=audio-fft) 04:50:32 INFO - PROCESS | 13368 | RSS: Main: 165343232 04:50:32 INFO - PROCESS | 13368 | 04:50:34 INFO - PROCESS | 13368 | Cycle 1(1): loaded http://localhost:51468/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51468/tests/kraken/driver.html?testName=audio-oscillator) 04:50:34 INFO - PROCESS | 13368 | RSS: Main: 165720064 04:50:34 INFO - PROCESS | 13368 | 04:50:37 INFO - PROCESS | 13368 | Cycle 1(1): loaded http://localhost:51468/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51468/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:50:37 INFO - PROCESS | 13368 | RSS: Main: 166121472 04:50:37 INFO - PROCESS | 13368 | 04:50:41 INFO - PROCESS | 13368 | Cycle 1(1): loaded http://localhost:51468/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51468/tests/kraken/driver.html?testName=imaging-darkroom) 04:50:41 INFO - PROCESS | 13368 | RSS: Main: 166432768 04:50:41 INFO - PROCESS | 13368 | 04:50:45 INFO - PROCESS | 13368 | Cycle 1(1): loaded http://localhost:51468/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51468/tests/kraken/driver.html?testName=imaging-desaturate) 04:50:45 INFO - PROCESS | 13368 | RSS: Main: 166215680 04:50:45 INFO - PROCESS | 13368 | 04:50:48 INFO - PROCESS | 13368 | Cycle 1(1): loaded http://localhost:51468/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51468/tests/kraken/driver.html?testName=json-parse-financial) 04:50:49 INFO - PROCESS | 13368 | RSS: Main: 166334464 04:50:49 INFO - PROCESS | 13368 | 04:50:51 INFO - PROCESS | 13368 | Cycle 1(1): loaded http://localhost:51468/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51468/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:50:51 INFO - PROCESS | 13368 | RSS: Main: 166903808 04:50:51 INFO - PROCESS | 13368 | 04:50:53 INFO - PROCESS | 13368 | Cycle 1(1): loaded http://localhost:51468/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51468/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:50:54 INFO - PROCESS | 13368 | RSS: Main: 166797312 04:50:54 INFO - PROCESS | 13368 | 04:50:56 INFO - PROCESS | 13368 | Cycle 1(1): loaded http://localhost:51468/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51468/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:50:57 INFO - PROCESS | 13368 | RSS: Main: 167632896 04:50:57 INFO - PROCESS | 13368 | 04:50:59 INFO - PROCESS | 13368 | Cycle 1(1): loaded http://localhost:51468/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51468/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:51:00 INFO - PROCESS | 13368 | RSS: Main: 167747584 04:51:00 INFO - PROCESS | 13368 | 04:51:03 INFO - PROCESS | 13368 | Cycle 1(1): loaded http://localhost:51468/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51468/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:51:04 INFO - PROCESS | 13368 | RSS: Main: 165896192 04:51:04 INFO - PROCESS | 13368 | 04:51:06 INFO - PROCESS | 13368 | Cycle 1(1): loaded http://localhost:51468/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51468/tests/kraken/driver.html?testName=ai-astar) 04:51:06 INFO - PROCESS | 13368 | RSS: Main: 166313984 04:51:06 INFO - PROCESS | 13368 | 04:51:06 INFO - PROCESS | 13368 | __start_tp_report 04:51:06 INFO - PROCESS | 13368 | _x_x_mozilla_page_load 04:51:06 INFO - PROCESS | 13368 | _x_x_mozilla_page_load_details 04:51:06 INFO - PROCESS | 13368 | |i|pagename|runs| 04:51:06 INFO - PROCESS | 13368 | |0;ai-astar;114;107;114;114;115;126;114;114;115;114 04:51:06 INFO - PROCESS | 13368 | |1;audio-beat-detection;167;145;155;201;135;159;162;135;201;134 04:51:06 INFO - PROCESS | 13368 | |2;audio-dft;162;165;164;171;162;160;163;162;167;163 04:51:06 INFO - PROCESS | 13368 | |3;audio-fft;76;77;78;155;75;74;74;74;156;75 04:51:06 INFO - PROCESS | 13368 | |4;audio-oscillator;95;95;154;98;94;95;142;94;94;137 04:51:06 INFO - PROCESS | 13368 | |5;imaging-gaussian-blur;118;118;119;118;118;119;118;118;118;117 04:51:06 INFO - PROCESS | 13368 | |6;imaging-darkroom;148;147;147;148;146;147;148;148;149;148 04:51:06 INFO - PROCESS | 13368 | |7;imaging-desaturate;95;96;95;95;96;95;96;97;95;96 04:51:06 INFO - PROCESS | 13368 | |8;json-parse-financial;67;66;67;66;66;67;66;91;68;69 04:51:06 INFO - PROCESS | 13368 | |9;json-stringify-tinderbox;65;66;65;65;65;64;65;64;65;67 04:51:06 INFO - PROCESS | 13368 | |10;stanford-crypto-aes;79;79;88;79;80;81;82;81;83;81 04:51:06 INFO - PROCESS | 13368 | |11;stanford-crypto-ccm;141;137;139;136;146;142;135;137;191;140 04:51:06 INFO - PROCESS | 13368 | |12;stanford-crypto-pbkdf2;219;219;218;228;219;218;218;227;219;218 04:51:06 INFO - PROCESS | 13368 | |13;stanford-crypto-sha256-iterative;75;77;75;76;75;77;76;79;74;79 04:51:06 INFO - PROCESS | 13368 | __end_tp_report 04:51:06 INFO - PROCESS | 13368 | __start_cc_report 04:51:06 INFO - PROCESS | 13368 | _x_x_mozilla_cycle_collect,926 04:51:06 INFO - PROCESS | 13368 | __end_cc_report 04:51:06 INFO - PROCESS | 13368 | __startTimestamp1461757866667__endTimestamp 04:51:06 INFO - PROCESS | 13368 | 04:51:06 INFO - PROCESS | 13368 | ------- Summary: start ------- 04:51:06 INFO - PROCESS | 13368 | Number of tests: 14 04:51:06 INFO - PROCESS | 13368 | 04:51:06 INFO - PROCESS | 13368 | [#0] ai-astar Cycles:10 Average:114.70 Median:114.00 stddev:4.60 (4.0%) stddev-sans-first:4.87 04:51:06 INFO - PROCESS | 13368 | Values: 114.0 107.0 114.0 114.0 115.0 126.0 114.0 114.0 115.0 114.0 04:51:06 INFO - PROCESS | 13368 | 04:51:06 INFO - PROCESS | 13368 | [#1] audio-beat-detection Cycles:10 Average:159.40 Median:160.50 stddev:24.92 (15.5%) stddev-sans-first:26.28 04:51:06 INFO - PROCESS | 13368 | Values: 167.0 145.0 155.0 201.0 135.0 159.0 162.0 135.0 201.0 134.0 04:51:06 INFO - PROCESS | 13368 | 04:51:06 INFO - PROCESS | 13368 | [#2] audio-dft Cycles:10 Average:163.90 Median:163.50 stddev:3.14 (1.9%) stddev-sans-first:3.26 04:51:06 INFO - PROCESS | 13368 | Values: 162.0 165.0 164.0 171.0 162.0 160.0 163.0 162.0 167.0 163.0 04:51:06 INFO - PROCESS | 13368 | 04:51:06 INFO - PROCESS | 13368 | [#3] audio-fft Cycles:10 Average:91.40 Median:75.00 stddev:33.81 (45.1%) stddev-sans-first:35.40 04:51:06 INFO - PROCESS | 13368 | Values: 76.0 77.0 78.0 155.0 75.0 74.0 74.0 74.0 156.0 75.0 04:51:06 INFO - PROCESS | 13368 | 04:51:06 INFO - PROCESS | 13368 | [#4] audio-oscillator Cycles:10 Average:109.80 Median:94.50 stddev:24.21 (25.6%) stddev-sans-first:25.08 04:51:06 INFO - PROCESS | 13368 | Values: 95.0 95.0 154.0 98.0 94.0 95.0 142.0 94.0 94.0 137.0 04:51:06 INFO - PROCESS | 13368 | 04:51:06 INFO - PROCESS | 13368 | [#5] imaging-gaussian-blur Cycles:10 Average:118.10 Median:118.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 04:51:06 INFO - PROCESS | 13368 | Values: 118.0 118.0 119.0 118.0 118.0 119.0 118.0 118.0 118.0 117.0 04:51:06 INFO - PROCESS | 13368 | 04:51:06 INFO - PROCESS | 13368 | [#6] imaging-darkroom Cycles:10 Average:147.60 Median:148.00 stddev:0.84 (0.6%) stddev-sans-first:0.88 04:51:06 INFO - PROCESS | 13368 | Values: 148.0 147.0 147.0 148.0 146.0 147.0 148.0 148.0 149.0 148.0 04:51:06 INFO - PROCESS | 13368 | 04:51:06 INFO - PROCESS | 13368 | [#7] imaging-desaturate Cycles:10 Average:95.60 Median:96.00 stddev:0.70 (0.7%) stddev-sans-first:0.71 04:51:06 INFO - PROCESS | 13368 | Values: 95.0 96.0 95.0 95.0 96.0 95.0 96.0 97.0 95.0 96.0 04:51:06 INFO - PROCESS | 13368 | 04:51:06 INFO - PROCESS | 13368 | [#8] json-parse-financial Cycles:10 Average:69.30 Median:67.00 stddev:7.69 (11.5%) stddev-sans-first:8.11 04:51:06 INFO - PROCESS | 13368 | Values: 67.0 66.0 67.0 66.0 66.0 67.0 66.0 91.0 68.0 69.0 04:51:06 INFO - PROCESS | 13368 | 04:51:06 INFO - PROCESS | 13368 | [#9] json-stringify-tinderbox Cycles:10 Average:65.10 Median:65.00 stddev:0.88 (1.3%) stddev-sans-first:0.93 04:51:06 INFO - PROCESS | 13368 | Values: 65.0 66.0 65.0 65.0 65.0 64.0 65.0 64.0 65.0 67.0 04:51:06 INFO - PROCESS | 13368 | 04:51:06 INFO - PROCESS | 13368 | [#10] stanford-crypto-aes Cycles:10 Average:81.30 Median:81.00 stddev:2.71 (3.3%) stddev-sans-first:2.74 04:51:06 INFO - PROCESS | 13368 | Values: 79.0 79.0 88.0 79.0 80.0 81.0 82.0 81.0 83.0 81.0 04:51:06 INFO - PROCESS | 13368 | 04:51:06 INFO - PROCESS | 13368 | [#11] stanford-crypto-ccm Cycles:10 Average:144.40 Median:140.50 stddev:16.69 (11.9%) stddev-sans-first:17.66 04:51:06 INFO - PROCESS | 13368 | Values: 141.0 137.0 139.0 136.0 146.0 142.0 135.0 137.0 191.0 140.0 04:51:06 INFO - PROCESS | 13368 | 04:51:06 INFO - PROCESS | 13368 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:220.30 Median:219.00 stddev:3.83 (1.7%) stddev-sans-first:4.03 04:51:06 INFO - PROCESS | 13368 | Values: 219.0 219.0 218.0 228.0 219.0 218.0 218.0 227.0 219.0 218.0 04:51:06 INFO - PROCESS | 13368 | 04:51:06 INFO - PROCESS | 13368 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.30 Median:76.50 stddev:1.70 (2.2%) stddev-sans-first:1.74 04:51:06 INFO - PROCESS | 13368 | Values: 75.0 77.0 75.0 76.0 75.0 77.0 76.0 79.0 74.0 79.0 04:51:06 INFO - PROCESS | 13368 | -------- Summary: end -------- 04:51:06 INFO - PROCESS | 13368 | 04:51:07 INFO - TEST-INFO | 13368: exit 0 04:51:07 INFO - TEST-OK | kraken | took 64521ms 04:51:07 INFO - SUITE-END | took 1179s 04:51:07 INFO - Completed test suite (00:19:39) 04:51:07 INFO - javascript benchmark 04:51:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2606.1530002749105, 2662.0948877381693, 2688.7042425333198, 2696.622472853801, 2713.280980540157, 4673.60274376678, 4738.431388233408, 4758.762061896909, 4766.285057241415, 4800.927986080212, 4635.212013957627, 4737.478877323491, 4750.691205071668, 4774.427068751755, 4818.590419814312, 4329.458817647786, 4413.293872980324, 4417.602415782579, 4423.0711550574415, 4423.447069116361, 625.8451311674078, 4054.755353628324, 4319.179355922364, 4339.609435150835, 4370.519242883281, 4320.762358070305, 4321.351797230409, 4322.0707547877355, 4364.345348197796, 4370.912581748347, 4319.740815551077, 4330.415393921811, 4330.458692663417, 4331.263685173528, 4366.650667946588, 4302.182585308813, 4359.411479450297, 4366.104948485553, 4388.188185185719, 4420.248557745159, 4652.232381657022, 4674.485806561275, 4675.4155730533685, 4680.4149481314835, 4704.976475117637, 4959.454460009396, 5113.284140220372, 5142.7685754140775, 5150.716710580917, 5160.92258616121, 5149.9742501287255, 5151.819686310999, 5157.30376399185, 5183.688978661255, 5202.0, 4741.668083234175, 4772.045590881838, 4780.856574302777, 4793.616510679138, 4811.735354555498, 5151.9484805152215, 5250.26496290512, 5254.894902102013, 5307.442718514578, 5308.309919710413, 4950.405951285797, 4971.975140124277, 4977.676451030671, 5001.5498605124985, 5015.04714104319, 5060.898782024413, 5080.898382032338, 5082.567981721595, 5083.61872859537, 5135.7432128394175, 4856.562909338177, 5007.324011258506, 5026.145555255615, 5076.796928122908, 5126.128558145049, 5033.0, 5038.0931432341795, 5045.621578381636, 5052.065367906949, 5129.9743501283, 4276.914461710749, 4278.080212754273, 4287.849925252601, 4298.828046878152, 4338.110687309094, 3588.2823435313044, 3623.148560088377, 3680.42953342227, 3735.4210097434943, 3776.885818683495, 4369.80335884886, 4446.911061778746, 4453.35426363182, 4460.8661740147845, 4495.595396414338, 5087.109755792721, 5098.1842905135, 5112.9744351278005, 5121.539061484485, 5162.74186290684, 4339.479262488521, 4449.510553839075, 4461.57615026572, 4507.594316511464, 4530.501644819067, 4609.95390046102, 4613.515580864028, 4617.659211174713, 4644.047970166108, 4648.302754586772, 4811.879703007453, 4877.804887804448, 4882.829100981448, 4887.780049897692, 4939.703617782872, 5038.571721403575, 5159.406668233051, 5203.505666961784, 5253.579713622978, 5258.0, 4791.880202994814, 4803.303520989367, 4814.398200224972, 4824.879378015437, 4862.17343051675, 4945.876353091202, 5039.647224694236, 5041.596672266283, 5045.344105266292, 5092.414372347227, 5103.617228707788, 5121.436641969306, 5125.051865404763, 5142.8971420572125, 5177.896442071213, 4969.652124351405, 5003.3495645565845, 5005.599552035756, 5018.322526459027, 5073.213651883964, 4862.9756851216935, 4864.440589332272, 4974.950250497449, 5001.174806156943, 5015.498450155102, 4760.785764640669, 4904.239842824368, 4906.460289368238, 4927.876803079952, 4948.233023881304, 4936.160852654985, 4983.800647974041, 4984.42679091909, 5004.199328107486, 5039.0, 4854.466008738966, 4903.092927808367, 4913.582345500672, 4954.083494553519, 4970.428400733962], "value": 4651.138292342211, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [564.1960206705446, 591.8520369907523, 608.1334098909055, 609.3571282297178, 629.8173529676394, 33221.66778332216, 34157.316853662946, 34272.0, 34300.31399372011, 34328.82835585822, 36927.26145477095, 37361.252774944485, 37456.25087498255, 37538.8123059385, 37633.623663763356, 7673.92326076739, 7841.451098423113, 7841.451098423113, 7844.921550784505, 7862.37101031919, 7671.041119860018, 7709.730159444421, 7783.027121609799, 7783.727569535067, 7799.0641123065025, 7804.765857024298, 7851.725189618364, 7874.527528348317, 7882.330001949841, 7887.250711182429, 7745.1093124290865, 7752.224777522231, 7762.534247945141, 7785.260400261966, 7787.0, 7638.236176382317, 7687.346575541086, 7708.57602831844, 7723.961380193119, 7727.304542591138, 25492.47045177295, 34340.313193736234, 34597.308053839035, 34697.479537806954, 34735.82632086849, 2779.7637200837953, 2928.941421171565, 2931.3551018775834, 2990.2524368907775, 3013.7588992880737, 25952.09167679141, 26207.475850483075, 26279.0, 26352.73647263522, 26673.599896001575, 3233.757468189895, 3341.4152523308326, 3361.126107212118, 3389.9491507627404, 3423.5207071010077, 191317.0, 191695.0, 192364.0, 193424.0, 194844.0, 29849.0, 49958.50041499611, 50239.74880125649, 50328.74835625799, 50375.496245037815, 31195.844020780205, 31374.215644609067, 31406.842965785483, 31496.527552086736, 31526.0, 11410.0, 11437.428128593703, 11547.364894930859, 11560.6531804046, 11562.537498500134, 9340.0, 9362.204212642002, 9381.671641492516, 9423.623055077856, 9442.95278523603, 7705.499142555716, 7813.765587032398, 7819.530828150329, 7853.450258481966, 7946.165652606393, 3107.502799552061, 3124.203328151307, 3129.8278594677054, 3165.09794708507, 3188.4101441233447, 856.7686724584328, 901.8241442918568, 902.5803001604286, 918.2699753695828, 1020.8468729690458, 26724.732752672615, 27223.319417014336, 27403.725962740515, 27857.16428507148, 27928.58107128395, 919.9080091990747, 1006.8892421833592, 1026.2251999740297, 1063.4576366053368, 1148.6841118692425, 14069.43722251119, 14643.92678036603, 14726.718389807444, 14843.257837108102, 14862.925685371503, 266915.0, 272479.0, 272834.0, 274714.0, 275608.0, 45586.316205256946, 45614.54385456103, 46090.07819843652, 46403.76798115988, 46488.767556162005, 1182.39697754146, 1215.489494412349, 1231.7723946230049, 1236.8701286365163, 1239.6714870559128, 25888.0, 26632.0, 27007.86496067507, 27030.729692702822, 27413.0, 40137.598624013386, 40189.59810401859, 40311.59688403078, 40502.0, 40579.18841623092, 234314.0, 235123.0, 235144.0, 235822.0, 236323.0, 24643.753562464146, 24787.380315492257, 24865.0, 25050.3737406559, 25300.11449599255, 39302.6069739299, 39395.606043939195, 39443.80278098591, 39700.60299396969, 40494.0, 33806.49290260599, 34599.23206143553, 34699.30601388009, 34720.82639586786, 34911.65088349084, 33660.0, 33846.83076584601, 34095.80664676725, 34155.65844341624, 34551.136221594665, 31713.841430793622, 32471.0, 32512.674873250966, 33954.0, 34641.0, 27642.447151056465, 29121.85439072791, 29221.707782921898, 29232.56151157777, 30759.384812303182, 23918.282451526484, 23946.640800388355, 24186.51626967486, 24747.752522474544, 24845.378865528506], "value": 15395.25696614188, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [373.32055658701165, 380.2490082087876, 390.2039838728993, 391.49692644951244, 392.4211787613271, 15129.924350378247, 15770.211489425526, 16661.416850410238, 16689.666206675858, 16722.080285584318, 13066.216027038385, 15231.54305370838, 15753.212339383042, 15771.447999320026, 15827.683446331095, 4634.721916685001, 4781.0, 4823.469418363977, 4825.396825396825, 4827.7586120694, 3412.368711788327, 6408.967955160241, 6940.097787287646, 6954.965225173867, 6955.513114082017, 6148.969255153718, 6477.74089036438, 6883.139607549057, 6933.0, 6941.409980151668, 6210.472109870667, 6396.680165991682, 6399.168108145957, 6421.454176395012, 6879.071325371111, 6620.834479138012, 6633.834154146137, 6661.9000714989315, 6663.333666633346, 6683.899741503881, 20130.496737581532, 21635.242766503095, 21996.780032199633, 22371.776282237293, 22391.0, 1967.4687834284664, 2023.0, 2025.43287879394, 2070.3892351756267, 2086.749590049204, 13815.585532433943, 14390.208538530376, 14391.856081439157, 14655.34050967709, 14669.33987970544, 1781.6971114910366, 1949.0741897598668, 1956.1979588368843, 1962.901854907249, 2035.4097311779567, 68861.0, 72359.63820180866, 72790.2720972794, 73021.63489182573, 73544.63227683827, 15350.692986140215, 15710.764338534931, 15757.763633545506, 16183.514494565183, 16292.0, 27428.862855685595, 27449.0, 27757.0, 27828.72171278261, 28020.29949251285, 8307.793672819425, 8608.526531040729, 8684.522351270742, 8773.868391974125, 8834.55827208637, 7076.646167691595, 7104.502684812117, 7209.170945341353, 7219.783406497813, 7276.490645654753, 3647.1976165243605, 8045.879311810328, 8267.875981860157, 8303.294219991369, 8333.416660833684, 1791.6652094189913, 1882.6140641168527, 1893.3184053740642, 1907.2561700936647, 1914.7510823592843, 834.3700506117842, 848.5799529233069, 852.5012867472474, 881.0484676549312, 949.639137127887, 15538.922305388402, 15721.449749258722, 16064.27710753019, 16187.757183642254, 16223.918880405523, 790.2808444315646, 830.3274347778319, 859.7420773767844, 863.2834747159842, 876.7720392697817, 5853.795117170878, 8874.112588741204, 9015.368924175376, 9068.274538036942, 9187.26501879848, 292165.0, 297068.0, 297893.0, 298282.0, 359525.0, 67874.3212567868, 70860.2913970864, 71229.6438517804, 71475.0, 74456.0, 994.0655783563427, 995.318207028188, 997.910188083076, 1045.7594753206654, 1047.3454091193005, 14747.0, 14978.251087445366, 15018.20735965892, 15075.718563921751, 15250.771238431655, 31869.521957170196, 32469.837650811594, 33194.834025830685, 34169.31661366808, 34270.828645856614, 40770.38844417277, 42635.786821065696, 44103.33844992264, 44545.331820022075, 45324.32013519734, 7508.174100848793, 7517.548947063194, 7581.241875812375, 7670.156282808775, 7734.961325193338, 10364.896351036394, 11247.268927519684, 11294.887051129384, 11341.546338146713, 11469.426528673699, 9433.962264150801, 9746.074122958307, 9879.0, 10019.699409017741, 10228.335158214692, 4640.303954406866, 7780.455368124403, 8912.86630700553, 9414.340996130206, 9589.369807132665, 9731.61073557078, 9782.315237933277, 9990.000999899952, 10425.0, 10433.26967112295, 7423.962880185564, 7499.9250007501405, 7502.324790768857, 7507.361874240751, 7556.319931206218, 8208.46644968075, 9759.365641233297, 9782.608695652094, 9941.25440591973, 9943.254255930979], "value": 8681.581586395061, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1440.517426662068, 1465.472429925227, 1586.8016497937758, 1741.9619045716502, 1744.4766570028987, 6464.385883341087, 7371.115466144057, 7389.445791565634, 7640.770776876689, 7644.61776911155, 7390.408767298617, 7394.075740532434, 7442.144153422358, 7465.701371945116, 7482.40140788737, 2172.728408948881, 2200.680901269315, 2202.0861342542826, 2203.151786562177, 2213.7896899794496, 2174.9782502174935, 2183.1049269799387, 2183.1922188790168, 2184.1809321504434, 2190.3647942096773, 2113.725215721954, 2128.0, 2132.5201829588295, 2140.079765700748, 2172.283146561631, 2087.2277257414703, 2090.1325949730845, 2092.0899408757164, 2092.9058192381303, 2093.497560585464, 2102.894855257231, 2116.788321167871, 2176.7823217678265, 2208.2823082498253, 2218.5230175512343, 7438.330550250449, 7592.544447333178, 7595.924040759633, 7605.885911711329, 7607.049118860143, 4513.480949690795, 4617.076584683044, 4651.069786042805, 4668.253079507296, 4694.201985662446, 2815.845128517931, 2970.287131088523, 3001.1896787867154, 3014.9849250753605, 3020.7885448018646, 897.1208215948342, 933.3233405780823, 934.8878134623889, 957.7222605444482, 960.6829746183743, 10244.385336879812, 10328.638497652546, 10369.325993810378, 10431.739206519822, 10471.057604815604, 4156.58434156588, 4245.320748680197, 4278.850240241639, 4287.528371879091, 4297.871063868089, 8865.734027979172, 8895.0, 8900.910990890137, 8923.910760892308, 8925.866112008325, 4617.607503362252, 4619.884502887455, 4653.557911998355, 4660.790264438108, 4668.976655116703, 3568.678818906311, 4158.334666453414, 4205.3271476563605, 4264.381664658669, 4306.698531102856, 3603.3694103532303, 3621.9818900905325, 3682.3924052531033, 3698.389765688631, 3714.8699795507027, 1215.6292330839178, 1222.9449674764658, 1225.2525959164902, 1225.8099994500867, 1241.5039876948256, 685.7702669605638, 843.9324854011664, 865.2256230673512, 873.7990262239593, 890.679355432044, 5030.094582975026, 5209.6874187548865, 5271.815486457956, 5412.83761487156, 5430.864228394322, 841.3773807382493, 890.123228619809, 900.0234745301422, 907.603035262615, 915.9375124855136, 3508.1405055760993, 3654.1778099927305, 3726.105734623725, 3740.682042026404, 3790.1093243087853, 9121.45271283712, 9395.24838012971, 9475.28935329853, 9512.809743805085, 9527.761805954906, 9375.765605859771, 9449.669261575811, 9553.426794392359, 9594.616215351447, 9667.613295468103, 6488.351164883568, 6841.555298905555, 6958.025876377311, 7024.262452442522, 7041.0846589944, 5247.685138891678, 5393.919091213556, 5394.460553944731, 5427.077396842467, 5435.809746658848, 8306.086330503518, 8574.657013719381, 8602.39783215169, 8666.350023748118, 8672.696455624024, 8678.435901666371, 8706.521141337287, 8725.127487251224, 8736.126387361213, 8747.868781968404, 6033.668148251712, 6317.5893566918, 6524.151860258136, 6524.543281970216, 6590.406863382319, 7093.964530177316, 7128.429725621834, 7176.677049532888, 7183.748568800067, 7225.530340528059, 6297.527685423704, 6487.156669632917, 6535.836604084936, 6590.703418346282, 6682.198136223684, 6478.125453063964, 6507.544471886922, 6527.967360163168, 6575.178102737158, 6613.437857782143, 6317.368263173646, 6337.524685648503, 6340.873182536231, 6414.454771344302, 6418.261899881573, 4324.481062272547, 4441.178381999341, 4492.77536123186, 4507.09858028389, 4520.367148599133, 4424.77876106187, 4439.289713645801, 4463.35281384204, 4552.977235113935, 4557.977210113928], "value": 4101.891514814362, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [296.70626080180614, 300.31228486765303, 304.95578141169545, 304.9786514943955, 307.6738657023555, 4872.366592342995, 4966.106100901836, 4973.875653108674, 4980.277859710344, 5031.874203144923, 4867.172580661288, 4901.509849015097, 4915.950840491603, 4933.333999910011, 4934.333864928233, 3677.264547090579, 3752.43713442983, 3869.129445874684, 3878.9806050969846, 3888.747231429962, 3859.5561510426237, 3869.4582758413844, 3874.9806250968704, 3895.454636350913, 3906.8437262509465, 3856.633619806114, 3868.3617203161443, 3869.342211823997, 3875.9418608720894, 3881.204353107606, 3625.3655610268097, 3821.980890095532, 3871.7870517121546, 3874.411491288558, 3875.748076375055, 3344.615369232545, 3351.631320554737, 3354.5135955286346, 3368.2421455172635, 3368.6294507604143, 4837.0, 4839.596517010063, 4840.128776820171, 4842.83050093245, 4861.975690121562, 4371.0, 4390.187815254157, 4393.582609652078, 4400.867973960786, 4417.315316125973, 5045.12427916088, 5157.53582177602, 5172.974135129338, 5178.326817513736, 5219.765110569995, 3637.5816781070253, 3650.543682039745, 3654.3604869147794, 3673.559172899269, 3678.1724112074835, 5392.4337944515655, 5445.782168713246, 5481.479259470344, 5497.367802702701, 5521.254630624854, 3666.175110600094, 3719.739618226752, 3747.5877653458097, 3753.9624603753614, 3766.322062028806, 3787.696984241254, 3788.0, 3803.9619603804163, 3815.561210460777, 3816.38937769961, 4366.148601022834, 4375.299952007664, 4379.0, 4386.627136693417, 4412.227860124465, 5067.670601410897, 5199.376074871039, 5242.1088414969545, 5247.10799164143, 5272.288241087481, 1371.7805151175764, 1373.5604606526015, 1382.8755412012968, 1383.481194552043, 1385.9328317195702, 848.3849209323191, 892.1212605583495, 911.2209061252667, 928.9581968811419, 930.4091901642371, 4458.8439404620685, 4581.954180458153, 4589.839355622537, 4622.838200662961, 4636.258198688193, 5364.3830959440165, 5402.6758394496455, 5416.458354164552, 5454.0, 5489.313835770528, 4441.0, 4565.406497155349, 4608.216603177468, 4622.329762184531, 4630.143423466669, 3056.8777248910237, 3067.2791893904914, 3080.3069309405205, 3093.5359696045325, 3100.5659207710937, 5395.70323632204, 5396.408059622421, 5397.568194544427, 5424.674519528841, 5439.592030597721, 5256.369235691742, 5587.888242235133, 5595.132754423071, 5882.49998750111, 5909.172715819789, 5087.720175390389, 5091.1854103343285, 5108.361454818148, 5121.4622464641425, 5149.2791009258735, 5431.701256430776, 5446.972765136149, 5460.5904557159165, 5491.478309560585, 5492.780288788404, 5183.341330774116, 5301.628885977944, 5331.280277162534, 5337.973310133424, 5338.786448542015, 5053.545180933734, 5100.2349647553165, 5113.488651135006, 5134.383873935151, 5147.0, 3865.458835762939, 3870.53553573573, 3884.6115388460926, 3910.8631197907935, 3937.3306537888056, 5274.525292723673, 5330.813421530228, 5361.758720857492, 5395.541378982831, 5396.865078373072, 5188.636795424435, 5195.558377537952, 5221.869453263699, 5231.267622532924, 5243.764030618708, 4980.825671101494, 5175.378954525481, 5213.218017297436, 5235.738213089407, 5249.501297376896, 5121.769520371667, 5143.031192733792, 5145.0, 5151.562117220078, 5173.586113110867, 4997.9500204997485, 5105.2086926526445, 5273.815416460406, 5290.576753859759, 5296.3379577552805, 5126.7183204198955, 5157.329547158995, 5165.4834516549545, 5188.351456067991, 5189.948100518946], "value": 3950.4675316658295, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [610.109240508857, 720.3264947274299, 726.1576571177435, 733.3546479098393, 766.1266156581498, 10865.40240286784, 11883.405829708512, 12011.339376334297, 12451.564195253168, 13444.596662100153, 13126.934365328183, 13311.800322995163, 13345.53290634828, 13371.26458044807, 13391.330433478312, 798.5767543202112, 810.4286478032973, 810.9052778748671, 811.7767613906163, 813.3005615170978, 796.0606484348466, 801.6232370785722, 802.3741481644298, 803.1927912448011, 807.7818988873, 843.1020962674762, 848.1857416879802, 848.9405741598091, 850.0521918061348, 851.6423102297019, 830.7341650671788, 843.4686147726954, 845.0746432656263, 845.4335595151264, 847.9703210387669, 605.8333958161497, 612.0727098445858, 612.7793994162098, 614.4623454477332, 615.0681717198448, 12363.876361236453, 12539.373031348396, 12555.372231388394, 12594.81107783384, 12612.936935315356, 11915.0, 12028.45871935765, 12183.147179697426, 12925.289109098996, 13017.349132543335, 9916.90083099167, 10003.149732272766, 10202.846957295647, 10212.131968782662, 10425.113865321457, 3241.6758324167395, 3254.544363789071, 3294.3246634439884, 3322.352141332441, 3327.8502467389026, 44689.0, 44881.0, 45072.098558028985, 45124.54875451236, 46127.76936115298, 9657.9034209657, 9747.853782193273, 9809.117179453884, 9887.258455615858, 10003.149732272694, 15401.922990384975, 16032.11823349727, 16851.831481685273, 16875.493735187963, 16943.813933024816, 7055.89416158758, 7102.325279098477, 7105.92894071063, 7124.92875071253, 7234.602096884617, 3822.9426558601645, 3952.940705889414, 3953.0, 3956.1098752780968, 3988.3219852625125, 2785.8467784271656, 2795.2452837734104, 2820.661520617498, 2821.238265668258, 2823.449427361686, 518.225253246399, 523.6805548615341, 536.9194620806832, 542.8534295740128, 546.5572885962304, 533.47186285577, 612.9532165190453, 616.1681729665006, 629.3769168523221, 640.5964242527272, 7452.0, 7456.105267367842, 7620.542767433972, 7687.730929417556, 7864.41016923733, 440.00558737253397, 442.6082916618751, 501.30569161710565, 516.0144124721763, 534.8208350202725, 3275.4922986937063, 3305.074579117851, 3345.381104495676, 3445.328161008579, 3460.2214501736908, 54555.72722136364, 54819.45180548223, 54870.0, 55032.449675502736, 55555.44444555583, 47479.5252047482, 48474.27288590674, 48743.0, 49476.0, 50065.24902126471, 44242.77878610586, 44282.99999999935, 44570.55429445729, 44957.55042449599, 45201.54798451973, 7202.89195662076, 7224.494285400182, 7385.150707668472, 7522.548647081193, 7526.736564220226, 30007.399852003273, 30718.539221911244, 30788.0, 31086.53370199494, 31191.84404077965, 36684.81657591802, 40517.0, 41218.1756364865, 41467.1706565873, 41641.79179104085, 10117.089461948166, 10164.898351016394, 10170.593176272867, 10184.949075254577, 10285.640002599874, 10327.380357178594, 10433.895661043596, 11027.062699670618, 11086.833697494705, 11108.722281943015, 9864.950675246864, 9921.305508614616, 10146.086852183338, 10159.187265018929, 10291.43397113136, 9729.173020293072, 9871.605135794487, 9923.950380248052, 9974.351667141613, 10539.789204216026, 10234.53944572511, 10340.069393754296, 10383.48082595852, 10430.68707938763, 10464.424456654653, 8827.470351778657, 8837.072107428627, 8888.60001299956, 8895.510746908982, 9031.683891063782, 8560.871586926076, 8937.46375217489, 8989.190972812477, 9051.14014168653, 9331.486768227813], "value": 5843.809903384342, "unit": "score"}], "name": "dromaeo_css", "value": 6237.151441345675, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 107.0, 114.0, 114.0, 115.0, 126.0, 114.0, 114.0, 115.0, 114.0], "value": 114.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 145.0, 155.0, 201.0, 135.0, 159.0, 162.0, 135.0, 201.0, 134.0], "value": 159.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 165.0, 164.0, 171.0, 162.0, 160.0, 163.0, 162.0, 167.0, 163.0], "value": 163.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 78.0, 155.0, 75.0, 74.0, 74.0, 74.0, 156.0, 75.0], "value": 91.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 154.0, 98.0, 94.0, 95.0, 142.0, 94.0, 94.0, 137.0], "value": 109.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 119.0, 118.0, 118.0, 119.0, 118.0, 118.0, 118.0, 117.0], "value": 118.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 147.0, 147.0, 148.0, 146.0, 147.0, 148.0, 148.0, 149.0, 148.0], "value": 147.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 95.0, 96.0, 95.0, 96.0, 97.0, 95.0, 96.0], "value": 95.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 67.0, 66.0, 66.0, 67.0, 66.0, 91.0, 68.0, 69.0], "value": 69.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 65.0, 65.0, 65.0, 64.0, 65.0, 64.0, 65.0, 67.0], "value": 65.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 88.0, 79.0, 80.0, 81.0, 82.0, 81.0, 83.0, 81.0], "value": 81.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 137.0, 139.0, 136.0, 146.0, 142.0, 135.0, 137.0, 191.0, 140.0], "value": 144.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [219.0, 219.0, 218.0, 228.0, 219.0, 218.0, 218.0, 227.0, 219.0, 218.0], "value": 220.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 75.0, 76.0, 75.0, 77.0, 76.0, 79.0, 74.0, 79.0], "value": 76.3, "unit": "score"}], "name": "kraken", "value": 1657.1999999999998, "alertThreshold": 2.0}]} 04:51:07 INFO - Return code: 0 04:51:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 04:51:07 ERROR - # TBPL SUCCESS # 04:51:07 INFO - Running post-action listener: _resource_record_post_action 04:51:07 INFO - Running post-run listener: _resource_record_post_run 04:51:09 INFO - Total resource usage - Wall time: 1197s; CPU: 13.0%; Read bytes: 4096; Write bytes: 398639104; Read time: 16; Write time: 332448 04:51:09 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 107405312; Read time: 0; Write time: 119528 04:51:09 INFO - run-tests - Wall time: 1181s; CPU: 13.0%; Read bytes: 4096; Write bytes: 291233792; Read time: 16; Write time: 212920 04:51:09 INFO - Running post-run listener: _upload_blobber_files 04:51:09 INFO - Blob upload gear active. 04:51:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:51:09 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 04:51:09 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', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:51:09 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 Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:51:09 INFO - (blobuploader) - INFO - Open directory for files ... 04:51:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:51:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:51:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:51:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:51:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:51:10 INFO - (blobuploader) - INFO - Done attempting. 04:51:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:51:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:51:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:51:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:51:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:51:11 INFO - (blobuploader) - INFO - Done attempting. 04:51:11 INFO - (blobuploader) - INFO - Iteration through files over. 04:51:11 INFO - Return code: 0 04:51:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:51:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:51:11 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bccd5e309b6a013f2cc7494e44100164d794ab11e2e63fe15f92597654cefd201a0a17da6c4acfe98ae9bb65cda7b22119402cfc9a5dcd5576ffb5c2c283acab", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e06bcb7751b1f7ed2f5418c5a48979e573d1bbd2b5563605d85c94a90d2bbde9910be3fc227835bc807e4df335c60abfeb68df19ee484574122ac0191ef7f506"} 04:51:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:51:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:51:11 INFO - Contents: 04:51:11 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/bccd5e309b6a013f2cc7494e44100164d794ab11e2e63fe15f92597654cefd201a0a17da6c4acfe98ae9bb65cda7b22119402cfc9a5dcd5576ffb5c2c283acab", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e06bcb7751b1f7ed2f5418c5a48979e573d1bbd2b5563605d85c94a90d2bbde9910be3fc227835bc807e4df335c60abfeb68df19ee484574122ac0191ef7f506"} 04:51:11 INFO - Running post-run listener: copy_logs_to_upload_dir 04:51:11 INFO - Copying logs to upload dir... 04:51:11 INFO - mkdir: /builds/slave/test/build/upload/logs 04:51:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1296.141328 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 36 secs) (at 2016-04-27 04:51:11.229149) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-27 04:51:11.232429) ========= 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-1461756572.954321-1283641448 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/Firefox-Non-PGO/sha512/bccd5e309b6a013f2cc7494e44100164d794ab11e2e63fe15f92597654cefd201a0a17da6c4acfe98ae9bb65cda7b22119402cfc9a5dcd5576ffb5c2c283acab", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e06bcb7751b1f7ed2f5418c5a48979e573d1bbd2b5563605d85c94a90d2bbde9910be3fc227835bc807e4df335c60abfeb68df19ee484574122ac0191ef7f506"} build_url:https://queue.taskcluster.net/v1/task/RsZhMrEDSbqvuE8XqQnoYg/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011253 build_url: 'https://queue.taskcluster.net/v1/task/RsZhMrEDSbqvuE8XqQnoYg/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/Firefox-Non-PGO/sha512/bccd5e309b6a013f2cc7494e44100164d794ab11e2e63fe15f92597654cefd201a0a17da6c4acfe98ae9bb65cda7b22119402cfc9a5dcd5576ffb5c2c283acab", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e06bcb7751b1f7ed2f5418c5a48979e573d1bbd2b5563605d85c94a90d2bbde9910be3fc227835bc807e4df335c60abfeb68df19ee484574122ac0191ef7f506"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-27 04:51:11.257475) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 04:51:11.257818) ========= 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-1461756572.954321-1283641448 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004453 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 04:51:11.340915) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-27 04:51:11.341261) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-27 04:51:11.341606) ========= ========= Total master_lag: 0.17 =========