builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-018 starttime: 1463979339.97 results: success (0) buildid: 20160522191132 builduid: ee1b1afa3303464fb76a34e193d4d1b2 revision: cb42beedc07e7fd7a13b76081cf3252543626f05 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-22 21:55:39.967359) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-22 21:55:39.968357) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-22 21:55:39.969047) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-22 21:55:39.987654) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-22 21:55:39.988269) ========= 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=62c568580acbfdaac3eec7be000001dc-1463976378.936844-496922173 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-22 21:55:40-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 114M=0s 2016-05-22 21:55:40 (114 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.106153 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-22 21:55:40.110425) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-22 21:55:40.110802) ========= 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=62c568580acbfdaac3eec7be000001dc-1463976378.936844-496922173 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022703 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-22 21:55:40.160348) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-22 21:55:40.160731) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev cb42beedc07e7fd7a13b76081cf3252543626f05 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev cb42beedc07e7fd7a13b76081cf3252543626f05 --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=62c568580acbfdaac3eec7be000001dc-1463976378.936844-496922173 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-22 21:55:40,640 truncating revision to first 12 chars 2016-05-22 21:55:40,640 Setting DEBUG logging. 2016-05-22 21:55:40,640 attempt 1/10 2016-05-22 21:55:40,640 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/cb42beedc07e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-22 21:55:41,364 unpacking tar archive at: mozilla-aurora-cb42beedc07e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.056478 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-22 21:55:41.230790) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-22 21:55:41.231177) ========= script_repo_revision: cb42beedc07e7fd7a13b76081cf3252543626f05 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-22 21:55:41.231658) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-22 21:55:41.231969) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-22 21:55:41.240764) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 30 secs) (at 2016-05-22 21:55:41.241076) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=62c568580acbfdaac3eec7be000001dc-1463976378.936844-496922173 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:55:41 INFO - MultiFileLogger online at 20160522 21:55:41 in /builds/slave/test 21:55:41 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 21:55:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:55:41 INFO - {'append_to_log': False, 21:55:41 INFO - 'base_work_dir': '/builds/slave/test', 21:55:41 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 21:55:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:55:41 INFO - 'branch': 'Mozilla-Aurora', 21:55:41 INFO - 'buildbot_json_path': 'buildprops.json', 21:55:41 INFO - 'config_files': ('talos/linux_config.py',), 21:55:41 INFO - 'default_actions': ('clobber', 21:55:41 INFO - 'read-buildbot-config', 21:55:41 INFO - 'download-and-extract', 21:55:41 INFO - 'populate-webroot', 21:55:41 INFO - 'create-virtualenv', 21:55:41 INFO - 'install', 21:55:41 INFO - 'run-tests'), 21:55:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:55:41 INFO - 'download_minidump_stackwalk': True, 21:55:41 INFO - 'download_symbols': 'ondemand', 21:55:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:55:41 INFO - 'tooltool.py': '/tools/tooltool.py', 21:55:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:55:41 INFO - '/tools/misc-python/virtualenv.py')}, 21:55:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:55:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:55:41 INFO - 'installer_path': 'installer.exe', 21:55:41 INFO - 'log_level': 'info', 21:55:41 INFO - 'log_name': 'talos', 21:55:41 INFO - 'log_to_console': True, 21:55:41 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:55:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:55:41 INFO - 'opt_config_files': (), 21:55:41 INFO - 'pip_index': False, 21:55:41 INFO - 'sps_profile': False, 21:55:41 INFO - 'sps_profile_interval': 0, 21:55:41 INFO - 'suite': 'dromaeojs-e10s', 21:55:41 INFO - 'system_bits': '32', 21:55:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:55:41 INFO - 'title': 'talos-linux64-ix-018', 21:55:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:55:41 INFO - 'use_talos_json': True, 21:55:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:55:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:55:41 INFO - 'work_dir': 'build'} 21:55:41 INFO - ##### 21:55:41 INFO - ##### Running clobber step. 21:55:41 INFO - ##### 21:55:41 INFO - Running pre-action listener: _resource_record_pre_action 21:55:41 INFO - Running main action method: clobber 21:55:41 INFO - rmtree: /builds/slave/test/build 21:55:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:55:42 INFO - Running post-action listener: _resource_record_post_action 21:55:42 INFO - ##### 21:55:42 INFO - ##### Running read-buildbot-config step. 21:55:42 INFO - ##### 21:55:42 INFO - Running pre-action listener: _resource_record_pre_action 21:55:42 INFO - Running main action method: read_buildbot_config 21:55:42 INFO - Using buildbot properties: 21:55:42 INFO - { 21:55:42 INFO - "project": "", 21:55:42 INFO - "product": "firefox", 21:55:42 INFO - "script_repo_revision": "production", 21:55:42 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s", 21:55:42 INFO - "repository": "", 21:55:42 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs-e10s", 21:55:42 INFO - "buildid": "20160522191132", 21:55:42 INFO - "pgo_build": "True", 21:55:42 INFO - "basedir": "/builds/slave/test", 21:55:42 INFO - "buildnumber": 327, 21:55:42 INFO - "slavename": "talos-linux64-ix-018", 21:55:42 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 21:55:42 INFO - "platform": "ubuntu64_hw", 21:55:42 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 21:55:42 INFO - "branch": "mozilla-aurora", 21:55:42 INFO - "repo_path": "releases/mozilla-aurora", 21:55:42 INFO - "revision": "cb42beedc07e7fd7a13b76081cf3252543626f05", 21:55:42 INFO - "stage_platform": "linux64-pgo", 21:55:42 INFO - "builduid": "ee1b1afa3303464fb76a34e193d4d1b2", 21:55:42 INFO - "slavebuilddir": "test" 21:55:42 INFO - } 21:55:42 INFO - Found installer url https://queue.taskcluster.net/v1/task/fOGnC-nPSk227Ths8t8ZaA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2. 21:55:42 INFO - Running post-action listener: _resource_record_post_action 21:55:42 INFO - ##### 21:55:42 INFO - ##### Running download-and-extract step. 21:55:42 INFO - ##### 21:55:42 INFO - Running pre-action listener: _resource_record_pre_action 21:55:42 INFO - Running main action method: download_and_extract 21:55:42 INFO - mkdir: /builds/slave/test/build/tests 21:55:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:55:42 INFO - https://queue.taskcluster.net/v1/task/fOGnC-nPSk227Ths8t8ZaA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 21:55:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fOGnC-nPSk227Ths8t8ZaA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 21:55:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fOGnC-nPSk227Ths8t8ZaA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 21:55:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fOGnC-nPSk227Ths8t8ZaA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 21:55:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fOGnC-nPSk227Ths8t8ZaA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 21:55:44 INFO - Downloaded 1361 bytes. 21:55:44 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.test_packages.json 21:55:44 INFO - Using the following test package requirements: 21:55:44 INFO - {u'common': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'], 21:55:44 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 21:55:44 INFO - u'firefox-48.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 21:55:44 INFO - u'gtest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 21:55:44 INFO - u'firefox-48.0a2.en-US.linux-x86_64.gtest.tests.zip'], 21:55:44 INFO - u'jittest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 21:55:44 INFO - u'jsshell-linux-x86_64.zip'], 21:55:44 INFO - u'mochitest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 21:55:44 INFO - u'firefox-48.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 21:55:44 INFO - u'mozbase': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'], 21:55:44 INFO - u'reftest': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 21:55:44 INFO - u'firefox-48.0a2.en-US.linux-x86_64.reftest.tests.zip'], 21:55:44 INFO - u'talos': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 21:55:44 INFO - u'firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip'], 21:55:44 INFO - u'web-platform': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 21:55:44 INFO - u'firefox-48.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 21:55:44 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 21:55:44 INFO - u'firefox-48.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 21:55:44 INFO - Downloading packages: [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:55:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:55:44 INFO - https://queue.taskcluster.net/v1/task/fOGnC-nPSk227Ths8t8ZaA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:55:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fOGnC-nPSk227Ths8t8ZaA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 21:55:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fOGnC-nPSk227Ths8t8ZaA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 21:55:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fOGnC-nPSk227Ths8t8ZaA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 21:55:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fOGnC-nPSk227Ths8t8ZaA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:55:48 INFO - Downloaded 23812096 bytes. 21:55:48 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:55:48 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 21:55:49 INFO - Return code: 0 21:55:49 INFO - Downloading packages: [u'firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 21:55:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:55:49 INFO - https://queue.taskcluster.net/v1/task/fOGnC-nPSk227Ths8t8ZaA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:55:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fOGnC-nPSk227Ths8t8ZaA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 21:55:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fOGnC-nPSk227Ths8t8ZaA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 21:55:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fOGnC-nPSk227Ths8t8ZaA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip 21:55:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fOGnC-nPSk227Ths8t8ZaA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:55:53 INFO - Downloaded 23812096 bytes. 21:55:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:55:53 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 21:55:54 INFO - Return code: 0 21:55:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:55:54 INFO - https://queue.taskcluster.net/v1/task/fOGnC-nPSk227Ths8t8ZaA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 21:55:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fOGnC-nPSk227Ths8t8ZaA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip 21:55:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fOGnC-nPSk227Ths8t8ZaA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip 21:55:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fOGnC-nPSk227Ths8t8ZaA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip 21:55:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fOGnC-nPSk227Ths8t8ZaA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 21:55:55 INFO - Downloaded 10840145 bytes. 21:55:55 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:55:55 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 21:55:55 INFO - Return code: 0 21:55:55 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')]} 21:55:55 INFO - https://queue.taskcluster.net/v1/task/fOGnC-nPSk227Ths8t8ZaA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 21:55:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fOGnC-nPSk227Ths8t8ZaA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 21:55:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fOGnC-nPSk227Ths8t8ZaA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 21:55:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fOGnC-nPSk227Ths8t8ZaA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 21:55:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fOGnC-nPSk227Ths8t8ZaA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 21:55:57 INFO - Downloaded 62727439 bytes. 21:55:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fOGnC-nPSk227Ths8t8ZaA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 21:55:57 INFO - mkdir: /builds/slave/test/properties 21:55:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:55:57 INFO - Writing to file /builds/slave/test/properties/build_url 21:55:57 INFO - Contents: 21:55:57 INFO - build_url:https://queue.taskcluster.net/v1/task/fOGnC-nPSk227Ths8t8ZaA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 21:55:57 INFO - Running post-action listener: _resource_record_post_action 21:55:57 INFO - Running post-action listener: set_extra_try_arguments 21:55:57 INFO - ##### 21:55:57 INFO - ##### Running populate-webroot step. 21:55:57 INFO - ##### 21:55:57 INFO - Running pre-action listener: _resource_record_pre_action 21:55:57 INFO - Running main action method: populate_webroot 21:55:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:55:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:55:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:55:57 INFO - '--tppagecycles', 21:55:57 INFO - '7'], 21:55:57 INFO - 'tests': ['tsvgm']}}, 21:55:57 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:55:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:55:57 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:55:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:55:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:55:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:55:57 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:55:57 INFO - 'talos_options': ['--disable-e10s'], 21:55:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:55:57 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:55:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:55:57 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:55:57 INFO - 'talos_options': ['--disable-e10s'], 21:55:57 INFO - 'tests': ['damp', 'tps']}, 21:55:57 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:55:57 INFO - 'tests': ['damp', 'tps']}, 21:55:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:55:57 INFO - 'tests': ['dromaeo_dom']}, 21:55:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:55:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:55:57 INFO - 'tests': ['a11yr', 21:55:57 INFO - 'ts_paint', 21:55:57 INFO - 'tpaint', 21:55:57 INFO - 'sessionrestore', 21:55:57 INFO - 'sessionrestore_no_auto_restore', 21:55:57 INFO - 'tabpaint']}, 21:55:57 INFO - 'other-e10s': {'tests': ['a11yr', 21:55:57 INFO - 'ts_paint', 21:55:57 INFO - 'tpaint', 21:55:57 INFO - 'sessionrestore', 21:55:57 INFO - 'sessionrestore_no_auto_restore', 21:55:57 INFO - 'tabpaint']}, 21:55:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:55:57 INFO - 'ts_paint', 21:55:57 INFO - 'tpaint', 21:55:57 INFO - 'sessionrestore', 21:55:57 INFO - 'sessionrestore_no_auto_restore', 21:55:57 INFO - 'tabpaint']}, 21:55:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:55:57 INFO - 'ts_paint', 21:55:57 INFO - 'tpaint', 21:55:57 INFO - 'sessionrestore', 21:55:57 INFO - 'sessionrestore_no_auto_restore', 21:55:57 INFO - 'tabpaint']}, 21:55:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:55:57 INFO - 'tests': ['a11yr', 21:55:57 INFO - 'ts_paint', 21:55:57 INFO - 'tpaint', 21:55:57 INFO - 'sessionrestore', 21:55:57 INFO - 'sessionrestore_no_auto_restore', 21:55:57 INFO - 'tabpaint']}, 21:55:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:55:57 INFO - 'tests': ['a11yr', 21:55:57 INFO - 'ts_paint', 21:55:57 INFO - 'tpaint', 21:55:57 INFO - 'sessionrestore', 21:55:57 INFO - 'sessionrestore_no_auto_restore', 21:55:57 INFO - 'tabpaint']}, 21:55:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:55:57 INFO - 'tests': ['tsvgx', 21:55:57 INFO - 'tsvgr_opacity', 21:55:57 INFO - 'tart', 21:55:57 INFO - 'tscrollx', 21:55:57 INFO - 'cart']}, 21:55:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:55:57 INFO - 'tsvgr_opacity', 21:55:57 INFO - 'tart', 21:55:57 INFO - 'tscrollx', 21:55:57 INFO - 'cart']}, 21:55:57 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:55:57 INFO - 'talos_options': ['--disable-e10s'], 21:55:57 INFO - 'tests': ['tp5o']}, 21:55:57 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:55:57 INFO - 'tests': ['tp5o']}, 21:55:57 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:55:57 INFO - 'talos_options': ['--disable-e10s', 21:55:57 INFO - '--xperf_path', 21:55:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:55:57 INFO - 'tests': ['tp5n']}, 21:55:57 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:55:57 INFO - 'talos_options': ['--xperf_path', 21:55:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:55:57 INFO - 'tests': ['tp5n']}}, 21:55:57 INFO - 'talos.zip': {'path': '', 21:55:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:55:57 INFO - Running post-action listener: _resource_record_post_action 21:55:57 INFO - ##### 21:55:57 INFO - ##### Running create-virtualenv step. 21:55:57 INFO - ##### 21:55:57 INFO - Running pre-action listener: _resource_record_pre_action 21:55:57 INFO - Running main action method: create_virtualenv 21:55:57 INFO - Creating virtualenv /builds/slave/test/build/venv 21:55:57 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 21:55:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:55:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:55:58 INFO - Using real prefix '/usr' 21:55:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:55:58 INFO - Installing distribute.............................................................................................................................................................................................done. 21:56:00 INFO - Installing pip.................done. 21:56:00 INFO - Return code: 0 21:56:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:56: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')]} 21:56:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:56:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56: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')]} 21:56:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:56:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:00 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 0x7f87f357df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a698a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b345a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc3410>, '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 0x2bc4310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bc47a0>, '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': '62c568580acbfdaac3eec7be000001dc-1463976378.936844-496922173', '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 21:56:00 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 21:56:00 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 21:56:00 INFO - Using env: {'DISPLAY': ':0', 21:56:00 INFO - 'HOME': '/home/cltbld', 21:56:00 INFO - 'LANG': 'en_US.UTF-8', 21:56:00 INFO - 'LANGUAGE': 'en_US:en', 21:56:00 INFO - 'LOGNAME': 'cltbld', 21:56:00 INFO - 'MAIL': '/var/mail/cltbld', 21:56:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:56:00 INFO - 'MOZ_NO_REMOTE': '1', 21:56:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:56:00 INFO - 'NO_EM_RESTART': '1', 21:56:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:56:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:56:00 INFO - 'PWD': '/builds/slave/test', 21:56:00 INFO - 'SHELL': '/bin/bash', 21:56:00 INFO - 'SHLVL': '1', 21:56:00 INFO - 'TERM': 'linux', 21:56:00 INFO - 'TMOUT': '86400', 21:56:00 INFO - 'USER': 'cltbld', 21:56:00 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1463976378.936844-496922173', 21:56:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:56:00 INFO - '_': '/tools/buildbot/bin/python'} 21:56:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:56:00 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 21:56:00 INFO - Cleaning up... 21:56:00 INFO - Return code: 0 21:56:00 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 21:56: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')]} 21:56:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:56:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56: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')]} 21:56:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:56:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:00 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 0x7f87f357df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a698a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b345a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc3410>, '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 0x2bc4310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bc47a0>, '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': '62c568580acbfdaac3eec7be000001dc-1463976378.936844-496922173', '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 21:56:00 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 21:56:00 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 21:56:00 INFO - Using env: {'DISPLAY': ':0', 21:56:00 INFO - 'HOME': '/home/cltbld', 21:56:00 INFO - 'LANG': 'en_US.UTF-8', 21:56:00 INFO - 'LANGUAGE': 'en_US:en', 21:56:00 INFO - 'LOGNAME': 'cltbld', 21:56:00 INFO - 'MAIL': '/var/mail/cltbld', 21:56:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:56:00 INFO - 'MOZ_NO_REMOTE': '1', 21:56:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:56:00 INFO - 'NO_EM_RESTART': '1', 21:56:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:56:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:56:00 INFO - 'PWD': '/builds/slave/test', 21:56:00 INFO - 'SHELL': '/bin/bash', 21:56:00 INFO - 'SHLVL': '1', 21:56:00 INFO - 'TERM': 'linux', 21:56:00 INFO - 'TMOUT': '86400', 21:56:00 INFO - 'USER': 'cltbld', 21:56:00 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1463976378.936844-496922173', 21:56:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:56:00 INFO - '_': '/tools/buildbot/bin/python'} 21:56:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:56:01 INFO - Downloading/unpacking psutil>=0.7.1 21:56:01 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 21:56:01 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 21:56:01 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 21:56:01 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 21:56:01 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 21:56:01 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 21:56:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:56:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:56:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:56:03 INFO - Installing collected packages: psutil 21:56:03 INFO - Running setup.py install for psutil 21:56:03 INFO - building 'psutil._psutil_linux' extension 21:56:03 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 21:56:03 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 21:56:03 INFO - building 'psutil._psutil_posix' extension 21:56:03 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 21:56:03 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 21:56:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:56:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:56:03 INFO - Successfully installed psutil 21:56:03 INFO - Cleaning up... 21:56:03 INFO - Return code: 0 21:56:03 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 21:56:03 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')]} 21:56:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:56:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:03 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')]} 21:56:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:56:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:03 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 0x7f87f357df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a698a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b345a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc3410>, '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 0x2bc4310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bc47a0>, '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': '62c568580acbfdaac3eec7be000001dc-1463976378.936844-496922173', '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 21:56:03 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 21:56:03 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 21:56:03 INFO - Using env: {'DISPLAY': ':0', 21:56:03 INFO - 'HOME': '/home/cltbld', 21:56:03 INFO - 'LANG': 'en_US.UTF-8', 21:56:03 INFO - 'LANGUAGE': 'en_US:en', 21:56:03 INFO - 'LOGNAME': 'cltbld', 21:56:03 INFO - 'MAIL': '/var/mail/cltbld', 21:56:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:56:03 INFO - 'MOZ_NO_REMOTE': '1', 21:56:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:56:03 INFO - 'NO_EM_RESTART': '1', 21:56:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:56:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:56:03 INFO - 'PWD': '/builds/slave/test', 21:56:03 INFO - 'SHELL': '/bin/bash', 21:56:03 INFO - 'SHLVL': '1', 21:56:03 INFO - 'TERM': 'linux', 21:56:03 INFO - 'TMOUT': '86400', 21:56:03 INFO - 'USER': 'cltbld', 21:56:03 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1463976378.936844-496922173', 21:56:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:56:03 INFO - '_': '/tools/buildbot/bin/python'} 21:56:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:56:03 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 21:56:03 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 21:56:03 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 21:56:03 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 21:56:03 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 21:56:03 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 21:56:03 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 21:56:05 INFO - Downloading mozsystemmonitor-0.0.tar.gz 21:56:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:56:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 21:56:05 INFO - Installing collected packages: mozsystemmonitor 21:56:05 INFO - Running setup.py install for mozsystemmonitor 21:56:05 INFO - Successfully installed mozsystemmonitor 21:56:05 INFO - Cleaning up... 21:56:05 INFO - Return code: 0 21:56:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:56:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:56:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:56:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:56:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:56:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f87f357df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a698a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b345a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc3410>, '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 0x2bc4310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bc47a0>, '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': '62c568580acbfdaac3eec7be000001dc-1463976378.936844-496922173', '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 21:56:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 21:56:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 21:56:05 INFO - Using env: {'DISPLAY': ':0', 21:56:05 INFO - 'HOME': '/home/cltbld', 21:56:05 INFO - 'LANG': 'en_US.UTF-8', 21:56:05 INFO - 'LANGUAGE': 'en_US:en', 21:56:05 INFO - 'LOGNAME': 'cltbld', 21:56:05 INFO - 'MAIL': '/var/mail/cltbld', 21:56:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:56:05 INFO - 'MOZ_NO_REMOTE': '1', 21:56:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:56:05 INFO - 'NO_EM_RESTART': '1', 21:56:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:56:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:56:05 INFO - 'PWD': '/builds/slave/test', 21:56:05 INFO - 'SHELL': '/bin/bash', 21:56:05 INFO - 'SHLVL': '1', 21:56:05 INFO - 'TERM': 'linux', 21:56:05 INFO - 'TMOUT': '86400', 21:56:05 INFO - 'USER': 'cltbld', 21:56:05 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1463976378.936844-496922173', 21:56:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:56:05 INFO - '_': '/tools/buildbot/bin/python'} 21:56:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:56:06 INFO - Downloading/unpacking blobuploader==1.2.4 21:56:06 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 21:56:06 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 21:56:06 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 21:56:06 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 21:56:06 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 21:56:06 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 21:56:07 INFO - Downloading blobuploader-1.2.4.tar.gz 21:56:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:56:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:56:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:56:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:56:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:56:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:56:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:56:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:56:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:56:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:56:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:56:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:56:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:56:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:56:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:56:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:56:08 INFO - Downloading docopt-0.6.1.tar.gz 21:56:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:56:08 INFO - Installing collected packages: blobuploader, requests, docopt 21:56:08 INFO - Running setup.py install for blobuploader 21:56:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:56:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:56:08 INFO - Running setup.py install for requests 21:56:09 INFO - Running setup.py install for docopt 21:56:09 INFO - Successfully installed blobuploader requests docopt 21:56:09 INFO - Cleaning up... 21:56:09 INFO - Return code: 0 21:56:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:56: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')]} 21:56:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:56:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56: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')]} 21:56:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:56:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:09 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 0x7f87f357df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a698a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b345a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc3410>, '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 0x2bc4310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bc47a0>, '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': '62c568580acbfdaac3eec7be000001dc-1463976378.936844-496922173', '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 21:56:09 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 21:56:09 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 21:56:09 INFO - Using env: {'DISPLAY': ':0', 21:56:09 INFO - 'HOME': '/home/cltbld', 21:56:09 INFO - 'LANG': 'en_US.UTF-8', 21:56:09 INFO - 'LANGUAGE': 'en_US:en', 21:56:09 INFO - 'LOGNAME': 'cltbld', 21:56:09 INFO - 'MAIL': '/var/mail/cltbld', 21:56:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:56:09 INFO - 'MOZ_NO_REMOTE': '1', 21:56:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:56:09 INFO - 'NO_EM_RESTART': '1', 21:56:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:56:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:56:09 INFO - 'PWD': '/builds/slave/test', 21:56:09 INFO - 'SHELL': '/bin/bash', 21:56:09 INFO - 'SHLVL': '1', 21:56:09 INFO - 'TERM': 'linux', 21:56:09 INFO - 'TMOUT': '86400', 21:56:09 INFO - 'USER': 'cltbld', 21:56:09 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1463976378.936844-496922173', 21:56:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:56:09 INFO - '_': '/tools/buildbot/bin/python'} 21:56:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:56:09 INFO - Running setup.py (path:/tmp/pip-OCIhDY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:56:09 INFO - Running setup.py (path:/tmp/pip-k4OUXB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:56:09 INFO - Running setup.py (path:/tmp/pip-nTWlzH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:56:09 INFO - Running setup.py (path:/tmp/pip-zMa8ti-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:56:09 INFO - Running setup.py (path:/tmp/pip-7l9UL1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:56:09 INFO - Running setup.py (path:/tmp/pip-jP3s75-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:56:09 INFO - Running setup.py (path:/tmp/pip-EmVEKJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:56:10 INFO - Running setup.py (path:/tmp/pip-uG_HkM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:56:10 INFO - Running setup.py (path:/tmp/pip-BQ45kD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:56:10 INFO - Running setup.py (path:/tmp/pip-eE6YC4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:56:10 INFO - Running setup.py (path:/tmp/pip-l6dTBZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:56:10 INFO - Running setup.py (path:/tmp/pip-RVlvGR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:56:10 INFO - Running setup.py (path:/tmp/pip-RuNuwc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:56:10 INFO - Running setup.py (path:/tmp/pip-KG4brF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:56:10 INFO - Running setup.py (path:/tmp/pip-e_7Jbf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:56:10 INFO - Running setup.py (path:/tmp/pip-sEAHRa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:56:10 INFO - Running setup.py (path:/tmp/pip-Prachw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:56:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:56:10 INFO - Running setup.py install for manifestparser 21:56:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:56:10 INFO - Running setup.py install for mozcrash 21:56:10 INFO - Running setup.py install for mozdebug 21:56:11 INFO - Running setup.py install for mozdevice 21:56:11 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:56:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:56:11 INFO - Running setup.py install for mozfile 21:56:11 INFO - Running setup.py install for mozhttpd 21:56:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:56:11 INFO - Running setup.py install for mozinfo 21:56:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:56:11 INFO - Running setup.py install for mozInstall 21:56:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:56:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:56:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:56:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:56:11 INFO - Running setup.py install for mozleak 21:56:11 INFO - Running setup.py install for mozlog 21:56:11 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:56:11 INFO - Running setup.py install for moznetwork 21:56:11 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:56:11 INFO - Running setup.py install for mozprocess 21:56:11 INFO - Running setup.py install for mozprofile 21:56:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:56:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:56:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:56:12 INFO - Running setup.py install for mozrunner 21:56:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:56:12 INFO - Running setup.py install for mozscreenshot 21:56:12 INFO - Running setup.py install for moztest 21:56:12 INFO - Running setup.py install for mozversion 21:56:12 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:56:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:56:12 INFO - Cleaning up... 21:56:12 INFO - Return code: 0 21:56:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:56:12 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')]} 21:56:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:56:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:12 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')]} 21:56:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:56:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:12 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 0x7f87f357df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a698a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b345a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc3410>, '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 0x2bc4310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bc47a0>, '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': '62c568580acbfdaac3eec7be000001dc-1463976378.936844-496922173', '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 21:56:12 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 21:56:12 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 21:56:12 INFO - Using env: {'DISPLAY': ':0', 21:56:12 INFO - 'HOME': '/home/cltbld', 21:56:12 INFO - 'LANG': 'en_US.UTF-8', 21:56:12 INFO - 'LANGUAGE': 'en_US:en', 21:56:12 INFO - 'LOGNAME': 'cltbld', 21:56:12 INFO - 'MAIL': '/var/mail/cltbld', 21:56:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:56:12 INFO - 'MOZ_NO_REMOTE': '1', 21:56:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:56:12 INFO - 'NO_EM_RESTART': '1', 21:56:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:56:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:56:12 INFO - 'PWD': '/builds/slave/test', 21:56:12 INFO - 'SHELL': '/bin/bash', 21:56:12 INFO - 'SHLVL': '1', 21:56:12 INFO - 'TERM': 'linux', 21:56:12 INFO - 'TMOUT': '86400', 21:56:12 INFO - 'USER': 'cltbld', 21:56:12 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1463976378.936844-496922173', 21:56:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:56:12 INFO - '_': '/tools/buildbot/bin/python'} 21:56:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:56:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:56:12 INFO - Running setup.py (path:/tmp/pip-g50ZSL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:56:12 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)) 21:56:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:56:12 INFO - Running setup.py (path:/tmp/pip-FojVzJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:56:12 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)) 21:56:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:56:12 INFO - Running setup.py (path:/tmp/pip-VxEeuk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:56:12 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)) 21:56:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:56:12 INFO - Running setup.py (path:/tmp/pip-WSI4ZT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:56:13 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)) 21:56:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:56:13 INFO - Running setup.py (path:/tmp/pip-dxQD5m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:56:13 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)) 21:56:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:56:13 INFO - Running setup.py (path:/tmp/pip-CU7F_9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:56:13 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)) 21:56:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:56:13 INFO - Running setup.py (path:/tmp/pip-nzTQt9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:56:13 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)) 21:56:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:56:13 INFO - Running setup.py (path:/tmp/pip-jnMHEN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:56:13 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)) 21:56:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:56:13 INFO - Running setup.py (path:/tmp/pip-t_Rehf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:56:13 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)) 21:56:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:56:13 INFO - Running setup.py (path:/tmp/pip-XUiKKh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:56:13 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)) 21:56:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:56:13 INFO - Running setup.py (path:/tmp/pip-tyRU5j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:56:13 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)) 21:56:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:56:13 INFO - Running setup.py (path:/tmp/pip-UUxETk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:56:13 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)) 21:56:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:56:13 INFO - Running setup.py (path:/tmp/pip-4hRoAj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:56:13 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)) 21:56:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:56:13 INFO - Running setup.py (path:/tmp/pip-uV5AQn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:56:13 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)) 21:56:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:56:13 INFO - Running setup.py (path:/tmp/pip-TbKZka-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:56:13 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)) 21:56:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:56:13 INFO - Running setup.py (path:/tmp/pip-NMDz27-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:56:13 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)) 21:56:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:56:13 INFO - Running setup.py (path:/tmp/pip-LiAa66-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:56:13 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)) 21:56:13 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)) 21:56:13 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)) 21:56:13 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)) 21:56:13 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)) 21:56:13 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)) 21:56:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:56:13 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 21:56:13 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 21:56:13 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 21:56:13 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 21:56:13 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 21:56:13 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 21:56:15 INFO - Downloading blessings-1.6.tar.gz 21:56:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:56:15 INFO - Installing collected packages: blessings 21:56:15 INFO - Running setup.py install for blessings 21:56:16 INFO - Successfully installed blessings 21:56:16 INFO - Cleaning up... 21:56:16 INFO - Return code: 0 21:56:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:56:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:56:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:56:16 INFO - Reading from file tmpfile_stdout 21:56:16 INFO - Current package versions: 21:56:16 INFO - argparse == 1.2.1 21:56:16 INFO - blessings == 1.6 21:56:16 INFO - blobuploader == 1.2.4 21:56:16 INFO - docopt == 0.6.1 21:56:16 INFO - manifestparser == 1.1 21:56:16 INFO - mozInstall == 1.12 21:56:16 INFO - mozcrash == 0.17 21:56:16 INFO - mozdebug == 0.1 21:56:16 INFO - mozdevice == 0.48 21:56:16 INFO - mozfile == 1.2 21:56:16 INFO - mozhttpd == 0.7 21:56:16 INFO - mozinfo == 0.9 21:56:16 INFO - mozleak == 0.1 21:56:16 INFO - mozlog == 3.1 21:56:16 INFO - moznetwork == 0.27 21:56:16 INFO - mozprocess == 0.22 21:56:16 INFO - mozprofile == 0.28 21:56:16 INFO - mozrunner == 6.11 21:56:16 INFO - mozscreenshot == 0.1 21:56:16 INFO - mozsystemmonitor == 0.0 21:56:16 INFO - moztest == 0.7 21:56:16 INFO - mozversion == 1.4 21:56:16 INFO - psutil == 3.1.1 21:56:16 INFO - requests == 1.2.3 21:56:16 INFO - wsgiref == 0.1.2 21:56:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:56:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:56:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:56:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:56:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:56:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:16 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 0x7f87f357df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a698a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b345a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc3410>, '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 0x2bc4310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bc47a0>, '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': '62c568580acbfdaac3eec7be000001dc-1463976378.936844-496922173', '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 21:56:16 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 21:56:16 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 21:56:16 INFO - Using env: {'DISPLAY': ':0', 21:56:16 INFO - 'HOME': '/home/cltbld', 21:56:16 INFO - 'LANG': 'en_US.UTF-8', 21:56:16 INFO - 'LANGUAGE': 'en_US:en', 21:56:16 INFO - 'LOGNAME': 'cltbld', 21:56:16 INFO - 'MAIL': '/var/mail/cltbld', 21:56:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:56:16 INFO - 'MOZ_NO_REMOTE': '1', 21:56:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:56:16 INFO - 'NO_EM_RESTART': '1', 21:56:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:56:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:56:16 INFO - 'PWD': '/builds/slave/test', 21:56:16 INFO - 'SHELL': '/bin/bash', 21:56:16 INFO - 'SHLVL': '1', 21:56:16 INFO - 'TERM': 'linux', 21:56:16 INFO - 'TMOUT': '86400', 21:56:16 INFO - 'USER': 'cltbld', 21:56:16 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1463976378.936844-496922173', 21:56:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:56:16 INFO - '_': '/tools/buildbot/bin/python'} 21:56:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:56:16 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)) 21:56:16 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)) 21:56:16 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)) 21:56:16 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)) 21:56:16 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)) 21:56:16 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)) 21:56:16 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)) 21:56:16 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)) 21:56:16 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)) 21:56:16 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)) 21:56:16 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)) 21:56:16 INFO - Cleaning up... 21:56:16 INFO - Return code: 0 21:56:16 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:56:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:56:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:56:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:56:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:56:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:16 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 0x7f87f357df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a698a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b345a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc3410>, '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 0x2bc4310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bc47a0>, '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': '62c568580acbfdaac3eec7be000001dc-1463976378.936844-496922173', '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 21:56:16 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 21:56:16 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 21:56:16 INFO - Using env: {'DISPLAY': ':0', 21:56:16 INFO - 'HOME': '/home/cltbld', 21:56:16 INFO - 'LANG': 'en_US.UTF-8', 21:56:16 INFO - 'LANGUAGE': 'en_US:en', 21:56:16 INFO - 'LOGNAME': 'cltbld', 21:56:16 INFO - 'MAIL': '/var/mail/cltbld', 21:56:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:56:16 INFO - 'MOZ_NO_REMOTE': '1', 21:56:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:56:16 INFO - 'NO_EM_RESTART': '1', 21:56:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:56:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:56:16 INFO - 'PWD': '/builds/slave/test', 21:56:16 INFO - 'SHELL': '/bin/bash', 21:56:16 INFO - 'SHLVL': '1', 21:56:16 INFO - 'TERM': 'linux', 21:56:16 INFO - 'TMOUT': '86400', 21:56:16 INFO - 'USER': 'cltbld', 21:56:16 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1463976378.936844-496922173', 21:56:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:56:16 INFO - '_': '/tools/buildbot/bin/python'} 21:56:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:56:16 INFO - Downloading/unpacking jsonschema 21:56: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 21:56: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 21:56: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 21:56: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 21:56: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 21:56: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 21:56:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:56:18 INFO - Downloading/unpacking functools32 (from jsonschema) 21:56:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:56:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:56:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:56:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:56:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:56:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:56:18 INFO - Downloading functools32-3.2.3-2.tar.gz 21:56:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:56:18 INFO - warning: no files found matching '*.txt' 21:56:18 INFO - no previously-included directories found matching 'build' 21:56:18 INFO - no previously-included directories found matching 'dist' 21:56:18 INFO - no previously-included directories found matching '.git*' 21:56:18 INFO - Installing collected packages: jsonschema, functools32 21:56:18 INFO - Running setup.py install for functools32 21:56:19 INFO - warning: no files found matching '*.txt' 21:56:19 INFO - no previously-included directories found matching 'build' 21:56:19 INFO - no previously-included directories found matching 'dist' 21:56:19 INFO - no previously-included directories found matching '.git*' 21:56:19 INFO - Successfully installed jsonschema functools32 21:56:19 INFO - Cleaning up... 21:56:19 INFO - Return code: 0 21:56:19 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 21:56:19 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')]} 21:56:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:56:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:19 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')]} 21:56:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:56:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:19 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 0x7f87f357df10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a698a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2b345a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2bc3410>, '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 0x2bc4310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bc47a0>, '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': '62c568580acbfdaac3eec7be000001dc-1463976378.936844-496922173', '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 21:56:19 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 21:56:19 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 21:56:19 INFO - Using env: {'DISPLAY': ':0', 21:56:19 INFO - 'HOME': '/home/cltbld', 21:56:19 INFO - 'LANG': 'en_US.UTF-8', 21:56:19 INFO - 'LANGUAGE': 'en_US:en', 21:56:19 INFO - 'LOGNAME': 'cltbld', 21:56:19 INFO - 'MAIL': '/var/mail/cltbld', 21:56:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:56:19 INFO - 'MOZ_NO_REMOTE': '1', 21:56:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:56:19 INFO - 'NO_EM_RESTART': '1', 21:56:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:56:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:56:19 INFO - 'PWD': '/builds/slave/test', 21:56:19 INFO - 'SHELL': '/bin/bash', 21:56:19 INFO - 'SHLVL': '1', 21:56:19 INFO - 'TERM': 'linux', 21:56:19 INFO - 'TMOUT': '86400', 21:56:19 INFO - 'USER': 'cltbld', 21:56:19 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1463976378.936844-496922173', 21:56:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:56:19 INFO - '_': '/tools/buildbot/bin/python'} 21:56:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:56:19 INFO - Downloading/unpacking flake8 21:56:19 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 21:56:19 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 21:56:19 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 21:56:19 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 21:56:19 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 21:56:19 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 21:56:21 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 21:56:21 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 21:56:21 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 21:56:21 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 21:56:21 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 21:56:21 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 21:56:21 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 21:56:21 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 21:56:21 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 21:56:21 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 21:56:21 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 21:56:21 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 21:56:21 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 21:56:21 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 21:56:21 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 21:56:21 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 21:56:21 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 21:56:21 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 21:56:21 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 21:56:21 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 21:56:21 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 21:56:21 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 21:56:21 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 21:56:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 21:56:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:56:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:56:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:56:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:56:21 INFO - no previously-included directories found matching 'docs/_build' 21:56:21 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 21:56:22 INFO - Running setup.py install for pep8 21:56:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:56:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:56:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:56:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:56:22 INFO - no previously-included directories found matching 'docs/_build' 21:56:22 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 21:56:22 INFO - Successfully installed flake8 mccabe pyflakes pep8 21:56:22 INFO - Cleaning up... 21:56:22 INFO - Return code: 0 21:56:22 INFO - Running post-action listener: _resource_record_post_action 21:56:22 INFO - Running post-action listener: _start_resource_monitoring 21:56:22 INFO - Starting resource monitoring. 21:56:22 INFO - ##### 21:56:22 INFO - ##### Running install step. 21:56:22 INFO - ##### 21:56:22 INFO - Running pre-action listener: _resource_record_pre_action 21:56:22 INFO - Running main action method: install 21:56:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:56:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:56:22 INFO - Reading from file tmpfile_stdout 21:56:22 INFO - Detecting whether we're running mozinstall >=1.0... 21:56:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:56:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:56:22 INFO - Reading from file tmpfile_stdout 21:56:22 INFO - Output received: 21:56:22 INFO - Usage: mozinstall [options] installer 21:56:22 INFO - Options: 21:56:22 INFO - -h, --help show this help message and exit 21:56:22 INFO - -d DEST, --destination=DEST 21:56:22 INFO - Directory to install application into. [default: 21:56:22 INFO - "/builds/slave/test"] 21:56:22 INFO - --app=APP Application being installed. [default: firefox] 21:56:22 INFO - mkdir: /builds/slave/test/build/application 21:56:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 21:56:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 21:56:38 INFO - Reading from file tmpfile_stdout 21:56:38 INFO - Output received: 21:56:38 INFO - /builds/slave/test/build/application/firefox/firefox 21:56:38 INFO - Running post-action listener: _resource_record_post_action 21:56:38 INFO - ##### 21:56:38 INFO - ##### Running run-tests step. 21:56:38 INFO - ##### 21:56:38 INFO - Running pre-action listener: _resource_record_pre_action 21:56:38 INFO - Running main action method: run_tests 21:56:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:56:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:56:38 INFO - Python 2.7.3 21:56:38 INFO - Return code: 0 21:56:38 INFO - grabbing minidump binary from tooltool 21:56:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:56:38 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 0x2bc3410>, '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 0x2bc4310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2bc47a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:56:38 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 21:56:38 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 21:56:38 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:56:38 INFO - Return code: 0 21:56:38 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:56:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:56:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:56:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:56:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:56:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 21:56:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 21:56:39 INFO - Return code: 0 21:56:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fOGnC-nPSk227Ths8t8ZaA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--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 21:56:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fOGnC-nPSk227Ths8t8ZaA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-018 --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 21:56:39 INFO - Using env: {'DISPLAY': ':0', 21:56:39 INFO - 'HOME': '/home/cltbld', 21:56:39 INFO - 'LANG': 'en_US.UTF-8', 21:56:39 INFO - 'LANGUAGE': 'en_US:en', 21:56:39 INFO - 'LOGNAME': 'cltbld', 21:56:39 INFO - 'MAIL': '/var/mail/cltbld', 21:56:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:56:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:56:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:56:39 INFO - 'MOZ_NO_REMOTE': '1', 21:56:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:56:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:56:39 INFO - 'NO_EM_RESTART': '1', 21:56:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:56:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:56:39 INFO - 'PWD': '/builds/slave/test', 21:56:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:56:39 INFO - 'SHELL': '/bin/bash', 21:56:39 INFO - 'SHLVL': '1', 21:56:39 INFO - 'TERM': 'linux', 21:56:39 INFO - 'TMOUT': '86400', 21:56:39 INFO - 'USER': 'cltbld', 21:56:39 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1463976378.936844-496922173', 21:56:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:56:39 INFO - '_': '/tools/buildbot/bin/python'} 21:56:39 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fOGnC-nPSk227Ths8t8ZaA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--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 21:56:39 INFO - mozversion application_buildid: 20160522191132 21:56:39 INFO - mozversion application_changeset: cb42beedc07e7fd7a13b76081cf3252543626f05 21:56:39 INFO - mozversion application_display_name: FirefoxDeveloperEdition 21:56:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:56:39 INFO - mozversion application_name: Firefox 21:56:39 INFO - mozversion application_remotingname: firefox-dev 21:56:39 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 21:56:39 INFO - mozversion application_vendor: Mozilla 21:56:39 INFO - mozversion application_version: 48.0a2 21:56:39 INFO - mozversion platform_buildid: 20160522191132 21:56:39 INFO - mozversion platform_changeset: cb42beedc07e7fd7a13b76081cf3252543626f05 21:56:39 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 21:56:39 INFO - mozversion platform_version: 48.0a2 21:56:39 INFO - using testdate: 1463979399 21:56:39 INFO - actual date: 1463979399 21:56:39 INFO - starting webserver on 'localhost:47929' 21:56:39 INFO - SUITE-START | Running 2 tests 21:56:39 INFO - TEST-START | dromaeo_css 21:56:39 INFO - operating with platform_type : linux_ 21:56:39 INFO - Initialising browser for dromaeo_css test... 21:56:39 INFO - TEST-INFO | started process 2317 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbz2lb9/profile http://localhost:47929/getInfo.html) 21:56:43 INFO - PROCESS | 2317 | 21:56:43 INFO - PROCESS | 2317 | (process:2471): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 21:56:43 INFO - PROCESS | 2317 | __metrics Screen width/height:1600/1200 21:56:43 INFO - PROCESS | 2317 | colorDepth:24 21:56:43 INFO - PROCESS | 2317 | Browser inner width/height: 1024/697 21:56:43 INFO - PROCESS | 2317 | __metrics 21:56:44 ERROR - PROCESS | 2317 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 21:56:45 INFO - TEST-INFO | 2317: exit 0 21:56:45 INFO - Browser initialized. 21:56:45 INFO - Running cycle 1/1 for dromaeo_css test... 21:56:45 INFO - TEST-INFO | started process 2540 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbz2lb9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:56:46 INFO - PROCESS | 2540 | 21:56:46 INFO - PROCESS | 2540 | (process:2607): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 21:56:56 INFO - PROCESS | 2540 | RSS: Main: 151924736 21:56:56 INFO - PROCESS | 2540 | 21:57:46 INFO - PROCESS | 2540 | 1463979466576 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] 21:59:53 INFO - PROCESS | 2540 | Cycle 1(1): loaded http://localhost:47929/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47929/tests/dromaeo/cssquery-ext.html) 21:59:54 INFO - PROCESS | 2540 | RSS: Main: 155492352 21:59:54 INFO - PROCESS | 2540 | 22:02:56 INFO - PROCESS | 2540 | Cycle 1(1): loaded http://localhost:47929/tests/dromaeo/cssquery-ext.html (next: http://localhost:47929/tests/dromaeo/cssquery-jquery.html) 22:02:57 INFO - PROCESS | 2540 | RSS: Main: 153169920 22:02:57 INFO - PROCESS | 2540 | 22:05:59 INFO - PROCESS | 2540 | Cycle 1(1): loaded http://localhost:47929/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47929/tests/dromaeo/cssquery-mootools.html) 22:05:59 INFO - PROCESS | 2540 | RSS: Main: 152760320 22:05:59 INFO - PROCESS | 2540 | 22:09:01 INFO - PROCESS | 2540 | Cycle 1(1): loaded http://localhost:47929/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47929/tests/dromaeo/cssquery-prototype.html) 22:09:02 INFO - PROCESS | 2540 | RSS: Main: 152813568 22:09:02 INFO - PROCESS | 2540 | 22:12:04 INFO - PROCESS | 2540 | Cycle 1(1): loaded http://localhost:47929/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47929/tests/dromaeo/cssquery-yui.html) 22:12:04 INFO - PROCESS | 2540 | RSS: Main: 154370048 22:12:04 INFO - PROCESS | 2540 | 22:15:06 INFO - PROCESS | 2540 | Cycle 1(1): loaded http://localhost:47929/tests/dromaeo/cssquery-yui.html (next: http://localhost:47929/tests/dromaeo/cssquery-dojo.html) 22:15:07 INFO - PROCESS | 2540 | RSS: Main: 155889664 22:15:07 INFO - PROCESS | 2540 | 22:15:07 INFO - PROCESS | 2540 | __start_tp_report 22:15:07 INFO - PROCESS | 2540 | _x_x_mozilla_page_load 22:15:07 INFO - PROCESS | 2540 | _x_x_mozilla_page_load_details 22:15:07 INFO - PROCESS | 2540 | |i|pagename|runs| 22:15:07 INFO - PROCESS | 2540 | |0;dojo.html;3259.5110733389997;3356.949645755313;3377.594688637364;3386.1873150443903;3403.659634036597;5570.053090974534;5578.163275508676;5585.1063829787245;5586.636868603538;5588.385277619459;5583.664980101197;5632.127020311848;5646.661200327973;5672;5680.204771332017;5044.596432285427;5095.261187127864;5140.66585671932;5160.612954028444;5162.483751624826;5029.094762942669;5118.206677964921;5145.867909060001;5245.606579506533;5248.343957005374;5013.849584512471;5083.288339632454;5099.031184075033;5164.251183578379;5212.609054320922;5080.517350851682;5082.517160869711;5083.212102124176;5095.108356037678;5134.922976155361;4869.2939523768855;4879.170541008037;4964.801407943678;4996.050750357421;5078.720670363128;5406.675599464005;5436.3476382834315;5452.372977107644;5470.617056806025;5481.314835645544;5865.56008299375;5906.320773111104;5934;5952.226210592638;5958.523318134582;5879.941200588025;5962.075878238837;5977.013792724195;5991.131285963511;6054.788082417138;5623.044082505943;5630.15547667849;5640.210370548127;5641.210230567724;5649.067903795869;6133.938660613426;6151.200343955257;6155.045967874987;6167.84580385494;6168.907466388007;5660.235868157746;5717.971410142923;5755.050416681201;5803.245578074908;5876.08920617305;5957.6127551709;5972.163897054415;5982.252218472691;5995;5999.850003749854;5907.1434641976675;5919.6744179070565;5961.791337303261;5963.254593175853;5975.37258587842;5714.942735593929;5850.5904586678525;5909.438603332763;5926.703664816828;5956.404359564009;4463.352813841975;4488.977555112204;4492.348609451611;4493.415855938707;4504.932426013613;4035.5157381114454;4041.4948131483566;4051.6556092731857;4052.0882801370044;4066.1054567994993;5461.918071228934;5649.830505084854;5679.346875109415;5780.942190578125;5825.854353641193;5967.940320596826;5982.4316689914385;6009.308929473079;6009.399060093956;6040.36576159497;5708.20085188074;5740.225069615632;5794.420557944172;5815.389384114692;5835.824925252249;5004.449510553836;5006.324146240211;5043.142665746832;5054.4692807254705;5078.365204349457;6137.938620613826;6238.937610623836;6244.625322480666;6249.562530622813;6254.687265636791;5867.119932010232;5967.134765459071;5980.970095149671;5983.551233657406;6065.2418447694035;6306.747730090745;6340.334264902303;6343.619382837044;6345.0482427636225;6345.93654063472;5960.374160713062;5979.940200597938;5990.7004649768205;6000.8799824004145;6010.699465026819;5898.852528686817;5946.702664866652;6030.155778191144;6047.123167140828;6051.788187413419;5852.326982397079;5857.58996870232;5870.7945221917025;5887;5913.2608423947;5785.595008349375;5807.593468457337;5810.7094645266725;5868.3251426086545;5869.882602348014;5566.721663916707;5716;5761.337446193574;5769.192313076257;5809.50619197373;5736.311642602747;5777.277840269966;5779.855503612275;5779.8555036124435;5812.564057695774;5636.887262254814;5639.238702775073;5674.233978412862;5677.602567820213;5680.971595141998 22:15:07 INFO - PROCESS | 2540 | |1;ext.html;616;657.5298661457057;677.292229620047;680.1056610557115;680.2687111355294;39337.60662393375;39933.40099898504;40293.798531007305;40589.188216235656;40622.79688601553;43109.35335969963;43435;43901.341479877825;43985.78007109968;44035;7830.765077047683;7916.643751031217;7918.049834019926;7934.920650793505;7941.08677502086;8745.737627871142;8916.509591972439;8924.241439477652;9153.954230228874;9173.816523669522;9188.8621670675;9208.723738287828;9258.027907069762;9268.72193834186;9285.117913798178;9055.728328150166;9097.317701172438;9102.362834601581;9118.270538356948;9127.771805704877;9109.772255693595;9186.494742789144;9193.770155746093;9204.493752843591;9210.723678289662;43622.12755744899;43762.78118609387;43978.12043759139;44159.11681766379;44171.337429938576;3706.703463722896;3810.8666196682984;3872.1868407634147;4070.837166513336;4523.2988886722615;30149.849250753825;30570;30682.53976190359;31159.221019474695;31444.899428520122;4551.704139230939;4588.0365123324145;4633.351330813689;4638.350630911675;4705.623550116016;299543;300810;302097.4895125532;303613;303897;63445.36554634487;63978.36021639817;63987.36012639907;64116.35883641197;64165.67917160384;70563.64718176478;71536;71975.28024719791;71984.28015719776;72083.63958180281;18788.248470061317;18909.338173163873;18927.81072189288;18938.52653683669;18961.146748396357;14834.332455039548;14885.106893586419;14905.254737263094;14912.030718003294;14918.403263869324;19929;20085.799142008393;20130.29543965954;20176.29382971589;20881.26915557948;7545.283198096172;7806.4925779824125;7847.097583777824;7854.2145785421;7959.84080318402;1100.9724756881142;1116.3804088730833;1151.2977083978767;1166.084623570493;1201.3032441184266;33015.50476742851;33117.503237451456;33516.49725254123;33640.03356654511;33782.49326260108;1160.7040204747734;1189.3220864107375;1214.4656351205442;1237.826704261404;1274.1781550899618;17267.827321726872;17399;17494.912525437292;17602;17800.9109954452;500171;641348;646203;987484;1009757;57847.4215257842;58785.70607146937;58949.41050589525;59297.70351148216;59300.11049834256;1478.7633978563379;1486.8810495160362;1489.1586253766804;1501.8648321651103;1508.5248146833712;31309;31558.36883262276;31708.682913170574;32120.518192227602;32477.51283730793;54755.45244547663;55257.447425526836;55524.72237638786;55841;55909.440905590425;457661;467160;467255;467315;467852;31424;31424.371512570076;31602;31743.682563174996;31801.36397272088;50927.74536127296;51021.744891276794;51508.484915150366;52188.47811521988;52505.47494525159;41206.793966029974;41351.37972930469;41496;42020.78989605032;43243.78378108089;41756.58243417527;42807.3578896323;42810.35784463298;43769.78115109532;43801;43015.35476967911;44041.559584403745;44062;44120.779396102815;44181.55818441774;36217.27565448729;36229.63770362368;36532.81733591315;37207.441888371155;37856;28085.297867553476;28347.90153128119;28846.567301490075;29086.27284317909;29187.85406072956 22:15:07 INFO - PROCESS | 2540 | |2;jquery.html;414.45706124976283;422.1978241341451;427.37176350764366;434.69788496994585;435.69719045263537;18828.152733127008;18910.527236819085;19515.707264391047;19600.117994690238;19616.99999999998;18354;18778.061096945166;18844.152013159408;18924.43226703201;18951.33670321539;5410.134378499451;5809.419058094199;5824.359320474744;5864.530837532987;5878.588498805086;5626.662400255977;5762.6254293471;5813.563982701293;5833.32916714579;5848.67832269225;5763.049096899007;5812.4768770810615;5813.970930145343;5821.3887541808135;5832.300123985127;5644.181593668936;5759.769609215668;5770.798022069207;5791.247137872071;5806.390329015456;5152.458991805844;5201.921971170435;5207.765650545734;5223.869403264911;5224.843254702365;20660.896695516578;21647.567048659097;22494;23040;23071.653925191134;2390.342655769677;2397.6163813789885;2400.603900356439;2454.2637208837455;2463.7379415642886;9838.803223935482;9891.208703303719;9949.452780097092;10173.542190601367;10506.106980906632;1987.5826076523947;2073.2121793718443;2084.8436367272516;2174.70641463402;2180.149741600777;79761;80543.59728201322;81585.59207203986;81697.18302816956;82868.58565707193;10645.308054976402;10699.572017119384;10840.13278937683;10962;10981.450927453596;14634.875998444406;14693.92653036728;14717.190554519604;14827.851721482863;14833.110013399231;8647.697330593399;8688.826223475495;8768.210861022539;8799.824003520022;8955.686550970684;8670.263027642595;8679.132086791271;8696.695615653422;8797.47215167092;8808.823823523622;4523.7285762854335;4629.560191781776;4654.906901861944;4713.29300604912;4825.758712064383;1967.3901090661655;2018.7476565429322;2027.1688607671078;2038.3069756282935;2076.5327801244903;1052.6210564196883;1085.8208395614793;1120.5461787975883;1145.493596372595;1170.2861254634665;18590.44228673142;18683.252669893325;18807.341743038927;18846.905765471085;19298.614027719646;1060.0724366179593;1062.2511129653576;1069.0859315285475;1140.1562843495753;1167.7197472606683;10655.254132210826;10683.679489615324;10706;10735.624253151102;10830.566777328977;495830;500075;504951;512785;514483;90480;91900.54049729709;91960.54019729992;92986;93599.53200234091;1093.8960798724108;1100.4222783038967;1107.3521989636154;1110.0619976120165;1125.9493322800495;16732.41436549715;16757.664846703243;16832.915835420743;16972.575685607957;17080.23138958725;39554.81335559823;39973.2005359897;41287;41458.58541414547;41711.582884171985;56569.15146272727;57698.4230157693;57720.134197986226;58056.4194358051;58069.128963064744;8796.780080498038;8884.466931984101;8891.555422228992;8981.865272020794;9037.728868133703;12423.62729118128;13713.97145214093;13718.24549649739;13742.862571374159;13868.791968120688;11589.362585057903;11674.299542027506;11953.163278570764;12013.579524716593;12168;11368.94315528417;11393;11804.58683946092;11824.881751182378;12030.09774266951;11156.330620162817;12273.263604183421;12339.444724987578;12354.320512371905;12515.87484125147;8160.387970902088;8705.26005289532;8788.42875213109;8846.778830529289;8873.600687969187;11257.493412796608;11563.017143542893;11702.122340824302;11711.121665874924;11718.70703232426 22:15:07 INFO - PROCESS | 2540 | |3;mootools.html;1602.3991003373735;1614.7820044294021;1819.2086442397558;1919.5776929075598;1923.153812322579;7294.635268236593;7833.843323133534;8074.636641351139;8170.877436838445;8215.014198296205;8038.919610803905;8101.594920253993;8108.432409731321;8134;8167.877481837777;2377.7741114594087;2383.7497062808416;2409.409694624819;2411.409204744831;2421.769931856471;2336.9298921032396;2363.9645405318847;2365.9408514787096;2366.3965688749345;2377.9643305350346;2353.0705371378294;2393.4375421775867;2397.029203172709;2427.5387676341525;2436.805055595557;2342.332435255944;2347.6947996760396;2349.6240601503764;2352.882355882199;2393.988030059856;2328.7671232876746;2359.775821296974;2370.8221883358647;2373.1456675596773;2375.2280508834524;8230;8274.462159959583;8311.95844020776;8319.584020798997;8331.208535189147;5093.414257360414;5201.921971170435;5228.5817134629515;5266.4733526647415;5278.287431196778;3380.2732412531423;3474.7915125092577;3475.6350583188905;3525.682688558042;3529.205928666055;1180.816973369129;1189.2804853063858;1200.8018676918384;1212.77563650724;1214.8359971403836;11248.606298779585;11251.156163287786;11304.37825919574;11357.148213883991;11405.20163588549;4369.825206991684;4420.292753159479;4462.330650402466;4463.263561512379;4656.557627025427;9757.170640495637;9758.853617195748;9788.363756355957;9809.313348065709;9824.508774561386;5186;5187.740612969336;5231.346081739783;5235.712035837989;5289;4666.836660716858;4754.881127971759;4828.058528586962;4829.5653391194965;4836.951630483721;3920.2159568086495;3950.644442000234;3990.082281075369;3996.320625493673;4055.533613634411;1226.7362517058696;1232.1559731583714;1248.825164476974;1249.138094714644;1259.5276771210797;954.4225743425128;1026.5585798106886;1042.781015986636;1047.2145890582065;1048.3762161513887;5709.514691251207;5784.074548072288;5827.650340979555;5842.883142337215;5914.645121292736;1006.9798604027878;1018.6180182431589;1037.5071840875523;1038.43924280889;1044.0394836750208;4048.2105989332385;4070;4100.446439730657;4101.569335219759;4144.3990621359735;9456.810863782686;9705;9712;9714.319997600242;9721.173700235418;9464.290178236659;9502.762430939143;9549.474778887088;9640.807183856425;9645.565949532287;7525.698972041167;7629.351505122127;7656.196099409482;7684.6926122954455;7693.846123077619;5528.751206195811;5628.324601047901;5748.051571490824;5803.564732644984;5894.145348924468;8679.783005424915;8728.250601060645;8881.73354799357;8894.688685895962;9163.49600771964;8984.730458086267;8989.865152022594;9017.368784185306;9024.684136055206;9053.90946090557;6856.314368563104;7235.095613048369;7257;7267.491275610657;7290.744823931137;7911.248431399008;7938.24586664266;7996.9204157438;8001.9199808003505;8002.439829211899;6829.385355318045;6968.338007889242;7013.2986701329455;7108.0759501266575;7122.608256545733;6855.554388964901;6928.653567321513;6979.511434199558;6997.020417141706;7031.6835742390695;6488.318726533646;6653.234877989094;6659.167604049494;6668.899966500409;6688.464922806066;4923.483034281349;5107.540321371094;5147.2279158126585;5160.561352285098;5210.53105220532;4960.280759289955;5025.949740502694;5046.899062018666;5049.974750126225;5125.79496820138 22:15:07 INFO - PROCESS | 2540 | |4;prototype.html;338.2339002160106;344.50735448308916;346.2624609581592;348.42509858733104;349.2996541933423;6525.380088891559;6565.704543295551;6586.90119648205;6678.897981832992;6692.832679183023;6482.6110433373915;6513.511486638497;6522.478201743861;6535.44448721858;6538.869222615556;4890.706557606537;4892.31507588937;4894.388201474816;4902.338184345122;4910.558049775519;4750.216214324632;4853.878653033667;4877.585405240558;4894.902101957959;4910.386201724785;4832.855014349575;4837.9758101209445;4842.031593681278;4846;4854.12625727369;4834.444038935515;4836.129496690594;4842.176829938919;4843.757812109398;4865.172920603506;4199.811008504625;4203.369494575808;4210.094829611618;4218.957810421887;4220.3036498977635;6231.563790534665;6278.089676996809;6285.151504546873;6289.7169627366875;6291.653959032252;5557.444255574451;5667.234923285386;5671.347795003545;5674.120511320752;5686.829395118153;6849.2465828758795;6871.03805467235;6877.243503214642;6885.690143943534;6888.276730943279;4650.651201159926;4659.207934651117;4660.86017419478;4666.136764698542;4666.9066618667775;7166.749163779295;7186.245444228385;7221.133463984355;7236.384907282887;7248.492605517616;4749.121412538691;4754.572088512051;4761.619070474354;4772;4776.9761151194025;4745.715257084586;4822.71063736177;4829.2997515360075;4839.467658557556;4850.151223535867;5619.522340601013;5643.604947653625;5650.29371328584;5652.434756524397;5657.80197693087;6928.584284942919;6929.618870962045;6929.930700693029;6949.513534052669;6951.756688515878;1684.2757614226;1684.865210783159;1687.6793409252202;1690.3914590747322;1694.381550733998;1077.8868218837067;1086.4459125845876;1096.4408151842617;1097.9011888929874;1110.9277896936674;6720.327967203339;6783.898241526381;6785.9321406786285;6791.830204244933;6802.285759995229;7097.609631470319;7125.3230943060325;7129.572225666477;7153;7156;6575.342465753482;6726.831829204309;6800;6803.217629972518;6807.761728339485;3861.864834730771;3950.6246906543834;3950.940735888964;3955.960440395559;3956.8219430125714;7490.925090749131;7531.924680753232;7577.886331705029;7599.506032107895;7602.80992975169;7335.193128755833;7405.555666660018;7425.740099096506;7428.257174282637;7431.628418579157;7369.410447164214;7493.587852668046;7549.811254718676;7562.962185189149;7569.3566046886635;7153.177384600837;7192.568445893263;7237.240089790706;7240.732871747528;7254;7148.142222933281;7150.284971502808;7153.535020223669;7158.06945097134;7159.105111861018;7535.472516923763;7541.924580754342;7550.169481357156;7552.66013029404;7567.508111972704;4648.628109751279;4751.524847515221;4764.595009424099;4786.16242157614;4809.519048095162;7188.6046267455795;7199.676014579251;7215.747448839265;7222.458315626244;7229.421646268391;6942.965285173542;6973.232944376216;7005.859882802417;7016.5790052597;7019.824504387431;6967.372936435745;6967.512274140761;6975.372216500336;6977.581345119309;6984.860302794017;6706.497012723968;6856.588604683536;6863.588184708933;6928.341807528277;6938.687759050956;7049.154101507852;7050;7054.929450705427;7089.149302083577;7117.252688467636;6817.625030623363;6886.173659160932;6892.551984121016;6896.448284137357;6910.06714093611 22:15:07 INFO - PROCESS | 2540 | |5;yui.html;909.8225845960037;914.6798620482832;920.8388532006898;922.4557511068468;933.9252859771219;14840.925795371022;14943.476978305762;14970.850291497096;14989.77515337268;14999.550013499585;13994.160350378985;14046.719065618681;14090.506832260873;14117.294135293245;14117.57647270581;743.4869939741588;871.6252011634995;893.865920111982;896.7264984179823;897.905719899411;891.674538793339;896.0680891872445;901.1304091551651;901.1934318784683;903.5572569440959;947.4268067818973;949.6438835436711;955.4315182466421;959.0217977662815;960.7069843697669;946.8532377481467;947.275334369208;948.1229862377307;950.1638558068925;955.9569819358146;701.3442431326725;702.574942159991;702.9297070292981;702.9982275257737;704.4646068987555;14291.714165716732;14338.354774035193;14426.927865360605;14431.35058922351;14464.132152070804;14238.28808559568;14593.92703036478;14607.634809129751;14902.478413255483;14963.326650300654;11895.464704088337;11904.404779761064;12131.393430328448;12248.326342051183;12273;3999.6600288975474;4019.6382325590544;4023.275810354164;4069.1251380953236;4069.308217603015;53974.46025539773;54397.1840422394;54712.452875471135;54728.17907731387;54990.17514737282;10767.78464430707;10800.78398432027;10878.394520984337;11025.724356890982;11277.436128193558;18441.90779046123;18538.721919171225;18583.81416185821;18584.81415185858;18601.44195674132;8239.876401853977;8283.50298982063;8301.584920753938;8362.79093022667;8643;4673.112108699336;4756.405449318835;4760.238361862085;4950.455449900508;5051.217061355495;3370.0900756795527;3436.467347561132;3457.8616855325504;3459.532963049956;3468.9653103469145;623;631.0660222870177;631.1006815288196;631.9557630965788;652.9281779004306;644.8162273751958;674.5278305186389;678.135377393817;734.6216698400328;757.2503221810479;9121.771955701028;9559;10042.297039207331;10056.597736090474;10216.029477199654;601.1643815097019;629.9992570763424;678.4470656415006;727.5998200989435;731.3929438566083;4341.869743907688;4434.490033646172;4459.509453960061;4548.454185497695;4591.104734576726;66688.66655666691;67563.66218168878;67719.32280677228;68044.6597767008;68187.65906170437;54188.187177192376;57293.71353143208;57782.422175777705;58048.419515805144;58269.70865145732;52134;52323;53028.46971530312;53030.204546931825;53287.73356133272;8521.701740439055;8683.869741954002;8843.778905527413;8998.86501702488;9145.542722863964;39272;39782;39818.40272395974;40985.1802963945;41282.38076428916;46147.76926115348;49540;51290.23064653959;51290.23064654108;52307.73846130745;10394.16846652251;10496.79006419852;10658.62694805678;10942.890571094506;11235.55057797679;11619.244749091282;11748;11751;11768.470418831344;11881.881181188077;11700;11844.644660660195;11995.460204291003;12071.758564828478;12100.45547950362;12018.939905300418;12119.272843629411;12348.81476777831;12386.132970691964;12526;11923.880761192624;11928.463219154984;11966.64100076999;12015.098867584791;12133.272003679807;10060.597576096874;10152.187824974131;10308.226882983956;10417.270791044553;10541.4729263535;9760.170385517311;10149.847752283575;10182.694519164437;10197.439140847324;10310.896891030994 22:15:07 INFO - PROCESS | 2540 | __end_tp_report 22:15:07 INFO - PROCESS | 2540 | __start_cc_report 22:15:07 INFO - PROCESS | 2540 | _x_x_mozilla_cycle_collect,348 22:15:07 INFO - PROCESS | 2540 | __end_cc_report 22:15:07 INFO - PROCESS | 2540 | __startTimestamp1463980507172__endTimestamp 22:15:07 INFO - PROCESS | 2540 | 22:15:07 INFO - PROCESS | 2540 | ------- Summary: start ------- 22:15:07 INFO - PROCESS | 2540 | Number of tests: 6 22:15:07 INFO - PROCESS | 2540 | 22:15:07 INFO - PROCESS | 2540 | [#0] dojo.html Cycles:165 Average:5549.92 Median:5761.34 stddev:629.99 (10.9%) stddev-sans-first:605.76 22:15:07 INFO - PROCESS | 2540 | Values: 3259.5 3356.9 3377.6 3386.2 3403.7 5570.1 5578.2 5585.1 5586.6 5588.4 5583.7 5632.1 5646.7 5672.0 5680.2 5044.6 5095.3 5140.7 5160.6 5162.5 5029.1 5118.2 5145.9 5245.6 5248.3 5013.8 5083.3 5099.0 5164.3 5212.6 5080.5 5082.5 5083.2 5095.1 5134.9 4869.3 4879.2 4964.8 4996.1 5078.7 5406.7 5436.3 5452.4 5470.6 5481.3 5865.6 5906.3 5934.0 5952.2 5958.5 5879.9 5962.1 5977.0 5991.1 6054.8 5623.0 5630.2 5640.2 5641.2 5649.1 6133.9 6151.2 6155.0 6167.8 6168.9 5660.2 5718.0 5755.1 5803.2 5876.1 5957.6 5972.2 5982.3 5995.0 5999.9 5907.1 5919.7 5961.8 5963.3 5975.4 5714.9 5850.6 5909.4 5926.7 5956.4 4463.4 4489.0 4492.3 4493.4 4504.9 4035.5 4041.5 4051.7 4052.1 4066.1 5461.9 5649.8 5679.3 5780.9 5825.9 5967.9 5982.4 6009.3 6009.4 6040.4 5708.2 5740.2 5794.4 5815.4 5835.8 5004.4 5006.3 5043.1 5054.5 5078.4 6137.9 6238.9 6244.6 6249.6 6254.7 5867.1 5967.1 5981.0 5983.6 6065.2 6306.7 6340.3 6343.6 6345.0 6345.9 5960.4 5979.9 5990.7 6000.9 6010.7 5898.9 5946.7 6030.2 6047.1 6051.8 5852.3 5857.6 5870.8 5887.0 5913.3 5785.6 5807.6 5810.7 5868.3 5869.9 5566.7 5716.0 5761.3 5769.2 5809.5 5736.3 5777.3 5779.9 5779.9 5812.6 5636.9 5639.2 5674.2 5677.6 5681.0 22:15:07 INFO - PROCESS | 2540 | 22:15:07 INFO - PROCESS | 2540 | [#1] ext.html Cycles:180 Average:67014.51 Median:43528.56 stddev:149337.80 (343.1%) stddev-sans-first:149673.52 22:15:07 INFO - PROCESS | 2540 | Values: 616.0 657.5 677.3 680.1 680.3 39337.6 39933.4 40293.8 40589.2 40622.8 43109.4 43435.0 43901.3 43985.8 44035.0 7830.8 7916.6 7918.0 7934.9 7941.1 8745.7 8916.5 8924.2 9154.0 9173.8 9188.9 9208.7 9258.0 9268.7 9285.1 9055.7 9097.3 9102.4 9118.3 9127.8 9109.8 9186.5 9193.8 9204.5 9210.7 43622.1 43762.8 43978.1 44159.1 44171.3 3706.7 3810.9 3872.2 4070.8 4523.3 30149.8 30570.0 30682.5 31159.2 31444.9 4551.7 4588.0 4633.4 4638.4 4705.6 299543.0 300810.0 302097.5 303613.0 303897.0 63445.4 63978.4 63987.4 64116.4 64165.7 70563.6 71536.0 71975.3 71984.3 72083.6 18788.2 18909.3 18927.8 18938.5 18961.1 14834.3 14885.1 14905.3 14912.0 14918.4 19929.0 20085.8 20130.3 20176.3 20881.3 7545.3 7806.5 7847.1 7854.2 7959.8 1101.0 1116.4 1151.3 1166.1 1201.3 33015.5 33117.5 33516.5 33640.0 33782.5 1160.7 1189.3 1214.5 1237.8 1274.2 17267.8 17399.0 17494.9 17602.0 17800.9 500171.0 641348.0 646203.0 987484.0 1009757.0 57847.4 58785.7 58949.4 59297.7 59300.1 1478.8 1486.9 1489.2 1501.9 1508.5 31309.0 31558.4 31708.7 32120.5 32477.5 54755.5 55257.4 55524.7 55841.0 55909.4 457661.0 467160.0 467255.0 467315.0 467852.0 31424.0 31424.4 31602.0 31743.7 31801.4 50927.7 51021.7 51508.5 52188.5 52505.5 41206.8 41351.4 41496.0 42020.8 43243.8 41756.6 42807.4 42810.4 43769.8 43801.0 43015.4 44041.6 44062.0 44120.8 44181.6 36217.3 36229.6 36532.8 37207.4 37856.0 28085.3 28347.9 28846.6 29086.3 29187.9 22:15:07 INFO - PROCESS | 2540 | 22:15:07 INFO - PROCESS | 2540 | [#2] jquery.html Cycles:180 Average:29416.31 Median:23055.83 stddev:83282.84 (361.2%) stddev-sans-first:83488.00 22:15:07 INFO - PROCESS | 2540 | Values: 414.5 422.2 427.4 434.7 435.7 18828.2 18910.5 19515.7 19600.1 19617.0 18354.0 18778.1 18844.2 18924.4 18951.3 5410.1 5809.4 5824.4 5864.5 5878.6 5626.7 5762.6 5813.6 5833.3 5848.7 5763.0 5812.5 5814.0 5821.4 5832.3 5644.2 5759.8 5770.8 5791.2 5806.4 5152.5 5201.9 5207.8 5223.9 5224.8 20660.9 21647.6 22494.0 23040.0 23071.7 2390.3 2397.6 2400.6 2454.3 2463.7 9838.8 9891.2 9949.5 10173.5 10506.1 1987.6 2073.2 2084.8 2174.7 2180.1 79761.0 80543.6 81585.6 81697.2 82868.6 10645.3 10699.6 10840.1 10962.0 10981.5 14634.9 14693.9 14717.2 14827.9 14833.1 8647.7 8688.8 8768.2 8799.8 8955.7 8670.3 8679.1 8696.7 8797.5 8808.8 4523.7 4629.6 4654.9 4713.3 4825.8 1967.4 2018.7 2027.2 2038.3 2076.5 1052.6 1085.8 1120.5 1145.5 1170.3 18590.4 18683.3 18807.3 18846.9 19298.6 1060.1 1062.3 1069.1 1140.2 1167.7 10655.3 10683.7 10706.0 10735.6 10830.6 495830.0 500075.0 504951.0 512785.0 514483.0 90480.0 91900.5 91960.5 92986.0 93599.5 1093.9 1100.4 1107.4 1110.1 1125.9 16732.4 16757.7 16832.9 16972.6 17080.2 39554.8 39973.2 41287.0 41458.6 41711.6 56569.2 57698.4 57720.1 58056.4 58069.1 8796.8 8884.5 8891.6 8981.9 9037.7 12423.6 13714.0 13718.2 13742.9 13868.8 11589.4 11674.3 11953.2 12013.6 12168.0 11368.9 11393.0 11804.6 11824.9 12030.1 11156.3 12273.3 12339.4 12354.3 12515.9 8160.4 8705.3 8788.4 8846.8 8873.6 11257.5 11563.0 11702.1 11711.1 11718.7 22:15:07 INFO - PROCESS | 2540 | 22:15:07 INFO - PROCESS | 2540 | [#3] mootools.html Cycles:180 Average:5472.49 Median:5194.83 stddev:2892.40 (55.7%) stddev-sans-first:2885.89 22:15:07 INFO - PROCESS | 2540 | Values: 1602.4 1614.8 1819.2 1919.6 1923.2 7294.6 7833.8 8074.6 8170.9 8215.0 8038.9 8101.6 8108.4 8134.0 8167.9 2377.8 2383.7 2409.4 2411.4 2421.8 2336.9 2364.0 2365.9 2366.4 2378.0 2353.1 2393.4 2397.0 2427.5 2436.8 2342.3 2347.7 2349.6 2352.9 2394.0 2328.8 2359.8 2370.8 2373.1 2375.2 8230.0 8274.5 8312.0 8319.6 8331.2 5093.4 5201.9 5228.6 5266.5 5278.3 3380.3 3474.8 3475.6 3525.7 3529.2 1180.8 1189.3 1200.8 1212.8 1214.8 11248.6 11251.2 11304.4 11357.1 11405.2 4369.8 4420.3 4462.3 4463.3 4656.6 9757.2 9758.9 9788.4 9809.3 9824.5 5186.0 5187.7 5231.3 5235.7 5289.0 4666.8 4754.9 4828.1 4829.6 4837.0 3920.2 3950.6 3990.1 3996.3 4055.5 1226.7 1232.2 1248.8 1249.1 1259.5 954.4 1026.6 1042.8 1047.2 1048.4 5709.5 5784.1 5827.7 5842.9 5914.6 1007.0 1018.6 1037.5 1038.4 1044.0 4048.2 4070.0 4100.4 4101.6 4144.4 9456.8 9705.0 9712.0 9714.3 9721.2 9464.3 9502.8 9549.5 9640.8 9645.6 7525.7 7629.4 7656.2 7684.7 7693.8 5528.8 5628.3 5748.1 5803.6 5894.1 8679.8 8728.3 8881.7 8894.7 9163.5 8984.7 8989.9 9017.4 9024.7 9053.9 6856.3 7235.1 7257.0 7267.5 7290.7 7911.2 7938.2 7996.9 8001.9 8002.4 6829.4 6968.3 7013.3 7108.1 7122.6 6855.6 6928.7 6979.5 6997.0 7031.7 6488.3 6653.2 6659.2 6668.9 6688.5 4923.5 5107.5 5147.2 5160.6 5210.5 4960.3 5025.9 5046.9 5050.0 5125.8 22:15:07 INFO - PROCESS | 2540 | 22:15:07 INFO - PROCESS | 2540 | [#4] prototype.html Cycles:180 Average:5790.76 Median:6699.66 stddev:1804.10 (26.9%) stddev-sans-first:1762.13 22:15:07 INFO - PROCESS | 2540 | Values: 338.2 344.5 346.3 348.4 349.3 6525.4 6565.7 6586.9 6678.9 6692.8 6482.6 6513.5 6522.5 6535.4 6538.9 4890.7 4892.3 4894.4 4902.3 4910.6 4750.2 4853.9 4877.6 4894.9 4910.4 4832.9 4838.0 4842.0 4846.0 4854.1 4834.4 4836.1 4842.2 4843.8 4865.2 4199.8 4203.4 4210.1 4219.0 4220.3 6231.6 6278.1 6285.2 6289.7 6291.7 5557.4 5667.2 5671.3 5674.1 5686.8 6849.2 6871.0 6877.2 6885.7 6888.3 4650.7 4659.2 4660.9 4666.1 4666.9 7166.7 7186.2 7221.1 7236.4 7248.5 4749.1 4754.6 4761.6 4772.0 4777.0 4745.7 4822.7 4829.3 4839.5 4850.2 5619.5 5643.6 5650.3 5652.4 5657.8 6928.6 6929.6 6929.9 6949.5 6951.8 1684.3 1684.9 1687.7 1690.4 1694.4 1077.9 1086.4 1096.4 1097.9 1110.9 6720.3 6783.9 6785.9 6791.8 6802.3 7097.6 7125.3 7129.6 7153.0 7156.0 6575.3 6726.8 6800.0 6803.2 6807.8 3861.9 3950.6 3950.9 3956.0 3956.8 7490.9 7531.9 7577.9 7599.5 7602.8 7335.2 7405.6 7425.7 7428.3 7431.6 7369.4 7493.6 7549.8 7563.0 7569.4 7153.2 7192.6 7237.2 7240.7 7254.0 7148.1 7150.3 7153.5 7158.1 7159.1 7535.5 7541.9 7550.2 7552.7 7567.5 4648.6 4751.5 4764.6 4786.2 4809.5 7188.6 7199.7 7215.7 7222.5 7229.4 6943.0 6973.2 7005.9 7016.6 7019.8 6967.4 6967.5 6975.4 6977.6 6984.9 6706.5 6856.6 6863.6 6928.3 6938.7 7049.2 7050.0 7054.9 7089.1 7117.3 6817.6 6886.2 6892.6 6896.4 6910.1 22:15:07 INFO - PROCESS | 2540 | 22:15:07 INFO - PROCESS | 2540 | [#5] yui.html Cycles:180 Average:15341.72 Median:4569.78 stddev:18261.20 (399.6%) stddev-sans-first:18280.26 22:15:07 INFO - PROCESS | 2540 | Values: 909.8 914.7 920.8 922.5 933.9 14840.9 14943.5 14970.9 14989.8 14999.6 13994.2 14046.7 14090.5 14117.3 14117.6 743.5 871.6 893.9 896.7 897.9 891.7 896.1 901.1 901.2 903.6 947.4 949.6 955.4 959.0 960.7 946.9 947.3 948.1 950.2 956.0 701.3 702.6 702.9 703.0 704.5 14291.7 14338.4 14426.9 14431.4 14464.1 14238.3 14593.9 14607.6 14902.5 14963.3 11895.5 11904.4 12131.4 12248.3 12273.0 3999.7 4019.6 4023.3 4069.1 4069.3 53974.5 54397.2 54712.5 54728.2 54990.2 10767.8 10800.8 10878.4 11025.7 11277.4 18441.9 18538.7 18583.8 18584.8 18601.4 8239.9 8283.5 8301.6 8362.8 8643.0 4673.1 4756.4 4760.2 4950.5 5051.2 3370.1 3436.5 3457.9 3459.5 3469.0 623.0 631.1 631.1 632.0 652.9 644.8 674.5 678.1 734.6 757.3 9121.8 9559.0 10042.3 10056.6 10216.0 601.2 630.0 678.4 727.6 731.4 4341.9 4434.5 4459.5 4548.5 4591.1 66688.7 67563.7 67719.3 68044.7 68187.7 54188.2 57293.7 57782.4 58048.4 58269.7 52134.0 52323.0 53028.5 53030.2 53287.7 8521.7 8683.9 8843.8 8998.9 9145.5 39272.0 39782.0 39818.4 40985.2 41282.4 46147.8 49540.0 51290.2 51290.2 52307.7 10394.2 10496.8 10658.6 10942.9 11235.6 11619.2 11748.0 11751.0 11768.5 11881.9 11700.0 11844.6 11995.5 12071.8 12100.5 12018.9 12119.3 12348.8 12386.1 12526.0 11923.9 11928.5 11966.6 12015.1 12133.3 10060.6 10152.2 10308.2 10417.3 10541.5 9760.2 10149.8 10182.7 10197.4 10310.9 22:15:07 INFO - PROCESS | 2540 | -------- Summary: end -------- 22:15:07 INFO - PROCESS | 2540 | 22:15:07 INFO - TEST-INFO | 2540: exit 0 22:15:07 INFO - TEST-OK | dromaeo_css | took 1108568ms 22:15:07 INFO - TEST-START | kraken 22:15:07 INFO - operating with platform_type : linux_ 22:15:07 INFO - Initialising browser for kraken test... 22:15:07 INFO - TEST-INFO | started process 2875 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmplF0usm/profile http://localhost:47929/getInfo.html) 22:15:11 INFO - PROCESS | 2875 | 22:15:11 INFO - PROCESS | 2875 | (process:2962): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 22:15:12 INFO - PROCESS | 2875 | __metrics Screen width/height:1600/1200 22:15:12 INFO - PROCESS | 2875 | colorDepth:24 22:15:12 INFO - PROCESS | 2875 | Browser inner width/height: 1024/697 22:15:12 INFO - PROCESS | 2875 | __metrics 22:15:12 ERROR - PROCESS | 2875 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 22:15:13 INFO - TEST-INFO | 2875: exit 0 22:15:13 INFO - Browser initialized. 22:15:13 INFO - Running cycle 1/1 for kraken test... 22:15:13 INFO - TEST-INFO | started process 3013 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmplF0usm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:15:15 INFO - PROCESS | 3013 | 22:15:15 INFO - PROCESS | 3013 | (process:3084): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 22:15:25 INFO - PROCESS | 3013 | RSS: Main: 152485888 22:15:25 INFO - PROCESS | 3013 | 22:15:27 INFO - PROCESS | 3013 | Cycle 1(1): loaded http://localhost:47929/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47929/tests/kraken/driver.html?testName=audio-beat-detection) 22:15:28 INFO - PROCESS | 3013 | RSS: Main: 157249536 22:15:28 INFO - PROCESS | 3013 | 22:15:31 INFO - PROCESS | 3013 | Cycle 1(1): loaded http://localhost:47929/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47929/tests/kraken/driver.html?testName=audio-dft) 22:15:32 INFO - PROCESS | 3013 | RSS: Main: 157163520 22:15:32 INFO - PROCESS | 3013 | 22:15:34 INFO - PROCESS | 3013 | Cycle 1(1): loaded http://localhost:47929/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47929/tests/kraken/driver.html?testName=audio-fft) 22:15:35 INFO - PROCESS | 3013 | RSS: Main: 157495296 22:15:35 INFO - PROCESS | 3013 | 22:15:37 INFO - PROCESS | 3013 | Cycle 1(1): loaded http://localhost:47929/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47929/tests/kraken/driver.html?testName=audio-oscillator) 22:15:38 INFO - PROCESS | 3013 | RSS: Main: 157315072 22:15:38 INFO - PROCESS | 3013 | 22:15:40 INFO - PROCESS | 3013 | Cycle 1(1): loaded http://localhost:47929/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47929/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:15:41 INFO - PROCESS | 3013 | RSS: Main: 157237248 22:15:41 INFO - PROCESS | 3013 | 22:15:44 INFO - PROCESS | 3013 | Cycle 1(1): loaded http://localhost:47929/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47929/tests/kraken/driver.html?testName=imaging-darkroom) 22:15:44 INFO - PROCESS | 3013 | RSS: Main: 156889088 22:15:44 INFO - PROCESS | 3013 | 22:15:48 INFO - PROCESS | 3013 | Cycle 1(1): loaded http://localhost:47929/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47929/tests/kraken/driver.html?testName=imaging-desaturate) 22:15:48 INFO - PROCESS | 3013 | RSS: Main: 157495296 22:15:48 INFO - PROCESS | 3013 | 22:15:51 INFO - PROCESS | 3013 | Cycle 1(1): loaded http://localhost:47929/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47929/tests/kraken/driver.html?testName=json-parse-financial) 22:15:52 INFO - PROCESS | 3013 | RSS: Main: 156987392 22:15:52 INFO - PROCESS | 3013 | 22:15:53 INFO - PROCESS | 3013 | Cycle 1(1): loaded http://localhost:47929/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47929/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:15:54 INFO - PROCESS | 3013 | RSS: Main: 157343744 22:15:54 INFO - PROCESS | 3013 | 22:15:55 INFO - PROCESS | 3013 | Cycle 1(1): loaded http://localhost:47929/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47929/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:15:56 INFO - PROCESS | 3013 | RSS: Main: 157237248 22:15:56 INFO - PROCESS | 3013 | 22:15:58 INFO - PROCESS | 3013 | Cycle 1(1): loaded http://localhost:47929/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47929/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:15:59 INFO - PROCESS | 3013 | RSS: Main: 158089216 22:15:59 INFO - PROCESS | 3013 | 22:16:01 INFO - PROCESS | 3013 | Cycle 1(1): loaded http://localhost:47929/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47929/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:16:02 INFO - PROCESS | 3013 | RSS: Main: 158162944 22:16:02 INFO - PROCESS | 3013 | 22:16:04 INFO - PROCESS | 3013 | Cycle 1(1): loaded http://localhost:47929/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47929/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:16:05 INFO - PROCESS | 3013 | RSS: Main: 158117888 22:16:05 INFO - PROCESS | 3013 | 22:16:07 INFO - PROCESS | 3013 | Cycle 1(1): loaded http://localhost:47929/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47929/tests/kraken/driver.html?testName=ai-astar) 22:16:07 INFO - PROCESS | 3013 | RSS: Main: 159674368 22:16:07 INFO - PROCESS | 3013 | 22:16:07 INFO - PROCESS | 3013 | __start_tp_report 22:16:07 INFO - PROCESS | 3013 | _x_x_mozilla_page_load 22:16:07 INFO - PROCESS | 3013 | _x_x_mozilla_page_load_details 22:16:07 INFO - PROCESS | 3013 | |i|pagename|runs| 22:16:07 INFO - PROCESS | 3013 | |0;ai-astar;108;110;104;109;112;110;101;113;110;109 22:16:07 INFO - PROCESS | 3013 | |1;audio-beat-detection;161;127;164;182;127;158;159;128;176;130 22:16:07 INFO - PROCESS | 3013 | |2;audio-dft;162;164;163;160;162;162;163;161;162;164 22:16:07 INFO - PROCESS | 3013 | |3;audio-fft;76;77;74;186;72;76;76;76;170;74 22:16:07 INFO - PROCESS | 3013 | |4;audio-oscillator;92;100;148;92;92;92;150;93;92;143 22:16:07 INFO - PROCESS | 3013 | |5;imaging-gaussian-blur;117;117;118;117;118;118;117;117;117;117 22:16:07 INFO - PROCESS | 3013 | |6;imaging-darkroom;147;146;147;146;146;145;147;147;146;148 22:16:07 INFO - PROCESS | 3013 | |7;imaging-desaturate;95;94;95;95;95;95;95;95;95;95 22:16:07 INFO - PROCESS | 3013 | |8;json-parse-financial;56;55;55;56;56;59;56;74;56;54 22:16:07 INFO - PROCESS | 3013 | |9;json-stringify-tinderbox;58;58;59;60;58;58;58;59;58;59 22:16:07 INFO - PROCESS | 3013 | |10;stanford-crypto-aes;73;72;74;73;74;75;74;73;74;74 22:16:07 INFO - PROCESS | 3013 | |11;stanford-crypto-ccm;114;114;115;115;116;114;115;123;139;130 22:16:07 INFO - PROCESS | 3013 | |12;stanford-crypto-pbkdf2;159;160;161;157;158;153;164;167;159;155 22:16:07 INFO - PROCESS | 3013 | |13;stanford-crypto-sha256-iterative;69;65;66;68;72;66;67;64;66;66 22:16:07 INFO - PROCESS | 3013 | __end_tp_report 22:16:07 INFO - PROCESS | 3013 | __start_cc_report 22:16:07 INFO - PROCESS | 3013 | _x_x_mozilla_cycle_collect,830 22:16:07 INFO - PROCESS | 3013 | __end_cc_report 22:16:07 INFO - PROCESS | 3013 | __startTimestamp1463980567450__endTimestamp 22:16:07 INFO - PROCESS | 3013 | 22:16:07 INFO - PROCESS | 3013 | ------- Summary: start ------- 22:16:07 INFO - PROCESS | 3013 | Number of tests: 14 22:16:07 INFO - PROCESS | 3013 | 22:16:07 INFO - PROCESS | 3013 | [#0] ai-astar Cycles:10 Average:108.60 Median:110.00 stddev:3.60 (3.3%) stddev-sans-first:3.81 22:16:07 INFO - PROCESS | 3013 | Values: 108.0 110.0 104.0 109.0 112.0 110.0 101.0 113.0 110.0 109.0 22:16:07 INFO - PROCESS | 3013 | 22:16:07 INFO - PROCESS | 3013 | [#1] audio-beat-detection Cycles:10 Average:151.20 Median:160.00 stddev:21.32 (13.3%) stddev-sans-first:22.31 22:16:07 INFO - PROCESS | 3013 | Values: 161.0 127.0 164.0 182.0 127.0 158.0 159.0 128.0 176.0 130.0 22:16:07 INFO - PROCESS | 3013 | 22:16:07 INFO - PROCESS | 3013 | [#2] audio-dft Cycles:10 Average:162.30 Median:162.50 stddev:1.25 (0.8%) stddev-sans-first:1.32 22:16:07 INFO - PROCESS | 3013 | Values: 162.0 164.0 163.0 160.0 162.0 162.0 163.0 161.0 162.0 164.0 22:16:07 INFO - PROCESS | 3013 | 22:16:07 INFO - PROCESS | 3013 | [#3] audio-fft Cycles:10 Average:95.70 Median:76.00 stddev:43.56 (57.3%) stddev-sans-first:45.62 22:16:07 INFO - PROCESS | 3013 | Values: 76.0 77.0 74.0 186.0 72.0 76.0 76.0 76.0 170.0 74.0 22:16:07 INFO - PROCESS | 3013 | 22:16:07 INFO - PROCESS | 3013 | [#4] audio-oscillator Cycles:10 Average:109.40 Median:92.00 stddev:26.12 (28.4%) stddev-sans-first:26.93 22:16:07 INFO - PROCESS | 3013 | Values: 92.0 100.0 148.0 92.0 92.0 92.0 150.0 93.0 92.0 143.0 22:16:07 INFO - PROCESS | 3013 | 22:16:07 INFO - PROCESS | 3013 | [#5] imaging-gaussian-blur Cycles:10 Average:117.30 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 22:16:07 INFO - PROCESS | 3013 | Values: 117.0 117.0 118.0 117.0 118.0 118.0 117.0 117.0 117.0 117.0 22:16:07 INFO - PROCESS | 3013 | 22:16:07 INFO - PROCESS | 3013 | [#6] imaging-darkroom Cycles:10 Average:146.50 Median:147.00 stddev:0.85 (0.6%) stddev-sans-first:0.88 22:16:07 INFO - PROCESS | 3013 | Values: 147.0 146.0 147.0 146.0 146.0 145.0 147.0 147.0 146.0 148.0 22:16:07 INFO - PROCESS | 3013 | 22:16:07 INFO - PROCESS | 3013 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 22:16:07 INFO - PROCESS | 3013 | Values: 95.0 94.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 22:16:07 INFO - PROCESS | 3013 | 22:16:07 INFO - PROCESS | 3013 | [#8] json-parse-financial Cycles:10 Average:57.70 Median:56.00 stddev:5.87 (10.5%) stddev-sans-first:6.19 22:16:07 INFO - PROCESS | 3013 | Values: 56.0 55.0 55.0 56.0 56.0 59.0 56.0 74.0 56.0 54.0 22:16:07 INFO - PROCESS | 3013 | 22:16:07 INFO - PROCESS | 3013 | [#9] json-stringify-tinderbox Cycles:10 Average:58.50 Median:58.50 stddev:0.71 (1.2%) stddev-sans-first:0.73 22:16:07 INFO - PROCESS | 3013 | Values: 58.0 58.0 59.0 60.0 58.0 58.0 58.0 59.0 58.0 59.0 22:16:07 INFO - PROCESS | 3013 | 22:16:07 INFO - PROCESS | 3013 | [#10] stanford-crypto-aes Cycles:10 Average:73.60 Median:74.00 stddev:0.84 (1.1%) stddev-sans-first:0.87 22:16:07 INFO - PROCESS | 3013 | Values: 73.0 72.0 74.0 73.0 74.0 75.0 74.0 73.0 74.0 74.0 22:16:07 INFO - PROCESS | 3013 | 22:16:07 INFO - PROCESS | 3013 | [#11] stanford-crypto-ccm Cycles:10 Average:119.50 Median:115.50 stddev:8.61 (7.5%) stddev-sans-first:8.89 22:16:07 INFO - PROCESS | 3013 | Values: 114.0 114.0 115.0 115.0 116.0 114.0 115.0 123.0 139.0 130.0 22:16:07 INFO - PROCESS | 3013 | 22:16:07 INFO - PROCESS | 3013 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:159.30 Median:159.50 stddev:4.08 (2.6%) stddev-sans-first:4.33 22:16:07 INFO - PROCESS | 3013 | Values: 159.0 160.0 161.0 157.0 158.0 153.0 164.0 167.0 159.0 155.0 22:16:07 INFO - PROCESS | 3013 | 22:16:07 INFO - PROCESS | 3013 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.90 Median:66.50 stddev:2.28 (3.4%) stddev-sans-first:2.29 22:16:07 INFO - PROCESS | 3013 | Values: 69.0 65.0 66.0 68.0 72.0 66.0 67.0 64.0 66.0 66.0 22:16:07 INFO - PROCESS | 3013 | -------- Summary: end -------- 22:16:07 INFO - PROCESS | 3013 | 22:16:08 INFO - TEST-INFO | 3013: exit 0 22:16:08 INFO - TEST-OK | kraken | took 60477ms 22:16:08 INFO - SUITE-END | took 1169s 22:16:08 INFO - Completed test suite (00:19:29) 22:16:08 INFO - javascript benchmark 22:16:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3259.5110733389997, 3356.949645755313, 3377.594688637364, 3386.1873150443903, 3403.659634036597, 5570.053090974534, 5578.163275508676, 5585.1063829787245, 5586.636868603538, 5588.385277619459, 5583.664980101197, 5632.127020311848, 5646.661200327973, 5672.0, 5680.204771332017, 5044.596432285427, 5095.261187127864, 5140.66585671932, 5160.612954028444, 5162.483751624826, 5029.094762942669, 5118.206677964921, 5145.867909060001, 5245.606579506533, 5248.343957005374, 5013.849584512471, 5083.288339632454, 5099.031184075033, 5164.251183578379, 5212.609054320922, 5080.517350851682, 5082.517160869711, 5083.212102124176, 5095.108356037678, 5134.922976155361, 4869.2939523768855, 4879.170541008037, 4964.801407943678, 4996.050750357421, 5078.720670363128, 5406.675599464005, 5436.3476382834315, 5452.372977107644, 5470.617056806025, 5481.314835645544, 5865.56008299375, 5906.320773111104, 5934.0, 5952.226210592638, 5958.523318134582, 5879.941200588025, 5962.075878238837, 5977.013792724195, 5991.131285963511, 6054.788082417138, 5623.044082505943, 5630.15547667849, 5640.210370548127, 5641.210230567724, 5649.067903795869, 6133.938660613426, 6151.200343955257, 6155.045967874987, 6167.84580385494, 6168.907466388007, 5660.235868157746, 5717.971410142923, 5755.050416681201, 5803.245578074908, 5876.08920617305, 5957.6127551709, 5972.163897054415, 5982.252218472691, 5995.0, 5999.850003749854, 5907.1434641976675, 5919.6744179070565, 5961.791337303261, 5963.254593175853, 5975.37258587842, 5714.942735593929, 5850.5904586678525, 5909.438603332763, 5926.703664816828, 5956.404359564009, 4463.352813841975, 4488.977555112204, 4492.348609451611, 4493.415855938707, 4504.932426013613, 4035.5157381114454, 4041.4948131483566, 4051.6556092731857, 4052.0882801370044, 4066.1054567994993, 5461.918071228934, 5649.830505084854, 5679.346875109415, 5780.942190578125, 5825.854353641193, 5967.940320596826, 5982.4316689914385, 6009.308929473079, 6009.399060093956, 6040.36576159497, 5708.20085188074, 5740.225069615632, 5794.420557944172, 5815.389384114692, 5835.824925252249, 5004.449510553836, 5006.324146240211, 5043.142665746832, 5054.4692807254705, 5078.365204349457, 6137.938620613826, 6238.937610623836, 6244.625322480666, 6249.562530622813, 6254.687265636791, 5867.119932010232, 5967.134765459071, 5980.970095149671, 5983.551233657406, 6065.2418447694035, 6306.747730090745, 6340.334264902303, 6343.619382837044, 6345.0482427636225, 6345.93654063472, 5960.374160713062, 5979.940200597938, 5990.7004649768205, 6000.8799824004145, 6010.699465026819, 5898.852528686817, 5946.702664866652, 6030.155778191144, 6047.123167140828, 6051.788187413419, 5852.326982397079, 5857.58996870232, 5870.7945221917025, 5887.0, 5913.2608423947, 5785.595008349375, 5807.593468457337, 5810.7094645266725, 5868.3251426086545, 5869.882602348014, 5566.721663916707, 5716.0, 5761.337446193574, 5769.192313076257, 5809.50619197373, 5736.311642602747, 5777.277840269966, 5779.855503612275, 5779.8555036124435, 5812.564057695774, 5636.887262254814, 5639.238702775073, 5674.233978412862, 5677.602567820213, 5680.971595141998], "value": 5508.250291790263, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [616.0, 657.5298661457057, 677.292229620047, 680.1056610557115, 680.2687111355294, 39337.60662393375, 39933.40099898504, 40293.798531007305, 40589.188216235656, 40622.79688601553, 43109.35335969963, 43435.0, 43901.341479877825, 43985.78007109968, 44035.0, 7830.765077047683, 7916.643751031217, 7918.049834019926, 7934.920650793505, 7941.08677502086, 8745.737627871142, 8916.509591972439, 8924.241439477652, 9153.954230228874, 9173.816523669522, 9188.8621670675, 9208.723738287828, 9258.027907069762, 9268.72193834186, 9285.117913798178, 9055.728328150166, 9097.317701172438, 9102.362834601581, 9118.270538356948, 9127.771805704877, 9109.772255693595, 9186.494742789144, 9193.770155746093, 9204.493752843591, 9210.723678289662, 43622.12755744899, 43762.78118609387, 43978.12043759139, 44159.11681766379, 44171.337429938576, 3706.703463722896, 3810.8666196682984, 3872.1868407634147, 4070.837166513336, 4523.2988886722615, 30149.849250753825, 30570.0, 30682.53976190359, 31159.221019474695, 31444.899428520122, 4551.704139230939, 4588.0365123324145, 4633.351330813689, 4638.350630911675, 4705.623550116016, 299543.0, 300810.0, 302097.4895125532, 303613.0, 303897.0, 63445.36554634487, 63978.36021639817, 63987.36012639907, 64116.35883641197, 64165.67917160384, 70563.64718176478, 71536.0, 71975.28024719791, 71984.28015719776, 72083.63958180281, 18788.248470061317, 18909.338173163873, 18927.81072189288, 18938.52653683669, 18961.146748396357, 14834.332455039548, 14885.106893586419, 14905.254737263094, 14912.030718003294, 14918.403263869324, 19929.0, 20085.799142008393, 20130.29543965954, 20176.29382971589, 20881.26915557948, 7545.283198096172, 7806.4925779824125, 7847.097583777824, 7854.2145785421, 7959.84080318402, 1100.9724756881142, 1116.3804088730833, 1151.2977083978767, 1166.084623570493, 1201.3032441184266, 33015.50476742851, 33117.503237451456, 33516.49725254123, 33640.03356654511, 33782.49326260108, 1160.7040204747734, 1189.3220864107375, 1214.4656351205442, 1237.826704261404, 1274.1781550899618, 17267.827321726872, 17399.0, 17494.912525437292, 17602.0, 17800.9109954452, 500171.0, 641348.0, 646203.0, 987484.0, 1009757.0, 57847.4215257842, 58785.70607146937, 58949.41050589525, 59297.70351148216, 59300.11049834256, 1478.7633978563379, 1486.8810495160362, 1489.1586253766804, 1501.8648321651103, 1508.5248146833712, 31309.0, 31558.36883262276, 31708.682913170574, 32120.518192227602, 32477.51283730793, 54755.45244547663, 55257.447425526836, 55524.72237638786, 55841.0, 55909.440905590425, 457661.0, 467160.0, 467255.0, 467315.0, 467852.0, 31424.0, 31424.371512570076, 31602.0, 31743.682563174996, 31801.36397272088, 50927.74536127296, 51021.744891276794, 51508.484915150366, 52188.47811521988, 52505.47494525159, 41206.793966029974, 41351.37972930469, 41496.0, 42020.78989605032, 43243.78378108089, 41756.58243417527, 42807.3578896323, 42810.35784463298, 43769.78115109532, 43801.0, 43015.35476967911, 44041.559584403745, 44062.0, 44120.779396102815, 44181.55818441774, 36217.27565448729, 36229.63770362368, 36532.81733591315, 37207.441888371155, 37856.0, 28085.297867553476, 28347.90153128119, 28846.567301490075, 29086.27284317909, 29187.85406072956], "value": 21238.206975508718, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [414.45706124976283, 422.1978241341451, 427.37176350764366, 434.69788496994585, 435.69719045263537, 18828.152733127008, 18910.527236819085, 19515.707264391047, 19600.117994690238, 19616.99999999998, 18354.0, 18778.061096945166, 18844.152013159408, 18924.43226703201, 18951.33670321539, 5410.134378499451, 5809.419058094199, 5824.359320474744, 5864.530837532987, 5878.588498805086, 5626.662400255977, 5762.6254293471, 5813.563982701293, 5833.32916714579, 5848.67832269225, 5763.049096899007, 5812.4768770810615, 5813.970930145343, 5821.3887541808135, 5832.300123985127, 5644.181593668936, 5759.769609215668, 5770.798022069207, 5791.247137872071, 5806.390329015456, 5152.458991805844, 5201.921971170435, 5207.765650545734, 5223.869403264911, 5224.843254702365, 20660.896695516578, 21647.567048659097, 22494.0, 23040.0, 23071.653925191134, 2390.342655769677, 2397.6163813789885, 2400.603900356439, 2454.2637208837455, 2463.7379415642886, 9838.803223935482, 9891.208703303719, 9949.452780097092, 10173.542190601367, 10506.106980906632, 1987.5826076523947, 2073.2121793718443, 2084.8436367272516, 2174.70641463402, 2180.149741600777, 79761.0, 80543.59728201322, 81585.59207203986, 81697.18302816956, 82868.58565707193, 10645.308054976402, 10699.572017119384, 10840.13278937683, 10962.0, 10981.450927453596, 14634.875998444406, 14693.92653036728, 14717.190554519604, 14827.851721482863, 14833.110013399231, 8647.697330593399, 8688.826223475495, 8768.210861022539, 8799.824003520022, 8955.686550970684, 8670.263027642595, 8679.132086791271, 8696.695615653422, 8797.47215167092, 8808.823823523622, 4523.7285762854335, 4629.560191781776, 4654.906901861944, 4713.29300604912, 4825.758712064383, 1967.3901090661655, 2018.7476565429322, 2027.1688607671078, 2038.3069756282935, 2076.5327801244903, 1052.6210564196883, 1085.8208395614793, 1120.5461787975883, 1145.493596372595, 1170.2861254634665, 18590.44228673142, 18683.252669893325, 18807.341743038927, 18846.905765471085, 19298.614027719646, 1060.0724366179593, 1062.2511129653576, 1069.0859315285475, 1140.1562843495753, 1167.7197472606683, 10655.254132210826, 10683.679489615324, 10706.0, 10735.624253151102, 10830.566777328977, 495830.0, 500075.0, 504951.0, 512785.0, 514483.0, 90480.0, 91900.54049729709, 91960.54019729992, 92986.0, 93599.53200234091, 1093.8960798724108, 1100.4222783038967, 1107.3521989636154, 1110.0619976120165, 1125.9493322800495, 16732.41436549715, 16757.664846703243, 16832.915835420743, 16972.575685607957, 17080.23138958725, 39554.81335559823, 39973.2005359897, 41287.0, 41458.58541414547, 41711.582884171985, 56569.15146272727, 57698.4230157693, 57720.134197986226, 58056.4194358051, 58069.128963064744, 8796.780080498038, 8884.466931984101, 8891.555422228992, 8981.865272020794, 9037.728868133703, 12423.62729118128, 13713.97145214093, 13718.24549649739, 13742.862571374159, 13868.791968120688, 11589.362585057903, 11674.299542027506, 11953.163278570764, 12013.579524716593, 12168.0, 11368.94315528417, 11393.0, 11804.58683946092, 11824.881751182378, 12030.09774266951, 11156.330620162817, 12273.263604183421, 12339.444724987578, 12354.320512371905, 12515.87484125147, 8160.387970902088, 8705.26005289532, 8788.42875213109, 8846.778830529289, 8873.600687969187, 11257.493412796608, 11563.017143542893, 11702.122340824302, 11711.121665874924, 11718.70703232426], "value": 9378.301398638998, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1602.3991003373735, 1614.7820044294021, 1819.2086442397558, 1919.5776929075598, 1923.153812322579, 7294.635268236593, 7833.843323133534, 8074.636641351139, 8170.877436838445, 8215.014198296205, 8038.919610803905, 8101.594920253993, 8108.432409731321, 8134.0, 8167.877481837777, 2377.7741114594087, 2383.7497062808416, 2409.409694624819, 2411.409204744831, 2421.769931856471, 2336.9298921032396, 2363.9645405318847, 2365.9408514787096, 2366.3965688749345, 2377.9643305350346, 2353.0705371378294, 2393.4375421775867, 2397.029203172709, 2427.5387676341525, 2436.805055595557, 2342.332435255944, 2347.6947996760396, 2349.6240601503764, 2352.882355882199, 2393.988030059856, 2328.7671232876746, 2359.775821296974, 2370.8221883358647, 2373.1456675596773, 2375.2280508834524, 8230.0, 8274.462159959583, 8311.95844020776, 8319.584020798997, 8331.208535189147, 5093.414257360414, 5201.921971170435, 5228.5817134629515, 5266.4733526647415, 5278.287431196778, 3380.2732412531423, 3474.7915125092577, 3475.6350583188905, 3525.682688558042, 3529.205928666055, 1180.816973369129, 1189.2804853063858, 1200.8018676918384, 1212.77563650724, 1214.8359971403836, 11248.606298779585, 11251.156163287786, 11304.37825919574, 11357.148213883991, 11405.20163588549, 4369.825206991684, 4420.292753159479, 4462.330650402466, 4463.263561512379, 4656.557627025427, 9757.170640495637, 9758.853617195748, 9788.363756355957, 9809.313348065709, 9824.508774561386, 5186.0, 5187.740612969336, 5231.346081739783, 5235.712035837989, 5289.0, 4666.836660716858, 4754.881127971759, 4828.058528586962, 4829.5653391194965, 4836.951630483721, 3920.2159568086495, 3950.644442000234, 3990.082281075369, 3996.320625493673, 4055.533613634411, 1226.7362517058696, 1232.1559731583714, 1248.825164476974, 1249.138094714644, 1259.5276771210797, 954.4225743425128, 1026.5585798106886, 1042.781015986636, 1047.2145890582065, 1048.3762161513887, 5709.514691251207, 5784.074548072288, 5827.650340979555, 5842.883142337215, 5914.645121292736, 1006.9798604027878, 1018.6180182431589, 1037.5071840875523, 1038.43924280889, 1044.0394836750208, 4048.2105989332385, 4070.0, 4100.446439730657, 4101.569335219759, 4144.3990621359735, 9456.810863782686, 9705.0, 9712.0, 9714.319997600242, 9721.173700235418, 9464.290178236659, 9502.762430939143, 9549.474778887088, 9640.807183856425, 9645.565949532287, 7525.698972041167, 7629.351505122127, 7656.196099409482, 7684.6926122954455, 7693.846123077619, 5528.751206195811, 5628.324601047901, 5748.051571490824, 5803.564732644984, 5894.145348924468, 8679.783005424915, 8728.250601060645, 8881.73354799357, 8894.688685895962, 9163.49600771964, 8984.730458086267, 8989.865152022594, 9017.368784185306, 9024.684136055206, 9053.90946090557, 6856.314368563104, 7235.095613048369, 7257.0, 7267.491275610657, 7290.744823931137, 7911.248431399008, 7938.24586664266, 7996.9204157438, 8001.9199808003505, 8002.439829211899, 6829.385355318045, 6968.338007889242, 7013.2986701329455, 7108.0759501266575, 7122.608256545733, 6855.554388964901, 6928.653567321513, 6979.511434199558, 6997.020417141706, 7031.6835742390695, 6488.318726533646, 6653.234877989094, 6659.167604049494, 6668.899966500409, 6688.464922806066, 4923.483034281349, 5107.540321371094, 5147.2279158126585, 5160.561352285098, 5210.53105220532, 4960.280759289955, 5025.949740502694, 5046.899062018666, 5049.974750126225, 5125.79496820138], "value": 4515.1631301710695, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [338.2339002160106, 344.50735448308916, 346.2624609581592, 348.42509858733104, 349.2996541933423, 6525.380088891559, 6565.704543295551, 6586.90119648205, 6678.897981832992, 6692.832679183023, 6482.6110433373915, 6513.511486638497, 6522.478201743861, 6535.44448721858, 6538.869222615556, 4890.706557606537, 4892.31507588937, 4894.388201474816, 4902.338184345122, 4910.558049775519, 4750.216214324632, 4853.878653033667, 4877.585405240558, 4894.902101957959, 4910.386201724785, 4832.855014349575, 4837.9758101209445, 4842.031593681278, 4846.0, 4854.12625727369, 4834.444038935515, 4836.129496690594, 4842.176829938919, 4843.757812109398, 4865.172920603506, 4199.811008504625, 4203.369494575808, 4210.094829611618, 4218.957810421887, 4220.3036498977635, 6231.563790534665, 6278.089676996809, 6285.151504546873, 6289.7169627366875, 6291.653959032252, 5557.444255574451, 5667.234923285386, 5671.347795003545, 5674.120511320752, 5686.829395118153, 6849.2465828758795, 6871.03805467235, 6877.243503214642, 6885.690143943534, 6888.276730943279, 4650.651201159926, 4659.207934651117, 4660.86017419478, 4666.136764698542, 4666.9066618667775, 7166.749163779295, 7186.245444228385, 7221.133463984355, 7236.384907282887, 7248.492605517616, 4749.121412538691, 4754.572088512051, 4761.619070474354, 4772.0, 4776.9761151194025, 4745.715257084586, 4822.71063736177, 4829.2997515360075, 4839.467658557556, 4850.151223535867, 5619.522340601013, 5643.604947653625, 5650.29371328584, 5652.434756524397, 5657.80197693087, 6928.584284942919, 6929.618870962045, 6929.930700693029, 6949.513534052669, 6951.756688515878, 1684.2757614226, 1684.865210783159, 1687.6793409252202, 1690.3914590747322, 1694.381550733998, 1077.8868218837067, 1086.4459125845876, 1096.4408151842617, 1097.9011888929874, 1110.9277896936674, 6720.327967203339, 6783.898241526381, 6785.9321406786285, 6791.830204244933, 6802.285759995229, 7097.609631470319, 7125.3230943060325, 7129.572225666477, 7153.0, 7156.0, 6575.342465753482, 6726.831829204309, 6800.0, 6803.217629972518, 6807.761728339485, 3861.864834730771, 3950.6246906543834, 3950.940735888964, 3955.960440395559, 3956.8219430125714, 7490.925090749131, 7531.924680753232, 7577.886331705029, 7599.506032107895, 7602.80992975169, 7335.193128755833, 7405.555666660018, 7425.740099096506, 7428.257174282637, 7431.628418579157, 7369.410447164214, 7493.587852668046, 7549.811254718676, 7562.962185189149, 7569.3566046886635, 7153.177384600837, 7192.568445893263, 7237.240089790706, 7240.732871747528, 7254.0, 7148.142222933281, 7150.284971502808, 7153.535020223669, 7158.06945097134, 7159.105111861018, 7535.472516923763, 7541.924580754342, 7550.169481357156, 7552.66013029404, 7567.508111972704, 4648.628109751279, 4751.524847515221, 4764.595009424099, 4786.16242157614, 4809.519048095162, 7188.6046267455795, 7199.676014579251, 7215.747448839265, 7222.458315626244, 7229.421646268391, 6942.965285173542, 6973.232944376216, 7005.859882802417, 7016.5790052597, 7019.824504387431, 6967.372936435745, 6967.512274140761, 6975.372216500336, 6977.581345119309, 6984.860302794017, 6706.497012723968, 6856.588604683536, 6863.588184708933, 6928.341807528277, 6938.687759050956, 7049.154101507852, 7050.0, 7054.929450705427, 7089.149302083577, 7117.252688467636, 6817.625030623363, 6886.173659160932, 6892.551984121016, 6896.448284137357, 6910.06714093611], "value": 5190.441510437751, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [909.8225845960037, 914.6798620482832, 920.8388532006898, 922.4557511068468, 933.9252859771219, 14840.925795371022, 14943.476978305762, 14970.850291497096, 14989.77515337268, 14999.550013499585, 13994.160350378985, 14046.719065618681, 14090.506832260873, 14117.294135293245, 14117.57647270581, 743.4869939741588, 871.6252011634995, 893.865920111982, 896.7264984179823, 897.905719899411, 891.674538793339, 896.0680891872445, 901.1304091551651, 901.1934318784683, 903.5572569440959, 947.4268067818973, 949.6438835436711, 955.4315182466421, 959.0217977662815, 960.7069843697669, 946.8532377481467, 947.275334369208, 948.1229862377307, 950.1638558068925, 955.9569819358146, 701.3442431326725, 702.574942159991, 702.9297070292981, 702.9982275257737, 704.4646068987555, 14291.714165716732, 14338.354774035193, 14426.927865360605, 14431.35058922351, 14464.132152070804, 14238.28808559568, 14593.92703036478, 14607.634809129751, 14902.478413255483, 14963.326650300654, 11895.464704088337, 11904.404779761064, 12131.393430328448, 12248.326342051183, 12273.0, 3999.6600288975474, 4019.6382325590544, 4023.275810354164, 4069.1251380953236, 4069.308217603015, 53974.46025539773, 54397.1840422394, 54712.452875471135, 54728.17907731387, 54990.17514737282, 10767.78464430707, 10800.78398432027, 10878.394520984337, 11025.724356890982, 11277.436128193558, 18441.90779046123, 18538.721919171225, 18583.81416185821, 18584.81415185858, 18601.44195674132, 8239.876401853977, 8283.50298982063, 8301.584920753938, 8362.79093022667, 8643.0, 4673.112108699336, 4756.405449318835, 4760.238361862085, 4950.455449900508, 5051.217061355495, 3370.0900756795527, 3436.467347561132, 3457.8616855325504, 3459.532963049956, 3468.9653103469145, 623.0, 631.0660222870177, 631.1006815288196, 631.9557630965788, 652.9281779004306, 644.8162273751958, 674.5278305186389, 678.135377393817, 734.6216698400328, 757.2503221810479, 9121.771955701028, 9559.0, 10042.297039207331, 10056.597736090474, 10216.029477199654, 601.1643815097019, 629.9992570763424, 678.4470656415006, 727.5998200989435, 731.3929438566083, 4341.869743907688, 4434.490033646172, 4459.509453960061, 4548.454185497695, 4591.104734576726, 66688.66655666691, 67563.66218168878, 67719.32280677228, 68044.6597767008, 68187.65906170437, 54188.187177192376, 57293.71353143208, 57782.422175777705, 58048.419515805144, 58269.70865145732, 52134.0, 52323.0, 53028.46971530312, 53030.204546931825, 53287.73356133272, 8521.701740439055, 8683.869741954002, 8843.778905527413, 8998.86501702488, 9145.542722863964, 39272.0, 39782.0, 39818.40272395974, 40985.1802963945, 41282.38076428916, 46147.76926115348, 49540.0, 51290.23064653959, 51290.23064654108, 52307.73846130745, 10394.16846652251, 10496.79006419852, 10658.62694805678, 10942.890571094506, 11235.55057797679, 11619.244749091282, 11748.0, 11751.0, 11768.470418831344, 11881.881181188077, 11700.0, 11844.644660660195, 11995.460204291003, 12071.758564828478, 12100.45547950362, 12018.939905300418, 12119.272843629411, 12348.81476777831, 12386.132970691964, 12526.0, 11923.880761192624, 11928.463219154984, 11966.64100076999, 12015.098867584791, 12133.272003679807, 10060.597576096874, 10152.187824974131, 10308.226882983956, 10417.270791044553, 10541.4729263535, 9760.170385517311, 10149.847752283575, 10182.694519164437, 10197.439140847324, 10310.896891030994], "value": 6911.818360886977, "unit": "score"}], "name": "dromaeo_css", "value": 7498.270242382343, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 110.0, 104.0, 109.0, 112.0, 110.0, 101.0, 113.0, 110.0, 109.0], "value": 108.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 127.0, 164.0, 182.0, 127.0, 158.0, 159.0, 128.0, 176.0, 130.0], "value": 151.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 164.0, 163.0, 160.0, 162.0, 162.0, 163.0, 161.0, 162.0, 164.0], "value": 162.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 74.0, 186.0, 72.0, 76.0, 76.0, 76.0, 170.0, 74.0], "value": 95.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 100.0, 148.0, 92.0, 92.0, 92.0, 150.0, 93.0, 92.0, 143.0], "value": 109.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 118.0, 117.0, 118.0, 118.0, 117.0, 117.0, 117.0, 117.0], "value": 117.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 147.0, 146.0, 146.0, 145.0, 147.0, 147.0, 146.0, 148.0], "value": 146.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 55.0, 56.0, 56.0, 59.0, 56.0, 74.0, 56.0, 54.0], "value": 57.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 59.0, 60.0, 58.0, 58.0, 58.0, 59.0, 58.0, 59.0], "value": 58.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 72.0, 74.0, 73.0, 74.0, 75.0, 74.0, 73.0, 74.0, 74.0], "value": 73.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 115.0, 115.0, 116.0, 114.0, 115.0, 123.0, 139.0, 130.0], "value": 119.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 160.0, 161.0, 157.0, 158.0, 153.0, 164.0, 167.0, 159.0, 155.0], "value": 159.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 65.0, 66.0, 68.0, 72.0, 66.0, 67.0, 64.0, 66.0, 66.0], "value": 66.9, "unit": "score"}], "name": "kraken", "value": 1521.3999999999999, "alertThreshold": 2.0}]} 22:16:08 INFO - Return code: 0 22:16:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 22:16:08 ERROR - # TBPL SUCCESS # 22:16:08 INFO - Running post-action listener: _resource_record_post_action 22:16:08 INFO - Running post-run listener: _resource_record_post_run 22:16:09 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 12288; Write bytes: 499163136; Read time: 500; Write time: 557976 22:16:09 INFO - install - Wall time: 16s; CPU: 15.0%; Read bytes: 4096; Write bytes: 248176640; Read time: 240; Write time: 380420 22:16:09 INFO - run-tests - Wall time: 1171s; CPU: 13.0%; Read bytes: 8192; Write bytes: 250388480; Read time: 260; Write time: 177556 22:16:09 INFO - Running post-run listener: _upload_blobber_files 22:16:09 INFO - Blob upload gear active. 22:16:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:16:09 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 22:16:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:16:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:16:10 INFO - (blobuploader) - INFO - Open directory for files ... 22:16:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:16:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:16:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:16:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:16:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:16:11 INFO - (blobuploader) - INFO - Done attempting. 22:16:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:16:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:16:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:16:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:16:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:16:11 INFO - (blobuploader) - INFO - Done attempting. 22:16:11 INFO - (blobuploader) - INFO - Iteration through files over. 22:16:11 INFO - Return code: 0 22:16:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:16:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:16:11 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0ee6c5bf543840e221b03bf3f9101efb79890709790e8214b6e90b8526f6e2d4f9dddbada07c01c10d0e6700c26ed7f92db3108eb20a57127500849c0e251215", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1b61d44dd02922dadf57bf1ca1d3883ab4cb8e2fef5be5dfd3355678ab00853ac8e31e739287e636637c38fab91945f4cf198e3f715d3825769b5c59edd083b5"} 22:16:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:16:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:16:11 INFO - Contents: 22:16:11 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0ee6c5bf543840e221b03bf3f9101efb79890709790e8214b6e90b8526f6e2d4f9dddbada07c01c10d0e6700c26ed7f92db3108eb20a57127500849c0e251215", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1b61d44dd02922dadf57bf1ca1d3883ab4cb8e2fef5be5dfd3355678ab00853ac8e31e739287e636637c38fab91945f4cf198e3f715d3825769b5c59edd083b5"} 22:16:11 INFO - Running post-run listener: copy_logs_to_upload_dir 22:16:11 INFO - Copying logs to upload dir... 22:16:11 INFO - mkdir: /builds/slave/test/build/upload/logs 22:16:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1230.226681 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 30 secs) (at 2016-05-22 22:16:11.487404) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-22 22:16:11.490992) ========= 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=62c568580acbfdaac3eec7be000001dc-1463976378.936844-496922173 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/Mozilla-Aurora/sha512/0ee6c5bf543840e221b03bf3f9101efb79890709790e8214b6e90b8526f6e2d4f9dddbada07c01c10d0e6700c26ed7f92db3108eb20a57127500849c0e251215", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1b61d44dd02922dadf57bf1ca1d3883ab4cb8e2fef5be5dfd3355678ab00853ac8e31e739287e636637c38fab91945f4cf198e3f715d3825769b5c59edd083b5"} build_url:https://queue.taskcluster.net/v1/task/fOGnC-nPSk227Ths8t8ZaA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011429 build_url: 'https://queue.taskcluster.net/v1/task/fOGnC-nPSk227Ths8t8ZaA/artifacts/public/build/firefox-48.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0ee6c5bf543840e221b03bf3f9101efb79890709790e8214b6e90b8526f6e2d4f9dddbada07c01c10d0e6700c26ed7f92db3108eb20a57127500849c0e251215", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1b61d44dd02922dadf57bf1ca1d3883ab4cb8e2fef5be5dfd3355678ab00853ac8e31e739287e636637c38fab91945f4cf198e3f715d3825769b5c59edd083b5"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-22 22:16:11.516552) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-22 22:16:11.516862) ========= 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=62c568580acbfdaac3eec7be000001dc-1463976378.936844-496922173 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004472 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-22 22:16:11.601350) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-22 22:16:11.601687) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-22 22:16:11.602001) ========= ========= Total master_lag: 0.17 =========