builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-023 starttime: 1460765593.4 results: success (0) buildid: 20160415154116 builduid: 5670e814ac16439ba664de8cb8aabf05 revision: 29782c3286ca0d496b16cd3a7a0edd0741641d99 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-15 17:13:13.401102) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-15 17:13:13.401563) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-15 17:13:13.401869) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-15 17:13:13.418968) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-15 17:13:13.419356) ========= 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=6d15e502d8e29d5d2676d664000001c3-1460765266.121002-399972084 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-04-15 17:13:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 223M=0s 2016-04-15 17:13:13 (223 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.091165 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-15 17:13:13.524553) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-15 17:13:13.524857) ========= 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=6d15e502d8e29d5d2676d664000001c3-1460765266.121002-399972084 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021602 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-15 17:13:13.575056) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-15 17:13:13.575541) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 29782c3286ca0d496b16cd3a7a0edd0741641d99 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 29782c3286ca0d496b16cd3a7a0edd0741641d99 --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=6d15e502d8e29d5d2676d664000001c3-1460765266.121002-399972084 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-04-15 17:13:14,067 truncating revision to first 12 chars 2016-04-15 17:13:14,067 Setting DEBUG logging. 2016-04-15 17:13:14,067 attempt 1/10 2016-04-15 17:13:14,067 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/29782c3286ca?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-15 17:13:14,338 unpacking tar archive at: fx-team-29782c3286ca/testing/mozharness/ program finished with exit code 0 elapsedTime=0.556697 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-15 17:13:14.145306) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-15 17:13:14.145616) ========= script_repo_revision: 29782c3286ca0d496b16cd3a7a0edd0741641d99 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-15 17:13:14.146014) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-15 17:13:14.146304) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-15 17:13:14.335291) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-04-15 17:13:14.335747) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=6d15e502d8e29d5d2676d664000001c3-1460765266.121002-399972084 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:13:14 INFO - MultiFileLogger online at 20160415 17:13:14 in /builds/slave/test 17:13:14 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 17:13:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:13:14 INFO - {'append_to_log': False, 17:13:14 INFO - 'base_work_dir': '/builds/slave/test', 17:13:14 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 17:13:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:13:14 INFO - 'branch': 'Fx-Team-Non-PGO', 17:13:14 INFO - 'buildbot_json_path': 'buildprops.json', 17:13:14 INFO - 'config_files': ('talos/linux_config.py',), 17:13:14 INFO - 'default_actions': ('clobber', 17:13:14 INFO - 'read-buildbot-config', 17:13:14 INFO - 'download-and-extract', 17:13:14 INFO - 'populate-webroot', 17:13:14 INFO - 'create-virtualenv', 17:13:14 INFO - 'install', 17:13:14 INFO - 'run-tests'), 17:13:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:13:14 INFO - 'download_minidump_stackwalk': True, 17:13:14 INFO - 'download_symbols': 'ondemand', 17:13:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:13:14 INFO - 'tooltool.py': '/tools/tooltool.py', 17:13:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:13:14 INFO - '/tools/misc-python/virtualenv.py')}, 17:13:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:13:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:13:14 INFO - 'installer_path': 'installer.exe', 17:13:14 INFO - 'log_level': 'info', 17:13:14 INFO - 'log_name': 'talos', 17:13:14 INFO - 'log_to_console': True, 17:13:14 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:13:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:13:14 INFO - 'opt_config_files': (), 17:13:14 INFO - 'pip_index': False, 17:13:14 INFO - 'sps_profile': False, 17:13:14 INFO - 'sps_profile_interval': 0, 17:13:14 INFO - 'suite': 'dromaeojs-e10s', 17:13:14 INFO - 'system_bits': '32', 17:13:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:13:14 INFO - 'title': 'talos-linux64-ix-023', 17:13:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:13:14 INFO - 'use_talos_json': True, 17:13:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:13:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:13:14 INFO - 'work_dir': 'build'} 17:13:14 INFO - ##### 17:13:14 INFO - ##### Running clobber step. 17:13:14 INFO - ##### 17:13:14 INFO - Running pre-action listener: _resource_record_pre_action 17:13:14 INFO - Running main action method: clobber 17:13:14 INFO - rmtree: /builds/slave/test/build 17:13:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:13:15 INFO - Running post-action listener: _resource_record_post_action 17:13:15 INFO - ##### 17:13:15 INFO - ##### Running read-buildbot-config step. 17:13:15 INFO - ##### 17:13:15 INFO - Running pre-action listener: _resource_record_pre_action 17:13:15 INFO - Running main action method: read_buildbot_config 17:13:15 INFO - Using buildbot properties: 17:13:15 INFO - { 17:13:15 INFO - "project": "", 17:13:15 INFO - "product": "firefox", 17:13:15 INFO - "script_repo_revision": "production", 17:13:15 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 17:13:15 INFO - "repository": "", 17:13:15 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs-e10s", 17:13:15 INFO - "buildid": "20160415154116", 17:13:15 INFO - "pgo_build": "False", 17:13:15 INFO - "basedir": "/builds/slave/test", 17:13:15 INFO - "buildnumber": 1162, 17:13:15 INFO - "slavename": "talos-linux64-ix-023", 17:13:15 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 17:13:15 INFO - "platform": "ubuntu64_hw", 17:13:15 INFO - "scheduler": "tests-fx-team-linux64-talos", 17:13:15 INFO - "branch": "fx-team", 17:13:15 INFO - "repo_path": "integration/fx-team", 17:13:15 INFO - "revision": "29782c3286ca0d496b16cd3a7a0edd0741641d99", 17:13:15 INFO - "stage_platform": "linux64", 17:13:15 INFO - "builduid": "5670e814ac16439ba664de8cb8aabf05", 17:13:15 INFO - "slavebuilddir": "test" 17:13:15 INFO - } 17:13:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/e6bqZlQ5RPyLqAUQsyVnbw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2. 17:13:15 INFO - Running post-action listener: _resource_record_post_action 17:13:15 INFO - ##### 17:13:15 INFO - ##### Running download-and-extract step. 17:13:15 INFO - ##### 17:13:15 INFO - Running pre-action listener: _resource_record_pre_action 17:13:15 INFO - Running main action method: download_and_extract 17:13:15 INFO - mkdir: /builds/slave/test/build/tests 17:13:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:13:15 INFO - https://queue.taskcluster.net/v1/task/e6bqZlQ5RPyLqAUQsyVnbw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 17:13:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e6bqZlQ5RPyLqAUQsyVnbw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 17:13:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e6bqZlQ5RPyLqAUQsyVnbw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 17:13:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e6bqZlQ5RPyLqAUQsyVnbw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 17:13:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e6bqZlQ5RPyLqAUQsyVnbw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 17:13:20 INFO - Downloaded 1361 bytes. 17:13:20 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 17:13:20 INFO - Using the following test package requirements: 17:13:20 INFO - {u'common': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'], 17:13:20 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 17:13:20 INFO - u'firefox-48.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 17:13:20 INFO - u'gtest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 17:13:20 INFO - u'firefox-48.0a1.en-US.linux-x86_64.gtest.tests.zip'], 17:13:20 INFO - u'jittest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 17:13:20 INFO - u'jsshell-linux-x86_64.zip'], 17:13:20 INFO - u'mochitest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 17:13:20 INFO - u'firefox-48.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 17:13:20 INFO - u'mozbase': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'], 17:13:20 INFO - u'reftest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 17:13:20 INFO - u'firefox-48.0a1.en-US.linux-x86_64.reftest.tests.zip'], 17:13:20 INFO - u'talos': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 17:13:20 INFO - u'firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'], 17:13:20 INFO - u'web-platform': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 17:13:20 INFO - u'firefox-48.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 17:13:20 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 17:13:20 INFO - u'firefox-48.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 17:13:20 INFO - Downloading packages: [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 17:13:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:13:20 INFO - https://queue.taskcluster.net/v1/task/e6bqZlQ5RPyLqAUQsyVnbw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:13:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e6bqZlQ5RPyLqAUQsyVnbw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 17:13:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e6bqZlQ5RPyLqAUQsyVnbw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 17:13:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e6bqZlQ5RPyLqAUQsyVnbw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 17:13:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e6bqZlQ5RPyLqAUQsyVnbw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:13:26 INFO - Downloaded 22203703 bytes. 17:13:26 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:13:26 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 17:13:27 INFO - Return code: 0 17:13:27 INFO - Downloading packages: [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 17:13:27 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')]} 17:13:27 INFO - https://queue.taskcluster.net/v1/task/e6bqZlQ5RPyLqAUQsyVnbw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:13:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e6bqZlQ5RPyLqAUQsyVnbw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 17:13:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e6bqZlQ5RPyLqAUQsyVnbw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 17:13:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e6bqZlQ5RPyLqAUQsyVnbw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 17:13:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e6bqZlQ5RPyLqAUQsyVnbw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:13:28 INFO - Downloaded 22203703 bytes. 17:13:28 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:13:28 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 17:13:30 INFO - Return code: 0 17:13:30 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')]} 17:13:30 INFO - https://queue.taskcluster.net/v1/task/e6bqZlQ5RPyLqAUQsyVnbw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 17:13:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e6bqZlQ5RPyLqAUQsyVnbw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 17:13:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e6bqZlQ5RPyLqAUQsyVnbw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 17:13:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e6bqZlQ5RPyLqAUQsyVnbw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 17:13:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e6bqZlQ5RPyLqAUQsyVnbw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 17:13:31 INFO - Downloaded 10841076 bytes. 17:13:31 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:13:31 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 17:13:31 INFO - Return code: 0 17:13:31 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')]} 17:13:31 INFO - https://queue.taskcluster.net/v1/task/e6bqZlQ5RPyLqAUQsyVnbw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 17:13:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e6bqZlQ5RPyLqAUQsyVnbw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 17:13:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e6bqZlQ5RPyLqAUQsyVnbw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 17:13:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e6bqZlQ5RPyLqAUQsyVnbw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 17:13:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e6bqZlQ5RPyLqAUQsyVnbw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 17:13:32 INFO - Downloaded 54447341 bytes. 17:13:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/e6bqZlQ5RPyLqAUQsyVnbw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 17:13:32 INFO - mkdir: /builds/slave/test/properties 17:13:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:13:32 INFO - Writing to file /builds/slave/test/properties/build_url 17:13:32 INFO - Contents: 17:13:32 INFO - build_url:https://queue.taskcluster.net/v1/task/e6bqZlQ5RPyLqAUQsyVnbw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 17:13:33 INFO - Running post-action listener: _resource_record_post_action 17:13:33 INFO - Running post-action listener: set_extra_try_arguments 17:13:33 INFO - ##### 17:13:33 INFO - ##### Running populate-webroot step. 17:13:33 INFO - ##### 17:13:33 INFO - Running pre-action listener: _resource_record_pre_action 17:13:33 INFO - Running main action method: populate_webroot 17:13:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:13:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:13:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:13:33 INFO - '--tppagecycles', 17:13:33 INFO - '7'], 17:13:33 INFO - 'tests': ['tsvgm']}}, 17:13:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:13:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:13:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:13:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:13:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:13:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:13:33 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:13:33 INFO - 'talos_options': ['--disable-e10s'], 17:13:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:13:33 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:13:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:13:33 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:13:33 INFO - 'talos_options': ['--disable-e10s'], 17:13:33 INFO - 'tests': ['damp', 'tps']}, 17:13:33 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:13:33 INFO - 'tests': ['damp', 'tps']}, 17:13:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:13:33 INFO - 'tests': ['dromaeo_dom']}, 17:13:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:13:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:13:33 INFO - 'tests': ['a11yr', 17:13:33 INFO - 'ts_paint', 17:13:33 INFO - 'tpaint', 17:13:33 INFO - 'sessionrestore', 17:13:33 INFO - 'sessionrestore_no_auto_restore', 17:13:33 INFO - 'tabpaint']}, 17:13:33 INFO - 'other-e10s': {'tests': ['a11yr', 17:13:33 INFO - 'ts_paint', 17:13:33 INFO - 'tpaint', 17:13:33 INFO - 'sessionrestore', 17:13:33 INFO - 'sessionrestore_no_auto_restore', 17:13:33 INFO - 'tabpaint']}, 17:13:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:13:33 INFO - 'ts_paint', 17:13:33 INFO - 'tpaint', 17:13:33 INFO - 'sessionrestore', 17:13:33 INFO - 'sessionrestore_no_auto_restore', 17:13:33 INFO - 'tabpaint']}, 17:13:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:13:33 INFO - 'ts_paint', 17:13:33 INFO - 'tpaint', 17:13:33 INFO - 'sessionrestore', 17:13:33 INFO - 'sessionrestore_no_auto_restore', 17:13:33 INFO - 'tabpaint']}, 17:13:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:13:33 INFO - 'tests': ['a11yr', 17:13:33 INFO - 'ts_paint', 17:13:33 INFO - 'tpaint', 17:13:33 INFO - 'sessionrestore', 17:13:33 INFO - 'sessionrestore_no_auto_restore', 17:13:33 INFO - 'tabpaint']}, 17:13:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:13:33 INFO - 'tests': ['a11yr', 17:13:33 INFO - 'ts_paint', 17:13:33 INFO - 'tpaint', 17:13:33 INFO - 'sessionrestore', 17:13:33 INFO - 'sessionrestore_no_auto_restore', 17:13:33 INFO - 'tabpaint']}, 17:13:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:13:33 INFO - 'tests': ['tsvgx', 17:13:33 INFO - 'tsvgr_opacity', 17:13:33 INFO - 'tart', 17:13:33 INFO - 'tscrollx', 17:13:33 INFO - 'cart']}, 17:13:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:13:33 INFO - 'tsvgr_opacity', 17:13:33 INFO - 'tart', 17:13:33 INFO - 'tscrollx', 17:13:33 INFO - 'cart']}, 17:13:33 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:13:33 INFO - 'talos_options': ['--disable-e10s'], 17:13:33 INFO - 'tests': ['tp5o']}, 17:13:33 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:13:33 INFO - 'tests': ['tp5o']}, 17:13:33 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:13:33 INFO - 'talos_options': ['--disable-e10s', 17:13:33 INFO - '--xperf_path', 17:13:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:13:33 INFO - 'tests': ['tp5n']}, 17:13:33 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:13:33 INFO - 'talos_options': ['--xperf_path', 17:13:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:13:33 INFO - 'tests': ['tp5n']}}, 17:13:33 INFO - 'talos.zip': {'path': '', 17:13:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:13:33 INFO - Running post-action listener: _resource_record_post_action 17:13:33 INFO - ##### 17:13:33 INFO - ##### Running create-virtualenv step. 17:13:33 INFO - ##### 17:13:33 INFO - Running pre-action listener: _resource_record_pre_action 17:13:33 INFO - Running main action method: create_virtualenv 17:13:33 INFO - Creating virtualenv /builds/slave/test/build/venv 17:13:33 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 17:13:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:13:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:13:33 INFO - Using real prefix '/usr' 17:13:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:13:34 INFO - Installing distribute.............................................................................................................................................................................................done. 17:13:35 INFO - Installing pip.................done. 17:13:35 INFO - Return code: 0 17:13:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:13: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')]} 17:13:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:13:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:13: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')]} 17:13:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:13:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:13:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f32563eaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xda48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe6f5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xefe410>, '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 0xeff310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeff7a0>, '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': '6d15e502d8e29d5d2676d664000001c3-1460765266.121002-399972084', '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 17:13:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 17:13:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:13:35 INFO - Using env: {'DISPLAY': ':0', 17:13:35 INFO - 'HOME': '/home/cltbld', 17:13:35 INFO - 'LANG': 'en_US.UTF-8', 17:13:35 INFO - 'LANGUAGE': 'en_US:en', 17:13:35 INFO - 'LOGNAME': 'cltbld', 17:13:35 INFO - 'MAIL': '/var/mail/cltbld', 17:13:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:13:35 INFO - 'MOZ_NO_REMOTE': '1', 17:13:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:13:35 INFO - 'NO_EM_RESTART': '1', 17:13:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:13:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:13:35 INFO - 'PWD': '/builds/slave/test', 17:13:35 INFO - 'SHELL': '/bin/bash', 17:13:35 INFO - 'SHLVL': '1', 17:13:35 INFO - 'TERM': 'linux', 17:13:35 INFO - 'TMOUT': '86400', 17:13:35 INFO - 'USER': 'cltbld', 17:13:35 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1460765266.121002-399972084', 17:13:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:13:35 INFO - '_': '/tools/buildbot/bin/python'} 17:13:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:13:36 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 17:13:36 INFO - Cleaning up... 17:13:36 INFO - Return code: 0 17:13:36 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 17:13: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')]} 17:13:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:13:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:13: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')]} 17:13:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:13:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:13:36 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 0x7f32563eaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xda48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe6f5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xefe410>, '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 0xeff310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeff7a0>, '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': '6d15e502d8e29d5d2676d664000001c3-1460765266.121002-399972084', '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 17:13:36 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 17:13:36 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 17:13:36 INFO - Using env: {'DISPLAY': ':0', 17:13:36 INFO - 'HOME': '/home/cltbld', 17:13:36 INFO - 'LANG': 'en_US.UTF-8', 17:13:36 INFO - 'LANGUAGE': 'en_US:en', 17:13:36 INFO - 'LOGNAME': 'cltbld', 17:13:36 INFO - 'MAIL': '/var/mail/cltbld', 17:13:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:13:36 INFO - 'MOZ_NO_REMOTE': '1', 17:13:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:13:36 INFO - 'NO_EM_RESTART': '1', 17:13:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:13:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:13:36 INFO - 'PWD': '/builds/slave/test', 17:13:36 INFO - 'SHELL': '/bin/bash', 17:13:36 INFO - 'SHLVL': '1', 17:13:36 INFO - 'TERM': 'linux', 17:13:36 INFO - 'TMOUT': '86400', 17:13:36 INFO - 'USER': 'cltbld', 17:13:36 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1460765266.121002-399972084', 17:13:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:13:36 INFO - '_': '/tools/buildbot/bin/python'} 17:13:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:13:36 INFO - Downloading/unpacking psutil>=0.7.1 17:13:36 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 17:13:36 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 17:13:36 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 17:13:36 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 17:13:36 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 17:13:36 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 17:13:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:13:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:13:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:13:38 INFO - Installing collected packages: psutil 17:13:38 INFO - Running setup.py install for psutil 17:13:38 INFO - building 'psutil._psutil_linux' extension 17:13:38 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 17:13:38 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 17:13:38 INFO - building 'psutil._psutil_posix' extension 17:13:38 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 17:13:38 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 17:13:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:13:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:13:38 INFO - Successfully installed psutil 17:13:38 INFO - Cleaning up... 17:13:38 INFO - Return code: 0 17:13:38 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 17:13: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')]} 17:13:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:13:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:13: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')]} 17:13:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:13:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:13:38 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 0x7f32563eaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xda48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe6f5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xefe410>, '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 0xeff310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeff7a0>, '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': '6d15e502d8e29d5d2676d664000001c3-1460765266.121002-399972084', '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 17:13:38 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 17:13:38 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 17:13:38 INFO - Using env: {'DISPLAY': ':0', 17:13:38 INFO - 'HOME': '/home/cltbld', 17:13:38 INFO - 'LANG': 'en_US.UTF-8', 17:13:38 INFO - 'LANGUAGE': 'en_US:en', 17:13:38 INFO - 'LOGNAME': 'cltbld', 17:13:38 INFO - 'MAIL': '/var/mail/cltbld', 17:13:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:13:38 INFO - 'MOZ_NO_REMOTE': '1', 17:13:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:13:38 INFO - 'NO_EM_RESTART': '1', 17:13:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:13:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:13:38 INFO - 'PWD': '/builds/slave/test', 17:13:38 INFO - 'SHELL': '/bin/bash', 17:13:38 INFO - 'SHLVL': '1', 17:13:38 INFO - 'TERM': 'linux', 17:13:38 INFO - 'TMOUT': '86400', 17:13:38 INFO - 'USER': 'cltbld', 17:13:38 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1460765266.121002-399972084', 17:13:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:13:38 INFO - '_': '/tools/buildbot/bin/python'} 17:13:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:13:39 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 17:13:39 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 17:13:39 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 17:13:39 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 17:13:39 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 17:13:39 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 17:13:39 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 17:13:41 INFO - Downloading mozsystemmonitor-0.0.tar.gz 17:13:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:13:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 17:13:41 INFO - Installing collected packages: mozsystemmonitor 17:13:41 INFO - Running setup.py install for mozsystemmonitor 17:13:41 INFO - Successfully installed mozsystemmonitor 17:13:41 INFO - Cleaning up... 17:13:41 INFO - Return code: 0 17:13:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:13: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')]} 17:13:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:13:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:13: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')]} 17:13:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:13:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:13:41 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 0x7f32563eaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xda48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe6f5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xefe410>, '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 0xeff310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeff7a0>, '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': '6d15e502d8e29d5d2676d664000001c3-1460765266.121002-399972084', '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 17:13:41 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 17:13:41 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 17:13:41 INFO - Using env: {'DISPLAY': ':0', 17:13:41 INFO - 'HOME': '/home/cltbld', 17:13:41 INFO - 'LANG': 'en_US.UTF-8', 17:13:41 INFO - 'LANGUAGE': 'en_US:en', 17:13:41 INFO - 'LOGNAME': 'cltbld', 17:13:41 INFO - 'MAIL': '/var/mail/cltbld', 17:13:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:13:41 INFO - 'MOZ_NO_REMOTE': '1', 17:13:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:13:41 INFO - 'NO_EM_RESTART': '1', 17:13:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:13:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:13:41 INFO - 'PWD': '/builds/slave/test', 17:13:41 INFO - 'SHELL': '/bin/bash', 17:13:41 INFO - 'SHLVL': '1', 17:13:41 INFO - 'TERM': 'linux', 17:13:41 INFO - 'TMOUT': '86400', 17:13:41 INFO - 'USER': 'cltbld', 17:13:41 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1460765266.121002-399972084', 17:13:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:13:41 INFO - '_': '/tools/buildbot/bin/python'} 17:13:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:13:41 INFO - Downloading/unpacking blobuploader==1.2.4 17:13:41 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 17:13:41 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 17:13:41 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 17:13:41 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 17:13:41 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 17:13:41 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 17:13:43 INFO - Downloading blobuploader-1.2.4.tar.gz 17:13:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:13:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:13:43 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 17:13:43 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 17:13:43 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 17:13:43 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 17:13:43 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 17:13:43 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 17:13:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:13:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:13:43 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 17:13:43 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 17:13:43 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 17:13:43 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 17:13:43 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 17:13:43 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 17:13:43 INFO - Downloading docopt-0.6.1.tar.gz 17:13:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:13:44 INFO - Installing collected packages: blobuploader, requests, docopt 17:13:44 INFO - Running setup.py install for blobuploader 17:13:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:13:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:13:44 INFO - Running setup.py install for requests 17:13:44 INFO - Running setup.py install for docopt 17:13:44 INFO - Successfully installed blobuploader requests docopt 17:13:44 INFO - Cleaning up... 17:13:44 INFO - Return code: 0 17:13:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:13: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')]} 17:13:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:13:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:13: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')]} 17:13:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:13:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:13:44 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 0x7f32563eaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xda48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe6f5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xefe410>, '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 0xeff310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeff7a0>, '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': '6d15e502d8e29d5d2676d664000001c3-1460765266.121002-399972084', '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 17:13:44 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 17:13:44 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 17:13:44 INFO - Using env: {'DISPLAY': ':0', 17:13:44 INFO - 'HOME': '/home/cltbld', 17:13:44 INFO - 'LANG': 'en_US.UTF-8', 17:13:44 INFO - 'LANGUAGE': 'en_US:en', 17:13:44 INFO - 'LOGNAME': 'cltbld', 17:13:44 INFO - 'MAIL': '/var/mail/cltbld', 17:13:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:13:44 INFO - 'MOZ_NO_REMOTE': '1', 17:13:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:13:44 INFO - 'NO_EM_RESTART': '1', 17:13:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:13:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:13:44 INFO - 'PWD': '/builds/slave/test', 17:13:44 INFO - 'SHELL': '/bin/bash', 17:13:44 INFO - 'SHLVL': '1', 17:13:44 INFO - 'TERM': 'linux', 17:13:44 INFO - 'TMOUT': '86400', 17:13:44 INFO - 'USER': 'cltbld', 17:13:44 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1460765266.121002-399972084', 17:13:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:13:44 INFO - '_': '/tools/buildbot/bin/python'} 17:13:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:13:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:13:44 INFO - Running setup.py (path:/tmp/pip-WfbMVk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:13:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:13:44 INFO - Running setup.py (path:/tmp/pip-woBC39-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:13:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:13:45 INFO - Running setup.py (path:/tmp/pip-kANh3f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:13:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:13:45 INFO - Running setup.py (path:/tmp/pip-Ui2PcC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:13:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:13:45 INFO - Running setup.py (path:/tmp/pip-CBzuXL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:13:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:13:45 INFO - Running setup.py (path:/tmp/pip-YyMw3P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:13:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:13:45 INFO - Running setup.py (path:/tmp/pip-jhPAG7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:13:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:13:45 INFO - Running setup.py (path:/tmp/pip-er2kLI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:13:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:13:45 INFO - Running setup.py (path:/tmp/pip-_VHoyb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:13:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:13:45 INFO - Running setup.py (path:/tmp/pip-hSTyx9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:13:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:13:45 INFO - Running setup.py (path:/tmp/pip-KHaRfI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:13:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:13:45 INFO - Running setup.py (path:/tmp/pip-5vc83j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:13:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:13:45 INFO - Running setup.py (path:/tmp/pip-8oMSmd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:13:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:13:45 INFO - Running setup.py (path:/tmp/pip-TeGCMc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:13:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:13:45 INFO - Running setup.py (path:/tmp/pip-lb7QZQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:13:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:13:45 INFO - Running setup.py (path:/tmp/pip-xlP4ZZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:13:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:13:46 INFO - Running setup.py (path:/tmp/pip-jEZSsn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:13:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:13:46 INFO - Running setup.py install for manifestparser 17:13:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:13:46 INFO - Running setup.py install for mozcrash 17:13:46 INFO - Running setup.py install for mozdebug 17:13:46 INFO - Running setup.py install for mozdevice 17:13:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:13:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:13:46 INFO - Running setup.py install for mozfile 17:13:46 INFO - Running setup.py install for mozhttpd 17:13:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:13:46 INFO - Running setup.py install for mozinfo 17:13:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:13:46 INFO - Running setup.py install for mozInstall 17:13:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:13:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:13:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:13:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:13:46 INFO - Running setup.py install for mozleak 17:13:46 INFO - Running setup.py install for mozlog 17:13:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:13:47 INFO - Running setup.py install for moznetwork 17:13:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:13:47 INFO - Running setup.py install for mozprocess 17:13:47 INFO - Running setup.py install for mozprofile 17:13:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:13:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:13:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:13:47 INFO - Running setup.py install for mozrunner 17:13:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:13:47 INFO - Running setup.py install for mozscreenshot 17:13:47 INFO - Running setup.py install for moztest 17:13:47 INFO - Running setup.py install for mozversion 17:13:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:13:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:13:47 INFO - Cleaning up... 17:13:47 INFO - Return code: 0 17:13:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:13:47 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')]} 17:13:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:13:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:13:47 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')]} 17:13:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:13:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:13:47 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 0x7f32563eaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xda48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe6f5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xefe410>, '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 0xeff310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeff7a0>, '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': '6d15e502d8e29d5d2676d664000001c3-1460765266.121002-399972084', '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 17:13:47 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 17:13:47 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 17:13:47 INFO - Using env: {'DISPLAY': ':0', 17:13:47 INFO - 'HOME': '/home/cltbld', 17:13:47 INFO - 'LANG': 'en_US.UTF-8', 17:13:47 INFO - 'LANGUAGE': 'en_US:en', 17:13:47 INFO - 'LOGNAME': 'cltbld', 17:13:47 INFO - 'MAIL': '/var/mail/cltbld', 17:13:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:13:47 INFO - 'MOZ_NO_REMOTE': '1', 17:13:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:13:47 INFO - 'NO_EM_RESTART': '1', 17:13:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:13:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:13:47 INFO - 'PWD': '/builds/slave/test', 17:13:47 INFO - 'SHELL': '/bin/bash', 17:13:47 INFO - 'SHLVL': '1', 17:13:47 INFO - 'TERM': 'linux', 17:13:47 INFO - 'TMOUT': '86400', 17:13:47 INFO - 'USER': 'cltbld', 17:13:47 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1460765266.121002-399972084', 17:13:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:13:47 INFO - '_': '/tools/buildbot/bin/python'} 17:13:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:13:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:13:48 INFO - Running setup.py (path:/tmp/pip-DRpbmv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:13:48 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)) 17:13:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:13:48 INFO - Running setup.py (path:/tmp/pip-UzlLz_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:13:48 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)) 17:13:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:13:48 INFO - Running setup.py (path:/tmp/pip-r6bRyY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:13:48 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)) 17:13:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:13:48 INFO - Running setup.py (path:/tmp/pip-tUfQlP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:13:48 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)) 17:13:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:13:48 INFO - Running setup.py (path:/tmp/pip-qbxEod-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:13:48 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)) 17:13:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:13:48 INFO - Running setup.py (path:/tmp/pip-ST0UEX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:13:48 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)) 17:13:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:13:48 INFO - Running setup.py (path:/tmp/pip-f7llJg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:13:48 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)) 17:13:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:13:48 INFO - Running setup.py (path:/tmp/pip-JZS2Rv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:13:48 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)) 17:13:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:13:48 INFO - Running setup.py (path:/tmp/pip-Oq9vZK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:13:48 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)) 17:13:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:13:48 INFO - Running setup.py (path:/tmp/pip-v1LQ2_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:13:48 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)) 17:13:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:13:48 INFO - Running setup.py (path:/tmp/pip-75xW8Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:13:48 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)) 17:13:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:13:48 INFO - Running setup.py (path:/tmp/pip-5eLQJk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:13:48 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)) 17:13:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:13:48 INFO - Running setup.py (path:/tmp/pip-mEnIUa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:13:49 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)) 17:13:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:13:49 INFO - Running setup.py (path:/tmp/pip-Gm2jLc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:13:49 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)) 17:13:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:13:49 INFO - Running setup.py (path:/tmp/pip-0B2bCE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:13:49 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)) 17:13:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:13:49 INFO - Running setup.py (path:/tmp/pip-6frZHG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:13:49 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)) 17:13:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:13:49 INFO - Running setup.py (path:/tmp/pip-fqtsWc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:13:49 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)) 17:13:49 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)) 17:13:49 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)) 17:13:49 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)) 17:13:49 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)) 17:13:49 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)) 17:13:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:13: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 17:13: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 17:13: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 17:13: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 17:13: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 17:13: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 17:13:51 INFO - Downloading blessings-1.6.tar.gz 17:13:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:13:51 INFO - Installing collected packages: blessings 17:13:51 INFO - Running setup.py install for blessings 17:13:51 INFO - Successfully installed blessings 17:13:51 INFO - Cleaning up... 17:13:51 INFO - Return code: 0 17:13:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:13:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:13:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:13:51 INFO - Reading from file tmpfile_stdout 17:13:51 INFO - Current package versions: 17:13:51 INFO - argparse == 1.2.1 17:13:51 INFO - blessings == 1.6 17:13:51 INFO - blobuploader == 1.2.4 17:13:51 INFO - docopt == 0.6.1 17:13:51 INFO - manifestparser == 1.1 17:13:51 INFO - mozInstall == 1.12 17:13:51 INFO - mozcrash == 0.17 17:13:51 INFO - mozdebug == 0.1 17:13:51 INFO - mozdevice == 0.48 17:13:51 INFO - mozfile == 1.2 17:13:51 INFO - mozhttpd == 0.7 17:13:51 INFO - mozinfo == 0.9 17:13:51 INFO - mozleak == 0.1 17:13:51 INFO - mozlog == 3.1 17:13:51 INFO - moznetwork == 0.27 17:13:51 INFO - mozprocess == 0.22 17:13:51 INFO - mozprofile == 0.28 17:13:51 INFO - mozrunner == 6.11 17:13:51 INFO - mozscreenshot == 0.1 17:13:51 INFO - mozsystemmonitor == 0.0 17:13:51 INFO - moztest == 0.7 17:13:51 INFO - mozversion == 1.4 17:13:51 INFO - psutil == 3.1.1 17:13:51 INFO - requests == 1.2.3 17:13:51 INFO - wsgiref == 0.1.2 17:13:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:13:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:13:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:13:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:13:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:13:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:13:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:13:51 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 0x7f32563eaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xda48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe6f5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xefe410>, '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 0xeff310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeff7a0>, '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': '6d15e502d8e29d5d2676d664000001c3-1460765266.121002-399972084', '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 17:13:51 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 17:13:51 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 17:13:51 INFO - Using env: {'DISPLAY': ':0', 17:13:51 INFO - 'HOME': '/home/cltbld', 17:13:51 INFO - 'LANG': 'en_US.UTF-8', 17:13:51 INFO - 'LANGUAGE': 'en_US:en', 17:13:51 INFO - 'LOGNAME': 'cltbld', 17:13:51 INFO - 'MAIL': '/var/mail/cltbld', 17:13:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:13:51 INFO - 'MOZ_NO_REMOTE': '1', 17:13:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:13:51 INFO - 'NO_EM_RESTART': '1', 17:13:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:13:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:13:51 INFO - 'PWD': '/builds/slave/test', 17:13:51 INFO - 'SHELL': '/bin/bash', 17:13:51 INFO - 'SHLVL': '1', 17:13:51 INFO - 'TERM': 'linux', 17:13:51 INFO - 'TMOUT': '86400', 17:13:51 INFO - 'USER': 'cltbld', 17:13:51 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1460765266.121002-399972084', 17:13:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:13:51 INFO - '_': '/tools/buildbot/bin/python'} 17:13:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:13:51 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)) 17:13:51 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)) 17:13:51 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)) 17:13:51 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)) 17:13:51 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)) 17:13:51 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)) 17:13:51 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)) 17:13:51 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)) 17:13:51 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)) 17:13:51 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)) 17:13:51 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)) 17:13:51 INFO - Cleaning up... 17:13:51 INFO - Return code: 0 17:13:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:13:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:13:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:13:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:13:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:13:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:13:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:13:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f32563eaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xda48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe6f5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xefe410>, '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 0xeff310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeff7a0>, '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': '6d15e502d8e29d5d2676d664000001c3-1460765266.121002-399972084', '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 17:13:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 17:13:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 17:13:51 INFO - Using env: {'DISPLAY': ':0', 17:13:51 INFO - 'HOME': '/home/cltbld', 17:13:51 INFO - 'LANG': 'en_US.UTF-8', 17:13:51 INFO - 'LANGUAGE': 'en_US:en', 17:13:51 INFO - 'LOGNAME': 'cltbld', 17:13:51 INFO - 'MAIL': '/var/mail/cltbld', 17:13:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:13:51 INFO - 'MOZ_NO_REMOTE': '1', 17:13:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:13:51 INFO - 'NO_EM_RESTART': '1', 17:13:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:13:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:13:51 INFO - 'PWD': '/builds/slave/test', 17:13:51 INFO - 'SHELL': '/bin/bash', 17:13:51 INFO - 'SHLVL': '1', 17:13:51 INFO - 'TERM': 'linux', 17:13:51 INFO - 'TMOUT': '86400', 17:13:51 INFO - 'USER': 'cltbld', 17:13:51 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1460765266.121002-399972084', 17:13:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:13:51 INFO - '_': '/tools/buildbot/bin/python'} 17:13:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:13:52 INFO - Downloading/unpacking jsonschema 17:13: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 17:13: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 17:13: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 17:13: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 17:13: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 17:13: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 17:13:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:13:53 INFO - Downloading/unpacking functools32 (from jsonschema) 17:13:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:13:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:13:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:13:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:13:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:13:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:13:54 INFO - Downloading functools32-3.2.3-2.tar.gz 17:13:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:13:54 INFO - warning: no files found matching '*.txt' 17:13:54 INFO - no previously-included directories found matching 'build' 17:13:54 INFO - no previously-included directories found matching 'dist' 17:13:54 INFO - no previously-included directories found matching '.git*' 17:13:54 INFO - Installing collected packages: jsonschema, functools32 17:13:54 INFO - Running setup.py install for functools32 17:13:54 INFO - warning: no files found matching '*.txt' 17:13:54 INFO - no previously-included directories found matching 'build' 17:13:54 INFO - no previously-included directories found matching 'dist' 17:13:54 INFO - no previously-included directories found matching '.git*' 17:13:54 INFO - Successfully installed jsonschema functools32 17:13:54 INFO - Cleaning up... 17:13:54 INFO - Return code: 0 17:13:54 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 17:13: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')]} 17:13:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:13:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:13: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')]} 17:13:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:13:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:13: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f32563eaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xda48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe6f5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xefe410>, '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 0xeff310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeff7a0>, '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': '6d15e502d8e29d5d2676d664000001c3-1460765266.121002-399972084', '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 17:13: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', 'flake8'] in /builds/slave/test/build 17:13: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 flake8 17:13:54 INFO - Using env: {'DISPLAY': ':0', 17:13:54 INFO - 'HOME': '/home/cltbld', 17:13:54 INFO - 'LANG': 'en_US.UTF-8', 17:13:54 INFO - 'LANGUAGE': 'en_US:en', 17:13:54 INFO - 'LOGNAME': 'cltbld', 17:13:54 INFO - 'MAIL': '/var/mail/cltbld', 17:13:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:13:54 INFO - 'MOZ_NO_REMOTE': '1', 17:13:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:13:54 INFO - 'NO_EM_RESTART': '1', 17:13:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:13:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:13:54 INFO - 'PWD': '/builds/slave/test', 17:13:54 INFO - 'SHELL': '/bin/bash', 17:13:54 INFO - 'SHLVL': '1', 17:13:54 INFO - 'TERM': 'linux', 17:13:54 INFO - 'TMOUT': '86400', 17:13:54 INFO - 'USER': 'cltbld', 17:13:54 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1460765266.121002-399972084', 17:13:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:13:54 INFO - '_': '/tools/buildbot/bin/python'} 17:13:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:13:54 INFO - Downloading/unpacking flake8 17:13: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 17:13: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 17:13: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 17:13: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 17:13: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 17:13: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 17:13:56 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 17:13:56 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 17:13: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 17:13: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 17:13: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 17:13: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 17:13: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 17:13: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 17:13:56 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 17:13:56 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 17:13: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 17:13: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 17:13: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 17:13: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 17:13: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 17:13: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 17:13:56 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 17:13: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 17:13: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 17:13: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 17:13: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 17:13: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 17:13: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 17:13:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 17:13:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:13:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:13:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:13:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:13:57 INFO - no previously-included directories found matching 'docs/_build' 17:13:57 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 17:13:57 INFO - Running setup.py install for pep8 17:13:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:13:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:13:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:13:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:13:57 INFO - no previously-included directories found matching 'docs/_build' 17:13:57 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 17:13:57 INFO - Successfully installed flake8 mccabe pyflakes pep8 17:13:57 INFO - Cleaning up... 17:13:57 INFO - Return code: 0 17:13:57 INFO - Running post-action listener: _resource_record_post_action 17:13:57 INFO - Running post-action listener: _start_resource_monitoring 17:13:57 INFO - Starting resource monitoring. 17:13:57 INFO - ##### 17:13:57 INFO - ##### Running install step. 17:13:57 INFO - ##### 17:13:57 INFO - Running pre-action listener: _resource_record_pre_action 17:13:57 INFO - Running main action method: install 17:13:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:13:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:13:57 INFO - Reading from file tmpfile_stdout 17:13:57 INFO - Detecting whether we're running mozinstall >=1.0... 17:13:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:13:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:13:57 INFO - Reading from file tmpfile_stdout 17:13:57 INFO - Output received: 17:13:57 INFO - Usage: mozinstall [options] installer 17:13:57 INFO - Options: 17:13:57 INFO - -h, --help show this help message and exit 17:13:57 INFO - -d DEST, --destination=DEST 17:13:57 INFO - Directory to install application into. [default: 17:13:57 INFO - "/builds/slave/test"] 17:13:57 INFO - --app=APP Application being installed. [default: firefox] 17:13:57 INFO - mkdir: /builds/slave/test/build/application 17:13:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 17:13:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 17:14:13 INFO - Reading from file tmpfile_stdout 17:14:13 INFO - Output received: 17:14:13 INFO - /builds/slave/test/build/application/firefox/firefox 17:14:13 INFO - Running post-action listener: _resource_record_post_action 17:14:13 INFO - ##### 17:14:13 INFO - ##### Running run-tests step. 17:14:13 INFO - ##### 17:14:13 INFO - Running pre-action listener: _resource_record_pre_action 17:14:13 INFO - Running main action method: run_tests 17:14:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:14:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:14:13 INFO - Python 2.7.3 17:14:13 INFO - Return code: 0 17:14:13 INFO - grabbing minidump binary from tooltool 17:14:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:14:13 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 0xefe410>, '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 0xeff310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xeff7a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:14:13 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 17:14:13 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 17:14:13 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:14:13 INFO - Return code: 0 17:14:13 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:14:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:14:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:14:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:14:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:14:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 17:14:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 17:14:14 INFO - Return code: 0 17:14:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/e6bqZlQ5RPyLqAUQsyVnbw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-023', '--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 17:14:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/e6bqZlQ5RPyLqAUQsyVnbw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-023 --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 17:14:14 INFO - Using env: {'DISPLAY': ':0', 17:14:14 INFO - 'HOME': '/home/cltbld', 17:14:14 INFO - 'LANG': 'en_US.UTF-8', 17:14:14 INFO - 'LANGUAGE': 'en_US:en', 17:14:14 INFO - 'LOGNAME': 'cltbld', 17:14:14 INFO - 'MAIL': '/var/mail/cltbld', 17:14:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:14:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:14:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:14:14 INFO - 'MOZ_NO_REMOTE': '1', 17:14:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:14:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:14:14 INFO - 'NO_EM_RESTART': '1', 17:14:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:14:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:14:14 INFO - 'PWD': '/builds/slave/test', 17:14:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:14:14 INFO - 'SHELL': '/bin/bash', 17:14:14 INFO - 'SHLVL': '1', 17:14:14 INFO - 'TERM': 'linux', 17:14:14 INFO - 'TMOUT': '86400', 17:14:14 INFO - 'USER': 'cltbld', 17:14:14 INFO - 'XDG_SESSION_COOKIE': '6d15e502d8e29d5d2676d664000001c3-1460765266.121002-399972084', 17:14:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:14:14 INFO - '_': '/tools/buildbot/bin/python'} 17:14:14 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/e6bqZlQ5RPyLqAUQsyVnbw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-023', '--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 17:14:14 INFO - mozversion application_buildid: 20160415154116 17:14:14 INFO - mozversion application_changeset: 29782c3286ca0d496b16cd3a7a0edd0741641d99 17:14:14 INFO - mozversion application_display_name: Nightly 17:14:14 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:14:14 INFO - mozversion application_name: Firefox 17:14:14 INFO - mozversion application_remotingname: firefox 17:14:14 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 17:14:14 INFO - mozversion application_vendor: Mozilla 17:14:14 INFO - mozversion application_version: 48.0a1 17:14:14 INFO - mozversion platform_buildid: 20160415154116 17:14:14 INFO - mozversion platform_changeset: 29782c3286ca0d496b16cd3a7a0edd0741641d99 17:14:14 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 17:14:14 INFO - mozversion platform_version: 48.0a1 17:14:14 INFO - using testdate: 1460765654 17:14:14 INFO - actual date: 1460765654 17:14:14 INFO - starting webserver on 'localhost:33470' 17:14:14 INFO - SUITE-START | Running 2 tests 17:14:14 INFO - TEST-START | dromaeo_css 17:14:14 INFO - operating with platform_type : linux_ 17:14:14 INFO - Initialising browser for dromaeo_css test... 17:14:14 INFO - TEST-INFO | started process 5690 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3DUjT1/profile http://localhost:33470/getInfo.html) 17:14:18 INFO - PROCESS | 5690 | 17:14:18 INFO - PROCESS | 5690 | (process:5755): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 17:14:18 INFO - PROCESS | 5690 | JavaScript error: jar:file:///builds/slave/test/build/application/firefox/omni.ja!/components/Weave.js, line 13: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIXPCComponents_Utils.import] 17:14:19 INFO - PROCESS | 5690 | __metrics Screen width/height:1600/1200 17:14:19 INFO - PROCESS | 5690 | colorDepth:24 17:14:19 INFO - PROCESS | 5690 | Browser inner width/height: 1024/697 17:14:19 INFO - PROCESS | 5690 | __metrics 17:14:20 ERROR - PROCESS | 5690 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 17:14:20 INFO - TEST-INFO | 5690: exit 0 17:14:20 INFO - Browser initialized. 17:14:20 INFO - Running cycle 1/1 for dromaeo_css test... 17:14:20 INFO - TEST-INFO | started process 5806 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3DUjT1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:14:22 INFO - PROCESS | 5806 | 17:14:22 INFO - PROCESS | 5806 | (process:5865): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 17:14:32 INFO - PROCESS | 5806 | RSS: Main: 164581376 17:14:32 INFO - PROCESS | 5806 | 17:15:22 INFO - PROCESS | 5806 | 1460765722427 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] 17:17:33 INFO - PROCESS | 5806 | Cycle 1(1): loaded http://localhost:33470/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33470/tests/dromaeo/cssquery-ext.html) 17:17:34 INFO - PROCESS | 5806 | RSS: Main: 167886848 17:17:34 INFO - PROCESS | 5806 | 17:20:36 INFO - PROCESS | 5806 | Cycle 1(1): loaded http://localhost:33470/tests/dromaeo/cssquery-ext.html (next: http://localhost:33470/tests/dromaeo/cssquery-jquery.html) 17:20:37 INFO - PROCESS | 5806 | RSS: Main: 166047744 17:20:37 INFO - PROCESS | 5806 | 17:23:39 INFO - PROCESS | 5806 | Cycle 1(1): loaded http://localhost:33470/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33470/tests/dromaeo/cssquery-mootools.html) 17:23:39 INFO - PROCESS | 5806 | RSS: Main: 167030784 17:23:39 INFO - PROCESS | 5806 | 17:26:41 INFO - PROCESS | 5806 | Cycle 1(1): loaded http://localhost:33470/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33470/tests/dromaeo/cssquery-prototype.html) 17:26:42 INFO - PROCESS | 5806 | RSS: Main: 164933632 17:26:42 INFO - PROCESS | 5806 | 17:29:44 INFO - PROCESS | 5806 | Cycle 1(1): loaded http://localhost:33470/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33470/tests/dromaeo/cssquery-yui.html) 17:29:45 INFO - PROCESS | 5806 | RSS: Main: 166211584 17:29:45 INFO - PROCESS | 5806 | 17:32:48 INFO - PROCESS | 5806 | Cycle 1(1): loaded http://localhost:33470/tests/dromaeo/cssquery-yui.html (next: http://localhost:33470/tests/dromaeo/cssquery-dojo.html) 17:32:48 INFO - PROCESS | 5806 | RSS: Main: 167854080 17:32:48 INFO - PROCESS | 5806 | 17:32:48 INFO - PROCESS | 5806 | __start_tp_report 17:32:48 INFO - PROCESS | 5806 | _x_x_mozilla_page_load 17:32:48 INFO - PROCESS | 5806 | _x_x_mozilla_page_load_details 17:32:48 INFO - PROCESS | 5806 | |i|pagename|runs| 17:32:48 INFO - PROCESS | 5806 | |0;dojo.html;2610.4518051209243;2674.5720684690446;2691.676998760149;2733.289344770359;2741.6709994800626;4705.2942058691215;4771.689840160388;4784.497627749084;4812.855614331575;4824.445188803289;4607.930881036778;4729;4767.308740232673;4780;4841.8305359312435;4367.497737760166;4480.596746292833;4487.237169681162;4488.573585509372;4490.461144662645;507.08333533224277;4326.101831750674;4342.4789025317;4396.912061758763;4429.402030725843;4355.934660980056;4388.648908087345;4390.8902277443;4397.758123303217;4423.579759922803;4150.27370210215;4396.252637051677;4445.888852778674;4464.910701785979;4488.102379524075;4344.4352234209355;4395.230834603932;4397.978010109961;4422.402975598286;4457.442819647544;4633.536646335373;4654.418197725285;4660.04469083837;4671.04243630055;4746.691465054761;4880.219164933629;5095.643304968654;5123.1546794778815;5131.871703207413;5133.589312854962;5104.081265372231;5126.897462050775;5127.717975511346;5131.3329267195395;5182.792688292464;4769.284607308862;4778.331033655291;4792.520747925215;4803.663743537919;4830.686005409672;5094.133997220481;5252;5291.597726602454;5292.364916210079;5336.252924590561;4937.975310123426;4954.1082605130705;4972.5027497249985;4996.7501624918605;5018.648694591417;5024.949750502521;5039.924401133986;5042.016806722654;5066.9746651266505;5098.413682426569;4915.5330243626795;5063.493650634907;5067.797288108434;5155.329807125125;5162.896742065138;4842.975785121052;5039.77321020555;5075.949240507622;5091.2363145527725;5131.7690703918415;4288.935665965013;4307.289297265916;4315.331123675835;4323.373110898902;4353.542877997765;3674.632536746304;3720.069982504374;3779.4897688811657;3808.504894363715;3808.9525380520577;4407.074514351958;4409.228385032607;4411.470623525133;4440.934175797785;4455.844045458393;5078.162103252922;5099.56653684441;5102.770375333044;5106.872328191825;5125.1287281162295;4292.098659281586;4369.628581570602;4408.228560001987;4435.3125265583885;4454.665900057508;4565.406497155415;4574.222382195035;4582.679212455163;4584.312353147055;4593.954060459419;4692.835750748844;4847.491013443679;4853.417589889165;4860.927086093782;4902.681325713817;5018.172001619838;5152.871178220575;5175.81884634051;5217.06092903281;5246.816361427332;4834.419869615669;4896.583790377716;4902.8774280643265;4905.90188196227;4968.056069346812;4858.295547145573;5077.086124497626;5119.74401279942;5126.4360920297995;5130.204818253177;5068.721220332917;5101.311322971352;5116.769745361393;5169.689818610896;5178.404483484447;4953.18272485036;4964.726940018334;4972.627052970969;4989.077020751172;4992.201247800334;4750.501197374226;4944;4977.4773648767805;4997.925031124609;5020.7991680333835;4721.386219791405;4865.683730557503;4896.143174944442;4918.631102667243;4944.060628480577;5026.271190677405;5037;5046.091703493406;5052.216906379517;5115.462876397924;4735.573798358164;4937.382827146606;4956.281339205867;4958;5002.724850133278 17:32:48 INFO - PROCESS | 5806 | |1;ext.html;586.8796896636187;599.9310079340877;605.2222893581747;622.1321256846699;639.8592309691866;33009.339813203755;33824.83087584565;34025.82987085061;34051.318973620575;34054;33505.162370940736;33703.15742106448;33897.830510847474;33986;34163.82918085406;7372.189059203497;7504.962475187616;7534.472586918918;7556.47104702671;7565.35694465971;7598.8480230395635;7690.384769218449;7730.18833022533;7735.342495887856;7743.806404839867;7674.884876726854;7767.145613982458;7790.766277011699;7805.921940780577;7808.375329973588;7679.424043196755;7705.267999540035;7735.955645987776;7754.457187996843;7760.991071160741;7556.622168891534;7662.885056724153;7663.3486153677;7664.080310362792;7665.616719164075;29227.70772292271;35051.1237219069;35441.82279088614;35535.28929421423;35544.28911421783;2955.5271156615054;3086.783925125242;3117.1427857339186;3207.951880721791;3285.342931413727;25012.874935625387;25791;26349.3412664683;26405;26682.066127685634;3435.2957643683235;3439.914002149966;3475.9652403475893;3521.841517131735;3545.8227088645463;198679;200919;201620;201838.99999999854;202048;33861.153471163416;53045.204321935205;53368.73315633397;53476.73261633667;53479.73260133674;33939.32121357559;34130.65869341275;34196;34244;34277.657223427945;13532.661683457993;13539.187648741106;13653.726925461633;13724.107932984323;13741.931290343684;11139.665810025712;11191.832122518168;11201.887981120248;11229.157813164045;11235.494402751896;8060.476069055492;8132.227438393344;8153.8776918346275;8208.466449680871;8235.547044912588;3163.3040731039137;3223.435898717715;3257.478803391446;3275;3277.721393681516;893.647009431284;900.6082354175954;951.286535098676;975.4001289207087;979.3340528440729;26656.733432665813;27890;28356.43287134287;28441.288967775556;28738.712612873605;994.5425104451889;1008.1682611845257;1011.4841430870309;1049.18163832211;1077.6766969908997;14682.55952321432;14725.116493010453;15017.624559385884;15218.391264349524;15223.31495082724;336246;338297;339886.60113399045;340590;340854;46328.768356158005;47016;47113.5288647116;47151.29273060907;47412.52587474081;1203.9179354045987;1212.2241765270235;1235.4008305971597;1272.739088486858;1284.9614511564855;26517;27648.44703105967;27699.86150069237;27872.303192420353;28268.293292667026;42327.15345693131;42621;42741;42826.357604635334;42858.78570607127;303695;305106;305579;305589;305811;25375.36561585975;25729;25910.222693319232;26029.6095558563;38941.221175576895;35971.82014089913;36586.8170659145;36628.45057324089;36862.8156859214;37011.81494092512;31617.52573711442;31628;31993.840030800628;32452.35095298128;32950.340993179525;34064.65935340714;34276.657233427344;34432;34723.30553388969;34791.65208347884;30051;30486.542701859045;36157.81921090378;37457.81271093627;37497.25005499929;26779.661016949463;31153.68846311508;31227.375452491277;31345.69973118693;31465.528017079305;24550.877245613658;24569.754302457462;24768.876155619106;24797.62803557984;24962.126325579244 17:32:48 INFO - PROCESS | 5806 | |2;jquery.html;382.35954775750616;382.6517868739447;385.5777923174125;386.6293987813202;394.4083874188717;15298.541043768691;16240;16875.734319925992;16875.91562042188;16898.40855570054;17031.82968170318;17475.563110922234;17823.287068517242;18055.187516561753;18667.62664746704;5640.407757185488;5837;5852.209951656514;5883.970305196595;5884.529237660999;4074.5314288856716;6737.258901520853;7108.537945033582;7117.110361204849;7122.821929451753;6876.69054892531;6958.582485050888;7014.859702805941;7093.32613401726;7135.108111486064;6699.464042876558;6951.58290502569;6971.3028697130385;6999.405050570707;6999.510034297601;6946.513744037919;6960.547564408348;6975.651217439158;6985.860282794316;6989.196242432135;20218.393448196577;21628.89185554078;21720.34838954818;21748.347549573537;21838.126474940982;1996.9700454493195;2069.4826293426645;2086.8017538333834;2113.0913707105938;2242.338510139513;12919.22484650924;13756.518521851687;13893.235872027035;14013.929930350385;14603.78094328586;1951.6291904538093;2012.7685316188533;2038.7043878637662;2041.530447996954;2054.8356131509445;61462.69268653673;64392.35607643911;65147;67100.6644966772;77805;16035.278412471467;16518.917405413136;16596.17019149038;16634.16829158537;17087.487375378758;29816;29831.85084074609;29922.55116173259;30065.849670751508;30251.697483024887;8919.509426981449;8956.865647015169;9074.45553266806;9193.172614464731;9198.218151457068;7492.550446973199;7647.808804779925;7707.306342429209;7777.805554861174;7941.126476087626;3567.500549923013;3882.9223415532097;8522.403431759842;8536.774296723162;8643.524606146722;1834.1929550997518;1851.6759567075708;1877.1271358818215;1883.51970247586;1889.6315218532254;383.2029378891898;384.5865694378554;392.6701570680585;825.3974598543098;890.6570970176399;9368.718938431857;10270.383776973405;10283.28017038815;10373.429461379546;10521.158307335394;404.96152865477734;407.4702886247866;409.9426080348754;410.2512913932086;411.53291014697936;5104.183330667075;5534.976029434568;5554.277943867352;5609.214709940612;5631.690257035821;370560;371846;384127;394710;404160;76309;78700.21299787043;80107.19892801114;80355;81917.18082819096;426.74608607878264;432.0257818618994;436.310629205854;436.4130244820711;438.76306794331344;9348.298877584344;9432.905670943477;9533.47565883883;9645.324827262024;9694.75763105928;29197.270068248465;30061.69938300589;31347.216319592193;32172.678273216967;32738;48764.26853597128;48971.26543101785;49182;49352.50647493479;49362.75318623384;7032.894506582507;7184.533005354635;7212.134543854771;7502.812429689301;7519.43604229674;7554.357879580297;8455.788605284917;9735.490998895182;9882.308238423242;10335.534900929626;9029.322800790098;9113.77215569616;9192.632294708137;9440.527973601429;9583.568739406604;9049.683261085831;9053.296911450981;9057.728268151966;9358.11097945694;9409.200217981548;9170.816583668422;9208.769780755534;9362.531873406166;9651.758706032406;9726.31915765918;7118.679659415235;7136.892946605909;7240.96379518099;7257.310555497219;7434.7769566913075;8262.669493220445;9493.335466517277;9535.380200287236;9580.233581313618;9593.952030240083 17:32:48 INFO - PROCESS | 5806 | |3;mootools.html;1386.625611085007;1443.509206869664;1548.256836718375;1722.3024675006611;1763.2153691607232;6995.440364770819;7561.962190189055;7769.223077692228;7813.726519571823;7826.60866956652;7785.455018148732;7792.961035194816;7862.803429914255;7879.684812607503;7930.762077137695;2252.256755270765;2266.478709896725;2282.041542552132;2285.9771402285933;2290.702208712865;2193.6599827026757;2196.066671664544;2213.4023813570325;2236.2508559632543;2246.8988895499742;2167.0464995402053;2173.7500187478486;2176.564687062586;2182.4216582605623;2183.945401364963;2158.816500597451;2171.5873983943;2174.652055671093;2177.7060096886953;2179.6730490426403;2151.064287035129;2151.9784802151935;2157.503774131943;2166.0361139293022;2171.4137182960512;7782.299593036654;7830.2952734254195;7850.9214907850765;7896.131425543185;7899.05211374633;4568.406107206042;4668.766561671937;4677.672562920597;4708.693934894255;4726.621870250372;2888.234617826278;2903.187107609873;2924.1666125154443;2979.493486107367;2997.430488207234;885.1900511032397;896.3456676626034;898.3262553085186;911.118540214455;911.1503647595392;10309.432981186032;10665.093467055309;10668.359898406121;10690;10702.090322322612;4080.387941808693;4278.871633850989;4280.464941882265;4285.528591854893;4296.4414626098405;8582.055973842873;8595.011573669108;8616.39685222041;8622.396432249809;8650.480971141626;4713.57577817998;4721.716696998191;4727.030958653468;4734.834280800155;4743.0988112258565;3820.4078367853026;4326.913461730748;4341.413909122291;4359.585839345257;4365.083332500147;3494.1614012636783;3535.1338921964284;3536.5402497675136;3563.1626567756557;3647.1976165243605;1080.2654195147375;1088.5591335509132;1100.5597760895705;1102.3881745631252;1109.8557187565564;704.4610872682402;713.7930000299958;862.7713655881197;867.6355930509201;869.4739682492001;4987.426445958688;5032.823851165192;5056.216286475602;5098.949010509921;5170.793168273227;879.331707901999;885.5926273913943;895.7626229049307;898.4205187654057;898.9056149104381;3573.2317551726505;3583.104223944014;3604.6575575320303;3615.7830530168276;3710.4805327254207;9619.951900240454;9871.25965552572;9886.80226395468;9937.354071985297;9988.101070903653;9721.173700235558;9801.215902727765;9840.261980351503;9871.309008369346;9874.950625246971;7042.6830792614455;7369.373603243785;7371.373433258126;7378.667959941816;7383.593902335316;5118.846434606968;5142.5885929126325;5240.161574148118;5257.710825904612;5308.283381743415;8555.401121921406;8807.295416366804;8815;8894.733158005012;8900.465972041698;8878.73363799087;9098.636054557743;9182.494962776844;9201.493917834583;9343.112404321579;6632.701528431136;6744.494162937701;6800.863982720417;6830.487713421613;6879.036934829228;7222.241664625349;7243.855122897618;7260.818479538054;7271.600061996641;7298.96350518244;6519.3480651936325;6527.477801775752;6539.771108011387;6619.602823830585;6697.196336439659;6349;6366.045093235866;6392.296847346695;6500.382463665944;6506.577072490273;6103.969480152571;6142.662153581596;6180.103884936569;6186.1030150627;6188.8762224754355;4467.285234362486;4521.5252398499;4557.043020965634;4596.057808149389;4643.048175124159;4513.0522590255105;4562.5665561771575;4580.038191979588;4602.7468489233415;4636.907261854812 17:32:48 INFO - PROCESS | 5806 | |4;prototype.html;310.998445007775;322.9935401291974;323.5373416015098;330.3525090821989;330.61978724466843;5283.762230699618;5297.814576489824;5327.600429967758;5337.172738225577;5365.382980957191;5217.895642087156;5228.8692782680355;5263.736703191235;5267.841964741064;5286.841394758154;3917.4515567820526;4052.2098190852794;4157.52188498322;4172.457580514529;4174.415581818548;4143.958560414402;4165.791710414483;4172.874813755592;4178.059936514291;4181.853635122756;4110.6300432961025;4144.8756537303925;4158.625723684868;4162.583741625844;4164.791760411982;4060.8375664973514;4112.629863312286;4143.19207754488;4152.563980782004;4160.791960401983;3618.6200448952745;3632.891013269606;3639.3267245559655;3644.562652481719;3650.8722194723323;5035.672681275705;5102.821401250939;5103.617228707862;5122.487751224885;5138.306328645623;4460.130274596455;4717.858464246078;4817.060673168733;4818.494058123882;4838.274258861164;5553.444655534455;5561.110222364402;5582.106862901956;5587.7485513152005;5601.29983752031;3896.649301562845;3904.511936007999;3906.7655940643654;3908.4332771748223;3917.5886531914307;5576.163575463673;5839.620424672383;5881.206037185011;5883.764649414018;5901.734421951065;3970.8411663533134;3976.5228172619472;3986.8405263789123;3992.6606238469476;4012.879613611596;4059.959400405958;4062.512498500199;4073.5926407359025;4079.714419990572;4085.9182816343505;4590.760494666421;4694.413198350207;4695.460022097503;4696.4501714434155;4699.859004229878;5600.4959553640365;5613.915791263134;5616.455474744396;5634.915476267859;5638;1401.1102949627061;1424.7391058912938;1428.4857451317516;1431.2414420357227;1433.4123009566028;872.4416373520825;917.3211823250747;937.4843835890233;957.0094204055548;964.0744884910564;4807;4825.638077144228;4848.733319667382;4854.902901941941;4874.7562621868765;5749;5764.682942438206;5786.305643322744;5786.971065144648;5800.8259752207505;4654.325122857234;4831.429785319785;4864.562189402971;4882.170031094652;4917.409910810654;3098.1325228935934;3127.2807254331633;3136.764742644311;3141.607299087614;3151.11768704763;5460.945390546123;5473.781048758085;5474.39781624021;5481.369642491165;5495.505404513613;5868.325142608569;5968.343482216953;5970.820875373659;5992.191054207713;6021.458068773743;5454.754536045887;5467.152591348347;5470.288862447856;5475.698836564027;5480.780768769284;5669.6314739541795;5776.249087618583;5827.8834423310445;5834.94165058361;5857.5313974881055;5674.914876276776;5689.288838895138;5733.426657334233;5738.856528586819;5745.770169193185;5454.454554544673;5460.481254280837;5470.671759694431;5497.120460726264;5514.558835293247;3801.4297855321925;3949.190415964778;3962.128331767007;4027.496562929634;4069.2471892699946;5775.768969241351;5781.913271300849;5797.507211887037;5815.970920145542;5819.6508209507565;5595.888082238251;5613.129964855454;5620.1288800236025;5620.381758006698;5624.775008999594;5482.23248745184;5568.220449137043;5569.387367389503;5607.831765047055;5629.746661400329;5304;5463.562914966873;5513.283273174462;5521.889562208814;5523.475269849358;5422.15956526739;5648.406917273792;5673.716314184192;5680.886382272414;5730.590298772224;5501.587380946525;5512.118061110203;5562.415946325577;5571.749271282721;5577.832665020056 17:32:48 INFO - PROCESS | 5806 | |5;yui.html;736.2931585677749;775.3409601838434;780.8008957715782;783.502475927786;797.0594698256062;12984.28586427746;13712.725745485084;13813.723725525497;13822.930885345559;13877.930610346933;13759.93120034401;13782.310884455763;13816.516421925235;13860.584182474517;13937.860621393784;654.7446495866616;799.0571126071234;821.5112008355019;829.3199576347398;832.014063334947;809.4131754477986;810.1776696652902;812.0742353716768;816.7713040348712;820.335528222139;858.6908712863365;861.2851333393269;864.9783755406103;867.1458613265928;867.2541614211805;854.7435769269225;856.9785755356135;860.5439117267827;860.926821220197;863.9308855291562;617.1360095865779;620.9534284928649;622.551762730833;623.1867413025999;623.9906401403982;12905.548305809252;12988;13034.217946923214;13060.412281447358;13081;12496.312702801342;12548.372581370986;12560.748785024249;12620.495180192782;12959.935200323938;9152.267818574499;11096.33421994683;11520.539178432853;11635.709107272301;11712.063034957184;3219.96780032199;3256.1859535116223;3289.687479689426;3293.875450334532;3333.5666363372848;46717.766411168064;47084;47283.76358118222;47521;47655;9578.808423831484;9579.425234485952;9630.903690963141;9688.273379496566;9753.707388778348;16417.671646567003;16466.506004819876;16539.586510337336;16542;16645.0845203515;6300;6304.905426418516;6327.3988971047675;6361.268454127742;6514.706838192293;3583.802890841029;3594.5686517617532;3879.6702280305926;3928.744631598937;3977.0455090778005;2829.943401131966;2889.5232286672463;2919.6058532097895;2924.6344206974127;2929.5019846626124;490.07131485834856;504.0548970679975;512.18562485648;513.7919142747119;520.4041372628395;552.3565046720606;564.3566334378811;567.6781961776272;574.12159396124;587.9088741245114;7827.608619568999;8183.181681831769;8195.836083278302;8351.3736469765;8492.915070849336;624.6345887655682;643.784332248702;648.230331195838;658.5456035335603;660.2341284110408;3871.2838124946975;3955.5253369595257;3961.187956468975;3974.841006359771;4095.365218391154;46478.535214648095;50992.74503627458;52225.47774522206;52796;53088.203676944875;43151.56848431538;44226.33660495095;44491.33263001058;44671.55328446674;44806.55193448089;42670;43687.563124368986;43719.56280437219;44225.33661995008;45306;8428.620712068094;8578.457055322006;8610.78473038179;8702.390832641655;8706.129387061243;28433.857830710713;28731;29283;29376.559351609314;29526.40947181087;36445;37627.62372376351;37761.62238377581;37762;38025.80987095047;11290.322580645188;11290.717732056764;11414.372209528556;11616.244944078608;11701.180917335705;11961.162718609614;12075.335856527623;12092.334921579397;12314.322712250912;12441.315727635067;11212.27120237182;11388.14588905852;11528.827067593826;11529.192956492965;11820.172587919107;11226.719332016439;11581.478833452345;12032.819507707567;12057.336846473178;12080.879191207976;10464.738381540523;10511.684649460529;10802.297850639683;10928.398938058483;10931.617393391192;9827.459489728133;10019.849702254618;10125.949370253102;10146;10186.643467478603;9464.384814987005;9592.376495527767;10009.099181073738;10243.795124097327;10301.278910476194 17:32:48 INFO - PROCESS | 5806 | __end_tp_report 17:32:48 INFO - PROCESS | 5806 | __start_cc_report 17:32:48 INFO - PROCESS | 5806 | _x_x_mozilla_cycle_collect,360 17:32:48 INFO - PROCESS | 5806 | __end_cc_report 17:32:48 INFO - PROCESS | 5806 | __startTimestamp1460766768425__endTimestamp 17:32:48 INFO - PROCESS | 5806 | 17:32:48 INFO - PROCESS | 5806 | ------- Summary: start ------- 17:32:48 INFO - PROCESS | 5806 | Number of tests: 6 17:32:48 INFO - PROCESS | 5806 | 17:32:48 INFO - PROCESS | 5806 | [#0] dojo.html Cycles:165 Average:4695.34 Median:4858.30 stddev:592.35 (12.2%) stddev-sans-first:571.14 17:32:48 INFO - PROCESS | 5806 | Values: 2610.5 2674.6 2691.7 2733.3 2741.7 4705.3 4771.7 4784.5 4812.9 4824.4 4607.9 4729.0 4767.3 4780.0 4841.8 4367.5 4480.6 4487.2 4488.6 4490.5 507.1 4326.1 4342.5 4396.9 4429.4 4355.9 4388.6 4390.9 4397.8 4423.6 4150.3 4396.3 4445.9 4464.9 4488.1 4344.4 4395.2 4398.0 4422.4 4457.4 4633.5 4654.4 4660.0 4671.0 4746.7 4880.2 5095.6 5123.2 5131.9 5133.6 5104.1 5126.9 5127.7 5131.3 5182.8 4769.3 4778.3 4792.5 4803.7 4830.7 5094.1 5252.0 5291.6 5292.4 5336.3 4938.0 4954.1 4972.5 4996.8 5018.6 5024.9 5039.9 5042.0 5067.0 5098.4 4915.5 5063.5 5067.8 5155.3 5162.9 4843.0 5039.8 5075.9 5091.2 5131.8 4288.9 4307.3 4315.3 4323.4 4353.5 3674.6 3720.1 3779.5 3808.5 3809.0 4407.1 4409.2 4411.5 4440.9 4455.8 5078.2 5099.6 5102.8 5106.9 5125.1 4292.1 4369.6 4408.2 4435.3 4454.7 4565.4 4574.2 4582.7 4584.3 4594.0 4692.8 4847.5 4853.4 4860.9 4902.7 5018.2 5152.9 5175.8 5217.1 5246.8 4834.4 4896.6 4902.9 4905.9 4968.1 4858.3 5077.1 5119.7 5126.4 5130.2 5068.7 5101.3 5116.8 5169.7 5178.4 4953.2 4964.7 4972.6 4989.1 4992.2 4750.5 4944.0 4977.5 4997.9 5020.8 4721.4 4865.7 4896.1 4918.6 4944.1 5026.3 5037.0 5046.1 5052.2 5115.5 4735.6 4937.4 4956.3 4958.0 5002.7 17:32:48 INFO - PROCESS | 5806 | 17:32:48 INFO - PROCESS | 5806 | [#1] ext.html Cycles:180 Average:42309.57 Median:186936.30 stddev:75778.50 (40.5%) stddev-sans-first:75926.33 17:32:48 INFO - PROCESS | 5806 | Values: 586.9 599.9 605.2 622.1 639.9 33009.3 33824.8 34025.8 34051.3 34054.0 33505.2 33703.2 33897.8 33986.0 34163.8 7372.2 7505.0 7534.5 7556.5 7565.4 7598.8 7690.4 7730.2 7735.3 7743.8 7674.9 7767.1 7790.8 7805.9 7808.4 7679.4 7705.3 7736.0 7754.5 7761.0 7556.6 7662.9 7663.3 7664.1 7665.6 29227.7 35051.1 35441.8 35535.3 35544.3 2955.5 3086.8 3117.1 3208.0 3285.3 25012.9 25791.0 26349.3 26405.0 26682.1 3435.3 3439.9 3476.0 3521.8 3545.8 198679.0 200919.0 201620.0 201839.0 202048.0 33861.2 53045.2 53368.7 53476.7 53479.7 33939.3 34130.7 34196.0 34244.0 34277.7 13532.7 13539.2 13653.7 13724.1 13741.9 11139.7 11191.8 11201.9 11229.2 11235.5 8060.5 8132.2 8153.9 8208.5 8235.5 3163.3 3223.4 3257.5 3275.0 3277.7 893.6 900.6 951.3 975.4 979.3 26656.7 27890.0 28356.4 28441.3 28738.7 994.5 1008.2 1011.5 1049.2 1077.7 14682.6 14725.1 15017.6 15218.4 15223.3 336246.0 338297.0 339886.6 340590.0 340854.0 46328.8 47016.0 47113.5 47151.3 47412.5 1203.9 1212.2 1235.4 1272.7 1285.0 26517.0 27648.4 27699.9 27872.3 28268.3 42327.2 42621.0 42741.0 42826.4 42858.8 303695.0 305106.0 305579.0 305589.0 305811.0 25375.4 25729.0 25910.2 26029.6 38941.2 35971.8 36586.8 36628.5 36862.8 37011.8 31617.5 31628.0 31993.8 32452.4 32950.3 34064.7 34276.7 34432.0 34723.3 34791.7 30051.0 30486.5 36157.8 37457.8 37497.3 26779.7 31153.7 31227.4 31345.7 31465.5 24550.9 24569.8 24768.9 24797.6 24962.1 17:32:48 INFO - PROCESS | 5806 | 17:32:48 INFO - PROCESS | 5806 | [#2] jquery.html Cycles:180 Average:24402.33 Median:5636.05 stddev:63612.77 (1128.7%) stddev-sans-first:63765.65 17:32:48 INFO - PROCESS | 5806 | Values: 382.4 382.7 385.6 386.6 394.4 15298.5 16240.0 16875.7 16875.9 16898.4 17031.8 17475.6 17823.3 18055.2 18667.6 5640.4 5837.0 5852.2 5884.0 5884.5 4074.5 6737.3 7108.5 7117.1 7122.8 6876.7 6958.6 7014.9 7093.3 7135.1 6699.5 6951.6 6971.3 6999.4 6999.5 6946.5 6960.5 6975.7 6985.9 6989.2 20218.4 21628.9 21720.3 21748.3 21838.1 1997.0 2069.5 2086.8 2113.1 2242.3 12919.2 13756.5 13893.2 14013.9 14603.8 1951.6 2012.8 2038.7 2041.5 2054.8 61462.7 64392.4 65147.0 67100.7 77805.0 16035.3 16518.9 16596.2 16634.2 17087.5 29816.0 29831.9 29922.6 30065.8 30251.7 8919.5 8956.9 9074.5 9193.2 9198.2 7492.6 7647.8 7707.3 7777.8 7941.1 3567.5 3882.9 8522.4 8536.8 8643.5 1834.2 1851.7 1877.1 1883.5 1889.6 383.2 384.6 392.7 825.4 890.7 9368.7 10270.4 10283.3 10373.4 10521.2 405.0 407.5 409.9 410.3 411.5 5104.2 5535.0 5554.3 5609.2 5631.7 370560.0 371846.0 384127.0 394710.0 404160.0 76309.0 78700.2 80107.2 80355.0 81917.2 426.7 432.0 436.3 436.4 438.8 9348.3 9432.9 9533.5 9645.3 9694.8 29197.3 30061.7 31347.2 32172.7 32738.0 48764.3 48971.3 49182.0 49352.5 49362.8 7032.9 7184.5 7212.1 7502.8 7519.4 7554.4 8455.8 9735.5 9882.3 10335.5 9029.3 9113.8 9192.6 9440.5 9583.6 9049.7 9053.3 9057.7 9358.1 9409.2 9170.8 9208.8 9362.5 9651.8 9726.3 7118.7 7136.9 7241.0 7257.3 7434.8 8262.7 9493.3 9535.4 9580.2 9594.0 17:32:48 INFO - PROCESS | 5806 | 17:32:48 INFO - PROCESS | 5806 | [#3] mootools.html Cycles:180 Average:5120.14 Median:5156.69 stddev:2859.12 (55.4%) stddev-sans-first:2853.38 17:32:48 INFO - PROCESS | 5806 | Values: 1386.6 1443.5 1548.3 1722.3 1763.2 6995.4 7562.0 7769.2 7813.7 7826.6 7785.5 7793.0 7862.8 7879.7 7930.8 2252.3 2266.5 2282.0 2286.0 2290.7 2193.7 2196.1 2213.4 2236.3 2246.9 2167.0 2173.8 2176.6 2182.4 2183.9 2158.8 2171.6 2174.7 2177.7 2179.7 2151.1 2152.0 2157.5 2166.0 2171.4 7782.3 7830.3 7850.9 7896.1 7899.1 4568.4 4668.8 4677.7 4708.7 4726.6 2888.2 2903.2 2924.2 2979.5 2997.4 885.2 896.3 898.3 911.1 911.2 10309.4 10665.1 10668.4 10690.0 10702.1 4080.4 4278.9 4280.5 4285.5 4296.4 8582.1 8595.0 8616.4 8622.4 8650.5 4713.6 4721.7 4727.0 4734.8 4743.1 3820.4 4326.9 4341.4 4359.6 4365.1 3494.2 3535.1 3536.5 3563.2 3647.2 1080.3 1088.6 1100.6 1102.4 1109.9 704.5 713.8 862.8 867.6 869.5 4987.4 5032.8 5056.2 5098.9 5170.8 879.3 885.6 895.8 898.4 898.9 3573.2 3583.1 3604.7 3615.8 3710.5 9620.0 9871.3 9886.8 9937.4 9988.1 9721.2 9801.2 9840.3 9871.3 9875.0 7042.7 7369.4 7371.4 7378.7 7383.6 5118.8 5142.6 5240.2 5257.7 5308.3 8555.4 8807.3 8815.0 8894.7 8900.5 8878.7 9098.6 9182.5 9201.5 9343.1 6632.7 6744.5 6800.9 6830.5 6879.0 7222.2 7243.9 7260.8 7271.6 7299.0 6519.3 6527.5 6539.8 6619.6 6697.2 6349.0 6366.0 6392.3 6500.4 6506.6 6104.0 6142.7 6180.1 6186.1 6188.9 4467.3 4521.5 4557.0 4596.1 4643.0 4513.1 4562.6 4580.0 4602.7 4636.9 17:32:48 INFO - PROCESS | 5806 | 17:32:48 INFO - PROCESS | 5806 | [#4] prototype.html Cycles:180 Average:4638.45 Median:5292.33 stddev:1363.26 (25.8%) stddev-sans-first:1327.83 17:32:48 INFO - PROCESS | 5806 | Values: 311.0 323.0 323.5 330.4 330.6 5283.8 5297.8 5327.6 5337.2 5365.4 5217.9 5228.9 5263.7 5267.8 5286.8 3917.5 4052.2 4157.5 4172.5 4174.4 4144.0 4165.8 4172.9 4178.1 4181.9 4110.6 4144.9 4158.6 4162.6 4164.8 4060.8 4112.6 4143.2 4152.6 4160.8 3618.6 3632.9 3639.3 3644.6 3650.9 5035.7 5102.8 5103.6 5122.5 5138.3 4460.1 4717.9 4817.1 4818.5 4838.3 5553.4 5561.1 5582.1 5587.7 5601.3 3896.6 3904.5 3906.8 3908.4 3917.6 5576.2 5839.6 5881.2 5883.8 5901.7 3970.8 3976.5 3986.8 3992.7 4012.9 4060.0 4062.5 4073.6 4079.7 4085.9 4590.8 4694.4 4695.5 4696.5 4699.9 5600.5 5613.9 5616.5 5634.9 5638.0 1401.1 1424.7 1428.5 1431.2 1433.4 872.4 917.3 937.5 957.0 964.1 4807.0 4825.6 4848.7 4854.9 4874.8 5749.0 5764.7 5786.3 5787.0 5800.8 4654.3 4831.4 4864.6 4882.2 4917.4 3098.1 3127.3 3136.8 3141.6 3151.1 5460.9 5473.8 5474.4 5481.4 5495.5 5868.3 5968.3 5970.8 5992.2 6021.5 5454.8 5467.2 5470.3 5475.7 5480.8 5669.6 5776.2 5827.9 5834.9 5857.5 5674.9 5689.3 5733.4 5738.9 5745.8 5454.5 5460.5 5470.7 5497.1 5514.6 3801.4 3949.2 3962.1 4027.5 4069.2 5775.8 5781.9 5797.5 5816.0 5819.7 5595.9 5613.1 5620.1 5620.4 5624.8 5482.2 5568.2 5569.4 5607.8 5629.7 5304.0 5463.6 5513.3 5521.9 5523.5 5422.2 5648.4 5673.7 5680.9 5730.6 5501.6 5512.1 5562.4 5571.7 5577.8 17:32:48 INFO - PROCESS | 5806 | 17:32:48 INFO - PROCESS | 5806 | [#5] yui.html Cycles:180 Average:12907.69 Median:23382.68 stddev:14274.53 (61.0%) stddev-sans-first:14285.30 17:32:48 INFO - PROCESS | 5806 | Values: 736.3 775.3 780.8 783.5 797.1 12984.3 13712.7 13813.7 13822.9 13877.9 13759.9 13782.3 13816.5 13860.6 13937.9 654.7 799.1 821.5 829.3 832.0 809.4 810.2 812.1 816.8 820.3 858.7 861.3 865.0 867.1 867.3 854.7 857.0 860.5 860.9 863.9 617.1 621.0 622.6 623.2 624.0 12905.5 12988.0 13034.2 13060.4 13081.0 12496.3 12548.4 12560.7 12620.5 12959.9 9152.3 11096.3 11520.5 11635.7 11712.1 3220.0 3256.2 3289.7 3293.9 3333.6 46717.8 47084.0 47283.8 47521.0 47655.0 9578.8 9579.4 9630.9 9688.3 9753.7 16417.7 16466.5 16539.6 16542.0 16645.1 6300.0 6304.9 6327.4 6361.3 6514.7 3583.8 3594.6 3879.7 3928.7 3977.0 2829.9 2889.5 2919.6 2924.6 2929.5 490.1 504.1 512.2 513.8 520.4 552.4 564.4 567.7 574.1 587.9 7827.6 8183.2 8195.8 8351.4 8492.9 624.6 643.8 648.2 658.5 660.2 3871.3 3955.5 3961.2 3974.8 4095.4 46478.5 50992.7 52225.5 52796.0 53088.2 43151.6 44226.3 44491.3 44671.6 44806.6 42670.0 43687.6 43719.6 44225.3 45306.0 8428.6 8578.5 8610.8 8702.4 8706.1 28433.9 28731.0 29283.0 29376.6 29526.4 36445.0 37627.6 37761.6 37762.0 38025.8 11290.3 11290.7 11414.4 11616.2 11701.2 11961.2 12075.3 12092.3 12314.3 12441.3 11212.3 11388.1 11528.8 11529.2 11820.2 11226.7 11581.5 12032.8 12057.3 12080.9 10464.7 10511.7 10802.3 10928.4 10931.6 9827.5 10019.8 10125.9 10146.0 10186.6 9464.4 9592.4 10009.1 10243.8 10301.3 17:32:48 INFO - PROCESS | 5806 | -------- Summary: end -------- 17:32:48 INFO - PROCESS | 5806 | 17:32:49 INFO - TEST-INFO | 5806: exit 0 17:32:49 INFO - TEST-OK | dromaeo_css | took 1114415ms 17:32:49 INFO - TEST-START | kraken 17:32:49 INFO - operating with platform_type : linux_ 17:32:49 INFO - Initialising browser for kraken test... 17:32:49 INFO - TEST-INFO | started process 6041 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpA7T2LY/profile http://localhost:33470/getInfo.html) 17:32:52 INFO - PROCESS | 6041 | 17:32:52 INFO - PROCESS | 6041 | (process:6109): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 17:32:53 INFO - PROCESS | 6041 | JavaScript error: jar:file:///builds/slave/test/build/application/firefox/omni.ja!/components/Weave.js, line 13: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIXPCComponents_Utils.import] 17:32:53 INFO - PROCESS | 6041 | __metrics Screen width/height:1600/1200 17:32:53 INFO - PROCESS | 6041 | colorDepth:24 17:32:53 INFO - PROCESS | 6041 | Browser inner width/height: 1024/697 17:32:53 INFO - PROCESS | 6041 | __metrics 17:32:54 ERROR - PROCESS | 6041 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 17:32:55 INFO - TEST-INFO | 6041: exit 0 17:32:55 INFO - Browser initialized. 17:32:55 INFO - Running cycle 1/1 for kraken test... 17:32:55 INFO - TEST-INFO | started process 6160 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpA7T2LY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:32:57 INFO - PROCESS | 6160 | 17:32:57 INFO - PROCESS | 6160 | (process:6218): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 17:33:07 INFO - PROCESS | 6160 | RSS: Main: 164831232 17:33:07 INFO - PROCESS | 6160 | 17:33:09 INFO - PROCESS | 6160 | Cycle 1(1): loaded http://localhost:33470/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33470/tests/kraken/driver.html?testName=audio-beat-detection) 17:33:10 INFO - PROCESS | 6160 | RSS: Main: 169074688 17:33:10 INFO - PROCESS | 6160 | 17:33:13 INFO - PROCESS | 6160 | Cycle 1(1): loaded http://localhost:33470/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33470/tests/kraken/driver.html?testName=audio-dft) 17:33:14 INFO - PROCESS | 6160 | RSS: Main: 172843008 17:33:14 INFO - PROCESS | 6160 | 17:33:17 INFO - PROCESS | 6160 | Cycle 1(1): loaded http://localhost:33470/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33470/tests/kraken/driver.html?testName=audio-fft) 17:33:17 INFO - PROCESS | 6160 | RSS: Main: 164536320 17:33:17 INFO - PROCESS | 6160 | 17:33:19 INFO - PROCESS | 6160 | Cycle 1(1): loaded http://localhost:33470/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33470/tests/kraken/driver.html?testName=audio-oscillator) 17:33:20 INFO - PROCESS | 6160 | RSS: Main: 165339136 17:33:20 INFO - PROCESS | 6160 | 17:33:22 INFO - PROCESS | 6160 | Cycle 1(1): loaded http://localhost:33470/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33470/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:33:23 INFO - PROCESS | 6160 | RSS: Main: 165826560 17:33:23 INFO - PROCESS | 6160 | 17:33:26 INFO - PROCESS | 6160 | Cycle 1(1): loaded http://localhost:33470/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33470/tests/kraken/driver.html?testName=imaging-darkroom) 17:33:27 INFO - PROCESS | 6160 | RSS: Main: 166187008 17:33:27 INFO - PROCESS | 6160 | 17:33:30 INFO - PROCESS | 6160 | Cycle 1(1): loaded http://localhost:33470/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33470/tests/kraken/driver.html?testName=imaging-desaturate) 17:33:31 INFO - PROCESS | 6160 | RSS: Main: 163667968 17:33:31 INFO - PROCESS | 6160 | 17:33:34 INFO - PROCESS | 6160 | Cycle 1(1): loaded http://localhost:33470/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33470/tests/kraken/driver.html?testName=json-parse-financial) 17:33:34 INFO - PROCESS | 6160 | RSS: Main: 164069376 17:33:34 INFO - PROCESS | 6160 | 17:33:36 INFO - PROCESS | 6160 | Cycle 1(1): loaded http://localhost:33470/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33470/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:33:37 INFO - PROCESS | 6160 | RSS: Main: 164806656 17:33:37 INFO - PROCESS | 6160 | 17:33:39 INFO - PROCESS | 6160 | Cycle 1(1): loaded http://localhost:33470/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33470/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:33:39 INFO - PROCESS | 6160 | RSS: Main: 164630528 17:33:39 INFO - PROCESS | 6160 | 17:33:41 INFO - PROCESS | 6160 | Cycle 1(1): loaded http://localhost:33470/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33470/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:33:42 INFO - PROCESS | 6160 | RSS: Main: 165011456 17:33:42 INFO - PROCESS | 6160 | 17:33:45 INFO - PROCESS | 6160 | Cycle 1(1): loaded http://localhost:33470/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33470/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:33:46 INFO - PROCESS | 6160 | RSS: Main: 164769792 17:33:46 INFO - PROCESS | 6160 | 17:33:48 INFO - PROCESS | 6160 | Cycle 1(1): loaded http://localhost:33470/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33470/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:33:49 INFO - PROCESS | 6160 | RSS: Main: 164642816 17:33:49 INFO - PROCESS | 6160 | 17:33:51 INFO - PROCESS | 6160 | Cycle 1(1): loaded http://localhost:33470/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33470/tests/kraken/driver.html?testName=ai-astar) 17:33:51 INFO - PROCESS | 6160 | RSS: Main: 166191104 17:33:51 INFO - PROCESS | 6160 | 17:33:51 INFO - PROCESS | 6160 | __start_tp_report 17:33:51 INFO - PROCESS | 6160 | _x_x_mozilla_page_load 17:33:51 INFO - PROCESS | 6160 | _x_x_mozilla_page_load_details 17:33:51 INFO - PROCESS | 6160 | |i|pagename|runs| 17:33:51 INFO - PROCESS | 6160 | |0;ai-astar;114;108;117;113;115;126;114;115;113;112 17:33:51 INFO - PROCESS | 6160 | |1;audio-beat-detection;159;132;158;199;134;157;165;136;206;134 17:33:51 INFO - PROCESS | 6160 | |2;audio-dft;162;162;165;175;164;164;161;161;166;162 17:33:51 INFO - PROCESS | 6160 | |3;audio-fft;76;77;77;152;76;75;74;75;161;74 17:33:51 INFO - PROCESS | 6160 | |4;audio-oscillator;94;94;139;94;94;95;144;94;94;152 17:33:51 INFO - PROCESS | 6160 | |5;imaging-gaussian-blur;117;118;117;117;118;119;120;118;117;118 17:33:51 INFO - PROCESS | 6160 | |6;imaging-darkroom;147;146;146;147;147;147;147;146;146;147 17:33:51 INFO - PROCESS | 6160 | |7;imaging-desaturate;95;96;96;94;95;95;96;95;95;100 17:33:51 INFO - PROCESS | 6160 | |8;json-parse-financial;69;66;72;67;66;74;67;82;65;64 17:33:51 INFO - PROCESS | 6160 | |9;json-stringify-tinderbox;64;64;65;66;65;64;64;65;64;64 17:33:51 INFO - PROCESS | 6160 | |10;stanford-crypto-aes;80;88;78;81;88;86;80;80;81;81 17:33:51 INFO - PROCESS | 6160 | |11;stanford-crypto-ccm;139;137;141;138;136;133;133;136;167;137 17:33:51 INFO - PROCESS | 6160 | |12;stanford-crypto-pbkdf2;176;180;177;174;177;181;186;178;175;176 17:33:51 INFO - PROCESS | 6160 | |13;stanford-crypto-sha256-iterative;75;73;73;76;82;74;80;72;77;76 17:33:51 INFO - PROCESS | 6160 | __end_tp_report 17:33:51 INFO - PROCESS | 6160 | __start_cc_report 17:33:51 INFO - PROCESS | 6160 | _x_x_mozilla_cycle_collect,898 17:33:51 INFO - PROCESS | 6160 | __end_cc_report 17:33:51 INFO - PROCESS | 6160 | __startTimestamp1460766831735__endTimestamp 17:33:51 INFO - PROCESS | 6160 | 17:33:51 INFO - PROCESS | 6160 | ------- Summary: start ------- 17:33:51 INFO - PROCESS | 6160 | Number of tests: 14 17:33:51 INFO - PROCESS | 6160 | 17:33:51 INFO - PROCESS | 6160 | [#0] ai-astar Cycles:10 Average:114.70 Median:114.50 stddev:4.62 (4.0%) stddev-sans-first:4.89 17:33:51 INFO - PROCESS | 6160 | Values: 114.0 108.0 117.0 113.0 115.0 126.0 114.0 115.0 113.0 112.0 17:33:51 INFO - PROCESS | 6160 | 17:33:51 INFO - PROCESS | 6160 | [#1] audio-beat-detection Cycles:10 Average:158.00 Median:158.50 stddev:26.56 (16.8%) stddev-sans-first:28.17 17:33:51 INFO - PROCESS | 6160 | Values: 159.0 132.0 158.0 199.0 134.0 157.0 165.0 136.0 206.0 134.0 17:33:51 INFO - PROCESS | 6160 | 17:33:51 INFO - PROCESS | 6160 | [#2] audio-dft Cycles:10 Average:164.20 Median:164.00 stddev:4.16 (2.5%) stddev-sans-first:4.33 17:33:51 INFO - PROCESS | 6160 | Values: 162.0 162.0 165.0 175.0 164.0 164.0 161.0 161.0 166.0 162.0 17:33:51 INFO - PROCESS | 6160 | 17:33:51 INFO - PROCESS | 6160 | [#3] audio-fft Cycles:10 Average:91.70 Median:75.50 stddev:34.23 (45.3%) stddev-sans-first:35.84 17:33:51 INFO - PROCESS | 6160 | Values: 76.0 77.0 77.0 152.0 76.0 75.0 74.0 75.0 161.0 74.0 17:33:51 INFO - PROCESS | 6160 | 17:33:51 INFO - PROCESS | 6160 | [#4] audio-oscillator Cycles:10 Average:109.40 Median:94.00 stddev:24.76 (26.3%) stddev-sans-first:25.63 17:33:51 INFO - PROCESS | 6160 | Values: 94.0 94.0 139.0 94.0 94.0 95.0 144.0 94.0 94.0 152.0 17:33:51 INFO - PROCESS | 6160 | 17:33:51 INFO - PROCESS | 6160 | [#5] imaging-gaussian-blur Cycles:10 Average:117.90 Median:118.00 stddev:0.99 (0.8%) stddev-sans-first:1.00 17:33:51 INFO - PROCESS | 6160 | Values: 117.0 118.0 117.0 117.0 118.0 119.0 120.0 118.0 117.0 118.0 17:33:51 INFO - PROCESS | 6160 | 17:33:51 INFO - PROCESS | 6160 | [#6] imaging-darkroom Cycles:10 Average:146.60 Median:147.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 17:33:51 INFO - PROCESS | 6160 | Values: 147.0 146.0 146.0 147.0 147.0 147.0 147.0 146.0 146.0 147.0 17:33:51 INFO - PROCESS | 6160 | 17:33:51 INFO - PROCESS | 6160 | [#7] imaging-desaturate Cycles:10 Average:95.70 Median:95.00 stddev:1.64 (1.7%) stddev-sans-first:1.72 17:33:51 INFO - PROCESS | 6160 | Values: 95.0 96.0 96.0 94.0 95.0 95.0 96.0 95.0 95.0 100.0 17:33:51 INFO - PROCESS | 6160 | 17:33:51 INFO - PROCESS | 6160 | [#8] json-parse-financial Cycles:10 Average:69.20 Median:68.00 stddev:5.47 (8.0%) stddev-sans-first:5.80 17:33:51 INFO - PROCESS | 6160 | Values: 69.0 66.0 72.0 67.0 66.0 74.0 67.0 82.0 65.0 64.0 17:33:51 INFO - PROCESS | 6160 | 17:33:51 INFO - PROCESS | 6160 | [#9] json-stringify-tinderbox Cycles:10 Average:64.50 Median:64.50 stddev:0.71 (1.1%) stddev-sans-first:0.73 17:33:51 INFO - PROCESS | 6160 | Values: 64.0 64.0 65.0 66.0 65.0 64.0 64.0 65.0 64.0 64.0 17:33:51 INFO - PROCESS | 6160 | 17:33:51 INFO - PROCESS | 6160 | [#10] stanford-crypto-aes Cycles:10 Average:82.30 Median:81.00 stddev:3.62 (4.5%) stddev-sans-first:3.75 17:33:51 INFO - PROCESS | 6160 | Values: 80.0 88.0 78.0 81.0 88.0 86.0 80.0 80.0 81.0 81.0 17:33:51 INFO - PROCESS | 6160 | 17:33:51 INFO - PROCESS | 6160 | [#11] stanford-crypto-ccm Cycles:10 Average:139.70 Median:137.50 stddev:9.90 (7.2%) stddev-sans-first:10.50 17:33:51 INFO - PROCESS | 6160 | Values: 139.0 137.0 141.0 138.0 136.0 133.0 133.0 136.0 167.0 137.0 17:33:51 INFO - PROCESS | 6160 | 17:33:51 INFO - PROCESS | 6160 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.00 Median:177.50 stddev:3.53 (2.0%) stddev-sans-first:3.67 17:33:51 INFO - PROCESS | 6160 | Values: 176.0 180.0 177.0 174.0 177.0 181.0 186.0 178.0 175.0 176.0 17:33:51 INFO - PROCESS | 6160 | 17:33:51 INFO - PROCESS | 6160 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.80 Median:76.00 stddev:3.19 (4.2%) stddev-sans-first:3.37 17:33:51 INFO - PROCESS | 6160 | Values: 75.0 73.0 73.0 76.0 82.0 74.0 80.0 72.0 77.0 76.0 17:33:51 INFO - PROCESS | 6160 | -------- Summary: end -------- 17:33:51 INFO - PROCESS | 6160 | 17:33:52 INFO - TEST-INFO | 6160: exit 0 17:33:52 INFO - TEST-OK | kraken | took 63524ms 17:33:52 INFO - SUITE-END | took 1177s 17:33:52 INFO - Completed test suite (00:19:38) 17:33:52 INFO - javascript benchmark 17:33:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2610.4518051209243, 2674.5720684690446, 2691.676998760149, 2733.289344770359, 2741.6709994800626, 4705.2942058691215, 4771.689840160388, 4784.497627749084, 4812.855614331575, 4824.445188803289, 4607.930881036778, 4729.0, 4767.308740232673, 4780.0, 4841.8305359312435, 4367.497737760166, 4480.596746292833, 4487.237169681162, 4488.573585509372, 4490.461144662645, 507.08333533224277, 4326.101831750674, 4342.4789025317, 4396.912061758763, 4429.402030725843, 4355.934660980056, 4388.648908087345, 4390.8902277443, 4397.758123303217, 4423.579759922803, 4150.27370210215, 4396.252637051677, 4445.888852778674, 4464.910701785979, 4488.102379524075, 4344.4352234209355, 4395.230834603932, 4397.978010109961, 4422.402975598286, 4457.442819647544, 4633.536646335373, 4654.418197725285, 4660.04469083837, 4671.04243630055, 4746.691465054761, 4880.219164933629, 5095.643304968654, 5123.1546794778815, 5131.871703207413, 5133.589312854962, 5104.081265372231, 5126.897462050775, 5127.717975511346, 5131.3329267195395, 5182.792688292464, 4769.284607308862, 4778.331033655291, 4792.520747925215, 4803.663743537919, 4830.686005409672, 5094.133997220481, 5252.0, 5291.597726602454, 5292.364916210079, 5336.252924590561, 4937.975310123426, 4954.1082605130705, 4972.5027497249985, 4996.7501624918605, 5018.648694591417, 5024.949750502521, 5039.924401133986, 5042.016806722654, 5066.9746651266505, 5098.413682426569, 4915.5330243626795, 5063.493650634907, 5067.797288108434, 5155.329807125125, 5162.896742065138, 4842.975785121052, 5039.77321020555, 5075.949240507622, 5091.2363145527725, 5131.7690703918415, 4288.935665965013, 4307.289297265916, 4315.331123675835, 4323.373110898902, 4353.542877997765, 3674.632536746304, 3720.069982504374, 3779.4897688811657, 3808.504894363715, 3808.9525380520577, 4407.074514351958, 4409.228385032607, 4411.470623525133, 4440.934175797785, 4455.844045458393, 5078.162103252922, 5099.56653684441, 5102.770375333044, 5106.872328191825, 5125.1287281162295, 4292.098659281586, 4369.628581570602, 4408.228560001987, 4435.3125265583885, 4454.665900057508, 4565.406497155415, 4574.222382195035, 4582.679212455163, 4584.312353147055, 4593.954060459419, 4692.835750748844, 4847.491013443679, 4853.417589889165, 4860.927086093782, 4902.681325713817, 5018.172001619838, 5152.871178220575, 5175.81884634051, 5217.06092903281, 5246.816361427332, 4834.419869615669, 4896.583790377716, 4902.8774280643265, 4905.90188196227, 4968.056069346812, 4858.295547145573, 5077.086124497626, 5119.74401279942, 5126.4360920297995, 5130.204818253177, 5068.721220332917, 5101.311322971352, 5116.769745361393, 5169.689818610896, 5178.404483484447, 4953.18272485036, 4964.726940018334, 4972.627052970969, 4989.077020751172, 4992.201247800334, 4750.501197374226, 4944.0, 4977.4773648767805, 4997.925031124609, 5020.7991680333835, 4721.386219791405, 4865.683730557503, 4896.143174944442, 4918.631102667243, 4944.060628480577, 5026.271190677405, 5037.0, 5046.091703493406, 5052.216906379517, 5115.462876397924, 4735.573798358164, 4937.382827146606, 4956.281339205867, 4958.0, 5002.724850133278], "value": 4660.078698441258, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [586.8796896636187, 599.9310079340877, 605.2222893581747, 622.1321256846699, 639.8592309691866, 33009.339813203755, 33824.83087584565, 34025.82987085061, 34051.318973620575, 34054.0, 33505.162370940736, 33703.15742106448, 33897.830510847474, 33986.0, 34163.82918085406, 7372.189059203497, 7504.962475187616, 7534.472586918918, 7556.47104702671, 7565.35694465971, 7598.8480230395635, 7690.384769218449, 7730.18833022533, 7735.342495887856, 7743.806404839867, 7674.884876726854, 7767.145613982458, 7790.766277011699, 7805.921940780577, 7808.375329973588, 7679.424043196755, 7705.267999540035, 7735.955645987776, 7754.457187996843, 7760.991071160741, 7556.622168891534, 7662.885056724153, 7663.3486153677, 7664.080310362792, 7665.616719164075, 29227.70772292271, 35051.1237219069, 35441.82279088614, 35535.28929421423, 35544.28911421783, 2955.5271156615054, 3086.783925125242, 3117.1427857339186, 3207.951880721791, 3285.342931413727, 25012.874935625387, 25791.0, 26349.3412664683, 26405.0, 26682.066127685634, 3435.2957643683235, 3439.914002149966, 3475.9652403475893, 3521.841517131735, 3545.8227088645463, 198679.0, 200919.0, 201620.0, 201838.99999999854, 202048.0, 33861.153471163416, 53045.204321935205, 53368.73315633397, 53476.73261633667, 53479.73260133674, 33939.32121357559, 34130.65869341275, 34196.0, 34244.0, 34277.657223427945, 13532.661683457993, 13539.187648741106, 13653.726925461633, 13724.107932984323, 13741.931290343684, 11139.665810025712, 11191.832122518168, 11201.887981120248, 11229.157813164045, 11235.494402751896, 8060.476069055492, 8132.227438393344, 8153.8776918346275, 8208.466449680871, 8235.547044912588, 3163.3040731039137, 3223.435898717715, 3257.478803391446, 3275.0, 3277.721393681516, 893.647009431284, 900.6082354175954, 951.286535098676, 975.4001289207087, 979.3340528440729, 26656.733432665813, 27890.0, 28356.43287134287, 28441.288967775556, 28738.712612873605, 994.5425104451889, 1008.1682611845257, 1011.4841430870309, 1049.18163832211, 1077.6766969908997, 14682.55952321432, 14725.116493010453, 15017.624559385884, 15218.391264349524, 15223.31495082724, 336246.0, 338297.0, 339886.60113399045, 340590.0, 340854.0, 46328.768356158005, 47016.0, 47113.5288647116, 47151.29273060907, 47412.52587474081, 1203.9179354045987, 1212.2241765270235, 1235.4008305971597, 1272.739088486858, 1284.9614511564855, 26517.0, 27648.44703105967, 27699.86150069237, 27872.303192420353, 28268.293292667026, 42327.15345693131, 42621.0, 42741.0, 42826.357604635334, 42858.78570607127, 303695.0, 305106.0, 305579.0, 305589.0, 305811.0, 25375.36561585975, 25729.0, 25910.222693319232, 26029.6095558563, 38941.221175576895, 35971.82014089913, 36586.8170659145, 36628.45057324089, 36862.8156859214, 37011.81494092512, 31617.52573711442, 31628.0, 31993.840030800628, 32452.35095298128, 32950.340993179525, 34064.65935340714, 34276.657233427344, 34432.0, 34723.30553388969, 34791.65208347884, 30051.0, 30486.542701859045, 36157.81921090378, 37457.81271093627, 37497.25005499929, 26779.661016949463, 31153.68846311508, 31227.375452491277, 31345.69973118693, 31465.528017079305, 24550.877245613658, 24569.754302457462, 24768.876155619106, 24797.62803557984, 24962.126325579244], "value": 15977.927803009014, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.35954775750616, 382.6517868739447, 385.5777923174125, 386.6293987813202, 394.4083874188717, 15298.541043768691, 16240.0, 16875.734319925992, 16875.91562042188, 16898.40855570054, 17031.82968170318, 17475.563110922234, 17823.287068517242, 18055.187516561753, 18667.62664746704, 5640.407757185488, 5837.0, 5852.209951656514, 5883.970305196595, 5884.529237660999, 4074.5314288856716, 6737.258901520853, 7108.537945033582, 7117.110361204849, 7122.821929451753, 6876.69054892531, 6958.582485050888, 7014.859702805941, 7093.32613401726, 7135.108111486064, 6699.464042876558, 6951.58290502569, 6971.3028697130385, 6999.405050570707, 6999.510034297601, 6946.513744037919, 6960.547564408348, 6975.651217439158, 6985.860282794316, 6989.196242432135, 20218.393448196577, 21628.89185554078, 21720.34838954818, 21748.347549573537, 21838.126474940982, 1996.9700454493195, 2069.4826293426645, 2086.8017538333834, 2113.0913707105938, 2242.338510139513, 12919.22484650924, 13756.518521851687, 13893.235872027035, 14013.929930350385, 14603.78094328586, 1951.6291904538093, 2012.7685316188533, 2038.7043878637662, 2041.530447996954, 2054.8356131509445, 61462.69268653673, 64392.35607643911, 65147.0, 67100.6644966772, 77805.0, 16035.278412471467, 16518.917405413136, 16596.17019149038, 16634.16829158537, 17087.487375378758, 29816.0, 29831.85084074609, 29922.55116173259, 30065.849670751508, 30251.697483024887, 8919.509426981449, 8956.865647015169, 9074.45553266806, 9193.172614464731, 9198.218151457068, 7492.550446973199, 7647.808804779925, 7707.306342429209, 7777.805554861174, 7941.126476087626, 3567.500549923013, 3882.9223415532097, 8522.403431759842, 8536.774296723162, 8643.524606146722, 1834.1929550997518, 1851.6759567075708, 1877.1271358818215, 1883.51970247586, 1889.6315218532254, 383.2029378891898, 384.5865694378554, 392.6701570680585, 825.3974598543098, 890.6570970176399, 9368.718938431857, 10270.383776973405, 10283.28017038815, 10373.429461379546, 10521.158307335394, 404.96152865477734, 407.4702886247866, 409.9426080348754, 410.2512913932086, 411.53291014697936, 5104.183330667075, 5534.976029434568, 5554.277943867352, 5609.214709940612, 5631.690257035821, 370560.0, 371846.0, 384127.0, 394710.0, 404160.0, 76309.0, 78700.21299787043, 80107.19892801114, 80355.0, 81917.18082819096, 426.74608607878264, 432.0257818618994, 436.310629205854, 436.4130244820711, 438.76306794331344, 9348.298877584344, 9432.905670943477, 9533.47565883883, 9645.324827262024, 9694.75763105928, 29197.270068248465, 30061.69938300589, 31347.216319592193, 32172.678273216967, 32738.0, 48764.26853597128, 48971.26543101785, 49182.0, 49352.50647493479, 49362.75318623384, 7032.894506582507, 7184.533005354635, 7212.134543854771, 7502.812429689301, 7519.43604229674, 7554.357879580297, 8455.788605284917, 9735.490998895182, 9882.308238423242, 10335.534900929626, 9029.322800790098, 9113.77215569616, 9192.632294708137, 9440.527973601429, 9583.568739406604, 9049.683261085831, 9053.296911450981, 9057.728268151966, 9358.11097945694, 9409.200217981548, 9170.816583668422, 9208.769780755534, 9362.531873406166, 9651.758706032406, 9726.31915765918, 7118.679659415235, 7136.892946605909, 7240.96379518099, 7257.310555497219, 7434.7769566913075, 8262.669493220445, 9493.335466517277, 9535.380200287236, 9580.233581313618, 9593.952030240083], "value": 8089.011480925769, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1386.625611085007, 1443.509206869664, 1548.256836718375, 1722.3024675006611, 1763.2153691607232, 6995.440364770819, 7561.962190189055, 7769.223077692228, 7813.726519571823, 7826.60866956652, 7785.455018148732, 7792.961035194816, 7862.803429914255, 7879.684812607503, 7930.762077137695, 2252.256755270765, 2266.478709896725, 2282.041542552132, 2285.9771402285933, 2290.702208712865, 2193.6599827026757, 2196.066671664544, 2213.4023813570325, 2236.2508559632543, 2246.8988895499742, 2167.0464995402053, 2173.7500187478486, 2176.564687062586, 2182.4216582605623, 2183.945401364963, 2158.816500597451, 2171.5873983943, 2174.652055671093, 2177.7060096886953, 2179.6730490426403, 2151.064287035129, 2151.9784802151935, 2157.503774131943, 2166.0361139293022, 2171.4137182960512, 7782.299593036654, 7830.2952734254195, 7850.9214907850765, 7896.131425543185, 7899.05211374633, 4568.406107206042, 4668.766561671937, 4677.672562920597, 4708.693934894255, 4726.621870250372, 2888.234617826278, 2903.187107609873, 2924.1666125154443, 2979.493486107367, 2997.430488207234, 885.1900511032397, 896.3456676626034, 898.3262553085186, 911.118540214455, 911.1503647595392, 10309.432981186032, 10665.093467055309, 10668.359898406121, 10690.0, 10702.090322322612, 4080.387941808693, 4278.871633850989, 4280.464941882265, 4285.528591854893, 4296.4414626098405, 8582.055973842873, 8595.011573669108, 8616.39685222041, 8622.396432249809, 8650.480971141626, 4713.57577817998, 4721.716696998191, 4727.030958653468, 4734.834280800155, 4743.0988112258565, 3820.4078367853026, 4326.913461730748, 4341.413909122291, 4359.585839345257, 4365.083332500147, 3494.1614012636783, 3535.1338921964284, 3536.5402497675136, 3563.1626567756557, 3647.1976165243605, 1080.2654195147375, 1088.5591335509132, 1100.5597760895705, 1102.3881745631252, 1109.8557187565564, 704.4610872682402, 713.7930000299958, 862.7713655881197, 867.6355930509201, 869.4739682492001, 4987.426445958688, 5032.823851165192, 5056.216286475602, 5098.949010509921, 5170.793168273227, 879.331707901999, 885.5926273913943, 895.7626229049307, 898.4205187654057, 898.9056149104381, 3573.2317551726505, 3583.104223944014, 3604.6575575320303, 3615.7830530168276, 3710.4805327254207, 9619.951900240454, 9871.25965552572, 9886.80226395468, 9937.354071985297, 9988.101070903653, 9721.173700235558, 9801.215902727765, 9840.261980351503, 9871.309008369346, 9874.950625246971, 7042.6830792614455, 7369.373603243785, 7371.373433258126, 7378.667959941816, 7383.593902335316, 5118.846434606968, 5142.5885929126325, 5240.161574148118, 5257.710825904612, 5308.283381743415, 8555.401121921406, 8807.295416366804, 8815.0, 8894.733158005012, 8900.465972041698, 8878.73363799087, 9098.636054557743, 9182.494962776844, 9201.493917834583, 9343.112404321579, 6632.701528431136, 6744.494162937701, 6800.863982720417, 6830.487713421613, 6879.036934829228, 7222.241664625349, 7243.855122897618, 7260.818479538054, 7271.600061996641, 7298.96350518244, 6519.3480651936325, 6527.477801775752, 6539.771108011387, 6619.602823830585, 6697.196336439659, 6349.0, 6366.045093235866, 6392.296847346695, 6500.382463665944, 6506.577072490273, 6103.969480152571, 6142.662153581596, 6180.103884936569, 6186.1030150627, 6188.8762224754355, 4467.285234362486, 4521.5252398499, 4557.043020965634, 4596.057808149389, 4643.048175124159, 4513.0522590255105, 4562.5665561771575, 4580.038191979588, 4602.7468489233415, 4636.907261854812], "value": 4129.37650211411, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [310.998445007775, 322.9935401291974, 323.5373416015098, 330.3525090821989, 330.61978724466843, 5283.762230699618, 5297.814576489824, 5327.600429967758, 5337.172738225577, 5365.382980957191, 5217.895642087156, 5228.8692782680355, 5263.736703191235, 5267.841964741064, 5286.841394758154, 3917.4515567820526, 4052.2098190852794, 4157.52188498322, 4172.457580514529, 4174.415581818548, 4143.958560414402, 4165.791710414483, 4172.874813755592, 4178.059936514291, 4181.853635122756, 4110.6300432961025, 4144.8756537303925, 4158.625723684868, 4162.583741625844, 4164.791760411982, 4060.8375664973514, 4112.629863312286, 4143.19207754488, 4152.563980782004, 4160.791960401983, 3618.6200448952745, 3632.891013269606, 3639.3267245559655, 3644.562652481719, 3650.8722194723323, 5035.672681275705, 5102.821401250939, 5103.617228707862, 5122.487751224885, 5138.306328645623, 4460.130274596455, 4717.858464246078, 4817.060673168733, 4818.494058123882, 4838.274258861164, 5553.444655534455, 5561.110222364402, 5582.106862901956, 5587.7485513152005, 5601.29983752031, 3896.649301562845, 3904.511936007999, 3906.7655940643654, 3908.4332771748223, 3917.5886531914307, 5576.163575463673, 5839.620424672383, 5881.206037185011, 5883.764649414018, 5901.734421951065, 3970.8411663533134, 3976.5228172619472, 3986.8405263789123, 3992.6606238469476, 4012.879613611596, 4059.959400405958, 4062.512498500199, 4073.5926407359025, 4079.714419990572, 4085.9182816343505, 4590.760494666421, 4694.413198350207, 4695.460022097503, 4696.4501714434155, 4699.859004229878, 5600.4959553640365, 5613.915791263134, 5616.455474744396, 5634.915476267859, 5638.0, 1401.1102949627061, 1424.7391058912938, 1428.4857451317516, 1431.2414420357227, 1433.4123009566028, 872.4416373520825, 917.3211823250747, 937.4843835890233, 957.0094204055548, 964.0744884910564, 4807.0, 4825.638077144228, 4848.733319667382, 4854.902901941941, 4874.7562621868765, 5749.0, 5764.682942438206, 5786.305643322744, 5786.971065144648, 5800.8259752207505, 4654.325122857234, 4831.429785319785, 4864.562189402971, 4882.170031094652, 4917.409910810654, 3098.1325228935934, 3127.2807254331633, 3136.764742644311, 3141.607299087614, 3151.11768704763, 5460.945390546123, 5473.781048758085, 5474.39781624021, 5481.369642491165, 5495.505404513613, 5868.325142608569, 5968.343482216953, 5970.820875373659, 5992.191054207713, 6021.458068773743, 5454.754536045887, 5467.152591348347, 5470.288862447856, 5475.698836564027, 5480.780768769284, 5669.6314739541795, 5776.249087618583, 5827.8834423310445, 5834.94165058361, 5857.5313974881055, 5674.914876276776, 5689.288838895138, 5733.426657334233, 5738.856528586819, 5745.770169193185, 5454.454554544673, 5460.481254280837, 5470.671759694431, 5497.120460726264, 5514.558835293247, 3801.4297855321925, 3949.190415964778, 3962.128331767007, 4027.496562929634, 4069.2471892699946, 5775.768969241351, 5781.913271300849, 5797.507211887037, 5815.970920145542, 5819.6508209507565, 5595.888082238251, 5613.129964855454, 5620.1288800236025, 5620.381758006698, 5624.775008999594, 5482.23248745184, 5568.220449137043, 5569.387367389503, 5607.831765047055, 5629.746661400329, 5304.0, 5463.562914966873, 5513.283273174462, 5521.889562208814, 5523.475269849358, 5422.15956526739, 5648.406917273792, 5673.716314184192, 5680.886382272414, 5730.590298772224, 5501.587380946525, 5512.118061110203, 5562.415946325577, 5571.749271282721, 5577.832665020056], "value": 4202.87864191585, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [736.2931585677749, 775.3409601838434, 780.8008957715782, 783.502475927786, 797.0594698256062, 12984.28586427746, 13712.725745485084, 13813.723725525497, 13822.930885345559, 13877.930610346933, 13759.93120034401, 13782.310884455763, 13816.516421925235, 13860.584182474517, 13937.860621393784, 654.7446495866616, 799.0571126071234, 821.5112008355019, 829.3199576347398, 832.014063334947, 809.4131754477986, 810.1776696652902, 812.0742353716768, 816.7713040348712, 820.335528222139, 858.6908712863365, 861.2851333393269, 864.9783755406103, 867.1458613265928, 867.2541614211805, 854.7435769269225, 856.9785755356135, 860.5439117267827, 860.926821220197, 863.9308855291562, 617.1360095865779, 620.9534284928649, 622.551762730833, 623.1867413025999, 623.9906401403982, 12905.548305809252, 12988.0, 13034.217946923214, 13060.412281447358, 13081.0, 12496.312702801342, 12548.372581370986, 12560.748785024249, 12620.495180192782, 12959.935200323938, 9152.267818574499, 11096.33421994683, 11520.539178432853, 11635.709107272301, 11712.063034957184, 3219.96780032199, 3256.1859535116223, 3289.687479689426, 3293.875450334532, 3333.5666363372848, 46717.766411168064, 47084.0, 47283.76358118222, 47521.0, 47655.0, 9578.808423831484, 9579.425234485952, 9630.903690963141, 9688.273379496566, 9753.707388778348, 16417.671646567003, 16466.506004819876, 16539.586510337336, 16542.0, 16645.0845203515, 6300.0, 6304.905426418516, 6327.3988971047675, 6361.268454127742, 6514.706838192293, 3583.802890841029, 3594.5686517617532, 3879.6702280305926, 3928.744631598937, 3977.0455090778005, 2829.943401131966, 2889.5232286672463, 2919.6058532097895, 2924.6344206974127, 2929.5019846626124, 490.07131485834856, 504.0548970679975, 512.18562485648, 513.7919142747119, 520.4041372628395, 552.3565046720606, 564.3566334378811, 567.6781961776272, 574.12159396124, 587.9088741245114, 7827.608619568999, 8183.181681831769, 8195.836083278302, 8351.3736469765, 8492.915070849336, 624.6345887655682, 643.784332248702, 648.230331195838, 658.5456035335603, 660.2341284110408, 3871.2838124946975, 3955.5253369595257, 3961.187956468975, 3974.841006359771, 4095.365218391154, 46478.535214648095, 50992.74503627458, 52225.47774522206, 52796.0, 53088.203676944875, 43151.56848431538, 44226.33660495095, 44491.33263001058, 44671.55328446674, 44806.55193448089, 42670.0, 43687.563124368986, 43719.56280437219, 44225.33661995008, 45306.0, 8428.620712068094, 8578.457055322006, 8610.78473038179, 8702.390832641655, 8706.129387061243, 28433.857830710713, 28731.0, 29283.0, 29376.559351609314, 29526.40947181087, 36445.0, 37627.62372376351, 37761.62238377581, 37762.0, 38025.80987095047, 11290.322580645188, 11290.717732056764, 11414.372209528556, 11616.244944078608, 11701.180917335705, 11961.162718609614, 12075.335856527623, 12092.334921579397, 12314.322712250912, 12441.315727635067, 11212.27120237182, 11388.14588905852, 11528.827067593826, 11529.192956492965, 11820.172587919107, 11226.719332016439, 11581.478833452345, 12032.819507707567, 12057.336846473178, 12080.879191207976, 10464.738381540523, 10511.684649460529, 10802.297850639683, 10928.398938058483, 10931.617393391192, 9827.459489728133, 10019.849702254618, 10125.949370253102, 10146.0, 10186.643467478603, 9464.384814987005, 9592.376495527767, 10009.099181073738, 10243.795124097327, 10301.278910476194], "value": 6071.191520628421, "unit": "score"}], "name": "dromaeo_css", "value": 6315.765385075817, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 108.0, 117.0, 113.0, 115.0, 126.0, 114.0, 115.0, 113.0, 112.0], "value": 114.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 132.0, 158.0, 199.0, 134.0, 157.0, 165.0, 136.0, 206.0, 134.0], "value": 158.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 165.0, 175.0, 164.0, 164.0, 161.0, 161.0, 166.0, 162.0], "value": 164.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 77.0, 152.0, 76.0, 75.0, 74.0, 75.0, 161.0, 74.0], "value": 91.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 139.0, 94.0, 94.0, 95.0, 144.0, 94.0, 94.0, 152.0], "value": 109.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 117.0, 117.0, 118.0, 119.0, 120.0, 118.0, 117.0, 118.0], "value": 117.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 147.0, 147.0, 147.0, 147.0, 146.0, 146.0, 147.0], "value": 146.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 96.0, 94.0, 95.0, 95.0, 96.0, 95.0, 95.0, 100.0], "value": 95.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 66.0, 72.0, 67.0, 66.0, 74.0, 67.0, 82.0, 65.0, 64.0], "value": 69.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 65.0, 66.0, 65.0, 64.0, 64.0, 65.0, 64.0, 64.0], "value": 64.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 88.0, 78.0, 81.0, 88.0, 86.0, 80.0, 80.0, 81.0, 81.0], "value": 82.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 137.0, 141.0, 138.0, 136.0, 133.0, 133.0, 136.0, 167.0, 137.0], "value": 139.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 180.0, 177.0, 174.0, 177.0, 181.0, 186.0, 178.0, 175.0, 176.0], "value": 178.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 73.0, 73.0, 76.0, 82.0, 74.0, 80.0, 72.0, 77.0, 76.0], "value": 75.8, "unit": "score"}], "name": "kraken", "value": 1607.7, "alertThreshold": 2.0}]} 17:33:52 INFO - Return code: 0 17:33:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 17:33:53 ERROR - # TBPL SUCCESS # 17:33:53 INFO - Running post-action listener: _resource_record_post_action 17:33:53 INFO - Running post-run listener: _resource_record_post_run 17:33:54 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 16384; Write bytes: 517681152; Read time: 212; Write time: 450592 17:33:54 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 4096; Write bytes: 233680896; Read time: 76; Write time: 219328 17:33:54 INFO - run-tests - Wall time: 1180s; CPU: 13.0%; Read bytes: 12288; Write bytes: 284000256; Read time: 136; Write time: 231264 17:33:54 INFO - Running post-run listener: _upload_blobber_files 17:33:54 INFO - Blob upload gear active. 17:33:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:33:54 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 17:33:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:33:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:33:54 INFO - (blobuploader) - INFO - Open directory for files ... 17:33:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:33:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:33:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:33:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:33:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:33:55 INFO - (blobuploader) - INFO - Done attempting. 17:33:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:33:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:33:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:33:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:33:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:33:56 INFO - (blobuploader) - INFO - Done attempting. 17:33:56 INFO - (blobuploader) - INFO - Iteration through files over. 17:33:56 INFO - Return code: 0 17:33:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:33:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:33:56 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/c15485322a841e6ee2a16414a1cf0e60b0ac7b4a7778370e7fa48e3b65e7ed21d0dd069e1fd26f709a28b3bc31df2e388d3f0f8ab8ea56c7d9fd1a6257a4123b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/0cc6a71a5e926c8ad8b67a6447b68ed19b334a3528a33113abc020aff8f3dc87b103ef31552865b6c5bee320307378e534e763fb4d7ac5ab4aa8aeddc8761aa2"} 17:33:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:33:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:33:56 INFO - Contents: 17:33:56 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/c15485322a841e6ee2a16414a1cf0e60b0ac7b4a7778370e7fa48e3b65e7ed21d0dd069e1fd26f709a28b3bc31df2e388d3f0f8ab8ea56c7d9fd1a6257a4123b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/0cc6a71a5e926c8ad8b67a6447b68ed19b334a3528a33113abc020aff8f3dc87b103ef31552865b6c5bee320307378e534e763fb4d7ac5ab4aa8aeddc8761aa2"} 17:33:56 INFO - Running post-run listener: copy_logs_to_upload_dir 17:33:56 INFO - Copying logs to upload dir... 17:33:56 INFO - mkdir: /builds/slave/test/build/upload/logs 17:33:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1241.486150 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2016-04-15 17:33:55.829263) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-15 17:33:55.832498) ========= 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=6d15e502d8e29d5d2676d664000001c3-1460765266.121002-399972084 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/c15485322a841e6ee2a16414a1cf0e60b0ac7b4a7778370e7fa48e3b65e7ed21d0dd069e1fd26f709a28b3bc31df2e388d3f0f8ab8ea56c7d9fd1a6257a4123b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/0cc6a71a5e926c8ad8b67a6447b68ed19b334a3528a33113abc020aff8f3dc87b103ef31552865b6c5bee320307378e534e763fb4d7ac5ab4aa8aeddc8761aa2"} build_url:https://queue.taskcluster.net/v1/task/e6bqZlQ5RPyLqAUQsyVnbw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011317 build_url: 'https://queue.taskcluster.net/v1/task/e6bqZlQ5RPyLqAUQsyVnbw/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/c15485322a841e6ee2a16414a1cf0e60b0ac7b4a7778370e7fa48e3b65e7ed21d0dd069e1fd26f709a28b3bc31df2e388d3f0f8ab8ea56c7d9fd1a6257a4123b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/0cc6a71a5e926c8ad8b67a6447b68ed19b334a3528a33113abc020aff8f3dc87b103ef31552865b6c5bee320307378e534e763fb4d7ac5ab4aa8aeddc8761aa2"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-15 17:33:55.857117) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-15 17:33:55.857427) ========= 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=6d15e502d8e29d5d2676d664000001c3-1460765266.121002-399972084 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004402 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-15 17:33:55.939808) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-15 17:33:55.940144) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-15 17:33:55.940456) ========= ========= Total master_lag: 0.15 =========