builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-060 starttime: 1462915134.75 results: success (0) buildid: 20160510135009 builduid: c6845876abb445c586df826bf9d10a62 revision: ee562525573f8896fe4f7a5ac053de3d97ae4ccb ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-10 14:18:54.749353) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-10 14:18:54.750280) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-10 14:18:54.750965) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-10 14:18:54.768993) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 14:18:54.769639) ========= 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=337fe3f4b1febe37b85fccc40000019e-1462915134.825064-448827991 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-10 14:18:55-- 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% 22.5M=0.001s 2016-05-10 14:18:55 (22.5 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.171051 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 14:18:54.955280) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 14:18:54.955643) ========= 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=337fe3f4b1febe37b85fccc40000019e-1462915134.825064-448827991 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014697 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 14:18:55.006108) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 14:18:55.006510) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev ee562525573f8896fe4f7a5ac053de3d97ae4ccb --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 ee562525573f8896fe4f7a5ac053de3d97ae4ccb --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=337fe3f4b1febe37b85fccc40000019e-1462915134.825064-448827991 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-10 14:18:55,728 truncating revision to first 12 chars 2016-05-10 14:18:55,728 Setting DEBUG logging. 2016-05-10 14:18:55,728 attempt 1/10 2016-05-10 14:18:55,728 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/ee562525573f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-10 14:18:56,193 unpacking tar archive at: fx-team-ee562525573f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.748707 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 14:18:55.769403) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-10 14:18:55.769787) ========= script_repo_revision: ee562525573f8896fe4f7a5ac053de3d97ae4ccb ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-10 14:18:55.770273) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-10 14:18:55.770598) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-10 14:18:55.781323) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-05-10 14:18:55.781680) ========= /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=337fe3f4b1febe37b85fccc40000019e-1462915134.825064-448827991 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:18:56 INFO - MultiFileLogger online at 20160510 14:18:56 in /builds/slave/test 14:18:56 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 14:18:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:18:56 INFO - {'append_to_log': False, 14:18:56 INFO - 'base_work_dir': '/builds/slave/test', 14:18:56 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 14:18:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:18:56 INFO - 'branch': 'Fx-Team-Non-PGO', 14:18:56 INFO - 'buildbot_json_path': 'buildprops.json', 14:18:56 INFO - 'config_files': ('talos/linux_config.py',), 14:18:56 INFO - 'default_actions': ('clobber', 14:18:56 INFO - 'read-buildbot-config', 14:18:56 INFO - 'download-and-extract', 14:18:56 INFO - 'populate-webroot', 14:18:56 INFO - 'create-virtualenv', 14:18:56 INFO - 'install', 14:18:56 INFO - 'run-tests'), 14:18:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:18:56 INFO - 'download_minidump_stackwalk': True, 14:18:56 INFO - 'download_symbols': 'ondemand', 14:18:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:18:56 INFO - 'tooltool.py': '/tools/tooltool.py', 14:18:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:18:56 INFO - '/tools/misc-python/virtualenv.py')}, 14:18:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:18:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:18:56 INFO - 'installer_path': 'installer.exe', 14:18:56 INFO - 'log_level': 'info', 14:18:56 INFO - 'log_name': 'talos', 14:18:56 INFO - 'log_to_console': True, 14:18:56 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:18:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:18:56 INFO - 'opt_config_files': (), 14:18:56 INFO - 'pip_index': False, 14:18:56 INFO - 'sps_profile': False, 14:18:56 INFO - 'sps_profile_interval': 0, 14:18:56 INFO - 'suite': 'dromaeojs-e10s', 14:18:56 INFO - 'system_bits': '32', 14:18:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:18:56 INFO - 'title': 'talos-linux64-ix-060', 14:18:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:18:56 INFO - 'use_talos_json': True, 14:18:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:18:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:18:56 INFO - 'work_dir': 'build'} 14:18:56 INFO - ##### 14:18:56 INFO - ##### Running clobber step. 14:18:56 INFO - ##### 14:18:56 INFO - Running pre-action listener: _resource_record_pre_action 14:18:56 INFO - Running main action method: clobber 14:18:56 INFO - rmtree: /builds/slave/test/build 14:18:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:18:57 INFO - Running post-action listener: _resource_record_post_action 14:18:57 INFO - ##### 14:18:57 INFO - ##### Finished clobber step (success) 14:18:57 INFO - ##### 14:18:57 INFO - ##### 14:18:57 INFO - ##### Running read-buildbot-config step. 14:18:57 INFO - ##### 14:18:57 INFO - Running pre-action listener: _resource_record_pre_action 14:18:57 INFO - Running main action method: read_buildbot_config 14:18:57 INFO - Using buildbot properties: 14:18:57 INFO - { 14:18:57 INFO - "project": "", 14:18:57 INFO - "product": "firefox", 14:18:57 INFO - "script_repo_revision": "production", 14:18:57 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 14:18:57 INFO - "repository": "", 14:18:57 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos dromaeojs-e10s", 14:18:57 INFO - "buildid": "20160510135009", 14:18:57 INFO - "pgo_build": "False", 14:18:57 INFO - "basedir": "/builds/slave/test", 14:18:57 INFO - "buildnumber": 1254, 14:18:57 INFO - "slavename": "talos-linux64-ix-060", 14:18:57 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 14:18:57 INFO - "platform": "ubuntu64_hw", 14:18:57 INFO - "scheduler": "tests-fx-team-linux64-talos", 14:18:57 INFO - "branch": "fx-team", 14:18:57 INFO - "repo_path": "integration/fx-team", 14:18:57 INFO - "revision": "ee562525573f8896fe4f7a5ac053de3d97ae4ccb", 14:18:57 INFO - "stage_platform": "linux64", 14:18:57 INFO - "builduid": "c6845876abb445c586df826bf9d10a62", 14:18:57 INFO - "slavebuilddir": "test" 14:18:57 INFO - } 14:18:57 INFO - Found installer url https://queue.taskcluster.net/v1/task/aQv61iDDRRyaE6dkYl149Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 14:18:57 INFO - Running post-action listener: _resource_record_post_action 14:18:57 INFO - ##### 14:18:57 INFO - ##### Finished read-buildbot-config step (success) 14:18:57 INFO - ##### 14:18:57 INFO - ##### 14:18:57 INFO - ##### Running download-and-extract step. 14:18:57 INFO - ##### 14:18:57 INFO - Running pre-action listener: _resource_record_pre_action 14:18:57 INFO - Running main action method: download_and_extract 14:18:57 INFO - mkdir: /builds/slave/test/build/tests 14:18:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:18:57 INFO - https://queue.taskcluster.net/v1/task/aQv61iDDRRyaE6dkYl149Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 14:18:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQv61iDDRRyaE6dkYl149Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 14:18:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQv61iDDRRyaE6dkYl149Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 14:18:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQv61iDDRRyaE6dkYl149Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 14:18:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQv61iDDRRyaE6dkYl149Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 14:18:58 INFO - Downloaded 1361 bytes. 14:18:58 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 14:18:58 INFO - Using the following test package requirements: 14:18:58 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 14:18:58 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:18:58 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 14:18:58 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:18:58 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 14:18:58 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:18:58 INFO - u'jsshell-linux-x86_64.zip'], 14:18:58 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:18:58 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 14:18:58 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 14:18:58 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:18:58 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 14:18:58 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:18:58 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 14:18:58 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:18:58 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 14:18:58 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 14:18:58 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 14:18:58 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 14:18:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:18:58 INFO - https://queue.taskcluster.net/v1/task/aQv61iDDRRyaE6dkYl149Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 14:18:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQv61iDDRRyaE6dkYl149Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 14:18:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQv61iDDRRyaE6dkYl149Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 14:18:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQv61iDDRRyaE6dkYl149Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 14:18:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQv61iDDRRyaE6dkYl149Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:18:59 INFO - Downloaded 22326367 bytes. 14:18:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:18:59 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 14:19:00 INFO - Return code: 0 14:19:00 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 14:19:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:19:00 INFO - https://queue.taskcluster.net/v1/task/aQv61iDDRRyaE6dkYl149Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 14:19:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQv61iDDRRyaE6dkYl149Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 14:19:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQv61iDDRRyaE6dkYl149Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 14:19:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQv61iDDRRyaE6dkYl149Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 14:19:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQv61iDDRRyaE6dkYl149Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:19:01 INFO - Downloaded 22326367 bytes. 14:19:01 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:19:01 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 14:19:02 INFO - Return code: 0 14:19:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:19:02 INFO - https://queue.taskcluster.net/v1/task/aQv61iDDRRyaE6dkYl149Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 14:19:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQv61iDDRRyaE6dkYl149Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 14:19:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQv61iDDRRyaE6dkYl149Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 14:19:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQv61iDDRRyaE6dkYl149Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 14:19:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQv61iDDRRyaE6dkYl149Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 14:19:03 INFO - Downloaded 10840152 bytes. 14:19:03 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:19:03 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 14:19:04 INFO - Return code: 0 14:19:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:19:04 INFO - https://queue.taskcluster.net/v1/task/aQv61iDDRRyaE6dkYl149Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 14:19:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQv61iDDRRyaE6dkYl149Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 14:19:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQv61iDDRRyaE6dkYl149Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 14:19:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQv61iDDRRyaE6dkYl149Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 14:19:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aQv61iDDRRyaE6dkYl149Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 14:19:05 INFO - Downloaded 54676591 bytes. 14:19:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aQv61iDDRRyaE6dkYl149Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 14:19:05 INFO - mkdir: /builds/slave/test/properties 14:19:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:19:05 INFO - Writing to file /builds/slave/test/properties/build_url 14:19:05 INFO - Contents: 14:19:05 INFO - build_url:https://queue.taskcluster.net/v1/task/aQv61iDDRRyaE6dkYl149Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 14:19:06 INFO - Running post-action listener: _resource_record_post_action 14:19:06 INFO - Running post-action listener: set_extra_try_arguments 14:19:06 INFO - ##### 14:19:06 INFO - ##### Finished download-and-extract step (success) 14:19:06 INFO - ##### 14:19:06 INFO - ##### 14:19:06 INFO - ##### Running populate-webroot step. 14:19:06 INFO - ##### 14:19:06 INFO - Running pre-action listener: _resource_record_pre_action 14:19:06 INFO - Running main action method: populate_webroot 14:19:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:19:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:19:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:19:06 INFO - '--tppagecycles', 14:19:06 INFO - '7'], 14:19:06 INFO - 'tests': ['tsvgm']}}, 14:19:06 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:19:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:19:06 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:19:06 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:19:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:19:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:19:06 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:19:06 INFO - 'talos_options': ['--disable-e10s'], 14:19:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:19:06 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:19:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:19:06 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:19:06 INFO - 'talos_options': ['--disable-e10s'], 14:19:06 INFO - 'tests': ['damp', 'tps']}, 14:19:06 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:19:06 INFO - 'tests': ['damp', 'tps']}, 14:19:06 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:19:06 INFO - 'tests': ['dromaeo_dom']}, 14:19:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:19:06 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:19:06 INFO - 'tests': ['a11yr', 14:19:06 INFO - 'ts_paint', 14:19:06 INFO - 'tpaint', 14:19:06 INFO - 'sessionrestore', 14:19:06 INFO - 'sessionrestore_no_auto_restore', 14:19:06 INFO - 'tabpaint']}, 14:19:06 INFO - 'other-e10s': {'tests': ['a11yr', 14:19:06 INFO - 'ts_paint', 14:19:06 INFO - 'tpaint', 14:19:06 INFO - 'sessionrestore', 14:19:06 INFO - 'sessionrestore_no_auto_restore', 14:19:06 INFO - 'tabpaint']}, 14:19:06 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:19:06 INFO - 'ts_paint', 14:19:06 INFO - 'tpaint', 14:19:06 INFO - 'sessionrestore', 14:19:06 INFO - 'sessionrestore_no_auto_restore', 14:19:06 INFO - 'tabpaint']}, 14:19:06 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:19:06 INFO - 'ts_paint', 14:19:06 INFO - 'tpaint', 14:19:06 INFO - 'sessionrestore', 14:19:06 INFO - 'sessionrestore_no_auto_restore', 14:19:06 INFO - 'tabpaint']}, 14:19:06 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:19:06 INFO - 'tests': ['a11yr', 14:19:06 INFO - 'ts_paint', 14:19:06 INFO - 'tpaint', 14:19:06 INFO - 'sessionrestore', 14:19:06 INFO - 'sessionrestore_no_auto_restore', 14:19:06 INFO - 'tabpaint']}, 14:19:06 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:19:06 INFO - 'tests': ['a11yr', 14:19:06 INFO - 'ts_paint', 14:19:06 INFO - 'tpaint', 14:19:06 INFO - 'sessionrestore', 14:19:06 INFO - 'sessionrestore_no_auto_restore', 14:19:06 INFO - 'tabpaint']}, 14:19:06 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:19:06 INFO - 'tests': ['tsvgx', 14:19:06 INFO - 'tsvgr_opacity', 14:19:06 INFO - 'tart', 14:19:06 INFO - 'tscrollx', 14:19:06 INFO - 'cart']}, 14:19:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:19:06 INFO - 'tsvgr_opacity', 14:19:06 INFO - 'tart', 14:19:06 INFO - 'tscrollx', 14:19:06 INFO - 'cart']}, 14:19:06 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:19:06 INFO - 'talos_options': ['--disable-e10s'], 14:19:06 INFO - 'tests': ['tp5o']}, 14:19:06 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:19:06 INFO - 'tests': ['tp5o']}, 14:19:06 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:19:06 INFO - 'talos_options': ['--disable-e10s', 14:19:06 INFO - '--xperf_path', 14:19:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:19:06 INFO - 'tests': ['tp5n']}, 14:19:06 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:19:06 INFO - 'talos_options': ['--xperf_path', 14:19:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:19:06 INFO - 'tests': ['tp5n']}}, 14:19:06 INFO - 'talos.zip': {'path': '', 14:19:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:19:06 INFO - Running post-action listener: _resource_record_post_action 14:19:06 INFO - ##### 14:19:06 INFO - ##### Finished populate-webroot step (success) 14:19:06 INFO - ##### 14:19:06 INFO - ##### 14:19:06 INFO - ##### Running create-virtualenv step. 14:19:06 INFO - ##### 14:19:06 INFO - Running pre-action listener: _resource_record_pre_action 14:19:06 INFO - Running main action method: create_virtualenv 14:19:06 INFO - Creating virtualenv /builds/slave/test/build/venv 14:19:06 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 14:19:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:19:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:19:06 INFO - Using real prefix '/usr' 14:19:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:19:06 INFO - Installing distribute.............................................................................................................................................................................................done. 14:19:08 INFO - Installing pip.................done. 14:19:08 INFO - Return code: 0 14:19:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:19:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:19:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:19:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa46b48ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27eb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28b6770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29459c0>, '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 0x29468c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2946d50>, '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': '337fe3f4b1febe37b85fccc40000019e-1462915134.825064-448827991', '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 14:19:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:19:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:19:08 INFO - Using env: {'DISPLAY': ':0', 14:19:08 INFO - 'HOME': '/home/cltbld', 14:19:08 INFO - 'LANG': 'en_US.UTF-8', 14:19:08 INFO - 'LANGUAGE': 'en_US:en', 14:19:08 INFO - 'LOGNAME': 'cltbld', 14:19:08 INFO - 'MAIL': '/var/mail/cltbld', 14:19:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:08 INFO - 'MOZ_NO_REMOTE': '1', 14:19:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:19:08 INFO - 'NO_EM_RESTART': '1', 14:19:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:19:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:08 INFO - 'PWD': '/builds/slave/test', 14:19:08 INFO - 'SHELL': '/bin/bash', 14:19:08 INFO - 'SHLVL': '1', 14:19:08 INFO - 'TERM': 'linux', 14:19:08 INFO - 'TMOUT': '86400', 14:19:08 INFO - 'USER': 'cltbld', 14:19:08 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1462915134.825064-448827991', 14:19:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:08 INFO - '_': '/tools/buildbot/bin/python'} 14:19:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:09 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 14:19:09 INFO - Cleaning up... 14:19:09 INFO - Return code: 0 14:19:09 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 14:19:09 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')]} 14:19:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:09 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')]} 14:19:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:09 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 0x7fa46b48ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27eb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28b6770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29459c0>, '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 0x29468c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2946d50>, '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': '337fe3f4b1febe37b85fccc40000019e-1462915134.825064-448827991', '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 14:19:09 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 14:19:09 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 14:19:09 INFO - Using env: {'DISPLAY': ':0', 14:19:09 INFO - 'HOME': '/home/cltbld', 14:19:09 INFO - 'LANG': 'en_US.UTF-8', 14:19:09 INFO - 'LANGUAGE': 'en_US:en', 14:19:09 INFO - 'LOGNAME': 'cltbld', 14:19:09 INFO - 'MAIL': '/var/mail/cltbld', 14:19:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:09 INFO - 'MOZ_NO_REMOTE': '1', 14:19:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:19:09 INFO - 'NO_EM_RESTART': '1', 14:19:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:19:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:09 INFO - 'PWD': '/builds/slave/test', 14:19:09 INFO - 'SHELL': '/bin/bash', 14:19:09 INFO - 'SHLVL': '1', 14:19:09 INFO - 'TERM': 'linux', 14:19:09 INFO - 'TMOUT': '86400', 14:19:09 INFO - 'USER': 'cltbld', 14:19:09 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1462915134.825064-448827991', 14:19:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:09 INFO - '_': '/tools/buildbot/bin/python'} 14:19:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:09 INFO - Downloading/unpacking psutil>=0.7.1 14:19:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:19:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:19:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:19:11 INFO - Installing collected packages: psutil 14:19:11 INFO - Running setup.py install for psutil 14:19:11 INFO - building 'psutil._psutil_linux' extension 14:19:11 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 14:19:11 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 14:19:11 INFO - building 'psutil._psutil_posix' extension 14:19:11 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 14:19:11 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 14:19:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:19:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:19:11 INFO - Successfully installed psutil 14:19:11 INFO - Cleaning up... 14:19:11 INFO - Return code: 0 14:19:11 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 14:19:11 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')]} 14:19:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:11 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')]} 14:19:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:11 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 0x7fa46b48ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27eb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28b6770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29459c0>, '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 0x29468c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2946d50>, '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': '337fe3f4b1febe37b85fccc40000019e-1462915134.825064-448827991', '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 14:19:11 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 14:19:11 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 14:19:11 INFO - Using env: {'DISPLAY': ':0', 14:19:11 INFO - 'HOME': '/home/cltbld', 14:19:11 INFO - 'LANG': 'en_US.UTF-8', 14:19:11 INFO - 'LANGUAGE': 'en_US:en', 14:19:11 INFO - 'LOGNAME': 'cltbld', 14:19:11 INFO - 'MAIL': '/var/mail/cltbld', 14:19:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:11 INFO - 'MOZ_NO_REMOTE': '1', 14:19:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:19:11 INFO - 'NO_EM_RESTART': '1', 14:19:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:19:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:11 INFO - 'PWD': '/builds/slave/test', 14:19:11 INFO - 'SHELL': '/bin/bash', 14:19:11 INFO - 'SHLVL': '1', 14:19:11 INFO - 'TERM': 'linux', 14:19:11 INFO - 'TMOUT': '86400', 14:19:11 INFO - 'USER': 'cltbld', 14:19:11 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1462915134.825064-448827991', 14:19:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:11 INFO - '_': '/tools/buildbot/bin/python'} 14:19:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:12 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 14:19:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:13 INFO - Downloading mozsystemmonitor-0.0.tar.gz 14:19:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:19:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 14:19:13 INFO - Installing collected packages: mozsystemmonitor 14:19:13 INFO - Running setup.py install for mozsystemmonitor 14:19:14 INFO - Successfully installed mozsystemmonitor 14:19:14 INFO - Cleaning up... 14:19:14 INFO - Return code: 0 14:19:14 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:19:14 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')]} 14:19:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:14 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')]} 14:19:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:14 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 0x7fa46b48ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27eb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28b6770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29459c0>, '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 0x29468c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2946d50>, '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': '337fe3f4b1febe37b85fccc40000019e-1462915134.825064-448827991', '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 14:19:14 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 14:19:14 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 14:19:14 INFO - Using env: {'DISPLAY': ':0', 14:19:14 INFO - 'HOME': '/home/cltbld', 14:19:14 INFO - 'LANG': 'en_US.UTF-8', 14:19:14 INFO - 'LANGUAGE': 'en_US:en', 14:19:14 INFO - 'LOGNAME': 'cltbld', 14:19:14 INFO - 'MAIL': '/var/mail/cltbld', 14:19:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:14 INFO - 'MOZ_NO_REMOTE': '1', 14:19:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:19:14 INFO - 'NO_EM_RESTART': '1', 14:19:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:19:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:14 INFO - 'PWD': '/builds/slave/test', 14:19:14 INFO - 'SHELL': '/bin/bash', 14:19:14 INFO - 'SHLVL': '1', 14:19:14 INFO - 'TERM': 'linux', 14:19:14 INFO - 'TMOUT': '86400', 14:19:14 INFO - 'USER': 'cltbld', 14:19:14 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1462915134.825064-448827991', 14:19:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:14 INFO - '_': '/tools/buildbot/bin/python'} 14:19:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:14 INFO - Downloading/unpacking blobuploader==1.2.4 14:19:14 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 14:19:14 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 14:19:14 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 14:19:14 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 14:19:14 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 14:19:14 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 14:19:16 INFO - Downloading blobuploader-1.2.4.tar.gz 14:19:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:19:16 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:19:16 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 14:19:16 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 14:19:16 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 14:19:16 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 14:19:16 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 14:19:16 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 14:19:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:19:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:19:16 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 14:19:16 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 14:19:16 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 14:19:16 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 14:19:16 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 14:19:16 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 14:19:16 INFO - Downloading docopt-0.6.1.tar.gz 14:19:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:19:16 INFO - Installing collected packages: blobuploader, requests, docopt 14:19:16 INFO - Running setup.py install for blobuploader 14:19:17 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:19:17 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:19:17 INFO - Running setup.py install for requests 14:19:17 INFO - Running setup.py install for docopt 14:19:17 INFO - Successfully installed blobuploader requests docopt 14:19:17 INFO - Cleaning up... 14:19:17 INFO - Return code: 0 14:19:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:19:17 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')]} 14:19:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:17 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')]} 14:19:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:17 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 0x7fa46b48ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27eb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28b6770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29459c0>, '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 0x29468c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2946d50>, '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': '337fe3f4b1febe37b85fccc40000019e-1462915134.825064-448827991', '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 14:19:17 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 14:19:17 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 14:19:17 INFO - Using env: {'DISPLAY': ':0', 14:19:17 INFO - 'HOME': '/home/cltbld', 14:19:17 INFO - 'LANG': 'en_US.UTF-8', 14:19:17 INFO - 'LANGUAGE': 'en_US:en', 14:19:17 INFO - 'LOGNAME': 'cltbld', 14:19:17 INFO - 'MAIL': '/var/mail/cltbld', 14:19:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:17 INFO - 'MOZ_NO_REMOTE': '1', 14:19:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:19:17 INFO - 'NO_EM_RESTART': '1', 14:19:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:19:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:17 INFO - 'PWD': '/builds/slave/test', 14:19:17 INFO - 'SHELL': '/bin/bash', 14:19:17 INFO - 'SHLVL': '1', 14:19:17 INFO - 'TERM': 'linux', 14:19:17 INFO - 'TMOUT': '86400', 14:19:17 INFO - 'USER': 'cltbld', 14:19:17 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1462915134.825064-448827991', 14:19:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:17 INFO - '_': '/tools/buildbot/bin/python'} 14:19:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:19:17 INFO - Running setup.py (path:/tmp/pip-tkrAfP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:19:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:19:17 INFO - Running setup.py (path:/tmp/pip-QcbAFT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:19:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:19:17 INFO - Running setup.py (path:/tmp/pip-tgpaW4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:19:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:19:17 INFO - Running setup.py (path:/tmp/pip-LEVQRC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:19:18 INFO - Running setup.py (path:/tmp/pip-JGzUxS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:19:18 INFO - Running setup.py (path:/tmp/pip-u_Y45c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:19:18 INFO - Running setup.py (path:/tmp/pip-Ryt0Qr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:19:18 INFO - Running setup.py (path:/tmp/pip-ib6C_3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:19:18 INFO - Running setup.py (path:/tmp/pip-LqfMhH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:19:18 INFO - Running setup.py (path:/tmp/pip-wTPD77-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:19:18 INFO - Running setup.py (path:/tmp/pip-VrTQLP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:19:18 INFO - Running setup.py (path:/tmp/pip-s2O52R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:19:18 INFO - Running setup.py (path:/tmp/pip-9TwKpN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:19:18 INFO - Running setup.py (path:/tmp/pip-9gxr_b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:19:18 INFO - Running setup.py (path:/tmp/pip-rUqEwE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:19:18 INFO - Running setup.py (path:/tmp/pip-vMSDQm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:19:18 INFO - Running setup.py (path:/tmp/pip-IhmHQ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:19:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:19:18 INFO - Running setup.py install for manifestparser 14:19:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:19:19 INFO - Running setup.py install for mozcrash 14:19:19 INFO - Running setup.py install for mozdebug 14:19:19 INFO - Running setup.py install for mozdevice 14:19:19 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:19:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:19:19 INFO - Running setup.py install for mozfile 14:19:19 INFO - Running setup.py install for mozhttpd 14:19:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:19:19 INFO - Running setup.py install for mozinfo 14:19:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:19:19 INFO - Running setup.py install for mozInstall 14:19:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:19:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:19:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:19:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:19:19 INFO - Running setup.py install for mozleak 14:19:19 INFO - Running setup.py install for mozlog 14:19:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:19:20 INFO - Running setup.py install for moznetwork 14:19:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:19:20 INFO - Running setup.py install for mozprocess 14:19:20 INFO - Running setup.py install for mozprofile 14:19:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:19:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:19:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:19:20 INFO - Running setup.py install for mozrunner 14:19:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:19:20 INFO - Running setup.py install for mozscreenshot 14:19:20 INFO - Running setup.py install for moztest 14:19:20 INFO - Running setup.py install for mozversion 14:19:20 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:19:20 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:19:20 INFO - Cleaning up... 14:19:20 INFO - Return code: 0 14:19:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:19: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')]} 14:19:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19: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')]} 14:19:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:20 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 0x7fa46b48ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27eb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28b6770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29459c0>, '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 0x29468c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2946d50>, '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': '337fe3f4b1febe37b85fccc40000019e-1462915134.825064-448827991', '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 14:19:20 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 14:19:20 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 14:19:20 INFO - Using env: {'DISPLAY': ':0', 14:19:20 INFO - 'HOME': '/home/cltbld', 14:19:20 INFO - 'LANG': 'en_US.UTF-8', 14:19:20 INFO - 'LANGUAGE': 'en_US:en', 14:19:20 INFO - 'LOGNAME': 'cltbld', 14:19:20 INFO - 'MAIL': '/var/mail/cltbld', 14:19:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:20 INFO - 'MOZ_NO_REMOTE': '1', 14:19:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:19:20 INFO - 'NO_EM_RESTART': '1', 14:19:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:19:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:20 INFO - 'PWD': '/builds/slave/test', 14:19:20 INFO - 'SHELL': '/bin/bash', 14:19:20 INFO - 'SHLVL': '1', 14:19:20 INFO - 'TERM': 'linux', 14:19:20 INFO - 'TMOUT': '86400', 14:19:20 INFO - 'USER': 'cltbld', 14:19:20 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1462915134.825064-448827991', 14:19:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:20 INFO - '_': '/tools/buildbot/bin/python'} 14:19:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:19:20 INFO - Running setup.py (path:/tmp/pip-LxNSR0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:19:21 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)) 14:19:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:19:21 INFO - Running setup.py (path:/tmp/pip-0bAv8P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:19:21 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)) 14:19:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:19:21 INFO - Running setup.py (path:/tmp/pip-WLyzLH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:19:21 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)) 14:19:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:19:21 INFO - Running setup.py (path:/tmp/pip-FXTDF4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:19:21 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)) 14:19:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:19:21 INFO - Running setup.py (path:/tmp/pip-T9gJxx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:19:21 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)) 14:19:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:19:21 INFO - Running setup.py (path:/tmp/pip-C4MpVh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:19:21 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)) 14:19:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:19:21 INFO - Running setup.py (path:/tmp/pip-9jjK8x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:19:21 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)) 14:19:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:19:21 INFO - Running setup.py (path:/tmp/pip-iNBRYi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:19:21 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)) 14:19:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:19:21 INFO - Running setup.py (path:/tmp/pip-seS19m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:19:21 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)) 14:19:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:19:21 INFO - Running setup.py (path:/tmp/pip-YWd_Qp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:19:21 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)) 14:19:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:19:21 INFO - Running setup.py (path:/tmp/pip-0cP5XF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:19:21 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)) 14:19:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:19:21 INFO - Running setup.py (path:/tmp/pip-gRGHw5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:19:21 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)) 14:19:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:19:21 INFO - Running setup.py (path:/tmp/pip-aJKcEc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:19:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 14:19:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:19:21 INFO - Running setup.py (path:/tmp/pip-vTI2Hw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:19:22 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)) 14:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:19:22 INFO - Running setup.py (path:/tmp/pip-6cOa37-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:19:22 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)) 14:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:19:22 INFO - Running setup.py (path:/tmp/pip-iSM7sG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:19:22 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)) 14:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:19:22 INFO - Running setup.py (path:/tmp/pip-eNPGkr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:19:22 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)) 14:19:22 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)) 14:19:22 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)) 14:19:22 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)) 14:19:22 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)) 14:19:22 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)) 14:19:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:19:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:24 INFO - Downloading blessings-1.6.tar.gz 14:19:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:19:24 INFO - Installing collected packages: blessings 14:19:24 INFO - Running setup.py install for blessings 14:19:24 INFO - Successfully installed blessings 14:19:24 INFO - Cleaning up... 14:19:24 INFO - Return code: 0 14:19:24 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:19:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:19:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:19:24 INFO - Reading from file tmpfile_stdout 14:19:24 INFO - Current package versions: 14:19:24 INFO - argparse == 1.2.1 14:19:24 INFO - blessings == 1.6 14:19:24 INFO - blobuploader == 1.2.4 14:19:24 INFO - docopt == 0.6.1 14:19:24 INFO - manifestparser == 1.1 14:19:24 INFO - mozInstall == 1.12 14:19:24 INFO - mozcrash == 0.17 14:19:24 INFO - mozdebug == 0.1 14:19:24 INFO - mozdevice == 0.48 14:19:24 INFO - mozfile == 1.2 14:19:24 INFO - mozhttpd == 0.7 14:19:24 INFO - mozinfo == 0.9 14:19:24 INFO - mozleak == 0.1 14:19:24 INFO - mozlog == 3.1 14:19:24 INFO - moznetwork == 0.27 14:19:24 INFO - mozprocess == 0.22 14:19:24 INFO - mozprofile == 0.28 14:19:24 INFO - mozrunner == 6.11 14:19:24 INFO - mozscreenshot == 0.1 14:19:24 INFO - mozsystemmonitor == 0.0 14:19:24 INFO - moztest == 0.7 14:19:24 INFO - mozversion == 1.4 14:19:24 INFO - psutil == 3.1.1 14:19:24 INFO - requests == 1.2.3 14:19:24 INFO - wsgiref == 0.1.2 14:19:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:19:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:19:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:19:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:24 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 0x7fa46b48ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27eb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28b6770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29459c0>, '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 0x29468c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2946d50>, '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': '337fe3f4b1febe37b85fccc40000019e-1462915134.825064-448827991', '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 14:19:24 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 14:19:24 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 14:19:24 INFO - Using env: {'DISPLAY': ':0', 14:19:24 INFO - 'HOME': '/home/cltbld', 14:19:24 INFO - 'LANG': 'en_US.UTF-8', 14:19:24 INFO - 'LANGUAGE': 'en_US:en', 14:19:24 INFO - 'LOGNAME': 'cltbld', 14:19:24 INFO - 'MAIL': '/var/mail/cltbld', 14:19:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:24 INFO - 'MOZ_NO_REMOTE': '1', 14:19:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:19:24 INFO - 'NO_EM_RESTART': '1', 14:19:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:19:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:24 INFO - 'PWD': '/builds/slave/test', 14:19:24 INFO - 'SHELL': '/bin/bash', 14:19:24 INFO - 'SHLVL': '1', 14:19:24 INFO - 'TERM': 'linux', 14:19:24 INFO - 'TMOUT': '86400', 14:19:24 INFO - 'USER': 'cltbld', 14:19:24 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1462915134.825064-448827991', 14:19:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:24 INFO - '_': '/tools/buildbot/bin/python'} 14:19:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:24 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)) 14:19:24 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)) 14:19:24 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)) 14:19:24 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)) 14:19:24 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)) 14:19:24 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)) 14:19:24 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)) 14:19:24 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)) 14:19:24 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)) 14:19:24 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)) 14:19:24 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)) 14:19:24 INFO - Cleaning up... 14:19:24 INFO - Return code: 0 14:19:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:19:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:19:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:19:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:24 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 0x7fa46b48ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27eb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28b6770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29459c0>, '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 0x29468c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2946d50>, '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': '337fe3f4b1febe37b85fccc40000019e-1462915134.825064-448827991', '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 14:19:24 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 14:19:24 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 14:19:24 INFO - Using env: {'DISPLAY': ':0', 14:19:24 INFO - 'HOME': '/home/cltbld', 14:19:24 INFO - 'LANG': 'en_US.UTF-8', 14:19:24 INFO - 'LANGUAGE': 'en_US:en', 14:19:24 INFO - 'LOGNAME': 'cltbld', 14:19:24 INFO - 'MAIL': '/var/mail/cltbld', 14:19:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:24 INFO - 'MOZ_NO_REMOTE': '1', 14:19:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:19:24 INFO - 'NO_EM_RESTART': '1', 14:19:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:19:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:24 INFO - 'PWD': '/builds/slave/test', 14:19:24 INFO - 'SHELL': '/bin/bash', 14:19:24 INFO - 'SHLVL': '1', 14:19:24 INFO - 'TERM': 'linux', 14:19:24 INFO - 'TMOUT': '86400', 14:19:24 INFO - 'USER': 'cltbld', 14:19:24 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1462915134.825064-448827991', 14:19:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:24 INFO - '_': '/tools/buildbot/bin/python'} 14:19:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:25 INFO - Downloading/unpacking jsonschema 14:19:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:26 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:19:26 INFO - Downloading/unpacking functools32 (from jsonschema) 14:19:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:27 INFO - Downloading functools32-3.2.3-2.tar.gz 14:19:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:19:27 INFO - warning: no files found matching '*.txt' 14:19:27 INFO - no previously-included directories found matching 'build' 14:19:27 INFO - no previously-included directories found matching 'dist' 14:19:27 INFO - no previously-included directories found matching '.git*' 14:19:27 INFO - Installing collected packages: jsonschema, functools32 14:19:27 INFO - Running setup.py install for functools32 14:19:27 INFO - warning: no files found matching '*.txt' 14:19:27 INFO - no previously-included directories found matching 'build' 14:19:27 INFO - no previously-included directories found matching 'dist' 14:19:27 INFO - no previously-included directories found matching '.git*' 14:19:27 INFO - Successfully installed jsonschema functools32 14:19:27 INFO - Cleaning up... 14:19:27 INFO - Return code: 0 14:19:27 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 14:19: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')]} 14:19:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19: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')]} 14:19:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:27 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 0x7fa46b48ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27eb8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x28b6770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29459c0>, '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 0x29468c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2946d50>, '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': '337fe3f4b1febe37b85fccc40000019e-1462915134.825064-448827991', '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 14:19:27 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 14:19:27 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 14:19:27 INFO - Using env: {'DISPLAY': ':0', 14:19:27 INFO - 'HOME': '/home/cltbld', 14:19:27 INFO - 'LANG': 'en_US.UTF-8', 14:19:27 INFO - 'LANGUAGE': 'en_US:en', 14:19:27 INFO - 'LOGNAME': 'cltbld', 14:19:27 INFO - 'MAIL': '/var/mail/cltbld', 14:19:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:27 INFO - 'MOZ_NO_REMOTE': '1', 14:19:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:19:27 INFO - 'NO_EM_RESTART': '1', 14:19:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:19:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:27 INFO - 'PWD': '/builds/slave/test', 14:19:27 INFO - 'SHELL': '/bin/bash', 14:19:27 INFO - 'SHLVL': '1', 14:19:27 INFO - 'TERM': 'linux', 14:19:27 INFO - 'TMOUT': '86400', 14:19:27 INFO - 'USER': 'cltbld', 14:19:27 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1462915134.825064-448827991', 14:19:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:27 INFO - '_': '/tools/buildbot/bin/python'} 14:19:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:27 INFO - Downloading/unpacking flake8 14:19:27 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 14:19:27 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 14:19:27 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 14:19:27 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 14:19:27 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 14:19:27 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 14:19:29 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 14:19:29 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 14:19:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:29 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 14:19:29 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 14:19:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:29 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 14:19:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 14:19:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:19:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:19:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:19:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:19:30 INFO - no previously-included directories found matching 'docs/_build' 14:19:30 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 14:19:30 INFO - Running setup.py install for pep8 14:19:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:19:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:19:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:19:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:19:30 INFO - no previously-included directories found matching 'docs/_build' 14:19:30 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 14:19:30 INFO - Successfully installed flake8 mccabe pyflakes pep8 14:19:30 INFO - Cleaning up... 14:19:30 INFO - Return code: 0 14:19:30 INFO - Running post-action listener: _resource_record_post_action 14:19:30 INFO - Running post-action listener: _start_resource_monitoring 14:19:30 INFO - Starting resource monitoring. 14:19:30 INFO - ##### 14:19:30 INFO - ##### Finished create-virtualenv step (success) 14:19:30 INFO - ##### 14:19:30 INFO - ##### 14:19:30 INFO - ##### Running install step. 14:19:30 INFO - ##### 14:19:30 INFO - Running pre-action listener: _resource_record_pre_action 14:19:30 INFO - Running main action method: install 14:19:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:19:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:19:30 INFO - Reading from file tmpfile_stdout 14:19:30 INFO - Detecting whether we're running mozinstall >=1.0... 14:19:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:19:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:19:30 INFO - Reading from file tmpfile_stdout 14:19:30 INFO - Output received: 14:19:30 INFO - Usage: mozinstall [options] installer 14:19:30 INFO - Options: 14:19:30 INFO - -h, --help show this help message and exit 14:19:30 INFO - -d DEST, --destination=DEST 14:19:30 INFO - Directory to install application into. [default: 14:19:30 INFO - "/builds/slave/test"] 14:19:30 INFO - --app=APP Application being installed. [default: firefox] 14:19:30 INFO - mkdir: /builds/slave/test/build/application 14:19:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 14:19:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 14:19:46 INFO - Reading from file tmpfile_stdout 14:19:46 INFO - Output received: 14:19:46 INFO - /builds/slave/test/build/application/firefox/firefox 14:19:46 INFO - Running post-action listener: _resource_record_post_action 14:19:46 INFO - ##### 14:19:46 INFO - ##### Finished install step (success) 14:19:46 INFO - ##### 14:19:46 INFO - ##### 14:19:46 INFO - ##### Running run-tests step. 14:19:46 INFO - ##### 14:19:46 INFO - Running pre-action listener: _resource_record_pre_action 14:19:46 INFO - Running main action method: run_tests 14:19:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:19:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:19:46 INFO - Python 2.7.3 14:19:46 INFO - Return code: 0 14:19:46 INFO - grabbing minidump binary from tooltool 14:19:46 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')]} 14:19:46 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 0x29459c0>, '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 0x29468c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2946d50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:19:46 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 14:19:46 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 14:19:46 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:19:46 INFO - Return code: 0 14:19:46 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:19:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:19:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:19:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:19:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:19:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 14:19:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 14:19:47 INFO - Return code: 0 14:19:47 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/aQv61iDDRRyaE6dkYl149Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-060', '--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 14:19:47 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/aQv61iDDRRyaE6dkYl149Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-060 --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 14:19:47 INFO - Using env: {'DISPLAY': ':0', 14:19:47 INFO - 'HOME': '/home/cltbld', 14:19:47 INFO - 'LANG': 'en_US.UTF-8', 14:19:47 INFO - 'LANGUAGE': 'en_US:en', 14:19:47 INFO - 'LOGNAME': 'cltbld', 14:19:47 INFO - 'MAIL': '/var/mail/cltbld', 14:19:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:19:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:19:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:47 INFO - 'MOZ_NO_REMOTE': '1', 14:19:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:19:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:19:47 INFO - 'NO_EM_RESTART': '1', 14:19:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:19:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:47 INFO - 'PWD': '/builds/slave/test', 14:19:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:19:47 INFO - 'SHELL': '/bin/bash', 14:19:47 INFO - 'SHLVL': '1', 14:19:47 INFO - 'TERM': 'linux', 14:19:47 INFO - 'TMOUT': '86400', 14:19:47 INFO - 'USER': 'cltbld', 14:19:47 INFO - 'XDG_SESSION_COOKIE': '337fe3f4b1febe37b85fccc40000019e-1462915134.825064-448827991', 14:19:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:47 INFO - '_': '/tools/buildbot/bin/python'} 14:19:47 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/aQv61iDDRRyaE6dkYl149Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-060', '--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 14:19:47 INFO - mozversion application_buildid: 20160510135009 14:19:47 INFO - mozversion application_changeset: ee562525573f8896fe4f7a5ac053de3d97ae4ccb 14:19:47 INFO - mozversion application_display_name: Nightly 14:19:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:19:47 INFO - mozversion application_name: Firefox 14:19:47 INFO - mozversion application_remotingname: firefox 14:19:47 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 14:19:47 INFO - mozversion application_vendor: Mozilla 14:19:47 INFO - mozversion application_version: 49.0a1 14:19:47 INFO - mozversion platform_buildid: 20160510135009 14:19:47 INFO - mozversion platform_changeset: ee562525573f8896fe4f7a5ac053de3d97ae4ccb 14:19:47 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 14:19:47 INFO - mozversion platform_version: 49.0a1 14:19:47 INFO - using testdate: 1462915187 14:19:47 INFO - actual date: 1462915187 14:19:47 INFO - starting webserver on 'localhost:60352' 14:19:47 INFO - SUITE-START | Running 2 tests 14:19:47 INFO - TEST-START | dromaeo_css 14:19:47 INFO - operating with platform_type : linux_ 14:19:47 INFO - Initialising browser for dromaeo_css test... 14:19:47 INFO - TEST-INFO | started process 14576 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5U9gvG/profile http://localhost:60352/getInfo.html) 14:19:51 INFO - PROCESS | 14576 | 14:19:51 INFO - PROCESS | 14576 | (process:14645): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 14:19:52 INFO - PROCESS | 14576 | __metrics Screen width/height:1600/1200 14:19:52 INFO - PROCESS | 14576 | colorDepth:24 14:19:52 INFO - PROCESS | 14576 | Browser inner width/height: 1024/697 14:19:52 INFO - PROCESS | 14576 | __metrics 14:19:53 ERROR - PROCESS | 14576 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 14:19:54 INFO - TEST-INFO | 14576: exit 0 14:19:54 INFO - Browser initialized. 14:19:54 INFO - Running cycle 1/1 for dromaeo_css test... 14:19:54 INFO - TEST-INFO | started process 14696 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5U9gvG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:19:56 INFO - PROCESS | 14696 | 14:19:56 INFO - PROCESS | 14696 | (process:14759): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 14:20:06 INFO - PROCESS | 14696 | RSS: Main: 164360192 14:20:06 INFO - PROCESS | 14696 | 14:20:55 INFO - PROCESS | 14696 | 1462915255695 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] 14:23:07 INFO - PROCESS | 14696 | Cycle 1(1): loaded http://localhost:60352/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60352/tests/dromaeo/cssquery-ext.html) 14:23:08 INFO - PROCESS | 14696 | RSS: Main: 167477248 14:23:08 INFO - PROCESS | 14696 | 14:26:10 INFO - PROCESS | 14696 | Cycle 1(1): loaded http://localhost:60352/tests/dromaeo/cssquery-ext.html (next: http://localhost:60352/tests/dromaeo/cssquery-jquery.html) 14:26:11 INFO - PROCESS | 14696 | RSS: Main: 166875136 14:26:11 INFO - PROCESS | 14696 | 14:29:13 INFO - PROCESS | 14696 | Cycle 1(1): loaded http://localhost:60352/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60352/tests/dromaeo/cssquery-mootools.html) 14:29:13 INFO - PROCESS | 14696 | RSS: Main: 167424000 14:29:13 INFO - PROCESS | 14696 | 14:32:15 INFO - PROCESS | 14696 | Cycle 1(1): loaded http://localhost:60352/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60352/tests/dromaeo/cssquery-prototype.html) 14:32:16 INFO - PROCESS | 14696 | RSS: Main: 165109760 14:32:16 INFO - PROCESS | 14696 | 14:35:18 INFO - PROCESS | 14696 | Cycle 1(1): loaded http://localhost:60352/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60352/tests/dromaeo/cssquery-yui.html) 14:35:19 INFO - PROCESS | 14696 | RSS: Main: 166010880 14:35:19 INFO - PROCESS | 14696 | 14:38:22 INFO - PROCESS | 14696 | Cycle 1(1): loaded http://localhost:60352/tests/dromaeo/cssquery-yui.html (next: http://localhost:60352/tests/dromaeo/cssquery-dojo.html) 14:38:22 INFO - PROCESS | 14696 | RSS: Main: 168017920 14:38:22 INFO - PROCESS | 14696 | 14:38:22 INFO - PROCESS | 14696 | __start_tp_report 14:38:22 INFO - PROCESS | 14696 | _x_x_mozilla_page_load 14:38:22 INFO - PROCESS | 14696 | _x_x_mozilla_page_load_details 14:38:22 INFO - PROCESS | 14696 | |i|pagename|runs| 14:38:22 INFO - PROCESS | 14696 | |0;dojo.html;2646.5368560501915;2681.8793154308055;2696.514627367074;2709.444563864408;2718.9803823566162;4770.689905156162;4820.879478013051;4872.415310162781;4876.804927802884;4935.309056732051;4711.010687755567;4764.499727528603;4780.90438191236;4825.348577941977;4829.78265978031;4402.493713222981;4403.119376124771;4422.18189634917;4436.223660859337;4541.568550987668;4365.738055716652;4414.763866117476;4453.777311134447;4463.4420697412825;4516.4806047304655;4340.414044104069;4462.174497717932;4464.308032255006;4504.234280172379;4510.278355463143;4452.933206001912;4453.131639330332;4462.174497717932;4479.3281007848755;4498.347739577743;4426.933595996063;4431.135928493945;4432.22436073689;4448.866534003985;4492.213862574069;4573.908521829578;4596.195665758479;4626.791794369262;4649.953500464986;4718.079974404993;4971.403431588196;5040.6471546991725;5048.1418158913075;5062.873428164288;5101.6173786965755;5085.949140508622;5092;5092.28707980883;5096.79612815487;5139.100657384943;4765.404324459442;4833.879153021132;4844.685095468783;4848.175810112219;4860.6111511079025;5295.285136506523;5324.41431442541;5326.334208223972;5335.919961200585;5384.6499977501335;4914.33656456381;5028.069807085701;5097.311862898536;5108.233764935289;5111.514406131411;5126.512981266774;5151.072806894795;5165.793368265227;5168.2764412982215;5212.635115541876;5113.437521872591;5137.717425541631;5144.382674079135;5153.2785410042625;5192.039472697563;4918.9508104919205;5122.282880396748;5126.53861152498;5127.512886275797;5139.357580302462;4244.893877653083;4304.677149213821;4378.211921854033;4482.932756008662;4506.864794056183;3817.9809100954317;3829.9808500957315;3875.3411919973673;3875.554311254242;3883.728139030241;4400.3399490076745;4414.426124603781;4436.778161091933;4441.955580444219;4490.2142125127975;5171;5197.58419326453;5206.479352064839;5208.869778255498;5261.894762104736;4425.402570652985;4453.4433195850515;4467.12890986261;4473.977630111829;4516;4596.103759766814;4623.86128416148;4645.628349732014;4700.106979673919;4742.715437073786;4802.711837289774;4823.469418364048;4841.201201801734;4858.1012512685265;4871.585915197106;5103.362079740033;5112.744362781772;5116.718580478109;5119.974400127976;5145.331106956221;4675.4155730533685;4710.128626204163;4743.193657078236;4764.523547645208;4841.564259216688;4977.726225057657;5001.1498045331655;5005.874853128701;5007.549320561167;5061.493850614909;5090.974545127251;5094.337736094432;5095.057414378203;5103.668261562986;5150.819721309736;4852.708837469763;4966.8758281043265;4996.325496057985;5022.045811295989;5031.572316353151;4975.527324904128;5028.74856257193;5033.874153146201;5041;5083.949160508496;4765.547273009059;4888.560029597354;4941.481144479922;4949.727764972961;4952.752362381794;4957.182064959241;4977.153883839828;4996.2755400466;5044.621653375938;5067.341245638044;4861.683990540603;4890.657653964188;4897;4900.019996000743;4964.6524743269065 14:38:22 INFO - PROCESS | 14696 | |1;ext.html;560.1092896174865;579.3308728418676;596.4244504053587;604.6787768725333;631.1700114349627;36811.631883681155;40455;40571.79714101433;40667.796661016655;40681.99999999996;41505.58494415062;42393;42422;42424.36363454551;42505;7822.647980840861;7910.881336779953;7960.164182760785;7963.641636126388;7975.561344126071;7656;7752.069751629812;7756.3794896408435;7787.96106019472;7793.454458187929;7682.385409167281;7713.614319284042;7713.961430192841;7736.032995875516;7737.381009519225;7785.1436342002335;7811.726589569366;7841.529508229496;7847.0975837778815;7868.685252589889;7820.45256832022;7824.726134585319;7849.843003139905;7853.607319633996;7908.644111015018;30992.225194370094;37041.81479092614;37447.62552374496;37508.81245593754;37522.24955500902;2831.2395414470934;2960.718731720483;3076.400101980115;3077.492213784723;3117.0804612639326;28332.716672833212;28691.85654071737;28725.856370718222;29424.41151176986;29735.85132074326;3034.9393012139853;3080.738137258336;3111.7510599152015;3126.5935428394164;3202.519622056687;219198;219479;220499;220924;220945;34142.48786268208;34297.82851085729;34387;34389.828050859585;34409.655903440645;33905.83047084749;36061.0984725384;36154.638453615655;36281.45577816335;36290.27419451649;14642.85357146415;14689.412423503154;14763.483278085216;14822.555323340317;14826.555203343916;12144.817827732591;12292.201006934522;12297.262164270172;12312.692182695326;12331.321777302335;8372.748817535483;8479.70321038773;8508.27679647237;8514.063453020162;8572.914270857336;3329.9500507492407;3338.9332213355597;3349.7487688423466;3368.6294507604143;3378.138574663445;950.6245033211843;984.921206303494;1050.6112738286884;1050.648032908984;1054.5518154784186;29396.706032939397;29577.52112394415;29785.85107074451;29948.85025574858;30116.69883301183;1021.9489025548693;1051.4637534857125;1065.733566608348;1075.8816530181673;1085.2837127495816;15384.538463846102;15849.683006339808;15990.120543370227;16092.356305747873;16104.838951610569;116802;117614;117813;383000;389268;51925.7403712979;52363.73818130885;52381.476185238425;52579.73710131425;52700.47299527032;1264.083539433903;1295.2228662802243;1300.1873828856965;1312.934353282332;1315.1395714321313;27445.725542744316;28734.568981465713;28841.423171536033;28954.710452896044;29421.11736647904;44741;45373;45409.31886021778;45471.31793023041;45509.54490455053;323701;323927;324769;325864;326642;26221.868890655423;26836.329091772863;27596.448071038867;27729.445411092067;29504.409911801213;44834;45417.77291113523;45731.08537829291;46229;46463.76768116138;38374;38893.80553097217;38963.41554876622;39234.41148382719;39287.41068884026;37262.62737372592;37680.623193767715;38084;39116.217675645756;39572.20855582815;33844.492332614536;37143.44284835675;37622.62377376301;38515.42226866655;38579.6142038576;33055;35008.429355042164;35041;35180.47229291512;35371.64628353786;26632.78423275647;26849.597256041565;27002.594961075207;27098;27318.726812732413 14:38:22 INFO - PROCESS | 14696 | |2;jquery.html;386.78340129527464;388.91055057336814;389.4112022946379;391.1999960081633;408.4118868828886;13124.278164700938;14008;15230.695386092286;16492;16528.752068718964;15989.84010159898;16505.83494165061;17250.39623613174;17368.56578585536;17370.392036278732;5155.639105262624;5334.706591137486;5338.946610533903;5340.439253878345;5348.197770334462;5102.668326558781;5289.391719952217;5309.601779866525;5330.38700549436;5334.946650533503;5246.344206974129;5247.160454327309;5247.632665713401;5256.395514515842;5279.102552566073;5023.422306434771;5270.341207349082;5278.868028299285;5294.338207724035;5294.682319060849;4667.15991121598;4712.835050773241;4715.962488252579;4723;4724.291356296549;16388.26252818626;19679.11443985024;20811.271605493734;20845.16619335225;20862;1770.5750619851271;1884.076802366844;1922.1542521290587;1927.4892153579312;2091.2994146960787;9175.31185161109;9521.047895210493;9577.808443831085;10879.292845965067;13502.797458038138;1710.1021963469277;1715.4939292908623;1744.2586900567208;1802.6755184066994;1836.4215272189351;62212.68893655548;65402.34597654058;66111.338886611;70894;76675;9959.103680668775;9973.551190196458;10003.149732272694;10228.846567301496;10309.587616495406;13418;13507.797383039262;13524;13688.65778355529;13729.76432121114;7524.887126693104;7589.696412143563;7673.808154796175;7775.833624956302;7815.491993020435;7260.782176534713;7360.815979600553;7421.220771818989;7499.475036747375;7520.699172033167;4309.698321117555;4384.12317536494;4408.713433626804;4441.689081764245;4580.770961451914;1815.972760408595;1816.2462578030234;1819.8635102367375;1846.6768315544725;1869.26164165154;906.428949761646;951.9048095190564;976.9951150244345;978.5107446276861;1024.902634249745;16321.83678163227;16364.83635163657;16862.409815656392;16883.32466701318;16992.57518562046;899.8065415935474;915.1397686174975;915.8626206069143;917.202034230224;926.4672813132476;9052.8642070369;9295.90704092964;9733.659321923698;10044.146247569039;10214.182865370753;283099;286169;286508;428895;430206;48659;52753;90041;90700;90809;926.6895589977298;977.6920270114749;978.6232300564192;984.7144328144758;990.6730778843108;15439.691206176038;15651.843481565038;15717.214139293219;15776.763348549552;15907.761383579487;36108.63891361053;36381.81809090938;36893.81553092218;36999;37725.245495090494;49688.25467618061;49802.25296620626;50013.249801252285;50137.247941280184;50359;6783.966080169567;7167.462440317101;7334.3032411920785;7426.4058875288765;7952.602369881599;8796.692115775917;11120.165172746407;11739.765204696028;12042.759144816879;12140.271583705005;6084.209052823105;9769.16962058233;10144.442055687008;10429.739256518344;10655.946720266349;10009.899901001188;10456.424896630759;10773.24587278883;10823.512941917475;10961.835572466578;10856.837147442637;11034.337939723642;11174.820909922812;11398.259113157617;11414.258073225214;7808.609569521387;8076.515409075474;8085.555294458862;8238.176182381714;8357.164283571594;8070.435069545076;9946.502674866373;10516.78966420652;10804.945975270073;10954.178436617445 14:38:22 INFO - PROCESS | 14696 | |3;mootools.html;1271.6693659648492;1283.8010108433193;1299.8245236893022;1557.3069983857188;1573.9533210415075;6485.929821579433;7231.493795434315;7508.587027713487;7637.694492220311;7744.961275193623;7545.54726716396;7717.151113377538;7777.92222077779;7788.610569471518;7793.571353575551;2179.095675294751;2212.126210146991;2216.1135545781653;2219.9001044953015;2232.7320721513443;2175.3365223606793;2213.103693004336;2223.2218723446745;2225.4213904384897;2234.084025549525;2185.530111026129;2192.0355043780687;2195.9670604940857;2197.48359135603;2231.7321921369457;2143.3998480425507;2172.098579089684;2182.6835108909117;2183.465051062492;2184.7924447177493;2141.496748264157;2162.7729088445803;2169.8372622053253;2196.461866842618;2202.9228976985887;7779.9611001945195;7850.411219158586;7867.724629637935;7892.842143157162;7897.091834438998;4678.672492925497;4791.089692958327;4815.470298267188;4838.346823178861;4849.175640141185;2983.925401864949;3002.369502404498;3089.721925026736;3118.5322201669705;3121.547375630521;907.2741806554795;943.8253923024263;980.764616492044;987.0425687083517;993.7118235711634;10494.632687855965;10518.474076296154;10562.735931601695;10569.365838049742;10578.57685692579;4257.446531950826;4288.635465985426;4364.388985542027;4374.518802931675;4400.053988392511;9047.502387368759;9084.909150908406;9137.954310228406;9158.725238242863;9181.403208791407;4617.145828021827;4681.157391669464;4702.36518070063;4733.71597704132;4779.211430114062;3719.5722491913234;4190.664746820247;4335.176316499855;4337.262665346899;4415.735055896656;3555.8577656893954;3605.5853576838476;3623.239119784822;3642.4354225095153;3760.4547340635454;1212.2544635049558;1212.3211001839;1219.8536175658978;1222.6637674639546;1229.7356068445326;733.2447578993645;791.8099656082499;797.7726347990795;802.1937952357843;842.9831403372021;5089.6691715038405;5100.872478188001;5122.334096567422;5136.768845401966;5164.767585458663;933.537898740128;936.8594710793299;945.3807755919884;952.7522844060594;959.472290240365;3543.6633519815578;3579.2304654499408;3594.7483676142942;3599.9640003600152;3646.4530320451613;9550.474723890253;9735.123838854395;9746.75633109178;9851.605935762633;9860.30977831545;8908.510031948175;9584.28117891161;9641.469719165372;9711.27165462579;9740.269479788902;6965.373116419547;7129.786106416712;7251.1661158966335;7254.854902901912;7317.451191160684;5168.922466162935;5337.466253374631;5364.839054828361;5423.2407462954425;5453.700046497481;8395.748127556182;8398.496090234605;8468.3648726347;8476.745697629081;8514.872276915976;8060.193980601893;8258.669653213805;8398.580070996304;8573.657053717781;8590.312774978112;5709.143628455598;6607.075009498578;6657.600543967377;6773.187217533928;6944.861102778017;7394.889076663747;7448;7465.253474652492;7471;7471.551706897603;6693.297203793726;6989.706904222735;7020.052292940388;7087.291270872872;7089.964550177217;6400.359964003748;6461.321561236003;6521.9673901630185;6599.307072757291;6619.271880093283;6023.216981792339;6088;6121.540884433596;6131.233595800525;6138.048602466625;4536.977315113403;4573.405457290531;4607.677462577587;4693.037927224979;4697.318888761179;4507.233770258998;4568.634509239319;4579.473360563577;4617.8152873884665;4640.605548528413 14:38:22 INFO - PROCESS | 14696 | |4;prototype.html;299.8126171143036;315.7505570599227;316.1937060495735;320.1260558674818;322.12864202332685;4874.610735940258;4931.1617025105725;5015.398152221734;5088.9745551272235;5104.719240441774;4935.3337299464565;4942.357493525846;4952.801887924478;4968.627352948534;4982.750862456881;3885.922281554367;3906.570277269512;3910.687145028405;3913.706472014596;3935.3309937310582;3684.521012268401;3909.120447899214;3909.3158697228014;3915.9804200978956;3938.6805420184273;3822.694184465236;3843.1929294848114;3843.9231215375676;3847.942280865789;3855.8264878080554;3877.2051729395407;3878.4376265441633;3883.650471457568;3888.5333759948985;3896.1428485733377;3399.677030682106;3420.9657903421144;3421.0763093964492;3427.5715535558056;3435.7766745161734;4582.502536117824;4724.811007559693;4751.786169622375;4859.708417494926;4885.413750349981;4368.584984426506;4384.640761363988;4388.078503514266;4409.294512877957;4442.822287108512;5211.296474975868;5214.973925130388;5237.659552129137;5256.264123022742;5273.495089832338;3635.818209089535;3692.593814680383;3733.253349330145;3740.214554943465;3757.3236817372863;5474.631342164459;5491.643043202219;5491.917621235684;5500.834974950758;5504.724763761796;3792.544894612664;3808.619138086169;3815.523059617548;3817.2938006468894;3825.827837747308;3870.845166193321;3873.438351439082;3875.825587848497;3895.7467764595212;3899.53205615328;4277.337012763027;4365.82122826835;4378.16814805186;4404.317330813729;4412.271975124068;5223.712695801767;5254.632175747737;5267.552258058108;5291.656042357235;5333.733313334318;1251.8622951475331;1297.3642914971597;1311.5868501422212;1317.1109501086728;1324.973500529984;831.9573536112727;837.8868852704807;842.9494230346199;846.6105591428012;852.624845068168;4025.6578190854107;4057.695672824491;4063.309237429644;4082.1631565528996;4101.302778527657;4688.484266730657;4750.334953106568;4763.857084287477;4796.880077998078;4797.56821886032;4059.7361171523758;4093.85671501496;4098.344264917599;4099.508059032935;4119.340905455173;2551.5662337402314;2675.7190494998135;2884.379858330427;2891.9132426027254;2903.259668784489;5067.974660126676;5075.441701412841;5086.898262034739;5103.897922041539;5134.332536770196;5353.2773075634295;5543.916841247385;5577.637453565505;5582;5596.58025648078;4911.582515486224;5022.547970682656;5024.773885175176;5048.29323894655;5050.444451110375;5366.275552800322;5370.113931201308;5375.4087050425205;5419.4038655747045;5470.249520153513;5128.076946149579;5306.469353064663;5349.144136938071;5382.946170538401;5406.972965135149;5020.397552293748;5026.798928042837;5031.144705400164;5060.342155519759;5075.720835353944;3782.413725872494;3810.4665346850907;3814.904627384338;3836.5204349456317;3860.4981352424006;5238.738063096906;5358.303420555303;5411.0801163803035;5428.592855535771;5448.128299472065;5106.285120083117;5156.4327923927585;5175.430702622783;5184.533391994739;5199.168133098686;5131.25596788471;5157.7679004444135;5159.17453207485;5180.896382072262;5189.792408303626;4999.850004499871;5097.6941383517105;5145.897082058263;5153.2270159476375;5169.560587349967;5270.209468579744;5275.525202731773;5279;5300.946990530046;5302.43578144456;5107.412647545431;5132.871678208075;5139.948600513947;5147.3308469898675;5156.278121062979 14:38:22 INFO - PROCESS | 14696 | |5;yui.html;703.4934846910223;714.0752725220842;739.0761548064919;747.9252074792521;762.748293063289;11627.593034243802;12103.939480302586;12125.454354554044;12139.271643701373;12193.329366884818;11530.538778448872;11611.477483513241;11613.825792613117;11623.941880290608;11628.825567616472;527.8337323743028;542.1325878594238;544.3032917865139;558.4220331956426;674.2482132422363;850.2815121222588;858.7166235142388;861.6165806216205;864.0581765875195;866.3242670716819;905.7011186308536;907.6233363154316;908.6637943960744;908.6774195160722;909.3952521573146;896.5293221058931;898.595631965618;899.096408109852;907.3739120007207;907.4872696926282;669.427639368338;669.7957123077459;670.4904272752743;671.2649648634737;671.7783131566571;12428;12429.813552796715;12433.067519936041;12442.688932776662;12445.25328480294;10804.621838235702;10933.56265749369;10981.670549883516;11011.174161937806;11157.163212759075;9857.408555486694;9908.157806586449;9929.156021738161;9966.750831229205;10102.898971010269;4074.449949256842;4127.2983592789;4160.126373461577;4199.811008504625;4421.557844215585;45084.549154508364;45559.08881822378;45894.54105458936;46391;46556.53443465556;8715.956420217984;8833.955830220937;9366.250699943988;9430.292728045424;9630.951845240728;15772.211389430711;15830.68338633244;15834;15838.9208053959;15863.682726345409;6319.842003949938;6352.205974253218;6599.934000659932;6693.899591506131;6787.04981302618;3500.317438099546;3551.857925682944;3601.4957905893198;3656.0859785053735;3709.573399059089;2751.876165572554;2829.3633932365465;2832.320243141631;2834.0789243495947;2844.104107206265;519.828456609318;530.3635637235333;531.9255304257407;534.1240365800091;542;462.55132521454806;473.66606542387535;514.3930162408319;544.6595877576515;586.6509426890993;7116.787410066571;7305.707771689073;7367.6001559704555;7439.995600593958;7605.315521602972;491.1257960829729;502.64563482744575;551.0439387662402;555.4306835493651;574.6494638270651;3499.3876071687355;3529.6646818552194;3530.435130379127;3559.199180184438;3576.141725985745;48720;48967.265491017664;49921;50183.49816501861;51076;45738.77130614392;46234.768826155654;46362.39584530862;47419.5258047422;47467.28799068017;42430.57569424328;42602.14795704131;43330.35004474936;43343;43796.3430548542;7180.928190718026;7408.555486670817;7414.147373051952;7511.586862722603;7527.585982771;27090.729092709607;27565.86217068902;28423;29655.85172074126;30530.84734576313;37452.62547374491;37686.2462750749;37968.430473542365;38666.80666596649;39016;10938.781224375309;10948.507317170586;11069.612563560237;11197.328160310408;11246.975544402087;11860.22908510944;12015.45930433115;12020.939895300467;12062.215955962834;12231.449584768528;11411.828822567833;11431.542738290374;11460.656180314603;11843.467043983213;12078.879211208126;10523.684289471328;10726.356418614909;10755.139588833032;10777.676669699922;10812.513436895515;10838.620648277272;11041.171912106463;11107.278026928223;11277.154213433862;11794.46924888365;8464.746057618282;8496.235338819288;8786.780330491789;8977.506237157018;8999;9234.584443699916;9633.061916115652;9672.322937394314;9675.419474831533;9719 14:38:22 INFO - PROCESS | 14696 | __end_tp_report 14:38:22 INFO - PROCESS | 14696 | __start_cc_report 14:38:22 INFO - PROCESS | 14696 | _x_x_mozilla_cycle_collect,351 14:38:22 INFO - PROCESS | 14696 | __end_cc_report 14:38:22 INFO - PROCESS | 14696 | __startTimestamp1462916302399__endTimestamp 14:38:22 INFO - PROCESS | 14696 | 14:38:22 INFO - PROCESS | 14696 | ------- Summary: start ------- 14:38:22 INFO - PROCESS | 14696 | Number of tests: 6 14:38:22 INFO - PROCESS | 14696 | 14:38:22 INFO - PROCESS | 14696 | [#0] dojo.html Cycles:165 Average:4746.51 Median:4860.61 stddev:487.30 (10.0%) stddev-sans-first:460.11 14:38:22 INFO - PROCESS | 14696 | Values: 2646.5 2681.9 2696.5 2709.4 2719.0 4770.7 4820.9 4872.4 4876.8 4935.3 4711.0 4764.5 4780.9 4825.3 4829.8 4402.5 4403.1 4422.2 4436.2 4541.6 4365.7 4414.8 4453.8 4463.4 4516.5 4340.4 4462.2 4464.3 4504.2 4510.3 4452.9 4453.1 4462.2 4479.3 4498.3 4426.9 4431.1 4432.2 4448.9 4492.2 4573.9 4596.2 4626.8 4650.0 4718.1 4971.4 5040.6 5048.1 5062.9 5101.6 5085.9 5092.0 5092.3 5096.8 5139.1 4765.4 4833.9 4844.7 4848.2 4860.6 5295.3 5324.4 5326.3 5335.9 5384.6 4914.3 5028.1 5097.3 5108.2 5111.5 5126.5 5151.1 5165.8 5168.3 5212.6 5113.4 5137.7 5144.4 5153.3 5192.0 4919.0 5122.3 5126.5 5127.5 5139.4 4244.9 4304.7 4378.2 4482.9 4506.9 3818.0 3830.0 3875.3 3875.6 3883.7 4400.3 4414.4 4436.8 4442.0 4490.2 5171.0 5197.6 5206.5 5208.9 5261.9 4425.4 4453.4 4467.1 4474.0 4516.0 4596.1 4623.9 4645.6 4700.1 4742.7 4802.7 4823.5 4841.2 4858.1 4871.6 5103.4 5112.7 5116.7 5120.0 5145.3 4675.4 4710.1 4743.2 4764.5 4841.6 4977.7 5001.1 5005.9 5007.5 5061.5 5091.0 5094.3 5095.1 5103.7 5150.8 4852.7 4966.9 4996.3 5022.0 5031.6 4975.5 5028.7 5033.9 5041.0 5083.9 4765.5 4888.6 4941.5 4949.7 4952.8 4957.2 4977.2 4996.3 5044.6 5067.3 4861.7 4890.7 4897.0 4900.0 4964.7 14:38:22 INFO - PROCESS | 14696 | 14:38:22 INFO - PROCESS | 14696 | [#1] ext.html Cycles:180 Average:41578.91 Median:35024.71 stddev:71781.58 (204.9%) stddev-sans-first:71916.87 14:38:22 INFO - PROCESS | 14696 | Values: 560.1 579.3 596.4 604.7 631.2 36811.6 40455.0 40571.8 40667.8 40682.0 41505.6 42393.0 42422.0 42424.4 42505.0 7822.6 7910.9 7960.2 7963.6 7975.6 7656.0 7752.1 7756.4 7788.0 7793.5 7682.4 7713.6 7714.0 7736.0 7737.4 7785.1 7811.7 7841.5 7847.1 7868.7 7820.5 7824.7 7849.8 7853.6 7908.6 30992.2 37041.8 37447.6 37508.8 37522.2 2831.2 2960.7 3076.4 3077.5 3117.1 28332.7 28691.9 28725.9 29424.4 29735.9 3034.9 3080.7 3111.8 3126.6 3202.5 219198.0 219479.0 220499.0 220924.0 220945.0 34142.5 34297.8 34387.0 34389.8 34409.7 33905.8 36061.1 36154.6 36281.5 36290.3 14642.9 14689.4 14763.5 14822.6 14826.6 12144.8 12292.2 12297.3 12312.7 12331.3 8372.7 8479.7 8508.3 8514.1 8572.9 3330.0 3338.9 3349.7 3368.6 3378.1 950.6 984.9 1050.6 1050.6 1054.6 29396.7 29577.5 29785.9 29948.9 30116.7 1021.9 1051.5 1065.7 1075.9 1085.3 15384.5 15849.7 15990.1 16092.4 16104.8 116802.0 117614.0 117813.0 383000.0 389268.0 51925.7 52363.7 52381.5 52579.7 52700.5 1264.1 1295.2 1300.2 1312.9 1315.1 27445.7 28734.6 28841.4 28954.7 29421.1 44741.0 45373.0 45409.3 45471.3 45509.5 323701.0 323927.0 324769.0 325864.0 326642.0 26221.9 26836.3 27596.4 27729.4 29504.4 44834.0 45417.8 45731.1 46229.0 46463.8 38374.0 38893.8 38963.4 39234.4 39287.4 37262.6 37680.6 38084.0 39116.2 39572.2 33844.5 37143.4 37622.6 38515.4 38579.6 33055.0 35008.4 35041.0 35180.5 35371.6 26632.8 26849.6 27002.6 27098.0 27318.7 14:38:22 INFO - PROCESS | 14696 | 14:38:22 INFO - PROCESS | 14696 | [#2] jquery.html Cycles:180 Average:22847.49 Median:4425.20 stddev:58186.58 (1314.9%) stddev-sans-first:58325.37 14:38:22 INFO - PROCESS | 14696 | Values: 386.8 388.9 389.4 391.2 408.4 13124.3 14008.0 15230.7 16492.0 16528.8 15989.8 16505.8 17250.4 17368.6 17370.4 5155.6 5334.7 5338.9 5340.4 5348.2 5102.7 5289.4 5309.6 5330.4 5334.9 5246.3 5247.2 5247.6 5256.4 5279.1 5023.4 5270.3 5278.9 5294.3 5294.7 4667.2 4712.8 4716.0 4723.0 4724.3 16388.3 19679.1 20811.3 20845.2 20862.0 1770.6 1884.1 1922.2 1927.5 2091.3 9175.3 9521.0 9577.8 10879.3 13502.8 1710.1 1715.5 1744.3 1802.7 1836.4 62212.7 65402.3 66111.3 70894.0 76675.0 9959.1 9973.6 10003.1 10228.8 10309.6 13418.0 13507.8 13524.0 13688.7 13729.8 7524.9 7589.7 7673.8 7775.8 7815.5 7260.8 7360.8 7421.2 7499.5 7520.7 4309.7 4384.1 4408.7 4441.7 4580.8 1816.0 1816.2 1819.9 1846.7 1869.3 906.4 951.9 977.0 978.5 1024.9 16321.8 16364.8 16862.4 16883.3 16992.6 899.8 915.1 915.9 917.2 926.5 9052.9 9295.9 9733.7 10044.1 10214.2 283099.0 286169.0 286508.0 428895.0 430206.0 48659.0 52753.0 90041.0 90700.0 90809.0 926.7 977.7 978.6 984.7 990.7 15439.7 15651.8 15717.2 15776.8 15907.8 36108.6 36381.8 36893.8 36999.0 37725.2 49688.3 49802.3 50013.2 50137.2 50359.0 6784.0 7167.5 7334.3 7426.4 7952.6 8796.7 11120.2 11739.8 12042.8 12140.3 6084.2 9769.2 10144.4 10429.7 10655.9 10009.9 10456.4 10773.2 10823.5 10961.8 10856.8 11034.3 11174.8 11398.3 11414.3 7808.6 8076.5 8085.6 8238.2 8357.2 8070.4 9946.5 10516.8 10804.9 10954.2 14:38:22 INFO - PROCESS | 14696 | 14:38:22 INFO - PROCESS | 14696 | [#3] mootools.html Cycles:180 Average:5103.63 Median:5253.19 stddev:2807.52 (53.4%) stddev-sans-first:2800.63 14:38:22 INFO - PROCESS | 14696 | Values: 1271.7 1283.8 1299.8 1557.3 1574.0 6485.9 7231.5 7508.6 7637.7 7745.0 7545.5 7717.2 7777.9 7788.6 7793.6 2179.1 2212.1 2216.1 2219.9 2232.7 2175.3 2213.1 2223.2 2225.4 2234.1 2185.5 2192.0 2196.0 2197.5 2231.7 2143.4 2172.1 2182.7 2183.5 2184.8 2141.5 2162.8 2169.8 2196.5 2202.9 7780.0 7850.4 7867.7 7892.8 7897.1 4678.7 4791.1 4815.5 4838.3 4849.2 2983.9 3002.4 3089.7 3118.5 3121.5 907.3 943.8 980.8 987.0 993.7 10494.6 10518.5 10562.7 10569.4 10578.6 4257.4 4288.6 4364.4 4374.5 4400.1 9047.5 9084.9 9138.0 9158.7 9181.4 4617.1 4681.2 4702.4 4733.7 4779.2 3719.6 4190.7 4335.2 4337.3 4415.7 3555.9 3605.6 3623.2 3642.4 3760.5 1212.3 1212.3 1219.9 1222.7 1229.7 733.2 791.8 797.8 802.2 843.0 5089.7 5100.9 5122.3 5136.8 5164.8 933.5 936.9 945.4 952.8 959.5 3543.7 3579.2 3594.7 3600.0 3646.5 9550.5 9735.1 9746.8 9851.6 9860.3 8908.5 9584.3 9641.5 9711.3 9740.3 6965.4 7129.8 7251.2 7254.9 7317.5 5168.9 5337.5 5364.8 5423.2 5453.7 8395.7 8398.5 8468.4 8476.7 8514.9 8060.2 8258.7 8398.6 8573.7 8590.3 5709.1 6607.1 6657.6 6773.2 6944.9 7394.9 7448.0 7465.3 7471.0 7471.6 6693.3 6989.7 7020.1 7087.3 7090.0 6400.4 6461.3 6522.0 6599.3 6619.3 6023.2 6088.0 6121.5 6131.2 6138.0 4537.0 4573.4 4607.7 4693.0 4697.3 4507.2 4568.6 4579.5 4617.8 4640.6 14:38:22 INFO - PROCESS | 14696 | 14:38:22 INFO - PROCESS | 14696 | [#4] prototype.html Cycles:180 Average:4290.11 Median:4898.50 stddev:1260.16 (25.7%) stddev-sans-first:1227.59 14:38:22 INFO - PROCESS | 14696 | Values: 299.8 315.8 316.2 320.1 322.1 4874.6 4931.2 5015.4 5089.0 5104.7 4935.3 4942.4 4952.8 4968.6 4982.8 3885.9 3906.6 3910.7 3913.7 3935.3 3684.5 3909.1 3909.3 3916.0 3938.7 3822.7 3843.2 3843.9 3847.9 3855.8 3877.2 3878.4 3883.7 3888.5 3896.1 3399.7 3421.0 3421.1 3427.6 3435.8 4582.5 4724.8 4751.8 4859.7 4885.4 4368.6 4384.6 4388.1 4409.3 4442.8 5211.3 5215.0 5237.7 5256.3 5273.5 3635.8 3692.6 3733.3 3740.2 3757.3 5474.6 5491.6 5491.9 5500.8 5504.7 3792.5 3808.6 3815.5 3817.3 3825.8 3870.8 3873.4 3875.8 3895.7 3899.5 4277.3 4365.8 4378.2 4404.3 4412.3 5223.7 5254.6 5267.6 5291.7 5333.7 1251.9 1297.4 1311.6 1317.1 1325.0 832.0 837.9 842.9 846.6 852.6 4025.7 4057.7 4063.3 4082.2 4101.3 4688.5 4750.3 4763.9 4796.9 4797.6 4059.7 4093.9 4098.3 4099.5 4119.3 2551.6 2675.7 2884.4 2891.9 2903.3 5068.0 5075.4 5086.9 5103.9 5134.3 5353.3 5543.9 5577.6 5582.0 5596.6 4911.6 5022.5 5024.8 5048.3 5050.4 5366.3 5370.1 5375.4 5419.4 5470.2 5128.1 5306.5 5349.1 5382.9 5407.0 5020.4 5026.8 5031.1 5060.3 5075.7 3782.4 3810.5 3814.9 3836.5 3860.5 5238.7 5358.3 5411.1 5428.6 5448.1 5106.3 5156.4 5175.4 5184.5 5199.2 5131.3 5157.8 5159.2 5180.9 5189.8 4999.9 5097.7 5145.9 5153.2 5169.6 5270.2 5275.5 5279.0 5300.9 5302.4 5107.4 5132.9 5139.9 5147.3 5156.3 14:38:22 INFO - PROCESS | 14696 | 14:38:22 INFO - PROCESS | 14696 | [#5] yui.html Cycles:180 Average:12527.58 Median:23348.66 stddev:14238.20 (61.0%) stddev-sans-first:14250.45 14:38:22 INFO - PROCESS | 14696 | Values: 703.5 714.1 739.1 747.9 762.7 11627.6 12103.9 12125.5 12139.3 12193.3 11530.5 11611.5 11613.8 11623.9 11628.8 527.8 542.1 544.3 558.4 674.2 850.3 858.7 861.6 864.1 866.3 905.7 907.6 908.7 908.7 909.4 896.5 898.6 899.1 907.4 907.5 669.4 669.8 670.5 671.3 671.8 12428.0 12429.8 12433.1 12442.7 12445.3 10804.6 10933.6 10981.7 11011.2 11157.2 9857.4 9908.2 9929.2 9966.8 10102.9 4074.4 4127.3 4160.1 4199.8 4421.6 45084.5 45559.1 45894.5 46391.0 46556.5 8716.0 8834.0 9366.3 9430.3 9631.0 15772.2 15830.7 15834.0 15838.9 15863.7 6319.8 6352.2 6599.9 6693.9 6787.0 3500.3 3551.9 3601.5 3656.1 3709.6 2751.9 2829.4 2832.3 2834.1 2844.1 519.8 530.4 531.9 534.1 542.0 462.6 473.7 514.4 544.7 586.7 7116.8 7305.7 7367.6 7440.0 7605.3 491.1 502.6 551.0 555.4 574.6 3499.4 3529.7 3530.4 3559.2 3576.1 48720.0 48967.3 49921.0 50183.5 51076.0 45738.8 46234.8 46362.4 47419.5 47467.3 42430.6 42602.1 43330.4 43343.0 43796.3 7180.9 7408.6 7414.1 7511.6 7527.6 27090.7 27565.9 28423.0 29655.9 30530.8 37452.6 37686.2 37968.4 38666.8 39016.0 10938.8 10948.5 11069.6 11197.3 11247.0 11860.2 12015.5 12020.9 12062.2 12231.4 11411.8 11431.5 11460.7 11843.5 12078.9 10523.7 10726.4 10755.1 10777.7 10812.5 10838.6 11041.2 11107.3 11277.2 11794.5 8464.7 8496.2 8786.8 8977.5 8999.0 9234.6 9633.1 9672.3 9675.4 9719.0 14:38:22 INFO - PROCESS | 14696 | -------- Summary: end -------- 14:38:22 INFO - PROCESS | 14696 | 14:38:23 INFO - TEST-INFO | 14696: exit 0 14:38:23 INFO - TEST-OK | dromaeo_css | took 1115550ms 14:38:23 INFO - TEST-START | kraken 14:38:23 INFO - operating with platform_type : linux_ 14:38:23 INFO - Initialising browser for kraken test... 14:38:23 INFO - TEST-INFO | started process 14932 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3VgY4D/profile http://localhost:60352/getInfo.html) 14:38:27 INFO - PROCESS | 14932 | 14:38:27 INFO - PROCESS | 14932 | (process:15001): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 14:38:28 INFO - PROCESS | 14932 | __metrics Screen width/height:1600/1200 14:38:28 INFO - PROCESS | 14932 | colorDepth:24 14:38:28 INFO - PROCESS | 14932 | Browser inner width/height: 1024/697 14:38:28 INFO - PROCESS | 14932 | __metrics 14:38:28 ERROR - PROCESS | 14932 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 14:38:29 INFO - TEST-INFO | 14932: exit 0 14:38:29 INFO - Browser initialized. 14:38:29 INFO - Running cycle 1/1 for kraken test... 14:38:29 INFO - TEST-INFO | started process 15052 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3VgY4D/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:38:31 INFO - PROCESS | 15052 | 14:38:31 INFO - PROCESS | 15052 | (process:15112): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 14:38:41 INFO - PROCESS | 15052 | RSS: Main: 164311040 14:38:41 INFO - PROCESS | 15052 | 14:38:44 INFO - PROCESS | 15052 | Cycle 1(1): loaded http://localhost:60352/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60352/tests/kraken/driver.html?testName=audio-beat-detection) 14:38:45 INFO - PROCESS | 15052 | RSS: Main: 167424000 14:38:45 INFO - PROCESS | 15052 | 14:38:48 INFO - PROCESS | 15052 | Cycle 1(1): loaded http://localhost:60352/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60352/tests/kraken/driver.html?testName=audio-dft) 14:38:48 INFO - PROCESS | 15052 | RSS: Main: 167514112 14:38:48 INFO - PROCESS | 15052 | 14:38:51 INFO - PROCESS | 15052 | Cycle 1(1): loaded http://localhost:60352/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60352/tests/kraken/driver.html?testName=audio-fft) 14:38:52 INFO - PROCESS | 15052 | RSS: Main: 163655680 14:38:52 INFO - PROCESS | 15052 | 14:38:54 INFO - PROCESS | 15052 | Cycle 1(1): loaded http://localhost:60352/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60352/tests/kraken/driver.html?testName=audio-oscillator) 14:38:55 INFO - PROCESS | 15052 | RSS: Main: 164597760 14:38:55 INFO - PROCESS | 15052 | 14:38:57 INFO - PROCESS | 15052 | Cycle 1(1): loaded http://localhost:60352/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60352/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:38:58 INFO - PROCESS | 15052 | RSS: Main: 164978688 14:38:58 INFO - PROCESS | 15052 | 14:39:01 INFO - PROCESS | 15052 | Cycle 1(1): loaded http://localhost:60352/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60352/tests/kraken/driver.html?testName=imaging-darkroom) 14:39:02 INFO - PROCESS | 15052 | RSS: Main: 164847616 14:39:02 INFO - PROCESS | 15052 | 14:39:05 INFO - PROCESS | 15052 | Cycle 1(1): loaded http://localhost:60352/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60352/tests/kraken/driver.html?testName=imaging-desaturate) 14:39:06 INFO - PROCESS | 15052 | RSS: Main: 163078144 14:39:06 INFO - PROCESS | 15052 | 14:39:09 INFO - PROCESS | 15052 | Cycle 1(1): loaded http://localhost:60352/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60352/tests/kraken/driver.html?testName=json-parse-financial) 14:39:10 INFO - PROCESS | 15052 | RSS: Main: 163446784 14:39:10 INFO - PROCESS | 15052 | 14:39:11 INFO - PROCESS | 15052 | Cycle 1(1): loaded http://localhost:60352/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60352/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:39:12 INFO - PROCESS | 15052 | RSS: Main: 163864576 14:39:12 INFO - PROCESS | 15052 | 14:39:14 INFO - PROCESS | 15052 | Cycle 1(1): loaded http://localhost:60352/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60352/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:39:15 INFO - PROCESS | 15052 | RSS: Main: 164184064 14:39:15 INFO - PROCESS | 15052 | 14:39:17 INFO - PROCESS | 15052 | Cycle 1(1): loaded http://localhost:60352/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60352/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:39:18 INFO - PROCESS | 15052 | RSS: Main: 164937728 14:39:18 INFO - PROCESS | 15052 | 14:39:20 INFO - PROCESS | 15052 | Cycle 1(1): loaded http://localhost:60352/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60352/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:39:21 INFO - PROCESS | 15052 | RSS: Main: 165339136 14:39:21 INFO - PROCESS | 15052 | 14:39:24 INFO - PROCESS | 15052 | Cycle 1(1): loaded http://localhost:60352/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60352/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:39:25 INFO - PROCESS | 15052 | RSS: Main: 164581376 14:39:25 INFO - PROCESS | 15052 | 14:39:27 INFO - PROCESS | 15052 | Cycle 1(1): loaded http://localhost:60352/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60352/tests/kraken/driver.html?testName=ai-astar) 14:39:27 INFO - PROCESS | 15052 | RSS: Main: 166830080 14:39:27 INFO - PROCESS | 15052 | 14:39:27 INFO - PROCESS | 15052 | __start_tp_report 14:39:27 INFO - PROCESS | 15052 | _x_x_mozilla_page_load 14:39:27 INFO - PROCESS | 15052 | _x_x_mozilla_page_load_details 14:39:27 INFO - PROCESS | 15052 | |i|pagename|runs| 14:39:27 INFO - PROCESS | 15052 | |0;ai-astar;111;115;105;115;115;114;109;115;115;114 14:39:27 INFO - PROCESS | 15052 | |1;audio-beat-detection;160;140;154;216;132;171;161;134;201;133 14:39:27 INFO - PROCESS | 15052 | |2;audio-dft;162;169;172;162;161;163;164;162;161;161 14:39:27 INFO - PROCESS | 15052 | |3;audio-fft;75;77;78;163;79;78;76;76;164;78 14:39:27 INFO - PROCESS | 15052 | |4;audio-oscillator;93;94;168;93;94;93;149;93;94;140 14:39:27 INFO - PROCESS | 15052 | |5;imaging-gaussian-blur;117;118;118;118;117;118;118;118;118;118 14:39:27 INFO - PROCESS | 15052 | |6;imaging-darkroom;148;147;147;148;147;147;147;148;147;147 14:39:27 INFO - PROCESS | 15052 | |7;imaging-desaturate;95;97;95;97;97;95;95;100;95;96 14:39:27 INFO - PROCESS | 15052 | |8;json-parse-financial;68;69;67;69;68;68;67;67;68;67 14:39:27 INFO - PROCESS | 15052 | |9;json-stringify-tinderbox;64;65;66;66;66;65;65;64;64;64 14:39:27 INFO - PROCESS | 15052 | |10;stanford-crypto-aes;83;86;83;89;89;84;90;85;140;98 14:39:27 INFO - PROCESS | 15052 | |11;stanford-crypto-ccm;150;133;136;136;148;141;131;135;159;138 14:39:27 INFO - PROCESS | 15052 | |12;stanford-crypto-pbkdf2;179;182;179;179;182;184;180;184;176;180 14:39:27 INFO - PROCESS | 15052 | |13;stanford-crypto-sha256-iterative;74;71;68;68;74;69;69;68;68;69 14:39:27 INFO - PROCESS | 15052 | __end_tp_report 14:39:27 INFO - PROCESS | 15052 | __start_cc_report 14:39:27 INFO - PROCESS | 15052 | _x_x_mozilla_cycle_collect,929 14:39:27 INFO - PROCESS | 15052 | __end_cc_report 14:39:27 INFO - PROCESS | 15052 | __startTimestamp1462916367290__endTimestamp 14:39:27 INFO - PROCESS | 15052 | 14:39:27 INFO - PROCESS | 15052 | ------- Summary: start ------- 14:39:27 INFO - PROCESS | 15052 | Number of tests: 14 14:39:27 INFO - PROCESS | 15052 | 14:39:27 INFO - PROCESS | 15052 | [#0] ai-astar Cycles:10 Average:112.80 Median:115.00 stddev:3.43 (3.0%) stddev-sans-first:3.57 14:39:27 INFO - PROCESS | 15052 | Values: 111.0 115.0 105.0 115.0 115.0 114.0 109.0 115.0 115.0 114.0 14:39:27 INFO - PROCESS | 15052 | 14:39:27 INFO - PROCESS | 15052 | [#1] audio-beat-detection Cycles:10 Average:160.20 Median:160.50 stddev:28.99 (18.1%) stddev-sans-first:30.75 14:39:27 INFO - PROCESS | 15052 | Values: 160.0 140.0 154.0 216.0 132.0 171.0 161.0 134.0 201.0 133.0 14:39:27 INFO - PROCESS | 15052 | 14:39:27 INFO - PROCESS | 15052 | [#2] audio-dft Cycles:10 Average:163.70 Median:162.50 stddev:3.77 (2.3%) stddev-sans-first:3.95 14:39:27 INFO - PROCESS | 15052 | Values: 162.0 169.0 172.0 162.0 161.0 163.0 164.0 162.0 161.0 161.0 14:39:27 INFO - PROCESS | 15052 | 14:39:27 INFO - PROCESS | 15052 | [#3] audio-fft Cycles:10 Average:94.40 Median:77.50 stddev:36.44 (47.0%) stddev-sans-first:37.97 14:39:27 INFO - PROCESS | 15052 | Values: 75.0 77.0 78.0 163.0 79.0 78.0 76.0 76.0 164.0 78.0 14:39:27 INFO - PROCESS | 15052 | 14:39:27 INFO - PROCESS | 15052 | [#4] audio-oscillator Cycles:10 Average:111.10 Median:93.00 stddev:29.24 (31.4%) stddev-sans-first:30.28 14:39:27 INFO - PROCESS | 15052 | Values: 93.0 94.0 168.0 93.0 94.0 93.0 149.0 93.0 94.0 140.0 14:39:27 INFO - PROCESS | 15052 | 14:39:27 INFO - PROCESS | 15052 | [#5] imaging-gaussian-blur Cycles:10 Average:117.80 Median:118.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 14:39:27 INFO - PROCESS | 15052 | Values: 117.0 118.0 118.0 118.0 117.0 118.0 118.0 118.0 118.0 118.0 14:39:27 INFO - PROCESS | 15052 | 14:39:27 INFO - PROCESS | 15052 | [#6] imaging-darkroom Cycles:10 Average:147.30 Median:147.00 stddev:0.48 (0.3%) stddev-sans-first:0.44 14:39:27 INFO - PROCESS | 15052 | Values: 148.0 147.0 147.0 148.0 147.0 147.0 147.0 148.0 147.0 147.0 14:39:27 INFO - PROCESS | 15052 | 14:39:27 INFO - PROCESS | 15052 | [#7] imaging-desaturate Cycles:10 Average:96.20 Median:95.50 stddev:1.62 (1.7%) stddev-sans-first:1.66 14:39:27 INFO - PROCESS | 15052 | Values: 95.0 97.0 95.0 97.0 97.0 95.0 95.0 100.0 95.0 96.0 14:39:27 INFO - PROCESS | 15052 | 14:39:27 INFO - PROCESS | 15052 | [#8] json-parse-financial Cycles:10 Average:67.80 Median:68.00 stddev:0.79 (1.2%) stddev-sans-first:0.83 14:39:27 INFO - PROCESS | 15052 | Values: 68.0 69.0 67.0 69.0 68.0 68.0 67.0 67.0 68.0 67.0 14:39:27 INFO - PROCESS | 15052 | 14:39:27 INFO - PROCESS | 15052 | [#9] json-stringify-tinderbox Cycles:10 Average:64.90 Median:65.00 stddev:0.88 (1.3%) stddev-sans-first:0.87 14:39:27 INFO - PROCESS | 15052 | Values: 64.0 65.0 66.0 66.0 66.0 65.0 65.0 64.0 64.0 64.0 14:39:27 INFO - PROCESS | 15052 | 14:39:27 INFO - PROCESS | 15052 | [#10] stanford-crypto-aes Cycles:10 Average:92.70 Median:87.50 stddev:17.22 (19.7%) stddev-sans-first:17.90 14:39:27 INFO - PROCESS | 15052 | Values: 83.0 86.0 83.0 89.0 89.0 84.0 90.0 85.0 140.0 98.0 14:39:27 INFO - PROCESS | 15052 | 14:39:27 INFO - PROCESS | 15052 | [#11] stanford-crypto-ccm Cycles:10 Average:140.70 Median:139.50 stddev:8.90 (6.4%) stddev-sans-first:8.77 14:39:27 INFO - PROCESS | 15052 | Values: 150.0 133.0 136.0 136.0 148.0 141.0 131.0 135.0 159.0 138.0 14:39:27 INFO - PROCESS | 15052 | 14:39:27 INFO - PROCESS | 15052 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.50 Median:181.00 stddev:2.51 (1.4%) stddev-sans-first:2.60 14:39:27 INFO - PROCESS | 15052 | Values: 179.0 182.0 179.0 179.0 182.0 184.0 180.0 184.0 176.0 180.0 14:39:27 INFO - PROCESS | 15052 | 14:39:27 INFO - PROCESS | 15052 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.80 Median:69.00 stddev:2.39 (3.5%) stddev-sans-first:2.00 14:39:27 INFO - PROCESS | 15052 | Values: 74.0 71.0 68.0 68.0 74.0 69.0 69.0 68.0 68.0 69.0 14:39:27 INFO - PROCESS | 15052 | -------- Summary: end -------- 14:39:27 INFO - PROCESS | 15052 | 14:39:28 INFO - TEST-INFO | 15052: exit 0 14:39:28 INFO - TEST-OK | kraken | took 65111ms 14:39:28 INFO - SUITE-END | took 1180s 14:39:28 INFO - Completed test suite (00:19:40) 14:39:28 INFO - javascript benchmark 14:39:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2646.5368560501915, 2681.8793154308055, 2696.514627367074, 2709.444563864408, 2718.9803823566162, 4770.689905156162, 4820.879478013051, 4872.415310162781, 4876.804927802884, 4935.309056732051, 4711.010687755567, 4764.499727528603, 4780.90438191236, 4825.348577941977, 4829.78265978031, 4402.493713222981, 4403.119376124771, 4422.18189634917, 4436.223660859337, 4541.568550987668, 4365.738055716652, 4414.763866117476, 4453.777311134447, 4463.4420697412825, 4516.4806047304655, 4340.414044104069, 4462.174497717932, 4464.308032255006, 4504.234280172379, 4510.278355463143, 4452.933206001912, 4453.131639330332, 4462.174497717932, 4479.3281007848755, 4498.347739577743, 4426.933595996063, 4431.135928493945, 4432.22436073689, 4448.866534003985, 4492.213862574069, 4573.908521829578, 4596.195665758479, 4626.791794369262, 4649.953500464986, 4718.079974404993, 4971.403431588196, 5040.6471546991725, 5048.1418158913075, 5062.873428164288, 5101.6173786965755, 5085.949140508622, 5092.0, 5092.28707980883, 5096.79612815487, 5139.100657384943, 4765.404324459442, 4833.879153021132, 4844.685095468783, 4848.175810112219, 4860.6111511079025, 5295.285136506523, 5324.41431442541, 5326.334208223972, 5335.919961200585, 5384.6499977501335, 4914.33656456381, 5028.069807085701, 5097.311862898536, 5108.233764935289, 5111.514406131411, 5126.512981266774, 5151.072806894795, 5165.793368265227, 5168.2764412982215, 5212.635115541876, 5113.437521872591, 5137.717425541631, 5144.382674079135, 5153.2785410042625, 5192.039472697563, 4918.9508104919205, 5122.282880396748, 5126.53861152498, 5127.512886275797, 5139.357580302462, 4244.893877653083, 4304.677149213821, 4378.211921854033, 4482.932756008662, 4506.864794056183, 3817.9809100954317, 3829.9808500957315, 3875.3411919973673, 3875.554311254242, 3883.728139030241, 4400.3399490076745, 4414.426124603781, 4436.778161091933, 4441.955580444219, 4490.2142125127975, 5171.0, 5197.58419326453, 5206.479352064839, 5208.869778255498, 5261.894762104736, 4425.402570652985, 4453.4433195850515, 4467.12890986261, 4473.977630111829, 4516.0, 4596.103759766814, 4623.86128416148, 4645.628349732014, 4700.106979673919, 4742.715437073786, 4802.711837289774, 4823.469418364048, 4841.201201801734, 4858.1012512685265, 4871.585915197106, 5103.362079740033, 5112.744362781772, 5116.718580478109, 5119.974400127976, 5145.331106956221, 4675.4155730533685, 4710.128626204163, 4743.193657078236, 4764.523547645208, 4841.564259216688, 4977.726225057657, 5001.1498045331655, 5005.874853128701, 5007.549320561167, 5061.493850614909, 5090.974545127251, 5094.337736094432, 5095.057414378203, 5103.668261562986, 5150.819721309736, 4852.708837469763, 4966.8758281043265, 4996.325496057985, 5022.045811295989, 5031.572316353151, 4975.527324904128, 5028.74856257193, 5033.874153146201, 5041.0, 5083.949160508496, 4765.547273009059, 4888.560029597354, 4941.481144479922, 4949.727764972961, 4952.752362381794, 4957.182064959241, 4977.153883839828, 4996.2755400466, 5044.621653375938, 5067.341245638044, 4861.683990540603, 4890.657653964188, 4897.0, 4900.019996000743, 4964.6524743269065], "value": 4715.96790906948, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [560.1092896174865, 579.3308728418676, 596.4244504053587, 604.6787768725333, 631.1700114349627, 36811.631883681155, 40455.0, 40571.79714101433, 40667.796661016655, 40681.99999999996, 41505.58494415062, 42393.0, 42422.0, 42424.36363454551, 42505.0, 7822.647980840861, 7910.881336779953, 7960.164182760785, 7963.641636126388, 7975.561344126071, 7656.0, 7752.069751629812, 7756.3794896408435, 7787.96106019472, 7793.454458187929, 7682.385409167281, 7713.614319284042, 7713.961430192841, 7736.032995875516, 7737.381009519225, 7785.1436342002335, 7811.726589569366, 7841.529508229496, 7847.0975837778815, 7868.685252589889, 7820.45256832022, 7824.726134585319, 7849.843003139905, 7853.607319633996, 7908.644111015018, 30992.225194370094, 37041.81479092614, 37447.62552374496, 37508.81245593754, 37522.24955500902, 2831.2395414470934, 2960.718731720483, 3076.400101980115, 3077.492213784723, 3117.0804612639326, 28332.716672833212, 28691.85654071737, 28725.856370718222, 29424.41151176986, 29735.85132074326, 3034.9393012139853, 3080.738137258336, 3111.7510599152015, 3126.5935428394164, 3202.519622056687, 219198.0, 219479.0, 220499.0, 220924.0, 220945.0, 34142.48786268208, 34297.82851085729, 34387.0, 34389.828050859585, 34409.655903440645, 33905.83047084749, 36061.0984725384, 36154.638453615655, 36281.45577816335, 36290.27419451649, 14642.85357146415, 14689.412423503154, 14763.483278085216, 14822.555323340317, 14826.555203343916, 12144.817827732591, 12292.201006934522, 12297.262164270172, 12312.692182695326, 12331.321777302335, 8372.748817535483, 8479.70321038773, 8508.27679647237, 8514.063453020162, 8572.914270857336, 3329.9500507492407, 3338.9332213355597, 3349.7487688423466, 3368.6294507604143, 3378.138574663445, 950.6245033211843, 984.921206303494, 1050.6112738286884, 1050.648032908984, 1054.5518154784186, 29396.706032939397, 29577.52112394415, 29785.85107074451, 29948.85025574858, 30116.69883301183, 1021.9489025548693, 1051.4637534857125, 1065.733566608348, 1075.8816530181673, 1085.2837127495816, 15384.538463846102, 15849.683006339808, 15990.120543370227, 16092.356305747873, 16104.838951610569, 116802.0, 117614.0, 117813.0, 383000.0, 389268.0, 51925.7403712979, 52363.73818130885, 52381.476185238425, 52579.73710131425, 52700.47299527032, 1264.083539433903, 1295.2228662802243, 1300.1873828856965, 1312.934353282332, 1315.1395714321313, 27445.725542744316, 28734.568981465713, 28841.423171536033, 28954.710452896044, 29421.11736647904, 44741.0, 45373.0, 45409.31886021778, 45471.31793023041, 45509.54490455053, 323701.0, 323927.0, 324769.0, 325864.0, 326642.0, 26221.868890655423, 26836.329091772863, 27596.448071038867, 27729.445411092067, 29504.409911801213, 44834.0, 45417.77291113523, 45731.08537829291, 46229.0, 46463.76768116138, 38374.0, 38893.80553097217, 38963.41554876622, 39234.41148382719, 39287.41068884026, 37262.62737372592, 37680.623193767715, 38084.0, 39116.217675645756, 39572.20855582815, 33844.492332614536, 37143.44284835675, 37622.62377376301, 38515.42226866655, 38579.6142038576, 33055.0, 35008.429355042164, 35041.0, 35180.47229291512, 35371.64628353786, 26632.78423275647, 26849.597256041565, 27002.594961075207, 27098.0, 27318.726812732413], "value": 16613.242548135197, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.78340129527464, 388.91055057336814, 389.4112022946379, 391.1999960081633, 408.4118868828886, 13124.278164700938, 14008.0, 15230.695386092286, 16492.0, 16528.752068718964, 15989.84010159898, 16505.83494165061, 17250.39623613174, 17368.56578585536, 17370.392036278732, 5155.639105262624, 5334.706591137486, 5338.946610533903, 5340.439253878345, 5348.197770334462, 5102.668326558781, 5289.391719952217, 5309.601779866525, 5330.38700549436, 5334.946650533503, 5246.344206974129, 5247.160454327309, 5247.632665713401, 5256.395514515842, 5279.102552566073, 5023.422306434771, 5270.341207349082, 5278.868028299285, 5294.338207724035, 5294.682319060849, 4667.15991121598, 4712.835050773241, 4715.962488252579, 4723.0, 4724.291356296549, 16388.26252818626, 19679.11443985024, 20811.271605493734, 20845.16619335225, 20862.0, 1770.5750619851271, 1884.076802366844, 1922.1542521290587, 1927.4892153579312, 2091.2994146960787, 9175.31185161109, 9521.047895210493, 9577.808443831085, 10879.292845965067, 13502.797458038138, 1710.1021963469277, 1715.4939292908623, 1744.2586900567208, 1802.6755184066994, 1836.4215272189351, 62212.68893655548, 65402.34597654058, 66111.338886611, 70894.0, 76675.0, 9959.103680668775, 9973.551190196458, 10003.149732272694, 10228.846567301496, 10309.587616495406, 13418.0, 13507.797383039262, 13524.0, 13688.65778355529, 13729.76432121114, 7524.887126693104, 7589.696412143563, 7673.808154796175, 7775.833624956302, 7815.491993020435, 7260.782176534713, 7360.815979600553, 7421.220771818989, 7499.475036747375, 7520.699172033167, 4309.698321117555, 4384.12317536494, 4408.713433626804, 4441.689081764245, 4580.770961451914, 1815.972760408595, 1816.2462578030234, 1819.8635102367375, 1846.6768315544725, 1869.26164165154, 906.428949761646, 951.9048095190564, 976.9951150244345, 978.5107446276861, 1024.902634249745, 16321.83678163227, 16364.83635163657, 16862.409815656392, 16883.32466701318, 16992.57518562046, 899.8065415935474, 915.1397686174975, 915.8626206069143, 917.202034230224, 926.4672813132476, 9052.8642070369, 9295.90704092964, 9733.659321923698, 10044.146247569039, 10214.182865370753, 283099.0, 286169.0, 286508.0, 428895.0, 430206.0, 48659.0, 52753.0, 90041.0, 90700.0, 90809.0, 926.6895589977298, 977.6920270114749, 978.6232300564192, 984.7144328144758, 990.6730778843108, 15439.691206176038, 15651.843481565038, 15717.214139293219, 15776.763348549552, 15907.761383579487, 36108.63891361053, 36381.81809090938, 36893.81553092218, 36999.0, 37725.245495090494, 49688.25467618061, 49802.25296620626, 50013.249801252285, 50137.247941280184, 50359.0, 6783.966080169567, 7167.462440317101, 7334.3032411920785, 7426.4058875288765, 7952.602369881599, 8796.692115775917, 11120.165172746407, 11739.765204696028, 12042.759144816879, 12140.271583705005, 6084.209052823105, 9769.16962058233, 10144.442055687008, 10429.739256518344, 10655.946720266349, 10009.899901001188, 10456.424896630759, 10773.24587278883, 10823.512941917475, 10961.835572466578, 10856.837147442637, 11034.337939723642, 11174.820909922812, 11398.259113157617, 11414.258073225214, 7808.609569521387, 8076.515409075474, 8085.555294458862, 8238.176182381714, 8357.164283571594, 8070.435069545076, 9946.502674866373, 10516.78966420652, 10804.945975270073, 10954.178436617445], "value": 8215.803907765678, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1271.6693659648492, 1283.8010108433193, 1299.8245236893022, 1557.3069983857188, 1573.9533210415075, 6485.929821579433, 7231.493795434315, 7508.587027713487, 7637.694492220311, 7744.961275193623, 7545.54726716396, 7717.151113377538, 7777.92222077779, 7788.610569471518, 7793.571353575551, 2179.095675294751, 2212.126210146991, 2216.1135545781653, 2219.9001044953015, 2232.7320721513443, 2175.3365223606793, 2213.103693004336, 2223.2218723446745, 2225.4213904384897, 2234.084025549525, 2185.530111026129, 2192.0355043780687, 2195.9670604940857, 2197.48359135603, 2231.7321921369457, 2143.3998480425507, 2172.098579089684, 2182.6835108909117, 2183.465051062492, 2184.7924447177493, 2141.496748264157, 2162.7729088445803, 2169.8372622053253, 2196.461866842618, 2202.9228976985887, 7779.9611001945195, 7850.411219158586, 7867.724629637935, 7892.842143157162, 7897.091834438998, 4678.672492925497, 4791.089692958327, 4815.470298267188, 4838.346823178861, 4849.175640141185, 2983.925401864949, 3002.369502404498, 3089.721925026736, 3118.5322201669705, 3121.547375630521, 907.2741806554795, 943.8253923024263, 980.764616492044, 987.0425687083517, 993.7118235711634, 10494.632687855965, 10518.474076296154, 10562.735931601695, 10569.365838049742, 10578.57685692579, 4257.446531950826, 4288.635465985426, 4364.388985542027, 4374.518802931675, 4400.053988392511, 9047.502387368759, 9084.909150908406, 9137.954310228406, 9158.725238242863, 9181.403208791407, 4617.145828021827, 4681.157391669464, 4702.36518070063, 4733.71597704132, 4779.211430114062, 3719.5722491913234, 4190.664746820247, 4335.176316499855, 4337.262665346899, 4415.735055896656, 3555.8577656893954, 3605.5853576838476, 3623.239119784822, 3642.4354225095153, 3760.4547340635454, 1212.2544635049558, 1212.3211001839, 1219.8536175658978, 1222.6637674639546, 1229.7356068445326, 733.2447578993645, 791.8099656082499, 797.7726347990795, 802.1937952357843, 842.9831403372021, 5089.6691715038405, 5100.872478188001, 5122.334096567422, 5136.768845401966, 5164.767585458663, 933.537898740128, 936.8594710793299, 945.3807755919884, 952.7522844060594, 959.472290240365, 3543.6633519815578, 3579.2304654499408, 3594.7483676142942, 3599.9640003600152, 3646.4530320451613, 9550.474723890253, 9735.123838854395, 9746.75633109178, 9851.605935762633, 9860.30977831545, 8908.510031948175, 9584.28117891161, 9641.469719165372, 9711.27165462579, 9740.269479788902, 6965.373116419547, 7129.786106416712, 7251.1661158966335, 7254.854902901912, 7317.451191160684, 5168.922466162935, 5337.466253374631, 5364.839054828361, 5423.2407462954425, 5453.700046497481, 8395.748127556182, 8398.496090234605, 8468.3648726347, 8476.745697629081, 8514.872276915976, 8060.193980601893, 8258.669653213805, 8398.580070996304, 8573.657053717781, 8590.312774978112, 5709.143628455598, 6607.075009498578, 6657.600543967377, 6773.187217533928, 6944.861102778017, 7394.889076663747, 7448.0, 7465.253474652492, 7471.0, 7471.551706897603, 6693.297203793726, 6989.706904222735, 7020.052292940388, 7087.291270872872, 7089.964550177217, 6400.359964003748, 6461.321561236003, 6521.9673901630185, 6599.307072757291, 6619.271880093283, 6023.216981792339, 6088.0, 6121.540884433596, 6131.233595800525, 6138.048602466625, 4536.977315113403, 4573.405457290531, 4607.677462577587, 4693.037927224979, 4697.318888761179, 4507.233770258998, 4568.634509239319, 4579.473360563577, 4617.8152873884665, 4640.605548528413], "value": 4140.457437186599, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.8126171143036, 315.7505570599227, 316.1937060495735, 320.1260558674818, 322.12864202332685, 4874.610735940258, 4931.1617025105725, 5015.398152221734, 5088.9745551272235, 5104.719240441774, 4935.3337299464565, 4942.357493525846, 4952.801887924478, 4968.627352948534, 4982.750862456881, 3885.922281554367, 3906.570277269512, 3910.687145028405, 3913.706472014596, 3935.3309937310582, 3684.521012268401, 3909.120447899214, 3909.3158697228014, 3915.9804200978956, 3938.6805420184273, 3822.694184465236, 3843.1929294848114, 3843.9231215375676, 3847.942280865789, 3855.8264878080554, 3877.2051729395407, 3878.4376265441633, 3883.650471457568, 3888.5333759948985, 3896.1428485733377, 3399.677030682106, 3420.9657903421144, 3421.0763093964492, 3427.5715535558056, 3435.7766745161734, 4582.502536117824, 4724.811007559693, 4751.786169622375, 4859.708417494926, 4885.413750349981, 4368.584984426506, 4384.640761363988, 4388.078503514266, 4409.294512877957, 4442.822287108512, 5211.296474975868, 5214.973925130388, 5237.659552129137, 5256.264123022742, 5273.495089832338, 3635.818209089535, 3692.593814680383, 3733.253349330145, 3740.214554943465, 3757.3236817372863, 5474.631342164459, 5491.643043202219, 5491.917621235684, 5500.834974950758, 5504.724763761796, 3792.544894612664, 3808.619138086169, 3815.523059617548, 3817.2938006468894, 3825.827837747308, 3870.845166193321, 3873.438351439082, 3875.825587848497, 3895.7467764595212, 3899.53205615328, 4277.337012763027, 4365.82122826835, 4378.16814805186, 4404.317330813729, 4412.271975124068, 5223.712695801767, 5254.632175747737, 5267.552258058108, 5291.656042357235, 5333.733313334318, 1251.8622951475331, 1297.3642914971597, 1311.5868501422212, 1317.1109501086728, 1324.973500529984, 831.9573536112727, 837.8868852704807, 842.9494230346199, 846.6105591428012, 852.624845068168, 4025.6578190854107, 4057.695672824491, 4063.309237429644, 4082.1631565528996, 4101.302778527657, 4688.484266730657, 4750.334953106568, 4763.857084287477, 4796.880077998078, 4797.56821886032, 4059.7361171523758, 4093.85671501496, 4098.344264917599, 4099.508059032935, 4119.340905455173, 2551.5662337402314, 2675.7190494998135, 2884.379858330427, 2891.9132426027254, 2903.259668784489, 5067.974660126676, 5075.441701412841, 5086.898262034739, 5103.897922041539, 5134.332536770196, 5353.2773075634295, 5543.916841247385, 5577.637453565505, 5582.0, 5596.58025648078, 4911.582515486224, 5022.547970682656, 5024.773885175176, 5048.29323894655, 5050.444451110375, 5366.275552800322, 5370.113931201308, 5375.4087050425205, 5419.4038655747045, 5470.249520153513, 5128.076946149579, 5306.469353064663, 5349.144136938071, 5382.946170538401, 5406.972965135149, 5020.397552293748, 5026.798928042837, 5031.144705400164, 5060.342155519759, 5075.720835353944, 3782.413725872494, 3810.4665346850907, 3814.904627384338, 3836.5204349456317, 3860.4981352424006, 5238.738063096906, 5358.303420555303, 5411.0801163803035, 5428.592855535771, 5448.128299472065, 5106.285120083117, 5156.4327923927585, 5175.430702622783, 5184.533391994739, 5199.168133098686, 5131.25596788471, 5157.7679004444135, 5159.17453207485, 5180.896382072262, 5189.792408303626, 4999.850004499871, 5097.6941383517105, 5145.897082058263, 5153.2270159476375, 5169.560587349967, 5270.209468579744, 5275.525202731773, 5279.0, 5300.946990530046, 5302.43578144456, 5107.412647545431, 5132.871678208075, 5139.948600513947, 5147.3308469898675, 5156.278121062979], "value": 3890.150011644625, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [703.4934846910223, 714.0752725220842, 739.0761548064919, 747.9252074792521, 762.748293063289, 11627.593034243802, 12103.939480302586, 12125.454354554044, 12139.271643701373, 12193.329366884818, 11530.538778448872, 11611.477483513241, 11613.825792613117, 11623.941880290608, 11628.825567616472, 527.8337323743028, 542.1325878594238, 544.3032917865139, 558.4220331956426, 674.2482132422363, 850.2815121222588, 858.7166235142388, 861.6165806216205, 864.0581765875195, 866.3242670716819, 905.7011186308536, 907.6233363154316, 908.6637943960744, 908.6774195160722, 909.3952521573146, 896.5293221058931, 898.595631965618, 899.096408109852, 907.3739120007207, 907.4872696926282, 669.427639368338, 669.7957123077459, 670.4904272752743, 671.2649648634737, 671.7783131566571, 12428.0, 12429.813552796715, 12433.067519936041, 12442.688932776662, 12445.25328480294, 10804.621838235702, 10933.56265749369, 10981.670549883516, 11011.174161937806, 11157.163212759075, 9857.408555486694, 9908.157806586449, 9929.156021738161, 9966.750831229205, 10102.898971010269, 4074.449949256842, 4127.2983592789, 4160.126373461577, 4199.811008504625, 4421.557844215585, 45084.549154508364, 45559.08881822378, 45894.54105458936, 46391.0, 46556.53443465556, 8715.956420217984, 8833.955830220937, 9366.250699943988, 9430.292728045424, 9630.951845240728, 15772.211389430711, 15830.68338633244, 15834.0, 15838.9208053959, 15863.682726345409, 6319.842003949938, 6352.205974253218, 6599.934000659932, 6693.899591506131, 6787.04981302618, 3500.317438099546, 3551.857925682944, 3601.4957905893198, 3656.0859785053735, 3709.573399059089, 2751.876165572554, 2829.3633932365465, 2832.320243141631, 2834.0789243495947, 2844.104107206265, 519.828456609318, 530.3635637235333, 531.9255304257407, 534.1240365800091, 542.0, 462.55132521454806, 473.66606542387535, 514.3930162408319, 544.6595877576515, 586.6509426890993, 7116.787410066571, 7305.707771689073, 7367.6001559704555, 7439.995600593958, 7605.315521602972, 491.1257960829729, 502.64563482744575, 551.0439387662402, 555.4306835493651, 574.6494638270651, 3499.3876071687355, 3529.6646818552194, 3530.435130379127, 3559.199180184438, 3576.141725985745, 48720.0, 48967.265491017664, 49921.0, 50183.49816501861, 51076.0, 45738.77130614392, 46234.768826155654, 46362.39584530862, 47419.5258047422, 47467.28799068017, 42430.57569424328, 42602.14795704131, 43330.35004474936, 43343.0, 43796.3430548542, 7180.928190718026, 7408.555486670817, 7414.147373051952, 7511.586862722603, 7527.585982771, 27090.729092709607, 27565.86217068902, 28423.0, 29655.85172074126, 30530.84734576313, 37452.62547374491, 37686.2462750749, 37968.430473542365, 38666.80666596649, 39016.0, 10938.781224375309, 10948.507317170586, 11069.612563560237, 11197.328160310408, 11246.975544402087, 11860.22908510944, 12015.45930433115, 12020.939895300467, 12062.215955962834, 12231.449584768528, 11411.828822567833, 11431.542738290374, 11460.656180314603, 11843.467043983213, 12078.879211208126, 10523.684289471328, 10726.356418614909, 10755.139588833032, 10777.676669699922, 10812.513436895515, 10838.620648277272, 11041.171912106463, 11107.278026928223, 11277.154213433862, 11794.46924888365, 8464.746057618282, 8496.235338819288, 8786.780330491789, 8977.506237157018, 8999.0, 9234.584443699916, 9633.061916115652, 9672.322937394314, 9675.419474831533, 9719.0], "value": 5819.1357491405, "unit": "score"}], "name": "dromaeo_css", "value": 6262.760064533148, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 115.0, 105.0, 115.0, 115.0, 114.0, 109.0, 115.0, 115.0, 114.0], "value": 112.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 140.0, 154.0, 216.0, 132.0, 171.0, 161.0, 134.0, 201.0, 133.0], "value": 160.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 169.0, 172.0, 162.0, 161.0, 163.0, 164.0, 162.0, 161.0, 161.0], "value": 163.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 78.0, 163.0, 79.0, 78.0, 76.0, 76.0, 164.0, 78.0], "value": 94.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 168.0, 93.0, 94.0, 93.0, 149.0, 93.0, 94.0, 140.0], "value": 111.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 118.0, 118.0, 117.0, 118.0, 118.0, 118.0, 118.0, 118.0], "value": 117.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 147.0, 147.0, 148.0, 147.0, 147.0, 147.0, 148.0, 147.0, 147.0], "value": 147.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 97.0, 95.0, 97.0, 97.0, 95.0, 95.0, 100.0, 95.0, 96.0], "value": 96.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 67.0, 69.0, 68.0, 68.0, 67.0, 67.0, 68.0, 67.0], "value": 67.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 66.0, 66.0, 66.0, 65.0, 65.0, 64.0, 64.0, 64.0], "value": 64.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 83.0, 89.0, 89.0, 84.0, 90.0, 85.0, 140.0, 98.0], "value": 92.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 133.0, 136.0, 136.0, 148.0, 141.0, 131.0, 135.0, 159.0, 138.0], "value": 140.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 182.0, 179.0, 179.0, 182.0, 184.0, 180.0, 184.0, 176.0, 180.0], "value": 180.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 71.0, 68.0, 68.0, 74.0, 69.0, 69.0, 68.0, 68.0, 69.0], "value": 69.8, "unit": "score"}], "name": "kraken", "value": 1619.9, "alertThreshold": 2.0}]} 14:39:28 INFO - Return code: 0 14:39:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 14:39:28 ERROR - # TBPL SUCCESS # 14:39:28 INFO - Running post-action listener: _resource_record_post_action 14:39:28 INFO - ##### 14:39:28 INFO - ##### Finished run-tests step (success) 14:39:28 INFO - ##### 14:39:28 INFO - Running post-run listener: _resource_record_post_run 14:39:29 INFO - Total resource usage - Wall time: 1198s; CPU: 13.0%; Read bytes: 4096; Write bytes: 533078016; Read time: 16; Write time: 507864 14:39:29 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 234811392; Read time: 0; Write time: 281488 14:39:29 INFO - run-tests - Wall time: 1182s; CPU: 13.0%; Read bytes: 4096; Write bytes: 292962304; Read time: 16; Write time: 225720 14:39:29 INFO - Running post-run listener: _upload_blobber_files 14:39:29 INFO - Blob upload gear active. 14:39:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:39:29 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 14:39:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:39:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:39:30 INFO - (blobuploader) - INFO - Open directory for files ... 14:39:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 14:39:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:39:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:39:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 14:39:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:39:32 INFO - (blobuploader) - INFO - Done attempting. 14:39:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:39:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:39:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:39:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:39:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:39:33 INFO - (blobuploader) - INFO - Done attempting. 14:39:33 INFO - (blobuploader) - INFO - Iteration through files over. 14:39:33 INFO - Return code: 0 14:39:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:39:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:39:33 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/2e802bdb8d54cfd60d14967567371bb50b6da0c14854b826f51a1d925608972fc71a7de73f08f41ec4257164b016eb0fa05c1243307fc0115af5fc9794b2c425", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/08fe9415e451a3c44cff9e727b4eaf024dd9e99c6ec4fa5fbf4153afa17ccb93469b966f3afc4f1333a28ac5c52a89b5178fc9bcf2d8ee0c9c119a1768e28cdc"} 14:39:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:39:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:39:33 INFO - Contents: 14:39:33 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/2e802bdb8d54cfd60d14967567371bb50b6da0c14854b826f51a1d925608972fc71a7de73f08f41ec4257164b016eb0fa05c1243307fc0115af5fc9794b2c425", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/08fe9415e451a3c44cff9e727b4eaf024dd9e99c6ec4fa5fbf4153afa17ccb93469b966f3afc4f1333a28ac5c52a89b5178fc9bcf2d8ee0c9c119a1768e28cdc"} 14:39:33 INFO - Running post-run listener: copy_logs_to_upload_dir 14:39:33 INFO - Copying logs to upload dir... 14:39:33 INFO - mkdir: /builds/slave/test/build/upload/logs 14:39:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1237.127211 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-05-10 14:39:32.920880) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-10 14:39:32.923954) ========= 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=337fe3f4b1febe37b85fccc40000019e-1462915134.825064-448827991 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/2e802bdb8d54cfd60d14967567371bb50b6da0c14854b826f51a1d925608972fc71a7de73f08f41ec4257164b016eb0fa05c1243307fc0115af5fc9794b2c425", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/08fe9415e451a3c44cff9e727b4eaf024dd9e99c6ec4fa5fbf4153afa17ccb93469b966f3afc4f1333a28ac5c52a89b5178fc9bcf2d8ee0c9c119a1768e28cdc"} build_url:https://queue.taskcluster.net/v1/task/aQv61iDDRRyaE6dkYl149Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011247 build_url: 'https://queue.taskcluster.net/v1/task/aQv61iDDRRyaE6dkYl149Q/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/2e802bdb8d54cfd60d14967567371bb50b6da0c14854b826f51a1d925608972fc71a7de73f08f41ec4257164b016eb0fa05c1243307fc0115af5fc9794b2c425", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team-Non-PGO/sha512/08fe9415e451a3c44cff9e727b4eaf024dd9e99c6ec4fa5fbf4153afa17ccb93469b966f3afc4f1333a28ac5c52a89b5178fc9bcf2d8ee0c9c119a1768e28cdc"}' ========= master_lag: 0.11 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-10 14:39:33.049199) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 14:39:33.049532) ========= 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=337fe3f4b1febe37b85fccc40000019e-1462915134.825064-448827991 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004392 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 14:39:33.134562) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-10 14:39:33.134933) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-10 14:39:33.163590) ========= ========= Total master_lag: 0.27 =========