builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-004 starttime: 1461600639.61 results: success (0) buildid: 20160425062348 builduid: abe90fc74b164da98c10377025c22763 revision: 8551b253f4061db31e4be7829c2f70c2610caf42 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-25 09:10:39.607465) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-25 09:10:39.608166) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-25 09:10:39.608434) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-25 09:10:39.625732) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 09:10:39.626057) ========= 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=c6927cf182745ad25b1d38b9000001fe-1461599278.47402-1034304102 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-04-25 09:10:39-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 227M=0s 2016-04-25 09:10:39 (227 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.079154 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 09:10:39.718363) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 09:10:39.718666) ========= 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=c6927cf182745ad25b1d38b9000001fe-1461599278.47402-1034304102 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013762 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 09:10:39.767729) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 09:10:39.768092) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 8551b253f4061db31e4be7829c2f70c2610caf42 --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 8551b253f4061db31e4be7829c2f70c2610caf42 --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=c6927cf182745ad25b1d38b9000001fe-1461599278.47402-1034304102 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-04-25 09:10:39,917 truncating revision to first 12 chars 2016-04-25 09:10:39,917 Setting DEBUG logging. 2016-04-25 09:10:39,917 attempt 1/10 2016-04-25 09:10:39,917 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/8551b253f406?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-25 09:10:40,176 unpacking tar archive at: mozilla-aurora-8551b253f406/testing/mozharness/ program finished with exit code 0 elapsedTime=0.581842 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 09:10:40.362854) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-25 09:10:40.363166) ========= script_repo_revision: 8551b253f4061db31e4be7829c2f70c2610caf42 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-25 09:10:40.363554) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-25 09:10:40.363809) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-25 09:10:40.373410) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-04-25 09:10:40.373708) ========= /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=c6927cf182745ad25b1d38b9000001fe-1461599278.47402-1034304102 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:10:40 INFO - MultiFileLogger online at 20160425 09:10:40 in /builds/slave/test 09:10:40 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 09:10:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:10:40 INFO - {'append_to_log': False, 09:10:40 INFO - 'base_work_dir': '/builds/slave/test', 09:10:40 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:10:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:10:40 INFO - 'branch': 'Mozilla-Aurora', 09:10:40 INFO - 'buildbot_json_path': 'buildprops.json', 09:10:40 INFO - 'config_files': ('talos/linux_config.py',), 09:10:40 INFO - 'default_actions': ('clobber', 09:10:40 INFO - 'read-buildbot-config', 09:10:40 INFO - 'download-and-extract', 09:10:40 INFO - 'populate-webroot', 09:10:40 INFO - 'create-virtualenv', 09:10:40 INFO - 'install', 09:10:40 INFO - 'run-tests'), 09:10:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:10:40 INFO - 'download_minidump_stackwalk': True, 09:10:40 INFO - 'download_symbols': 'ondemand', 09:10:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:10:40 INFO - 'tooltool.py': '/tools/tooltool.py', 09:10:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:10:40 INFO - '/tools/misc-python/virtualenv.py')}, 09:10:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:10:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:10:40 INFO - 'installer_path': 'installer.exe', 09:10:40 INFO - 'log_level': 'info', 09:10:40 INFO - 'log_name': 'talos', 09:10:40 INFO - 'log_to_console': True, 09:10:40 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:10:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:10:40 INFO - 'opt_config_files': (), 09:10:40 INFO - 'pip_index': False, 09:10:40 INFO - 'sps_profile': False, 09:10:40 INFO - 'sps_profile_interval': 0, 09:10:40 INFO - 'suite': 'dromaeojs-e10s', 09:10:40 INFO - 'system_bits': '32', 09:10:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:10:40 INFO - 'title': 'talos-linux64-ix-004', 09:10:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:10:40 INFO - 'use_talos_json': True, 09:10:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:10:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:10:40 INFO - 'work_dir': 'build'} 09:10:40 INFO - ##### 09:10:40 INFO - ##### Running clobber step. 09:10:40 INFO - ##### 09:10:40 INFO - Running pre-action listener: _resource_record_pre_action 09:10:40 INFO - Running main action method: clobber 09:10:40 INFO - rmtree: /builds/slave/test/build 09:10:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:10:41 INFO - Running post-action listener: _resource_record_post_action 09:10:41 INFO - ##### 09:10:41 INFO - ##### Running read-buildbot-config step. 09:10:41 INFO - ##### 09:10:41 INFO - Running pre-action listener: _resource_record_pre_action 09:10:41 INFO - Running main action method: read_buildbot_config 09:10:41 INFO - Using buildbot properties: 09:10:41 INFO - { 09:10:41 INFO - "project": "", 09:10:41 INFO - "product": "firefox", 09:10:41 INFO - "script_repo_revision": "production", 09:10:41 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s", 09:10:41 INFO - "repository": "", 09:10:41 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs-e10s", 09:10:41 INFO - "buildid": "20160425062348", 09:10:41 INFO - "pgo_build": "True", 09:10:41 INFO - "basedir": "/builds/slave/test", 09:10:41 INFO - "buildnumber": 252, 09:10:41 INFO - "slavename": "talos-linux64-ix-004", 09:10:41 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 09:10:41 INFO - "platform": "ubuntu64_hw", 09:10:41 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 09:10:41 INFO - "branch": "mozilla-aurora", 09:10:41 INFO - "repo_path": "releases/mozilla-aurora", 09:10:41 INFO - "revision": "8551b253f4061db31e4be7829c2f70c2610caf42", 09:10:41 INFO - "stage_platform": "linux64-pgo", 09:10:41 INFO - "builduid": "abe90fc74b164da98c10377025c22763", 09:10:41 INFO - "slavebuilddir": "test" 09:10:41 INFO - } 09:10:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZpuBLsfqRea_aIl5YentDQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2. 09:10:41 INFO - Running post-action listener: _resource_record_post_action 09:10:41 INFO - ##### 09:10:41 INFO - ##### Running download-and-extract step. 09:10:41 INFO - ##### 09:10:41 INFO - Running pre-action listener: _resource_record_pre_action 09:10:41 INFO - Running main action method: download_and_extract 09:10:41 INFO - mkdir: /builds/slave/test/build/tests 09:10:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:10:41 INFO - https://queue.taskcluster.net/v1/task/ZpuBLsfqRea_aIl5YentDQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 09:10:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZpuBLsfqRea_aIl5YentDQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.test_packages.json 09:10:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZpuBLsfqRea_aIl5YentDQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.test_packages.json 09:10:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZpuBLsfqRea_aIl5YentDQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.test_packages.json 09:10:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZpuBLsfqRea_aIl5YentDQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 09:10:49 INFO - Downloaded 1448 bytes. 09:10:49 INFO - Reading from file /builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.test_packages.json 09:10:49 INFO - Using the following test package requirements: 09:10:49 INFO - {u'common': [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip'], 09:10:49 INFO - u'cppunittest': [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', 09:10:49 INFO - u'firefox-47.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 09:10:49 INFO - u'gtest': [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', 09:10:49 INFO - u'firefox-47.0a2.en-US.linux-x86_64.gtest.tests.zip'], 09:10:49 INFO - u'jittest': [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', 09:10:49 INFO - u'jsshell-linux-x86_64.zip'], 09:10:49 INFO - u'mochitest': [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', 09:10:49 INFO - u'firefox-47.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 09:10:49 INFO - u'mozbase': [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip'], 09:10:49 INFO - u'reftest': [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', 09:10:49 INFO - u'firefox-47.0a2.en-US.linux-x86_64.reftest.tests.zip'], 09:10:49 INFO - u'talos': [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', 09:10:49 INFO - u'firefox-47.0a2.en-US.linux-x86_64.talos.tests.zip'], 09:10:49 INFO - u'web-platform': [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', 09:10:49 INFO - u'firefox-47.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 09:10:49 INFO - u'webapprt': [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip'], 09:10:49 INFO - u'xpcshell': [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', 09:10:49 INFO - u'firefox-47.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 09:10:49 INFO - Downloading packages: [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:10: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')]} 09:10:49 INFO - https://queue.taskcluster.net/v1/task/ZpuBLsfqRea_aIl5YentDQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:10:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZpuBLsfqRea_aIl5YentDQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip 09:10:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZpuBLsfqRea_aIl5YentDQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip 09:10:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZpuBLsfqRea_aIl5YentDQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip 09:10:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZpuBLsfqRea_aIl5YentDQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:10:53 INFO - Downloaded 23359918 bytes. 09:10:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:10:53 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 09:10:55 INFO - Return code: 0 09:10:55 INFO - Downloading packages: [u'firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-47.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:10: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')]} 09:10:55 INFO - https://queue.taskcluster.net/v1/task/ZpuBLsfqRea_aIl5YentDQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:10:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZpuBLsfqRea_aIl5YentDQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip 09:10:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZpuBLsfqRea_aIl5YentDQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip 09:10:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZpuBLsfqRea_aIl5YentDQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip 09:10:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZpuBLsfqRea_aIl5YentDQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:11:04 INFO - Downloaded 23359918 bytes. 09:11:04 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:11:04 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 09:11:05 INFO - Return code: 0 09:11: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')]} 09:11:05 INFO - https://queue.taskcluster.net/v1/task/ZpuBLsfqRea_aIl5YentDQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:11:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZpuBLsfqRea_aIl5YentDQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.talos.tests.zip 09:11:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZpuBLsfqRea_aIl5YentDQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.talos.tests.zip 09:11:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZpuBLsfqRea_aIl5YentDQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.talos.tests.zip 09:11:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZpuBLsfqRea_aIl5YentDQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:11:07 INFO - Downloaded 10845194 bytes. 09:11:07 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:11:07 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 09:11:08 INFO - Return code: 0 09:11:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:11:08 INFO - https://queue.taskcluster.net/v1/task/ZpuBLsfqRea_aIl5YentDQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:11:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZpuBLsfqRea_aIl5YentDQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2 09:11:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZpuBLsfqRea_aIl5YentDQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2 09:11:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZpuBLsfqRea_aIl5YentDQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2 09:11:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZpuBLsfqRea_aIl5YentDQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:11:10 INFO - Downloaded 60835591 bytes. 09:11:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZpuBLsfqRea_aIl5YentDQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2 09:11:10 INFO - mkdir: /builds/slave/test/properties 09:11:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:11:10 INFO - Writing to file /builds/slave/test/properties/build_url 09:11:10 INFO - Contents: 09:11:10 INFO - build_url:https://queue.taskcluster.net/v1/task/ZpuBLsfqRea_aIl5YentDQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2 09:11:10 INFO - Running post-action listener: _resource_record_post_action 09:11:10 INFO - Running post-action listener: set_extra_try_arguments 09:11:10 INFO - ##### 09:11:10 INFO - ##### Running populate-webroot step. 09:11:10 INFO - ##### 09:11:10 INFO - Running pre-action listener: _resource_record_pre_action 09:11:10 INFO - Running main action method: populate_webroot 09:11:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:11:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:11:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:11:10 INFO - '--tppagecycles', 09:11:10 INFO - '7'], 09:11:10 INFO - 'tests': ['tsvgm']}}, 09:11:10 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 09:11:10 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 09:11:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:11:10 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 09:11:10 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 09:11:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:11:10 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:11:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:11:10 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:11:10 INFO - 'talos_options': ['--e10s'], 09:11:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:11:10 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:11:10 INFO - 'tests': ['damp', 'tps']}, 09:11:10 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:11:10 INFO - 'talos_options': ['--e10s'], 09:11:10 INFO - 'tests': ['damp', 'tps']}, 09:11:10 INFO - 'g3': {'tests': ['dromaeo_dom']}, 09:11:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:11:10 INFO - 'other': {'tests': ['a11yr', 09:11:10 INFO - 'ts_paint', 09:11:10 INFO - 'tpaint', 09:11:10 INFO - 'sessionrestore', 09:11:10 INFO - 'sessionrestore_no_auto_restore', 09:11:10 INFO - 'tabpaint']}, 09:11:10 INFO - 'other-e10s': {'talos_options': ['--e10s'], 09:11:10 INFO - 'tests': ['a11yr', 09:11:10 INFO - 'ts_paint', 09:11:10 INFO - 'tpaint', 09:11:10 INFO - 'sessionrestore', 09:11:10 INFO - 'sessionrestore_no_auto_restore', 09:11:10 INFO - 'tabpaint']}, 09:11:10 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 09:11:10 INFO - 'tests': ['a11yr', 09:11:10 INFO - 'ts_paint', 09:11:10 INFO - 'tpaint', 09:11:10 INFO - 'sessionrestore', 09:11:10 INFO - 'sessionrestore_no_auto_restore', 09:11:10 INFO - 'tabpaint']}, 09:11:10 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 09:11:10 INFO - 'tests': ['a11yr', 09:11:10 INFO - 'ts_paint', 09:11:10 INFO - 'tpaint', 09:11:10 INFO - 'sessionrestore', 09:11:10 INFO - 'sessionrestore_no_auto_restore', 09:11:10 INFO - 'tabpaint']}, 09:11:10 INFO - 'other_l64': {'tests': ['a11yr', 09:11:10 INFO - 'ts_paint', 09:11:10 INFO - 'tpaint', 09:11:10 INFO - 'sessionrestore', 09:11:10 INFO - 'sessionrestore_no_auto_restore', 09:11:10 INFO - 'tabpaint']}, 09:11:10 INFO - 'other_nol64': {'tests': ['a11yr', 09:11:10 INFO - 'ts_paint', 09:11:10 INFO - 'tpaint', 09:11:10 INFO - 'sessionrestore', 09:11:10 INFO - 'sessionrestore_no_auto_restore', 09:11:10 INFO - 'tabpaint']}, 09:11:10 INFO - 'svgr': {'tests': ['tsvgx', 09:11:10 INFO - 'tsvgr_opacity', 09:11:10 INFO - 'tart', 09:11:10 INFO - 'tscrollx', 09:11:10 INFO - 'cart']}, 09:11:10 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 09:11:10 INFO - 'tests': ['tsvgx', 09:11:10 INFO - 'tsvgr_opacity', 09:11:10 INFO - 'tart', 09:11:10 INFO - 'tscrollx', 09:11:10 INFO - 'cart']}, 09:11:10 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:11:10 INFO - 'tests': ['tp5o']}, 09:11:10 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:11:10 INFO - 'talos_options': ['--e10s'], 09:11:10 INFO - 'tests': ['tp5o']}, 09:11:10 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:11:10 INFO - 'talos_options': ['--xperf_path', 09:11:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:11:10 INFO - 'tests': ['tp5n']}, 09:11:10 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:11:10 INFO - 'talos_options': ['--e10s', 09:11:10 INFO - '--xperf_path', 09:11:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:11:10 INFO - 'tests': ['tp5n']}}, 09:11:10 INFO - 'talos.zip': {'path': '', 09:11:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:11:10 INFO - Running post-action listener: _resource_record_post_action 09:11:10 INFO - ##### 09:11:10 INFO - ##### Running create-virtualenv step. 09:11:10 INFO - ##### 09:11:10 INFO - Running pre-action listener: _resource_record_pre_action 09:11:10 INFO - Running main action method: create_virtualenv 09:11:10 INFO - Creating virtualenv /builds/slave/test/build/venv 09:11:10 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 09:11:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:11:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:11:11 INFO - Using real prefix '/usr' 09:11:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:11:11 INFO - Installing distribute.............................................................................................................................................................................................done. 09:11:13 INFO - Installing pip.................done. 09:11:13 INFO - Return code: 0 09:11:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:11:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:11:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:11:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:13 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 0x7f79e3b76f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24508a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x251d410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ac160>, '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 0x25ad060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25ad4f0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1461599278.47402-1034304102', '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 09:11:13 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 09:11:13 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 09:11:13 INFO - Using env: {'DISPLAY': ':0', 09:11:13 INFO - 'HOME': '/home/cltbld', 09:11:13 INFO - 'LANG': 'en_US.UTF-8', 09:11:13 INFO - 'LANGUAGE': 'en_US:en', 09:11:13 INFO - 'LOGNAME': 'cltbld', 09:11:13 INFO - 'MAIL': '/var/mail/cltbld', 09:11:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:13 INFO - 'MOZ_NO_REMOTE': '1', 09:11:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:13 INFO - 'NO_EM_RESTART': '1', 09:11:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:13 INFO - 'PWD': '/builds/slave/test', 09:11:13 INFO - 'SHELL': '/bin/bash', 09:11:13 INFO - 'SHLVL': '1', 09:11:13 INFO - 'TERM': 'linux', 09:11:13 INFO - 'TMOUT': '86400', 09:11:13 INFO - 'USER': 'cltbld', 09:11:13 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1461599278.47402-1034304102', 09:11:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:13 INFO - '_': '/tools/buildbot/bin/python'} 09:11:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:13 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 09:11:13 INFO - Cleaning up... 09:11:13 INFO - Return code: 0 09:11:13 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:11:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:11:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:11:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:13 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 0x7f79e3b76f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24508a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x251d410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ac160>, '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 0x25ad060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25ad4f0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1461599278.47402-1034304102', '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 09:11:13 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 09:11:13 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 09:11:13 INFO - Using env: {'DISPLAY': ':0', 09:11:13 INFO - 'HOME': '/home/cltbld', 09:11:13 INFO - 'LANG': 'en_US.UTF-8', 09:11:13 INFO - 'LANGUAGE': 'en_US:en', 09:11:13 INFO - 'LOGNAME': 'cltbld', 09:11:13 INFO - 'MAIL': '/var/mail/cltbld', 09:11:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:13 INFO - 'MOZ_NO_REMOTE': '1', 09:11:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:13 INFO - 'NO_EM_RESTART': '1', 09:11:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:13 INFO - 'PWD': '/builds/slave/test', 09:11:13 INFO - 'SHELL': '/bin/bash', 09:11:13 INFO - 'SHLVL': '1', 09:11:13 INFO - 'TERM': 'linux', 09:11:13 INFO - 'TMOUT': '86400', 09:11:13 INFO - 'USER': 'cltbld', 09:11:13 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1461599278.47402-1034304102', 09:11:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:13 INFO - '_': '/tools/buildbot/bin/python'} 09:11:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:14 INFO - Downloading/unpacking psutil>=0.7.1 09:11:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:11:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:11:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:11:15 INFO - Installing collected packages: psutil 09:11:15 INFO - Running setup.py install for psutil 09:11:16 INFO - building 'psutil._psutil_linux' extension 09:11:16 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 09:11:16 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 09:11:16 INFO - building 'psutil._psutil_posix' extension 09:11:16 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 09:11:16 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 09:11:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:11:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:11:16 INFO - Successfully installed psutil 09:11:16 INFO - Cleaning up... 09:11:16 INFO - Return code: 0 09:11:16 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:11: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')]} 09:11:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11: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')]} 09:11:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11: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', '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 0x7f79e3b76f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24508a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x251d410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ac160>, '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 0x25ad060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25ad4f0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1461599278.47402-1034304102', '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 09:11: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 09:11: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 mozsystemmonitor==0.0.0 09:11:16 INFO - Using env: {'DISPLAY': ':0', 09:11:16 INFO - 'HOME': '/home/cltbld', 09:11:16 INFO - 'LANG': 'en_US.UTF-8', 09:11:16 INFO - 'LANGUAGE': 'en_US:en', 09:11:16 INFO - 'LOGNAME': 'cltbld', 09:11:16 INFO - 'MAIL': '/var/mail/cltbld', 09:11:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:16 INFO - 'MOZ_NO_REMOTE': '1', 09:11:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:16 INFO - 'NO_EM_RESTART': '1', 09:11:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:16 INFO - 'PWD': '/builds/slave/test', 09:11:16 INFO - 'SHELL': '/bin/bash', 09:11:16 INFO - 'SHLVL': '1', 09:11:16 INFO - 'TERM': 'linux', 09:11:16 INFO - 'TMOUT': '86400', 09:11:16 INFO - 'USER': 'cltbld', 09:11:16 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1461599278.47402-1034304102', 09:11:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:16 INFO - '_': '/tools/buildbot/bin/python'} 09:11:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:16 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:11: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 09:11: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 09:11: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 09:11: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 09:11: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 09:11: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 09:11:18 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:11:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:11:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:11:18 INFO - Installing collected packages: mozsystemmonitor 09:11:18 INFO - Running setup.py install for mozsystemmonitor 09:11:18 INFO - Successfully installed mozsystemmonitor 09:11:18 INFO - Cleaning up... 09:11:18 INFO - Return code: 0 09:11:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:11:18 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')]} 09:11:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:18 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')]} 09:11:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:18 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 0x7f79e3b76f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24508a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x251d410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ac160>, '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 0x25ad060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25ad4f0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1461599278.47402-1034304102', '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 09:11:18 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 09:11:18 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 09:11:18 INFO - Using env: {'DISPLAY': ':0', 09:11:18 INFO - 'HOME': '/home/cltbld', 09:11:18 INFO - 'LANG': 'en_US.UTF-8', 09:11:18 INFO - 'LANGUAGE': 'en_US:en', 09:11:18 INFO - 'LOGNAME': 'cltbld', 09:11:18 INFO - 'MAIL': '/var/mail/cltbld', 09:11:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:18 INFO - 'MOZ_NO_REMOTE': '1', 09:11:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:18 INFO - 'NO_EM_RESTART': '1', 09:11:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:18 INFO - 'PWD': '/builds/slave/test', 09:11:18 INFO - 'SHELL': '/bin/bash', 09:11:18 INFO - 'SHLVL': '1', 09:11:18 INFO - 'TERM': 'linux', 09:11:18 INFO - 'TMOUT': '86400', 09:11:18 INFO - 'USER': 'cltbld', 09:11:18 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1461599278.47402-1034304102', 09:11:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:18 INFO - '_': '/tools/buildbot/bin/python'} 09:11:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:18 INFO - Downloading/unpacking blobuploader==1.2.4 09:11: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 09:11: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 09:11: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 09:11: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 09:11: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 09:11: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 09:11:20 INFO - Downloading blobuploader-1.2.4.tar.gz 09:11:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:11:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:11:20 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 09:11:20 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 09:11:20 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 09:11:20 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 09:11:20 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 09:11:20 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 09:11:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:11:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:11: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 09:11: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 09:11: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 09:11: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 09:11: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 09:11: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 09:11:21 INFO - Downloading docopt-0.6.1.tar.gz 09:11:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:11:21 INFO - Installing collected packages: blobuploader, requests, docopt 09:11:21 INFO - Running setup.py install for blobuploader 09:11:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:11:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:11:21 INFO - Running setup.py install for requests 09:11:22 INFO - Running setup.py install for docopt 09:11:22 INFO - Successfully installed blobuploader requests docopt 09:11:22 INFO - Cleaning up... 09:11:22 INFO - Return code: 0 09:11:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:11:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:11:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:11:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:22 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 0x7f79e3b76f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24508a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x251d410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ac160>, '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 0x25ad060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25ad4f0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1461599278.47402-1034304102', '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 09:11:22 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 09:11:22 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 09:11:22 INFO - Using env: {'DISPLAY': ':0', 09:11:22 INFO - 'HOME': '/home/cltbld', 09:11:22 INFO - 'LANG': 'en_US.UTF-8', 09:11:22 INFO - 'LANGUAGE': 'en_US:en', 09:11:22 INFO - 'LOGNAME': 'cltbld', 09:11:22 INFO - 'MAIL': '/var/mail/cltbld', 09:11:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:22 INFO - 'MOZ_NO_REMOTE': '1', 09:11:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:22 INFO - 'NO_EM_RESTART': '1', 09:11:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:22 INFO - 'PWD': '/builds/slave/test', 09:11:22 INFO - 'SHELL': '/bin/bash', 09:11:22 INFO - 'SHLVL': '1', 09:11:22 INFO - 'TERM': 'linux', 09:11:22 INFO - 'TMOUT': '86400', 09:11:22 INFO - 'USER': 'cltbld', 09:11:22 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1461599278.47402-1034304102', 09:11:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:22 INFO - '_': '/tools/buildbot/bin/python'} 09:11:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:11:22 INFO - Running setup.py (path:/tmp/pip-E6FRHr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:11:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:11:22 INFO - Running setup.py (path:/tmp/pip-a2xnFm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:11:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:11:22 INFO - Running setup.py (path:/tmp/pip-nMMgJ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:11:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:11:22 INFO - Running setup.py (path:/tmp/pip-Rww0fg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:11:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:11:22 INFO - Running setup.py (path:/tmp/pip-sFxinn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:11:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:11:22 INFO - Running setup.py (path:/tmp/pip-nDIXUa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:11:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:11:22 INFO - Running setup.py (path:/tmp/pip-jIy6dJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:11:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:11:22 INFO - Running setup.py (path:/tmp/pip-kAuC5i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:11:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:11:23 INFO - Running setup.py (path:/tmp/pip-Bahli4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:11:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:11:23 INFO - Running setup.py (path:/tmp/pip-af6hLs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:11:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:11:23 INFO - Running setup.py (path:/tmp/pip-euhu4c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:11:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:11:23 INFO - Running setup.py (path:/tmp/pip-iw1YtS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:11:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:11:23 INFO - Running setup.py (path:/tmp/pip-UOrg7U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:11:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:11:23 INFO - Running setup.py (path:/tmp/pip-mCvRov-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:11:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:11:23 INFO - Running setup.py (path:/tmp/pip-TX98fN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:11:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:11:23 INFO - Running setup.py (path:/tmp/pip-WnQHTQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:11:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:11:23 INFO - Running setup.py (path:/tmp/pip-ZN0r13-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:11:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:11:23 INFO - Running setup.py install for manifestparser 09:11:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:11:23 INFO - Running setup.py install for mozcrash 09:11:23 INFO - Running setup.py install for mozdebug 09:11:23 INFO - Running setup.py install for mozdevice 09:11:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:11:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:11:24 INFO - Running setup.py install for mozfile 09:11:24 INFO - Running setup.py install for mozhttpd 09:11:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:11:24 INFO - Running setup.py install for mozinfo 09:11:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:11:24 INFO - Running setup.py install for mozInstall 09:11:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:11:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:11:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:11:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:11:24 INFO - Running setup.py install for mozleak 09:11:24 INFO - Running setup.py install for mozlog 09:11:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:11:24 INFO - Running setup.py install for moznetwork 09:11:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:11:24 INFO - Running setup.py install for mozprocess 09:11:24 INFO - Running setup.py install for mozprofile 09:11:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:11:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:11:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:11:25 INFO - Running setup.py install for mozrunner 09:11:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:11:25 INFO - Running setup.py install for mozscreenshot 09:11:25 INFO - Running setup.py install for moztest 09:11:25 INFO - Running setup.py install for mozversion 09:11:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:11:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:11:25 INFO - Cleaning up... 09:11:25 INFO - Return code: 0 09:11:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:11:25 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')]} 09:11:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:25 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')]} 09:11:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:25 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 0x7f79e3b76f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24508a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x251d410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ac160>, '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 0x25ad060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25ad4f0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1461599278.47402-1034304102', '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 09:11:25 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 09:11:25 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 09:11:25 INFO - Using env: {'DISPLAY': ':0', 09:11:25 INFO - 'HOME': '/home/cltbld', 09:11:25 INFO - 'LANG': 'en_US.UTF-8', 09:11:25 INFO - 'LANGUAGE': 'en_US:en', 09:11:25 INFO - 'LOGNAME': 'cltbld', 09:11:25 INFO - 'MAIL': '/var/mail/cltbld', 09:11:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:25 INFO - 'MOZ_NO_REMOTE': '1', 09:11:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:25 INFO - 'NO_EM_RESTART': '1', 09:11:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:25 INFO - 'PWD': '/builds/slave/test', 09:11:25 INFO - 'SHELL': '/bin/bash', 09:11:25 INFO - 'SHLVL': '1', 09:11:25 INFO - 'TERM': 'linux', 09:11:25 INFO - 'TMOUT': '86400', 09:11:25 INFO - 'USER': 'cltbld', 09:11:25 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1461599278.47402-1034304102', 09:11:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:25 INFO - '_': '/tools/buildbot/bin/python'} 09:11:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:11:25 INFO - Running setup.py (path:/tmp/pip-UNU8YA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:11:25 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)) 09:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:11:25 INFO - Running setup.py (path:/tmp/pip-3AQIk3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:11:25 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)) 09:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:11:25 INFO - Running setup.py (path:/tmp/pip-GBjYtq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:11:25 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)) 09:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:11:25 INFO - Running setup.py (path:/tmp/pip-DERqBa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:11:25 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)) 09:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:11:25 INFO - Running setup.py (path:/tmp/pip-v4sMI2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:11:26 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)) 09:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:11:26 INFO - Running setup.py (path:/tmp/pip-uVVdfd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:11:26 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)) 09:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:11:26 INFO - Running setup.py (path:/tmp/pip-6C2kKN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:11:26 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)) 09:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:11:26 INFO - Running setup.py (path:/tmp/pip-KxlNIQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:11:26 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)) 09:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:11:26 INFO - Running setup.py (path:/tmp/pip-pVLQGV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:11:26 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)) 09:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:11:26 INFO - Running setup.py (path:/tmp/pip-9lxzs3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:11:26 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)) 09:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:11:26 INFO - Running setup.py (path:/tmp/pip-TKfIa5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:11:26 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)) 09:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:11:26 INFO - Running setup.py (path:/tmp/pip-UQUScX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:11:26 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)) 09:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:11:26 INFO - Running setup.py (path:/tmp/pip-qlidcH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:11:26 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)) 09:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:11:26 INFO - Running setup.py (path:/tmp/pip-4Rirf3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:11:26 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)) 09:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:11:26 INFO - Running setup.py (path:/tmp/pip-IlHO0I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:11:26 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)) 09:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:11:26 INFO - Running setup.py (path:/tmp/pip-7ploND-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:11:26 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)) 09:11:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:11:26 INFO - Running setup.py (path:/tmp/pip-RKu2K8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:11:26 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)) 09:11:26 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)) 09:11:26 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)) 09:11:26 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)) 09:11:26 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)) 09:11:26 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)) 09:11:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:11:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:28 INFO - Downloading blessings-1.6.tar.gz 09:11:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:11:28 INFO - Installing collected packages: blessings 09:11:28 INFO - Running setup.py install for blessings 09:11:28 INFO - Successfully installed blessings 09:11:28 INFO - Cleaning up... 09:11:29 INFO - Return code: 0 09:11:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:11:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:11:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:11:29 INFO - Reading from file tmpfile_stdout 09:11:29 INFO - Current package versions: 09:11:29 INFO - argparse == 1.2.1 09:11:29 INFO - blessings == 1.6 09:11:29 INFO - blobuploader == 1.2.4 09:11:29 INFO - docopt == 0.6.1 09:11:29 INFO - manifestparser == 1.1 09:11:29 INFO - mozInstall == 1.12 09:11:29 INFO - mozcrash == 0.17 09:11:29 INFO - mozdebug == 0.1 09:11:29 INFO - mozdevice == 0.48 09:11:29 INFO - mozfile == 1.2 09:11:29 INFO - mozhttpd == 0.7 09:11:29 INFO - mozinfo == 0.9 09:11:29 INFO - mozleak == 0.1 09:11:29 INFO - mozlog == 3.1 09:11:29 INFO - moznetwork == 0.27 09:11:29 INFO - mozprocess == 0.22 09:11:29 INFO - mozprofile == 0.28 09:11:29 INFO - mozrunner == 6.11 09:11:29 INFO - mozscreenshot == 0.1 09:11:29 INFO - mozsystemmonitor == 0.0 09:11:29 INFO - moztest == 0.7 09:11:29 INFO - mozversion == 1.4 09:11:29 INFO - psutil == 3.1.1 09:11:29 INFO - requests == 1.2.3 09:11:29 INFO - wsgiref == 0.1.2 09:11:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:11:29 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')]} 09:11:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:29 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')]} 09:11:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:29 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 0x7f79e3b76f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24508a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x251d410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ac160>, '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 0x25ad060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25ad4f0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1461599278.47402-1034304102', '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 09:11:29 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 09:11:29 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 09:11:29 INFO - Using env: {'DISPLAY': ':0', 09:11:29 INFO - 'HOME': '/home/cltbld', 09:11:29 INFO - 'LANG': 'en_US.UTF-8', 09:11:29 INFO - 'LANGUAGE': 'en_US:en', 09:11:29 INFO - 'LOGNAME': 'cltbld', 09:11:29 INFO - 'MAIL': '/var/mail/cltbld', 09:11:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:29 INFO - 'MOZ_NO_REMOTE': '1', 09:11:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:29 INFO - 'NO_EM_RESTART': '1', 09:11:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:29 INFO - 'PWD': '/builds/slave/test', 09:11:29 INFO - 'SHELL': '/bin/bash', 09:11:29 INFO - 'SHLVL': '1', 09:11:29 INFO - 'TERM': 'linux', 09:11:29 INFO - 'TMOUT': '86400', 09:11:29 INFO - 'USER': 'cltbld', 09:11:29 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1461599278.47402-1034304102', 09:11:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:29 INFO - '_': '/tools/buildbot/bin/python'} 09:11:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:29 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)) 09:11:29 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)) 09:11:29 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)) 09:11:29 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)) 09:11:29 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)) 09:11:29 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)) 09:11:29 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)) 09:11:29 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)) 09:11:29 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)) 09:11:29 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)) 09:11:29 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)) 09:11:29 INFO - Cleaning up... 09:11:29 INFO - Return code: 0 09:11:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:11:29 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')]} 09:11:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:29 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')]} 09:11:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:29 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 0x7f79e3b76f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24508a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x251d410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ac160>, '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 0x25ad060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25ad4f0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1461599278.47402-1034304102', '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 09:11:29 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 09:11:29 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 09:11:29 INFO - Using env: {'DISPLAY': ':0', 09:11:29 INFO - 'HOME': '/home/cltbld', 09:11:29 INFO - 'LANG': 'en_US.UTF-8', 09:11:29 INFO - 'LANGUAGE': 'en_US:en', 09:11:29 INFO - 'LOGNAME': 'cltbld', 09:11:29 INFO - 'MAIL': '/var/mail/cltbld', 09:11:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:29 INFO - 'MOZ_NO_REMOTE': '1', 09:11:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:29 INFO - 'NO_EM_RESTART': '1', 09:11:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:29 INFO - 'PWD': '/builds/slave/test', 09:11:29 INFO - 'SHELL': '/bin/bash', 09:11:29 INFO - 'SHLVL': '1', 09:11:29 INFO - 'TERM': 'linux', 09:11:29 INFO - 'TMOUT': '86400', 09:11:29 INFO - 'USER': 'cltbld', 09:11:29 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1461599278.47402-1034304102', 09:11:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:29 INFO - '_': '/tools/buildbot/bin/python'} 09:11:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:29 INFO - Downloading/unpacking jsonschema 09:11:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:11:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:11:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:11:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:11:31 INFO - Downloading/unpacking functools32 (from jsonschema) 09:11:31 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 09:11:31 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 09:11:31 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 09:11:31 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 09:11:31 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 09:11:31 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 09:11:31 INFO - Downloading functools32-3.2.3-2.tar.gz 09:11:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:11:31 INFO - warning: no files found matching '*.txt' 09:11:31 INFO - no previously-included directories found matching 'build' 09:11:31 INFO - no previously-included directories found matching 'dist' 09:11:31 INFO - no previously-included directories found matching '.git*' 09:11:31 INFO - Installing collected packages: jsonschema, functools32 09:11:31 INFO - Running setup.py install for functools32 09:11:31 INFO - warning: no files found matching '*.txt' 09:11:31 INFO - no previously-included directories found matching 'build' 09:11:31 INFO - no previously-included directories found matching 'dist' 09:11:31 INFO - no previously-included directories found matching '.git*' 09:11:31 INFO - Successfully installed jsonschema functools32 09:11:31 INFO - Cleaning up... 09:11:31 INFO - Return code: 0 09:11:31 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:11:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:11:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:11:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:11:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:11:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:11:31 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 0x7f79e3b76f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24508a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x251d410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ac160>, '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 0x25ad060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25ad4f0>, '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': 'c6927cf182745ad25b1d38b9000001fe-1461599278.47402-1034304102', '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 09:11:31 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 09:11:31 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 09:11:31 INFO - Using env: {'DISPLAY': ':0', 09:11:31 INFO - 'HOME': '/home/cltbld', 09:11:31 INFO - 'LANG': 'en_US.UTF-8', 09:11:31 INFO - 'LANGUAGE': 'en_US:en', 09:11:31 INFO - 'LOGNAME': 'cltbld', 09:11:31 INFO - 'MAIL': '/var/mail/cltbld', 09:11:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:31 INFO - 'MOZ_NO_REMOTE': '1', 09:11:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:31 INFO - 'NO_EM_RESTART': '1', 09:11:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:31 INFO - 'PWD': '/builds/slave/test', 09:11:31 INFO - 'SHELL': '/bin/bash', 09:11:31 INFO - 'SHLVL': '1', 09:11:31 INFO - 'TERM': 'linux', 09:11:31 INFO - 'TMOUT': '86400', 09:11:31 INFO - 'USER': 'cltbld', 09:11:31 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1461599278.47402-1034304102', 09:11:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:31 INFO - '_': '/tools/buildbot/bin/python'} 09:11:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:11:32 INFO - Downloading/unpacking flake8 09:11:32 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 09:11:32 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 09:11:32 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 09:11:32 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 09:11:32 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 09:11:32 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 09:11:34 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:11:34 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:11:34 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 09:11:34 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 09:11:34 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 09:11:34 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 09:11:34 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 09:11:34 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 09:11:34 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:11:34 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:11:34 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 09:11:34 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 09:11:34 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 09:11:34 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 09:11:34 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 09:11:34 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 09:11:34 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:11:34 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 09:11:34 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 09:11:34 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 09:11:34 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 09:11:34 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 09:11:34 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 09:11:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:11:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:11:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:11:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:11:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:11:34 INFO - no previously-included directories found matching 'docs/_build' 09:11:34 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:11:34 INFO - Running setup.py install for pep8 09:11:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:11:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:11:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:11:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:11:34 INFO - no previously-included directories found matching 'docs/_build' 09:11:35 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:11:35 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:11:35 INFO - Cleaning up... 09:11:35 INFO - Return code: 0 09:11:35 INFO - Running post-action listener: _resource_record_post_action 09:11:35 INFO - Running post-action listener: _start_resource_monitoring 09:11:35 INFO - Starting resource monitoring. 09:11:35 INFO - ##### 09:11:35 INFO - ##### Running install step. 09:11:35 INFO - ##### 09:11:35 INFO - Running pre-action listener: _resource_record_pre_action 09:11:35 INFO - Running main action method: install 09:11:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:11:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:11:35 INFO - Reading from file tmpfile_stdout 09:11:35 INFO - Detecting whether we're running mozinstall >=1.0... 09:11:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:11:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:11:35 INFO - Reading from file tmpfile_stdout 09:11:35 INFO - Output received: 09:11:35 INFO - Usage: mozinstall [options] installer 09:11:35 INFO - Options: 09:11:35 INFO - -h, --help show this help message and exit 09:11:35 INFO - -d DEST, --destination=DEST 09:11:35 INFO - Directory to install application into. [default: 09:11:35 INFO - "/builds/slave/test"] 09:11:35 INFO - --app=APP Application being installed. [default: firefox] 09:11:35 INFO - mkdir: /builds/slave/test/build/application 09:11:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:11:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:11:50 INFO - Reading from file tmpfile_stdout 09:11:50 INFO - Output received: 09:11:50 INFO - /builds/slave/test/build/application/firefox/firefox 09:11:50 INFO - Running post-action listener: _resource_record_post_action 09:11:50 INFO - ##### 09:11:50 INFO - ##### Running run-tests step. 09:11:50 INFO - ##### 09:11:50 INFO - Running pre-action listener: _resource_record_pre_action 09:11:50 INFO - Running main action method: run_tests 09:11:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:11:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:11:50 INFO - Python 2.7.3 09:11:50 INFO - Return code: 0 09:11:50 INFO - grabbing minidump binary from tooltool 09:11:50 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')]} 09:11:50 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 0x25ac160>, '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 0x25ad060>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x25ad4f0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:11:50 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 09:11:50 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 09:11:50 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:11:50 INFO - Return code: 0 09:11:50 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:11:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:11:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:11:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:11:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:11:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:11:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:11:51 INFO - Return code: 0 09:11:51 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/ZpuBLsfqRea_aIl5YentDQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--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 09:11:51 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/ZpuBLsfqRea_aIl5YentDQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-004 --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 09:11:51 INFO - Using env: {'DISPLAY': ':0', 09:11:51 INFO - 'HOME': '/home/cltbld', 09:11:51 INFO - 'LANG': 'en_US.UTF-8', 09:11:51 INFO - 'LANGUAGE': 'en_US:en', 09:11:51 INFO - 'LOGNAME': 'cltbld', 09:11:51 INFO - 'MAIL': '/var/mail/cltbld', 09:11:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:11:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:11:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:11:51 INFO - 'MOZ_NO_REMOTE': '1', 09:11:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:11:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:11:51 INFO - 'NO_EM_RESTART': '1', 09:11:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:11:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:11:51 INFO - 'PWD': '/builds/slave/test', 09:11:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:11:51 INFO - 'SHELL': '/bin/bash', 09:11:51 INFO - 'SHLVL': '1', 09:11:51 INFO - 'TERM': 'linux', 09:11:51 INFO - 'TMOUT': '86400', 09:11:51 INFO - 'USER': 'cltbld', 09:11:51 INFO - 'XDG_SESSION_COOKIE': 'c6927cf182745ad25b1d38b9000001fe-1461599278.47402-1034304102', 09:11:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:11:51 INFO - '_': '/tools/buildbot/bin/python'} 09:11:51 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/ZpuBLsfqRea_aIl5YentDQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-004', '--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 09:11:52 INFO - mozversion application_buildid: 20160425062348 09:11:52 INFO - mozversion application_changeset: 8551b253f4061db31e4be7829c2f70c2610caf42 09:11:52 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:11:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:11:52 INFO - mozversion application_name: Firefox 09:11:52 INFO - mozversion application_remotingname: firefox-dev 09:11:52 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:11:52 INFO - mozversion application_vendor: Mozilla 09:11:52 INFO - mozversion application_version: 47.0a2 09:11:52 INFO - mozversion platform_buildid: 20160425062348 09:11:52 INFO - mozversion platform_changeset: 8551b253f4061db31e4be7829c2f70c2610caf42 09:11:52 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:11:52 INFO - mozversion platform_version: 47.0a2 09:11:52 INFO - using testdate: 1461600712 09:11:52 INFO - actual date: 1461600712 09:11:52 INFO - starting webserver on 'localhost:35725' 09:11:52 INFO - SUITE-START | Running 2 tests 09:11:52 INFO - TEST-START | dromaeo_css 09:11:52 INFO - operating with platform_type : linux_ 09:11:52 INFO - Initialising browser for dromaeo_css test... 09:11:52 INFO - TEST-INFO | started process 18069 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbZa5tk/profile http://localhost:35725/getInfo.html) 09:11:55 INFO - PROCESS | 18069 | 09:11:55 INFO - PROCESS | 18069 | (process:18144): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 09:11:56 INFO - PROCESS | 18069 | __metrics Screen width/height:1600/1200 09:11:56 INFO - PROCESS | 18069 | colorDepth:24 09:11:56 INFO - PROCESS | 18069 | Browser inner width/height: 1024/697 09:11:56 INFO - PROCESS | 18069 | __metrics 09:11:58 ERROR - PROCESS | 18069 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2849: TypeError: this.tabs is undefined 09:11:58 INFO - TEST-INFO | 18069: exit 0 09:11:58 INFO - Browser initialized. 09:11:58 INFO - Running cycle 1/1 for dromaeo_css test... 09:11:59 INFO - TEST-INFO | started process 18197 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbZa5tk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:12:00 INFO - PROCESS | 18197 | 09:12:00 INFO - PROCESS | 18197 | (process:18263): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 09:12:10 INFO - PROCESS | 18197 | RSS: Main: 151261184 09:12:10 INFO - PROCESS | 18197 | 09:13:00 INFO - PROCESS | 18197 | 1461600780302 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] 09:15:08 INFO - PROCESS | 18197 | Cycle 1(1): loaded http://localhost:35725/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35725/tests/dromaeo/cssquery-ext.html) 09:15:09 INFO - PROCESS | 18197 | RSS: Main: 154259456 09:15:09 INFO - PROCESS | 18197 | 09:18:11 INFO - PROCESS | 18197 | Cycle 1(1): loaded http://localhost:35725/tests/dromaeo/cssquery-ext.html (next: http://localhost:35725/tests/dromaeo/cssquery-jquery.html) 09:18:12 INFO - PROCESS | 18197 | RSS: Main: 153649152 09:18:12 INFO - PROCESS | 18197 | 09:21:14 INFO - PROCESS | 18197 | Cycle 1(1): loaded http://localhost:35725/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35725/tests/dromaeo/cssquery-mootools.html) 09:21:14 INFO - PROCESS | 18197 | RSS: Main: 152731648 09:21:14 INFO - PROCESS | 18197 | 09:24:16 INFO - PROCESS | 18197 | Cycle 1(1): loaded http://localhost:35725/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35725/tests/dromaeo/cssquery-prototype.html) 09:24:16 INFO - PROCESS | 18197 | RSS: Main: 152379392 09:24:16 INFO - PROCESS | 18197 | 09:27:19 INFO - PROCESS | 18197 | Cycle 1(1): loaded http://localhost:35725/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35725/tests/dromaeo/cssquery-yui.html) 09:27:19 INFO - PROCESS | 18197 | RSS: Main: 153624576 09:27:19 INFO - PROCESS | 18197 | 09:30:21 INFO - PROCESS | 18197 | Cycle 1(1): loaded http://localhost:35725/tests/dromaeo/cssquery-yui.html (next: http://localhost:35725/tests/dromaeo/cssquery-dojo.html) 09:30:22 INFO - PROCESS | 18197 | RSS: Main: 155295744 09:30:22 INFO - PROCESS | 18197 | 09:30:22 INFO - PROCESS | 18197 | __start_tp_report 09:30:22 INFO - PROCESS | 18197 | _x_x_mozilla_page_load 09:30:22 INFO - PROCESS | 18197 | _x_x_mozilla_page_load_details 09:30:22 INFO - PROCESS | 18197 | |i|pagename|runs| 09:30:22 INFO - PROCESS | 18197 | |0;dojo.html;3339.7662163648542;3396.03213084271;3414.436617958037;3439.6904278614934;3451.534042904207;5593.328800543935;5599.160125981105;5630.662160270386;5643.068893632556;5663.207150998864;5629.324481062268;5649.519790817775;5660.490555849972;5669.518090962263;5672.319321681393;5048.545630893228;5158.3552055993005;5159.148740457838;5187.299714538545;5190.844274671766;5020.573251273646;5149.382074151107;5203.19350500673;5213.322268105141;5214.765335559909;5120.411152717448;5135.2040433732645;5177.922331165017;5179.093658609747;5283.286756287909;5182.507661772125;5203.349581302318;5208.9739551302;5211.947880521184;5242.606804489678;4670.766461676936;5120.411152717448;5142.3829140503;5153.2785410042625;5168.793248270064;5469.042917489463;5485.561155107622;5499.1476321170285;5511.22842802008;5529.502344788948;5901.793437229677;6027.216461859974;6052.818415447499;6138.877222455526;6147.815565533041;6125.724342404603;6127.877442451126;6137.754489820401;6139.201903752528;6144.3717414885095;5647.068233741427;5691.26013618227;5694.572907031948;5698.572607054488;5701.543876489871;5954.047352423591;6167.290761562388;6205.937940620536;6211.2857021442205;6277.560570760094;5971.28344598651;5972.731227094791;5983.760649574056;5988.461038506555;5995.910061349084;6053.727582258721;6064.878702426015;6076.3316035236085;6083.969580152159;6094.939050609437;5765.769369225184;5963.165156878041;6019.69901504923;6021.909671354933;6061.090836374579;5950.077737950624;5969.641821490724;6002.279726432768;6003.189569408161;6018.668973206516;4487.439070116236;4530.320451932236;4550.658700597469;4557.703749256288;4566.657500687462;3999.2201520703766;4033.556308806029;4106.938395924063;4135.503739551273;4179.832806687759;5636.2954630671165;5723.5707321950185;5738.426157384228;5745.4541818528005;5770.13447982806;6097.5731698780655;6107.694615269219;6115.785947491905;6115.847103822351;6122.755089796358;5555.416681248492;5725.255716756795;5808.912866307009;5810.21562089121;5827.24245848037;5154.974225128851;5193.76628051731;5196.090684130262;5212.218167274863;5238.1618940969265;6054.848628784228;6258.405451482193;6260.686965651699;6263.404976527223;6264.37356264379;6089.451949324582;6113.689848656775;6132.509399248139;6139.124180917401;6179.3820617937845;6197.318294987457;6265.530085243534;6268.843278918063;6270.310265870842;6274.278457977391;5813.215215945793;5944.405559444194;6014.458698717136;6025.939740602713;6029.035354343284;6074.878502430015;6104.145419641342;6112.113743507255;6113.7153036952495;6118.588722523033;5834.679092649775;5904.586678932434;5927.792527261525;5941.554383421347;5993.490553303018;5707.143928410772;5852.209951656472;5886.3525012247765;5924.911126333022;5925.4370834770625;5831.7375718091935;5838.5621078418435;5843.1235314703135;5845.853853653693;5854.443827836349;5999.1601175836295;6014.609050411709;6018.277806663228;6030.246219222598;6035.969820151046;5581.218629391969;5770.2498675171955;5781.4507621775865;5811.883762324815;5901.203337549376 09:30:22 INFO - PROCESS | 18197 | |1;ext.html;561.9915701264479;577.7198058941412;577.9219805326279;584.2711870975869;623.8346838087906;36612.81693591532;37512.81243593782;37682.24635507288;37690.8115459423;37846.62153378465;39383.606163938355;39867.80066099673;40147.397789033115;40157.397639035364;40179.79910100453;8639.136086391374;8913.688020919237;8974.730758077236;8992.955035224815;9055.909440905605;8804.559772011406;8948.552572371387;8959.104089591054;8961.551922403887;8968.417052891573;8911.465312081264;9005.639774409048;9011;9012.549372531346;9049.185573298402;8883.200511953923;8900.688475903346;8964.686235981742;8977.147171018809;8990.190882820512;8894.155055269805;9021.458712477272;9033.503157326344;9033.54832258391;9036.457812531204;35008.649913501045;39879;40353.596464035574;40426;40441;3481.8433170507133;3687.50218720472;3749.531308586427;3791.0901383667997;3826.1582451860545;29651.40697186044;30118.84940575305;30191.69808301911;30240.395192096254;31041.37917241665;3751.643593858579;3772.1889793694486;3799.9430008549894;3833.769973801553;3902.4731661225655;230593;231024;231289;231322;231438.9999999983;47055.5294447058;61598;62992.05511917325;63261.367386325546;63298.367016330165;45124.548754512034;45250.77374613106;45267.32099018517;45359.546404536195;45400.3189952151;16450.835491645168;16678.16609169561;16688.91655541739;16704.081275529963;16759.41342053022;13143.277119758504;13320.333983300796;13322.933385333012;13336.399862006234;13368.598942031755;15623.843761562466;15890;15924.124173170356;15962.680746385007;16036.839631603769;5867.178594996619;6026.969865150646;6062.727177277033;6070.726817293233;6214.564980451415;1054.9103326217357;1104.745908441047;1130.327455164176;1133.7505748735266;1170.4264910193945;29717.257068573457;30110.69889301079;30137.397252054838;30229.546556801666;30237.848810755804;1122.7305446692735;1131.9830202546968;1162.215504534436;1162.9069674426025;1169.9941500292614;15342.923285383502;15657.556470735055;15699.764503532457;15799.289031993589;16089.597760056093;335414;339831;340216;342809;343186;56762.43237567654;56943;57622.423775762545;57637.423625764044;57971;1494.6039299585618;1495.7158139953374;1500.1149321900132;1513.9091654500764;1513.9772903406458;29038.854805725838;29282.414351713272;29393.706062939098;30256;30321.545176821925;51358;51769;52000.73999629977;52130.739346303024;52145;244150;249227;249770;250546;252372;34889.302213956085;35372;35406.645933540334;35522.289554208255;35616.82191589026;46671;47464.813379665786;47518.049639006334;47550.52449475461;48133;39895.80052099721;40009.79995100006;40211.79894100628;40963.38554921619;41215.79392103021;39329;39406.802965984985;39758.60241397549;39857.40213896736;40476.59523404728;35684.46473302851;39055.41416878692;39527.60472395239;40285.194296113325;42173.789131055375;32646.51030234501;33349.83325083359;33396.66603334033;33499.49750753692;33666.49500257547;23193.420164496023;23986.28041158768;23996.52006959886;24296.392590185387;24379.14672986437 09:30:22 INFO - PROCESS | 18197 | |2;jquery.html;365.32050386281514;389.873266260507;393.7165241026461;394.79076089672463;402.239766840671;17219.225134868928;18039;18394;18436.26254949802;18528.351507697233;12122.212056216362;12458.875411245885;12466;12603.054770892173;12608.68478288041;5169.56058735008;5246.921296180561;5654.802081927133;5731.971340143294;5735.913961290584;5612.7754889804355;5662.065759149736;5682.630629009122;5686.857828554278;5696.174054762057;5371.328583927009;5588.4411558844195;5598.468145526169;5650.830475085754;5675.4608312210485;5510.9448905511035;5524.309461317335;5556.305461817273;5581.665100093987;5878.2064421303;5710.828675139753;5732.398098199671;5742.167385729088;5744.655320680731;5762.654240745527;13859.37632806516;13991.230482323468;14028.649283767885;14164.716705665933;14185.219812910285;2383.749706280833;2408.446057406806;2472.888720007586;2474.4061425257987;2950.2476868398635;9820.950895245549;9827;9894.55474503649;9900.405975641484;10083.294169408144;1792.7400526923655;1840.5766673665132;1854.6568884756227;1875.962480750391;1925.0278609302352;60541.39458605402;63814;64248;68394.65802670954;73868.26131738667;10490.265681402228;10556.26106172576;10775.83836242457;10887.346759194315;11074.667759967213;15397.538073857802;15464.14947177916;15491.147986860831;15502.379904803682;15544.378224871105;7682.1549629540705;7896.763097107096;7998.280154785981;8200.138985406566;8273.586320684062;7930.286274235346;8053.838923221503;8146.0632027317615;8170.223828736261;8225.095239523647;4751.121042607128;4817.397825271841;4848.684835485753;4883.926741098886;4922.089413458521;2005.7091721700563;2007.1469625409143;2033.644112280345;2058.4442200606054;2112.6619740841393;927.1933417926447;969.5782334684435;1029.577873072037;1040.4693606260862;1067.6209945469247;14898.329575169144;17085.743713844302;17255.74116388255;17667.55831104233;18081.547961301072;976.3556053004983;988.204495381225;1036.44550165661;1044.9425281609433;1045.1899777672365;9580.520973951274;9811.166050885611;9999.950000249952;10041.598336066476;10125.392476451289;421820;422313;430396;430832;431390;73647.63176184085;76190.61904690551;77605.22394776093;78154;80173;1097.8517900083546;1115.3753897817248;1116.1684545014016;1120.3222050659397;1137.1357768096307;14969.477041644188;15601.375944962074;15629.609259768142;15631.531054067942;15836.60408489797;34202.31595368128;34231;34381.82809085939;35204.29591408209;35387.82306088453;50231.74884125556;50563.74718126386;50692.23961640652;50912.74543627406;51047.234291484914;8448.40861139739;8467.788305292417;8532.70135545253;8703.47779133254;9006.144416280444;11349.829752553553;11460.656180314603;12038.337891416057;12329.630111096682;12580.182288151242;10538.209634277306;10680.412577308323;10798.622048228275;10862.131029517777;11278;10846.403447810446;11033.22767406306;11078.612248571262;11290.604828830952;11452.198346115692;11144.832827507757;11195.328280303207;11221.71945701364;11470.655880323604;11598.478068487108;8325.791855203668;8452.788680283042;8512.957435213031;8856.247218986458;8911.777205569913;11114;11645.126615504041;11646.650600481998;11772.646820595395;11794.056475482112 09:30:22 INFO - PROCESS | 18197 | |3;mootools.html;1524.5121561100445;1531.127257463246;1690.7379356199785;1807.7978144533886;1813.6372725454903;7535.20880307568;8157.347412207024;8279.254867061964;8566.528840913747;8569.400141990056;8196;8272.379571532145;8347.499150051;8388.580570971457;8411.495310281372;2399.748026457223;2402.351365131418;2412.2763171048705;2414.843035202715;2426.684531010966;2381.892814823337;2385.0459816073535;2425.672534207877;2426.6360045993065;2435.744246854081;2370.431096536836;2372.489914668346;2396.4727759892794;2433.598456254716;2450.0567281596536;2401.7478164792706;2408.771166739157;2415.1184817541557;2422.9757702422926;2467.9136230232034;2357.233898982837;2374.5607062693457;2385.642153676945;2394.7006624171977;2396.544656515274;8500.149985001512;8546;8554;8566.657333706644;8598.699045533438;5128.25640282157;5217.791288348461;5224.190250511138;5224.4775522447835;5247.02929957959;3177.2215807127172;3250.528673342376;3285.4250506161325;3438.1060924159897;3454.913627159316;1123.4214379594516;1123.5842738186923;1137.2323681515027;1143.822707480342;1150.3155622404656;11591.536338546448;11622.70943226426;11644.883551164465;11659.00898423635;11683.065354771514;4511.481179664315;4519.796609152596;4569.565891240327;4583.289590113538;4602.148602508547;10071;10148.441835699108;10149.492525373702;10163.136133428594;10173.237007224488;5180.766865491062;5226.137687281632;5256.001359741636;5259.63182577216;5274.7098909560345;4095.5494895561465;4670.486246512881;4773.689710168828;4778.522147785194;4810.7113573185725;3791.563970143414;3886.4947556817997;3910.452536644872;3931.331673615435;3986.621270979252;1269.0862578943147;1273.4715093236389;1276.7765641012786;1278.9360531973364;1301.3363184775644;1146.656003199037;1149.6321177223208;1151.7005578549638;1363.9454421823016;1428.7999680044802;7781.06627204739;7925.445218834741;7948;7962.084360298524;8003.279704826477;1133.7675776465805;1144.5650652751901;1192.4633914738229;1218.7318789866392;1260.04236780046;6184.072389141665;6222.439980401786;6265.467435268053;6289.245290565162;6330.8100756977365;9974.700758977242;10234.130098941674;10294.227932905062;10369.118624916815;10425.426601536989;10262.897371026344;10286.691399258034;10407.53166107527;10573.312734672376;10596.364218146937;7965.920340796633;7978.880316795136;7992.480488768211;8006.559639219899;8027.5584842834205;7611;7640.694372225049;7691.269329413697;7715.382769378549;7763.961180194063;8704.173103555153;8950;8955.865662014945;9087.36388452802;9105.499197544199;9125.589348479201;9133.817323653622;9187.632494700405;9222.723318300461;9298.535073246447;6261.186045814015;6825.2151002633345;7002.719891204295;7165.677544510405;7309.634518274172;7987.361011119213;8011;8053;8072.233137851895;8117.878231826409;7229.385502232369;7394.186639469546;7431.591262480615;7460.962695186489;7468.066491688539;7326.597037163007;7448.962755186406;7596.354309883722;7608.923910761043;7629.809254768676;7311.305425984523;7374.926250737424;7409.444291678038;7468.5145465544565;7480.925190748023;6869.7595584154315;6895.482838787011;6906.412954898889;6963.512554121163;7123.287671233043;6942.305769423017;6955.547889387173;6994.755183568551;7007.194172670208;7020.157581090302 09:30:22 INFO - PROCESS | 18197 | |4;prototype.html;370.37961414630496;371.5133175540041;373.98878033658985;374.57859907603944;376.2099590859196;6368.299487056426;6422.839429014277;6436.839079023015;6457.773977910774;6460.580062295948;6272.9372706272925;6314;6320.525960552954;6346.111544383778;6361.332060133689;4718.268668356393;4792.496787837279;4847.248676455138;4867.099586576477;4949.183384741522;4825.758712064383;4842.2252439609665;4847.1032858921035;4851.733154676492;4874.415070191564;4776.211925032366;4818.25317075852;4823.734694591796;4824.517548245182;4826;4823.155947709154;4826.5414785595485;4834.975825120852;4844.2733589961435;4851.078295123915;4237.618614324695;4239.964816887911;4246.044639956016;4251.3622956556765;4252.617264446215;5807.506361959238;5975.25309336333;5991.161237426763;5993.910091348634;5998.220231369894;5557.055300598908;5559.16612508127;5564.610477266593;5576.358718747356;5588.3573389060375;6537;6565.474762019075;6590.945448728228;6608.107905432753;6613.537052406334;4593.494715581283;4636;4639.420072490939;4650.116477869194;4656.604188643969;6940.854758964765;6997.965010174968;7024.859502809915;7026.543274687129;7028.648567571652;4671.719696818134;4836.806527738921;4846.951530484721;4850.029994001214;4853.684510506806;4846.7818948147415;4892.828750993698;4896.632752543574;4899.926501102486;4905.994271174401;5619.494245517923;5629.296337957755;5646.6047376684055;5669.348024977098;5673.914891276634;6779;6781.762638307636;6788.219354774165;6804.421624161902;6816.250212476623;1717.4761697682077;1737.7480265361453;1743.808181100078;1744.7644567983414;1748.5978225008064;1107.617871834216;1124.0670243697712;1139.811931031387;1147.552454542729;1149.9310041397544;6492.415682588589;6506.642134682638;6519;6545.5090868184125;6558.213014438298;6962.547434416747;6972.2330543640155;6982.26686197952;6995.8950615740805;7009.649517524103;6322;6430.742770289229;6443.838904027343;6551.475881929529;6598.175228096488;3798.1074447504816;3803.581606023335;3820.4269359596283;3823.636754508317;3836.731428800013;7252.709891604277;7258.709651613876;7259.201487836333;7269.963650181715;7280.41756659466;7213.531120477257;7219.855602887913;7223.674934627954;7254.093238345207;7254.492185547067;7083.220845706968;7183.712651493986;7280.708771649181;7309.5248808827255;7312.744053958192;7010.789676309719;7034.437245020284;7035.366816986496;7046.224915259215;7079.8938015930835;6923.307669233238;6937.895931561132;6945.2707465715375;6949.235584085645;6951.374376306157;7212.7475538358;7278.854422911406;7284.781456556312;7295.562266264041;7348.1182258129365;4697.953020469751;4747.928781068218;4843.104025755247;4856.028794241095;4876.902461950812;6919.065926099912;6927.168739751262;6929.3070692930305;6967.895481567679;6970.372666460043;6693.765718200035;6765.154355705537;6766.221884483348;6780.050792889098;6783.287754785873;6756.358145976124;6780.32196780318;6788.626625535643;6796.422304104207;6799.762008329685;6441.742330306735;6544.738210471528;6659.034440006075;6666.433353165035;6668.599884006975;6825.897611535732;6852.862942741018;6871.828204294733;6898.551594146365;6902.206246281742;6685.298043705341;6717.899231511434;6732.192136943598;6735.562188457735;6736.02327662496 09:30:22 INFO - PROCESS | 18197 | |5;yui.html;705.1573369823062;786.3237615650543;795.7811601809501;797.2027972027972;806.2904643913355;13188.538401155962;13376.933115334434;13405.798913016288;13408.195508269486;13432.932835335834;13178.472861085545;13224.933875330635;13260.204387736743;13264.071514993955;13276.46894124234;758.3137260779004;889.5863423508067;904.055262250946;924.2375040591505;930.4928813796463;913.3971578758021;914.7347269291897;918.669279059538;919.737874705709;920.4661296448078;956.9760755981087;958.1663952361455;958.7267628725815;959.9232061435068;960.0207788056179;946.1106559833735;948.2698322291831;948.3219498058921;948.9715308540685;950.6862735297335;699.9755008574675;703.8275622472476;703.9225685174627;704.0671110778188;708.3129364516411;13963.58109256724;13988.230647314394;14027.088239264518;14066.929665351608;14090.718185636333;12227.633171004883;12435.44040518179;12609.180403273758;12717.936410317981;12777.680557986032;9514.143727064597;9547.140757331805;9610.135087842127;9612.66355677548;9633.855492167622;3306.553615261957;3338.0987133473827;3380.3577320309064;3384.3569721752788;3408.812515311657;48702.756486217695;48810.511894880954;48976.75511622454;49032.75483622595;50112.24831627528;9562.904370956341;9674.70975870725;9715.611375544899;9776.657816976514;9825.459599722084;19053.7141942871;19092.33176838804;19129.713054304197;19158.329458469165;19214.615707685767;7436.256374362521;7599.848003039909;7663.27198916102;7734.729284475016;7837.49056311338;4018.6383225509844;4023.5574086850424;4042.8382864685673;4071.2875246831686;4112.712110152261;3227.483602623569;3229.9031029069165;3279.9832052063934;3282.3271229397915;3283.737301015913;592.8636413624804;614.8094090831856;621.5338496127904;625.3121566277059;625.8529245627274;672.6031641331638;741.33650382907;747.637395863006;751.4476859508258;763.3625922354785;8842.292616590657;9107.426301025322;9179.357444978787;9250.352475326798;9307.162355388047;602.1960682488926;628.9654069026155;673.4780545077506;714.5355518912644;721.1490441279236;3794.6015668354976;3795.35478968576;3883.10688541637;3884.4173373993563;3997.9805149686645;58870.41129588735;59242.40757592455;59284.40715592789;59409.405905941254;60195.69902150461;52362;52427.47572524302;52796.73601632044;52974.205386919224;53745;49586;49786.50213497891;51216;51243.23135152976;57209.71395143081;8969.865452018095;8975.461472311683;9167.49578773174;9255.907440925504;9292.62829486813;36619.450708238866;37606.435903462014;38117;38377.80811095927;39079.218415632095;46311.76844115893;46468.7676561615;47256;47433;47718.761406192745;12698.492060317483;12798.61604151877;13002;13031.869681303066;13065.085444018827;13380.531681391105;13383.799243011168;13519.662008449473;13636.250006249355;14057.789133162807;11915.285082895054;12076.879231207575;12145.939270303592;12241.387930603612;12844.743105138032;12116.757664846476;12163.635090947286;12243.69390765238;12260.632181034583;12283.69290767738;11890.227135236182;11951.22317049389;12028.819567706667;12295.200811947196;12309.56916507918;10016.546389697474;10277.691669249934;10770.353778772986;10813.837792432962;10856.945715271373;10261.692149235534;10698.8930110701;10763.138948884227;10787.568497260021;10864.130869530576 09:30:22 INFO - PROCESS | 18197 | __end_tp_report 09:30:22 INFO - PROCESS | 18197 | __start_cc_report 09:30:22 INFO - PROCESS | 18197 | _x_x_mozilla_cycle_collect,321 09:30:22 INFO - PROCESS | 18197 | __end_cc_report 09:30:22 INFO - PROCESS | 18197 | __startTimestamp1461601822230__endTimestamp 09:30:22 INFO - PROCESS | 18197 | 09:30:22 INFO - PROCESS | 18197 | ------- Summary: start ------- 09:30:22 INFO - PROCESS | 18197 | Number of tests: 6 09:30:22 INFO - PROCESS | 18197 | 09:30:22 INFO - PROCESS | 18197 | [#0] dojo.html Cycles:165 Average:5620.78 Median:5831.74 stddev:634.94 (10.9%) stddev-sans-first:611.15 09:30:22 INFO - PROCESS | 18197 | Values: 3339.8 3396.0 3414.4 3439.7 3451.5 5593.3 5599.2 5630.7 5643.1 5663.2 5629.3 5649.5 5660.5 5669.5 5672.3 5048.5 5158.4 5159.1 5187.3 5190.8 5020.6 5149.4 5203.2 5213.3 5214.8 5120.4 5135.2 5177.9 5179.1 5283.3 5182.5 5203.3 5209.0 5211.9 5242.6 4670.8 5120.4 5142.4 5153.3 5168.8 5469.0 5485.6 5499.1 5511.2 5529.5 5901.8 6027.2 6052.8 6138.9 6147.8 6125.7 6127.9 6137.8 6139.2 6144.4 5647.1 5691.3 5694.6 5698.6 5701.5 5954.0 6167.3 6205.9 6211.3 6277.6 5971.3 5972.7 5983.8 5988.5 5995.9 6053.7 6064.9 6076.3 6084.0 6094.9 5765.8 5963.2 6019.7 6021.9 6061.1 5950.1 5969.6 6002.3 6003.2 6018.7 4487.4 4530.3 4550.7 4557.7 4566.7 3999.2 4033.6 4106.9 4135.5 4179.8 5636.3 5723.6 5738.4 5745.5 5770.1 6097.6 6107.7 6115.8 6115.8 6122.8 5555.4 5725.3 5808.9 5810.2 5827.2 5155.0 5193.8 5196.1 5212.2 5238.2 6054.8 6258.4 6260.7 6263.4 6264.4 6089.5 6113.7 6132.5 6139.1 6179.4 6197.3 6265.5 6268.8 6270.3 6274.3 5813.2 5944.4 6014.5 6025.9 6029.0 6074.9 6104.1 6112.1 6113.7 6118.6 5834.7 5904.6 5927.8 5941.6 5993.5 5707.1 5852.2 5886.4 5924.9 5925.4 5831.7 5838.6 5843.1 5845.9 5854.4 5999.2 6014.6 6018.3 6030.2 6036.0 5581.2 5770.2 5781.5 5811.9 5901.2 09:30:22 INFO - PROCESS | 18197 | 09:30:22 INFO - PROCESS | 18197 | [#1] ext.html Cycles:180 Average:45150.39 Median:20809.41 stddev:72508.08 (348.4%) stddev-sans-first:72634.20 09:30:22 INFO - PROCESS | 18197 | Values: 562.0 577.7 577.9 584.3 623.8 36612.8 37512.8 37682.2 37690.8 37846.6 39383.6 39867.8 40147.4 40157.4 40179.8 8639.1 8913.7 8974.7 8993.0 9055.9 8804.6 8948.6 8959.1 8961.6 8968.4 8911.5 9005.6 9011.0 9012.5 9049.2 8883.2 8900.7 8964.7 8977.1 8990.2 8894.2 9021.5 9033.5 9033.5 9036.5 35008.6 39879.0 40353.6 40426.0 40441.0 3481.8 3687.5 3749.5 3791.1 3826.2 29651.4 30118.8 30191.7 30240.4 31041.4 3751.6 3772.2 3799.9 3833.8 3902.5 230593.0 231024.0 231289.0 231322.0 231439.0 47055.5 61598.0 62992.1 63261.4 63298.4 45124.5 45250.8 45267.3 45359.5 45400.3 16450.8 16678.2 16688.9 16704.1 16759.4 13143.3 13320.3 13322.9 13336.4 13368.6 15623.8 15890.0 15924.1 15962.7 16036.8 5867.2 6027.0 6062.7 6070.7 6214.6 1054.9 1104.7 1130.3 1133.8 1170.4 29717.3 30110.7 30137.4 30229.5 30237.8 1122.7 1132.0 1162.2 1162.9 1170.0 15342.9 15657.6 15699.8 15799.3 16089.6 335414.0 339831.0 340216.0 342809.0 343186.0 56762.4 56943.0 57622.4 57637.4 57971.0 1494.6 1495.7 1500.1 1513.9 1514.0 29038.9 29282.4 29393.7 30256.0 30321.5 51358.0 51769.0 52000.7 52130.7 52145.0 244150.0 249227.0 249770.0 250546.0 252372.0 34889.3 35372.0 35406.6 35522.3 35616.8 46671.0 47464.8 47518.0 47550.5 48133.0 39895.8 40009.8 40211.8 40963.4 41215.8 39329.0 39406.8 39758.6 39857.4 40476.6 35684.5 39055.4 39527.6 40285.2 42173.8 32646.5 33349.8 33396.7 33499.5 33666.5 23193.4 23986.3 23996.5 24296.4 24379.1 09:30:22 INFO - PROCESS | 18197 | 09:30:22 INFO - PROCESS | 18197 | [#2] jquery.html Cycles:180 Average:25343.75 Median:34306.41 stddev:70223.80 (204.7%) stddev-sans-first:70395.75 09:30:22 INFO - PROCESS | 18197 | Values: 365.3 389.9 393.7 394.8 402.2 17219.2 18039.0 18394.0 18436.3 18528.4 12122.2 12458.9 12466.0 12603.1 12608.7 5169.6 5246.9 5654.8 5732.0 5735.9 5612.8 5662.1 5682.6 5686.9 5696.2 5371.3 5588.4 5598.5 5650.8 5675.5 5510.9 5524.3 5556.3 5581.7 5878.2 5710.8 5732.4 5742.2 5744.7 5762.7 13859.4 13991.2 14028.6 14164.7 14185.2 2383.7 2408.4 2472.9 2474.4 2950.2 9821.0 9827.0 9894.6 9900.4 10083.3 1792.7 1840.6 1854.7 1876.0 1925.0 60541.4 63814.0 64248.0 68394.7 73868.3 10490.3 10556.3 10775.8 10887.3 11074.7 15397.5 15464.1 15491.1 15502.4 15544.4 7682.2 7896.8 7998.3 8200.1 8273.6 7930.3 8053.8 8146.1 8170.2 8225.1 4751.1 4817.4 4848.7 4883.9 4922.1 2005.7 2007.1 2033.6 2058.4 2112.7 927.2 969.6 1029.6 1040.5 1067.6 14898.3 17085.7 17255.7 17667.6 18081.5 976.4 988.2 1036.4 1044.9 1045.2 9580.5 9811.2 10000.0 10041.6 10125.4 421820.0 422313.0 430396.0 430832.0 431390.0 73647.6 76190.6 77605.2 78154.0 80173.0 1097.9 1115.4 1116.2 1120.3 1137.1 14969.5 15601.4 15629.6 15631.5 15836.6 34202.3 34231.0 34381.8 35204.3 35387.8 50231.7 50563.7 50692.2 50912.7 51047.2 8448.4 8467.8 8532.7 8703.5 9006.1 11349.8 11460.7 12038.3 12329.6 12580.2 10538.2 10680.4 10798.6 10862.1 11278.0 10846.4 11033.2 11078.6 11290.6 11452.2 11144.8 11195.3 11221.7 11470.7 11598.5 8325.8 8452.8 8513.0 8856.2 8911.8 11114.0 11645.1 11646.7 11772.6 11794.1 09:30:22 INFO - PROCESS | 18197 | 09:30:22 INFO - PROCESS | 18197 | [#3] mootools.html Cycles:180 Average:5894.91 Median:5199.28 stddev:3073.04 (59.1%) stddev-sans-first:3064.10 09:30:22 INFO - PROCESS | 18197 | Values: 1524.5 1531.1 1690.7 1807.8 1813.6 7535.2 8157.3 8279.3 8566.5 8569.4 8196.0 8272.4 8347.5 8388.6 8411.5 2399.7 2402.4 2412.3 2414.8 2426.7 2381.9 2385.0 2425.7 2426.6 2435.7 2370.4 2372.5 2396.5 2433.6 2450.1 2401.7 2408.8 2415.1 2423.0 2467.9 2357.2 2374.6 2385.6 2394.7 2396.5 8500.1 8546.0 8554.0 8566.7 8598.7 5128.3 5217.8 5224.2 5224.5 5247.0 3177.2 3250.5 3285.4 3438.1 3454.9 1123.4 1123.6 1137.2 1143.8 1150.3 11591.5 11622.7 11644.9 11659.0 11683.1 4511.5 4519.8 4569.6 4583.3 4602.1 10071.0 10148.4 10149.5 10163.1 10173.2 5180.8 5226.1 5256.0 5259.6 5274.7 4095.5 4670.5 4773.7 4778.5 4810.7 3791.6 3886.5 3910.5 3931.3 3986.6 1269.1 1273.5 1276.8 1278.9 1301.3 1146.7 1149.6 1151.7 1363.9 1428.8 7781.1 7925.4 7948.0 7962.1 8003.3 1133.8 1144.6 1192.5 1218.7 1260.0 6184.1 6222.4 6265.5 6289.2 6330.8 9974.7 10234.1 10294.2 10369.1 10425.4 10262.9 10286.7 10407.5 10573.3 10596.4 7965.9 7978.9 7992.5 8006.6 8027.6 7611.0 7640.7 7691.3 7715.4 7764.0 8704.2 8950.0 8955.9 9087.4 9105.5 9125.6 9133.8 9187.6 9222.7 9298.5 6261.2 6825.2 7002.7 7165.7 7309.6 7987.4 8011.0 8053.0 8072.2 8117.9 7229.4 7394.2 7431.6 7461.0 7468.1 7326.6 7449.0 7596.4 7608.9 7629.8 7311.3 7374.9 7409.4 7468.5 7480.9 6869.8 6895.5 6906.4 6963.5 7123.3 6942.3 6955.5 6994.8 7007.2 7020.2 09:30:22 INFO - PROCESS | 18197 | 09:30:22 INFO - PROCESS | 18197 | [#4] prototype.html Cycles:180 Average:5659.22 Median:6442.79 stddev:1709.54 (26.5%) stddev-sans-first:1667.61 09:30:22 INFO - PROCESS | 18197 | Values: 370.4 371.5 374.0 374.6 376.2 6368.3 6422.8 6436.8 6457.8 6460.6 6272.9 6314.0 6320.5 6346.1 6361.3 4718.3 4792.5 4847.2 4867.1 4949.2 4825.8 4842.2 4847.1 4851.7 4874.4 4776.2 4818.3 4823.7 4824.5 4826.0 4823.2 4826.5 4835.0 4844.3 4851.1 4237.6 4240.0 4246.0 4251.4 4252.6 5807.5 5975.3 5991.2 5993.9 5998.2 5557.1 5559.2 5564.6 5576.4 5588.4 6537.0 6565.5 6590.9 6608.1 6613.5 4593.5 4636.0 4639.4 4650.1 4656.6 6940.9 6998.0 7024.9 7026.5 7028.6 4671.7 4836.8 4847.0 4850.0 4853.7 4846.8 4892.8 4896.6 4899.9 4906.0 5619.5 5629.3 5646.6 5669.3 5673.9 6779.0 6781.8 6788.2 6804.4 6816.3 1717.5 1737.7 1743.8 1744.8 1748.6 1107.6 1124.1 1139.8 1147.6 1149.9 6492.4 6506.6 6519.0 6545.5 6558.2 6962.5 6972.2 6982.3 6995.9 7009.6 6322.0 6430.7 6443.8 6551.5 6598.2 3798.1 3803.6 3820.4 3823.6 3836.7 7252.7 7258.7 7259.2 7270.0 7280.4 7213.5 7219.9 7223.7 7254.1 7254.5 7083.2 7183.7 7280.7 7309.5 7312.7 7010.8 7034.4 7035.4 7046.2 7079.9 6923.3 6937.9 6945.3 6949.2 6951.4 7212.7 7278.9 7284.8 7295.6 7348.1 4698.0 4747.9 4843.1 4856.0 4876.9 6919.1 6927.2 6929.3 6967.9 6970.4 6693.8 6765.2 6766.2 6780.1 6783.3 6756.4 6780.3 6788.6 6796.4 6799.8 6441.7 6544.7 6659.0 6666.4 6668.6 6825.9 6852.9 6871.8 6898.6 6902.2 6685.3 6717.9 6732.2 6735.6 6736.0 09:30:22 INFO - PROCESS | 18197 | 09:30:22 INFO - PROCESS | 18197 | [#5] yui.html Cycles:180 Average:14493.73 Median:48756.63 stddev:16787.12 (34.4%) stddev-sans-first:16802.28 09:30:22 INFO - PROCESS | 18197 | Values: 705.2 786.3 795.8 797.2 806.3 13188.5 13376.9 13405.8 13408.2 13432.9 13178.5 13224.9 13260.2 13264.1 13276.5 758.3 889.6 904.1 924.2 930.5 913.4 914.7 918.7 919.7 920.5 957.0 958.2 958.7 959.9 960.0 946.1 948.3 948.3 949.0 950.7 700.0 703.8 703.9 704.1 708.3 13963.6 13988.2 14027.1 14066.9 14090.7 12227.6 12435.4 12609.2 12717.9 12777.7 9514.1 9547.1 9610.1 9612.7 9633.9 3306.6 3338.1 3380.4 3384.4 3408.8 48702.8 48810.5 48976.8 49032.8 50112.2 9562.9 9674.7 9715.6 9776.7 9825.5 19053.7 19092.3 19129.7 19158.3 19214.6 7436.3 7599.8 7663.3 7734.7 7837.5 4018.6 4023.6 4042.8 4071.3 4112.7 3227.5 3229.9 3280.0 3282.3 3283.7 592.9 614.8 621.5 625.3 625.9 672.6 741.3 747.6 751.4 763.4 8842.3 9107.4 9179.4 9250.4 9307.2 602.2 629.0 673.5 714.5 721.1 3794.6 3795.4 3883.1 3884.4 3998.0 58870.4 59242.4 59284.4 59409.4 60195.7 52362.0 52427.5 52796.7 52974.2 53745.0 49586.0 49786.5 51216.0 51243.2 57209.7 8969.9 8975.5 9167.5 9255.9 9292.6 36619.5 37606.4 38117.0 38377.8 39079.2 46311.8 46468.8 47256.0 47433.0 47718.8 12698.5 12798.6 13002.0 13031.9 13065.1 13380.5 13383.8 13519.7 13636.3 14057.8 11915.3 12076.9 12145.9 12241.4 12844.7 12116.8 12163.6 12243.7 12260.6 12283.7 11890.2 11951.2 12028.8 12295.2 12309.6 10016.5 10277.7 10770.4 10813.8 10856.9 10261.7 10698.9 10763.1 10787.6 10864.1 09:30:22 INFO - PROCESS | 18197 | -------- Summary: end -------- 09:30:22 INFO - PROCESS | 18197 | 09:30:22 INFO - TEST-INFO | 18197: exit 0 09:30:22 INFO - TEST-OK | dromaeo_css | took 1110947ms 09:30:22 INFO - TEST-START | kraken 09:30:22 INFO - operating with platform_type : linux_ 09:30:22 INFO - Initialising browser for kraken test... 09:30:22 INFO - TEST-INFO | started process 18445 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPM6VaO/profile http://localhost:35725/getInfo.html) 09:30:26 INFO - PROCESS | 18445 | 09:30:26 INFO - PROCESS | 18445 | (process:18522): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 09:30:27 INFO - PROCESS | 18445 | __metrics Screen width/height:1600/1200 09:30:27 INFO - PROCESS | 18445 | colorDepth:24 09:30:27 INFO - PROCESS | 18445 | Browser inner width/height: 1024/697 09:30:27 INFO - PROCESS | 18445 | __metrics 09:30:28 ERROR - PROCESS | 18445 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2849: TypeError: this.tabs is undefined 09:30:29 INFO - TEST-INFO | 18445: exit 0 09:30:29 INFO - Browser initialized. 09:30:29 INFO - Running cycle 1/1 for kraken test... 09:30:29 INFO - TEST-INFO | started process 18573 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpPM6VaO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:30:30 INFO - PROCESS | 18573 | 09:30:30 INFO - PROCESS | 18573 | (process:18642): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 09:30:40 INFO - PROCESS | 18573 | RSS: Main: 151355392 09:30:40 INFO - PROCESS | 18573 | 09:30:43 INFO - PROCESS | 18573 | Cycle 1(1): loaded http://localhost:35725/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35725/tests/kraken/driver.html?testName=audio-beat-detection) 09:30:44 INFO - PROCESS | 18573 | RSS: Main: 155832320 09:30:44 INFO - PROCESS | 18573 | 09:30:46 INFO - PROCESS | 18573 | Cycle 1(1): loaded http://localhost:35725/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35725/tests/kraken/driver.html?testName=audio-dft) 09:30:47 INFO - PROCESS | 18573 | RSS: Main: 156119040 09:30:47 INFO - PROCESS | 18573 | 09:30:50 INFO - PROCESS | 18573 | Cycle 1(1): loaded http://localhost:35725/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35725/tests/kraken/driver.html?testName=audio-fft) 09:30:50 INFO - PROCESS | 18573 | RSS: Main: 156676096 09:30:50 INFO - PROCESS | 18573 | 09:30:52 INFO - PROCESS | 18573 | Cycle 1(1): loaded http://localhost:35725/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35725/tests/kraken/driver.html?testName=audio-oscillator) 09:30:53 INFO - PROCESS | 18573 | RSS: Main: 156495872 09:30:53 INFO - PROCESS | 18573 | 09:30:55 INFO - PROCESS | 18573 | Cycle 1(1): loaded http://localhost:35725/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35725/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:30:56 INFO - PROCESS | 18573 | RSS: Main: 156499968 09:30:56 INFO - PROCESS | 18573 | 09:30:59 INFO - PROCESS | 18573 | Cycle 1(1): loaded http://localhost:35725/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35725/tests/kraken/driver.html?testName=imaging-darkroom) 09:30:59 INFO - PROCESS | 18573 | RSS: Main: 156545024 09:30:59 INFO - PROCESS | 18573 | 09:31:03 INFO - PROCESS | 18573 | Cycle 1(1): loaded http://localhost:35725/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35725/tests/kraken/driver.html?testName=imaging-desaturate) 09:31:03 INFO - PROCESS | 18573 | RSS: Main: 156749824 09:31:03 INFO - PROCESS | 18573 | 09:31:06 INFO - PROCESS | 18573 | Cycle 1(1): loaded http://localhost:35725/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35725/tests/kraken/driver.html?testName=json-parse-financial) 09:31:07 INFO - PROCESS | 18573 | RSS: Main: 156749824 09:31:07 INFO - PROCESS | 18573 | 09:31:08 INFO - PROCESS | 18573 | Cycle 1(1): loaded http://localhost:35725/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35725/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:31:09 INFO - PROCESS | 18573 | RSS: Main: 156631040 09:31:09 INFO - PROCESS | 18573 | 09:31:11 INFO - PROCESS | 18573 | Cycle 1(1): loaded http://localhost:35725/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35725/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:31:11 INFO - PROCESS | 18573 | RSS: Main: 156778496 09:31:11 INFO - PROCESS | 18573 | 09:31:13 INFO - PROCESS | 18573 | Cycle 1(1): loaded http://localhost:35725/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35725/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:31:14 INFO - PROCESS | 18573 | RSS: Main: 157143040 09:31:14 INFO - PROCESS | 18573 | 09:31:16 INFO - PROCESS | 18573 | Cycle 1(1): loaded http://localhost:35725/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35725/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:31:17 INFO - PROCESS | 18573 | RSS: Main: 158031872 09:31:17 INFO - PROCESS | 18573 | 09:31:19 INFO - PROCESS | 18573 | Cycle 1(1): loaded http://localhost:35725/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35725/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:31:20 INFO - PROCESS | 18573 | RSS: Main: 157401088 09:31:20 INFO - PROCESS | 18573 | 09:31:22 INFO - PROCESS | 18573 | Cycle 1(1): loaded http://localhost:35725/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35725/tests/kraken/driver.html?testName=ai-astar) 09:31:22 INFO - PROCESS | 18573 | RSS: Main: 159137792 09:31:22 INFO - PROCESS | 18573 | 09:31:22 INFO - PROCESS | 18573 | __start_tp_report 09:31:22 INFO - PROCESS | 18573 | _x_x_mozilla_page_load 09:31:22 INFO - PROCESS | 18573 | _x_x_mozilla_page_load_details 09:31:22 INFO - PROCESS | 18573 | |i|pagename|runs| 09:31:22 INFO - PROCESS | 18573 | |0;ai-astar;114;117;110;111;112;123;111;119;111;113 09:31:22 INFO - PROCESS | 18573 | |1;audio-beat-detection;159;139;153;167;133;159;155;132;177;128 09:31:22 INFO - PROCESS | 18573 | |2;audio-dft;164;163;163;163;163;162;163;163;166;162 09:31:22 INFO - PROCESS | 18573 | |3;audio-fft;76;74;75;182;78;76;74;76;165;76 09:31:22 INFO - PROCESS | 18573 | |4;audio-oscillator;94;94;138;93;92;93;145;93;93;140 09:31:22 INFO - PROCESS | 18573 | |5;imaging-gaussian-blur;118;117;118;118;118;118;118;117;117;117 09:31:22 INFO - PROCESS | 18573 | |6;imaging-darkroom;147;147;146;147;146;147;147;147;148;147 09:31:22 INFO - PROCESS | 18573 | |7;imaging-desaturate;96;96;96;95;97;95;96;97;96;95 09:31:22 INFO - PROCESS | 18573 | |8;json-parse-financial;57;59;58;59;57;59;59;67;59;56 09:31:22 INFO - PROCESS | 18573 | |9;json-stringify-tinderbox;66;63;64;64;64;65;64;64;65;67 09:31:22 INFO - PROCESS | 18573 | |10;stanford-crypto-aes;77;84;80;78;81;78;78;85;77;80 09:31:22 INFO - PROCESS | 18573 | |11;stanford-crypto-ccm;111;116;130;121;115;112;120;114;114;131 09:31:22 INFO - PROCESS | 18573 | |12;stanford-crypto-pbkdf2;139;147;148;146;142;146;149;145;146;145 09:31:22 INFO - PROCESS | 18573 | |13;stanford-crypto-sha256-iterative;70;67;65;67;66;65;67;65;69;66 09:31:22 INFO - PROCESS | 18573 | __end_tp_report 09:31:22 INFO - PROCESS | 18573 | __start_cc_report 09:31:22 INFO - PROCESS | 18573 | _x_x_mozilla_cycle_collect,827 09:31:22 INFO - PROCESS | 18573 | __end_cc_report 09:31:22 INFO - PROCESS | 18573 | __startTimestamp1461601882498__endTimestamp 09:31:22 INFO - PROCESS | 18573 | 09:31:22 INFO - PROCESS | 18573 | ------- Summary: start ------- 09:31:22 INFO - PROCESS | 18573 | Number of tests: 14 09:31:22 INFO - PROCESS | 18573 | 09:31:22 INFO - PROCESS | 18573 | [#0] ai-astar Cycles:10 Average:114.10 Median:113.50 stddev:4.25 (3.7%) stddev-sans-first:4.51 09:31:22 INFO - PROCESS | 18573 | Values: 114.0 117.0 110.0 111.0 112.0 123.0 111.0 119.0 111.0 113.0 09:31:22 INFO - PROCESS | 18573 | 09:31:22 INFO - PROCESS | 18573 | [#1] audio-beat-detection Cycles:10 Average:150.20 Median:157.00 stddev:16.44 (10.5%) stddev-sans-first:17.12 09:31:22 INFO - PROCESS | 18573 | Values: 159.0 139.0 153.0 167.0 133.0 159.0 155.0 132.0 177.0 128.0 09:31:22 INFO - PROCESS | 18573 | 09:31:22 INFO - PROCESS | 18573 | [#2] audio-dft Cycles:10 Average:163.20 Median:163.00 stddev:1.14 (0.7%) stddev-sans-first:1.17 09:31:22 INFO - PROCESS | 18573 | Values: 164.0 163.0 163.0 163.0 163.0 162.0 163.0 163.0 166.0 162.0 09:31:22 INFO - PROCESS | 18573 | 09:31:22 INFO - PROCESS | 18573 | [#3] audio-fft Cycles:10 Average:95.20 Median:76.00 stddev:41.48 (54.6%) stddev-sans-first:43.41 09:31:22 INFO - PROCESS | 18573 | Values: 76.0 74.0 75.0 182.0 78.0 76.0 74.0 76.0 165.0 76.0 09:31:22 INFO - PROCESS | 18573 | 09:31:22 INFO - PROCESS | 18573 | [#4] audio-oscillator Cycles:10 Average:107.50 Median:93.00 stddev:23.19 (24.9%) stddev-sans-first:24.07 09:31:22 INFO - PROCESS | 18573 | Values: 94.0 94.0 138.0 93.0 92.0 93.0 145.0 93.0 93.0 140.0 09:31:22 INFO - PROCESS | 18573 | 09:31:22 INFO - PROCESS | 18573 | [#5] imaging-gaussian-blur Cycles:10 Average:117.60 Median:118.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 09:31:22 INFO - PROCESS | 18573 | Values: 118.0 117.0 118.0 118.0 118.0 118.0 118.0 117.0 117.0 117.0 09:31:22 INFO - PROCESS | 18573 | 09:31:22 INFO - PROCESS | 18573 | [#6] imaging-darkroom Cycles:10 Average:146.90 Median:147.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 09:31:22 INFO - PROCESS | 18573 | Values: 147.0 147.0 146.0 147.0 146.0 147.0 147.0 147.0 148.0 147.0 09:31:22 INFO - PROCESS | 18573 | 09:31:22 INFO - PROCESS | 18573 | [#7] imaging-desaturate Cycles:10 Average:95.90 Median:96.00 stddev:0.74 (0.8%) stddev-sans-first:0.78 09:31:22 INFO - PROCESS | 18573 | Values: 96.0 96.0 96.0 95.0 97.0 95.0 96.0 97.0 96.0 95.0 09:31:22 INFO - PROCESS | 18573 | 09:31:22 INFO - PROCESS | 18573 | [#8] json-parse-financial Cycles:10 Average:59.00 Median:59.00 stddev:3.02 (5.1%) stddev-sans-first:3.11 09:31:22 INFO - PROCESS | 18573 | Values: 57.0 59.0 58.0 59.0 57.0 59.0 59.0 67.0 59.0 56.0 09:31:22 INFO - PROCESS | 18573 | 09:31:22 INFO - PROCESS | 18573 | [#9] json-stringify-tinderbox Cycles:10 Average:64.60 Median:64.50 stddev:1.17 (1.8%) stddev-sans-first:1.13 09:31:22 INFO - PROCESS | 18573 | Values: 66.0 63.0 64.0 64.0 64.0 65.0 64.0 64.0 65.0 67.0 09:31:22 INFO - PROCESS | 18573 | 09:31:22 INFO - PROCESS | 18573 | [#10] stanford-crypto-aes Cycles:10 Average:79.80 Median:80.00 stddev:2.82 (3.5%) stddev-sans-first:2.80 09:31:22 INFO - PROCESS | 18573 | Values: 77.0 84.0 80.0 78.0 81.0 78.0 78.0 85.0 77.0 80.0 09:31:22 INFO - PROCESS | 18573 | 09:31:22 INFO - PROCESS | 18573 | [#11] stanford-crypto-ccm Cycles:10 Average:118.40 Median:118.00 stddev:7.11 (6.0%) stddev-sans-first:7.01 09:31:22 INFO - PROCESS | 18573 | Values: 111.0 116.0 130.0 121.0 115.0 112.0 120.0 114.0 114.0 131.0 09:31:22 INFO - PROCESS | 18573 | 09:31:22 INFO - PROCESS | 18573 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:145.30 Median:146.00 stddev:2.91 (2.0%) stddev-sans-first:2.00 09:31:22 INFO - PROCESS | 18573 | Values: 139.0 147.0 148.0 146.0 142.0 146.0 149.0 145.0 146.0 145.0 09:31:22 INFO - PROCESS | 18573 | 09:31:22 INFO - PROCESS | 18573 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.70 Median:67.00 stddev:1.70 (2.5%) stddev-sans-first:1.32 09:31:22 INFO - PROCESS | 18573 | Values: 70.0 67.0 65.0 67.0 66.0 65.0 67.0 65.0 69.0 66.0 09:31:22 INFO - PROCESS | 18573 | -------- Summary: end -------- 09:31:22 INFO - PROCESS | 18573 | 09:31:23 INFO - TEST-INFO | 18573: exit 0 09:31:23 INFO - TEST-OK | kraken | took 60442ms 09:31:23 INFO - SUITE-END | took 1171s 09:31:23 INFO - Completed test suite (00:19:31) 09:31:23 INFO - javascript benchmark 09:31:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3339.7662163648542, 3396.03213084271, 3414.436617958037, 3439.6904278614934, 3451.534042904207, 5593.328800543935, 5599.160125981105, 5630.662160270386, 5643.068893632556, 5663.207150998864, 5629.324481062268, 5649.519790817775, 5660.490555849972, 5669.518090962263, 5672.319321681393, 5048.545630893228, 5158.3552055993005, 5159.148740457838, 5187.299714538545, 5190.844274671766, 5020.573251273646, 5149.382074151107, 5203.19350500673, 5213.322268105141, 5214.765335559909, 5120.411152717448, 5135.2040433732645, 5177.922331165017, 5179.093658609747, 5283.286756287909, 5182.507661772125, 5203.349581302318, 5208.9739551302, 5211.947880521184, 5242.606804489678, 4670.766461676936, 5120.411152717448, 5142.3829140503, 5153.2785410042625, 5168.793248270064, 5469.042917489463, 5485.561155107622, 5499.1476321170285, 5511.22842802008, 5529.502344788948, 5901.793437229677, 6027.216461859974, 6052.818415447499, 6138.877222455526, 6147.815565533041, 6125.724342404603, 6127.877442451126, 6137.754489820401, 6139.201903752528, 6144.3717414885095, 5647.068233741427, 5691.26013618227, 5694.572907031948, 5698.572607054488, 5701.543876489871, 5954.047352423591, 6167.290761562388, 6205.937940620536, 6211.2857021442205, 6277.560570760094, 5971.28344598651, 5972.731227094791, 5983.760649574056, 5988.461038506555, 5995.910061349084, 6053.727582258721, 6064.878702426015, 6076.3316035236085, 6083.969580152159, 6094.939050609437, 5765.769369225184, 5963.165156878041, 6019.69901504923, 6021.909671354933, 6061.090836374579, 5950.077737950624, 5969.641821490724, 6002.279726432768, 6003.189569408161, 6018.668973206516, 4487.439070116236, 4530.320451932236, 4550.658700597469, 4557.703749256288, 4566.657500687462, 3999.2201520703766, 4033.556308806029, 4106.938395924063, 4135.503739551273, 4179.832806687759, 5636.2954630671165, 5723.5707321950185, 5738.426157384228, 5745.4541818528005, 5770.13447982806, 6097.5731698780655, 6107.694615269219, 6115.785947491905, 6115.847103822351, 6122.755089796358, 5555.416681248492, 5725.255716756795, 5808.912866307009, 5810.21562089121, 5827.24245848037, 5154.974225128851, 5193.76628051731, 5196.090684130262, 5212.218167274863, 5238.1618940969265, 6054.848628784228, 6258.405451482193, 6260.686965651699, 6263.404976527223, 6264.37356264379, 6089.451949324582, 6113.689848656775, 6132.509399248139, 6139.124180917401, 6179.3820617937845, 6197.318294987457, 6265.530085243534, 6268.843278918063, 6270.310265870842, 6274.278457977391, 5813.215215945793, 5944.405559444194, 6014.458698717136, 6025.939740602713, 6029.035354343284, 6074.878502430015, 6104.145419641342, 6112.113743507255, 6113.7153036952495, 6118.588722523033, 5834.679092649775, 5904.586678932434, 5927.792527261525, 5941.554383421347, 5993.490553303018, 5707.143928410772, 5852.209951656472, 5886.3525012247765, 5924.911126333022, 5925.4370834770625, 5831.7375718091935, 5838.5621078418435, 5843.1235314703135, 5845.853853653693, 5854.443827836349, 5999.1601175836295, 6014.609050411709, 6018.277806663228, 6030.246219222598, 6035.969820151046, 5581.218629391969, 5770.2498675171955, 5781.4507621775865, 5811.883762324815, 5901.203337549376], "unit": "score", "name": "dojo.html", "value": 5579.060228629746}, {"lowerIsBetter": false, "replicates": [561.9915701264479, 577.7198058941412, 577.9219805326279, 584.2711870975869, 623.8346838087906, 36612.81693591532, 37512.81243593782, 37682.24635507288, 37690.8115459423, 37846.62153378465, 39383.606163938355, 39867.80066099673, 40147.397789033115, 40157.397639035364, 40179.79910100453, 8639.136086391374, 8913.688020919237, 8974.730758077236, 8992.955035224815, 9055.909440905605, 8804.559772011406, 8948.552572371387, 8959.104089591054, 8961.551922403887, 8968.417052891573, 8911.465312081264, 9005.639774409048, 9011.0, 9012.549372531346, 9049.185573298402, 8883.200511953923, 8900.688475903346, 8964.686235981742, 8977.147171018809, 8990.190882820512, 8894.155055269805, 9021.458712477272, 9033.503157326344, 9033.54832258391, 9036.457812531204, 35008.649913501045, 39879.0, 40353.596464035574, 40426.0, 40441.0, 3481.8433170507133, 3687.50218720472, 3749.531308586427, 3791.0901383667997, 3826.1582451860545, 29651.40697186044, 30118.84940575305, 30191.69808301911, 30240.395192096254, 31041.37917241665, 3751.643593858579, 3772.1889793694486, 3799.9430008549894, 3833.769973801553, 3902.4731661225655, 230593.0, 231024.0, 231289.0, 231322.0, 231438.9999999983, 47055.5294447058, 61598.0, 62992.05511917325, 63261.367386325546, 63298.367016330165, 45124.548754512034, 45250.77374613106, 45267.32099018517, 45359.546404536195, 45400.3189952151, 16450.835491645168, 16678.16609169561, 16688.91655541739, 16704.081275529963, 16759.41342053022, 13143.277119758504, 13320.333983300796, 13322.933385333012, 13336.399862006234, 13368.598942031755, 15623.843761562466, 15890.0, 15924.124173170356, 15962.680746385007, 16036.839631603769, 5867.178594996619, 6026.969865150646, 6062.727177277033, 6070.726817293233, 6214.564980451415, 1054.9103326217357, 1104.745908441047, 1130.327455164176, 1133.7505748735266, 1170.4264910193945, 29717.257068573457, 30110.69889301079, 30137.397252054838, 30229.546556801666, 30237.848810755804, 1122.7305446692735, 1131.9830202546968, 1162.215504534436, 1162.9069674426025, 1169.9941500292614, 15342.923285383502, 15657.556470735055, 15699.764503532457, 15799.289031993589, 16089.597760056093, 335414.0, 339831.0, 340216.0, 342809.0, 343186.0, 56762.43237567654, 56943.0, 57622.423775762545, 57637.423625764044, 57971.0, 1494.6039299585618, 1495.7158139953374, 1500.1149321900132, 1513.9091654500764, 1513.9772903406458, 29038.854805725838, 29282.414351713272, 29393.706062939098, 30256.0, 30321.545176821925, 51358.0, 51769.0, 52000.73999629977, 52130.739346303024, 52145.0, 244150.0, 249227.0, 249770.0, 250546.0, 252372.0, 34889.302213956085, 35372.0, 35406.645933540334, 35522.289554208255, 35616.82191589026, 46671.0, 47464.813379665786, 47518.049639006334, 47550.52449475461, 48133.0, 39895.80052099721, 40009.79995100006, 40211.79894100628, 40963.38554921619, 41215.79392103021, 39329.0, 39406.802965984985, 39758.60241397549, 39857.40213896736, 40476.59523404728, 35684.46473302851, 39055.41416878692, 39527.60472395239, 40285.194296113325, 42173.789131055375, 32646.51030234501, 33349.83325083359, 33396.66603334033, 33499.49750753692, 33666.49500257547, 23193.420164496023, 23986.28041158768, 23996.52006959886, 24296.392590185387, 24379.14672986437], "unit": "score", "name": "ext.html", "value": 18737.26428243233}, {"lowerIsBetter": false, "replicates": [365.32050386281514, 389.873266260507, 393.7165241026461, 394.79076089672463, 402.239766840671, 17219.225134868928, 18039.0, 18394.0, 18436.26254949802, 18528.351507697233, 12122.212056216362, 12458.875411245885, 12466.0, 12603.054770892173, 12608.68478288041, 5169.56058735008, 5246.921296180561, 5654.802081927133, 5731.971340143294, 5735.913961290584, 5612.7754889804355, 5662.065759149736, 5682.630629009122, 5686.857828554278, 5696.174054762057, 5371.328583927009, 5588.4411558844195, 5598.468145526169, 5650.830475085754, 5675.4608312210485, 5510.9448905511035, 5524.309461317335, 5556.305461817273, 5581.665100093987, 5878.2064421303, 5710.828675139753, 5732.398098199671, 5742.167385729088, 5744.655320680731, 5762.654240745527, 13859.37632806516, 13991.230482323468, 14028.649283767885, 14164.716705665933, 14185.219812910285, 2383.749706280833, 2408.446057406806, 2472.888720007586, 2474.4061425257987, 2950.2476868398635, 9820.950895245549, 9827.0, 9894.55474503649, 9900.405975641484, 10083.294169408144, 1792.7400526923655, 1840.5766673665132, 1854.6568884756227, 1875.962480750391, 1925.0278609302352, 60541.39458605402, 63814.0, 64248.0, 68394.65802670954, 73868.26131738667, 10490.265681402228, 10556.26106172576, 10775.83836242457, 10887.346759194315, 11074.667759967213, 15397.538073857802, 15464.14947177916, 15491.147986860831, 15502.379904803682, 15544.378224871105, 7682.1549629540705, 7896.763097107096, 7998.280154785981, 8200.138985406566, 8273.586320684062, 7930.286274235346, 8053.838923221503, 8146.0632027317615, 8170.223828736261, 8225.095239523647, 4751.121042607128, 4817.397825271841, 4848.684835485753, 4883.926741098886, 4922.089413458521, 2005.7091721700563, 2007.1469625409143, 2033.644112280345, 2058.4442200606054, 2112.6619740841393, 927.1933417926447, 969.5782334684435, 1029.577873072037, 1040.4693606260862, 1067.6209945469247, 14898.329575169144, 17085.743713844302, 17255.74116388255, 17667.55831104233, 18081.547961301072, 976.3556053004983, 988.204495381225, 1036.44550165661, 1044.9425281609433, 1045.1899777672365, 9580.520973951274, 9811.166050885611, 9999.950000249952, 10041.598336066476, 10125.392476451289, 421820.0, 422313.0, 430396.0, 430832.0, 431390.0, 73647.63176184085, 76190.61904690551, 77605.22394776093, 78154.0, 80173.0, 1097.8517900083546, 1115.3753897817248, 1116.1684545014016, 1120.3222050659397, 1137.1357768096307, 14969.477041644188, 15601.375944962074, 15629.609259768142, 15631.531054067942, 15836.60408489797, 34202.31595368128, 34231.0, 34381.82809085939, 35204.29591408209, 35387.82306088453, 50231.74884125556, 50563.74718126386, 50692.23961640652, 50912.74543627406, 51047.234291484914, 8448.40861139739, 8467.788305292417, 8532.70135545253, 8703.47779133254, 9006.144416280444, 11349.829752553553, 11460.656180314603, 12038.337891416057, 12329.630111096682, 12580.182288151242, 10538.209634277306, 10680.412577308323, 10798.622048228275, 10862.131029517777, 11278.0, 10846.403447810446, 11033.22767406306, 11078.612248571262, 11290.604828830952, 11452.198346115692, 11144.832827507757, 11195.328280303207, 11221.71945701364, 11470.655880323604, 11598.478068487108, 8325.791855203668, 8452.788680283042, 8512.957435213031, 8856.247218986458, 8911.777205569913, 11114.0, 11645.126615504041, 11646.650600481998, 11772.646820595395, 11794.056475482112], "unit": "score", "name": "jquery.html", "value": 8659.76750339862}, {"lowerIsBetter": false, "replicates": [1524.5121561100445, 1531.127257463246, 1690.7379356199785, 1807.7978144533886, 1813.6372725454903, 7535.20880307568, 8157.347412207024, 8279.254867061964, 8566.528840913747, 8569.400141990056, 8196.0, 8272.379571532145, 8347.499150051, 8388.580570971457, 8411.495310281372, 2399.748026457223, 2402.351365131418, 2412.2763171048705, 2414.843035202715, 2426.684531010966, 2381.892814823337, 2385.0459816073535, 2425.672534207877, 2426.6360045993065, 2435.744246854081, 2370.431096536836, 2372.489914668346, 2396.4727759892794, 2433.598456254716, 2450.0567281596536, 2401.7478164792706, 2408.771166739157, 2415.1184817541557, 2422.9757702422926, 2467.9136230232034, 2357.233898982837, 2374.5607062693457, 2385.642153676945, 2394.7006624171977, 2396.544656515274, 8500.149985001512, 8546.0, 8554.0, 8566.657333706644, 8598.699045533438, 5128.25640282157, 5217.791288348461, 5224.190250511138, 5224.4775522447835, 5247.02929957959, 3177.2215807127172, 3250.528673342376, 3285.4250506161325, 3438.1060924159897, 3454.913627159316, 1123.4214379594516, 1123.5842738186923, 1137.2323681515027, 1143.822707480342, 1150.3155622404656, 11591.536338546448, 11622.70943226426, 11644.883551164465, 11659.00898423635, 11683.065354771514, 4511.481179664315, 4519.796609152596, 4569.565891240327, 4583.289590113538, 4602.148602508547, 10071.0, 10148.441835699108, 10149.492525373702, 10163.136133428594, 10173.237007224488, 5180.766865491062, 5226.137687281632, 5256.001359741636, 5259.63182577216, 5274.7098909560345, 4095.5494895561465, 4670.486246512881, 4773.689710168828, 4778.522147785194, 4810.7113573185725, 3791.563970143414, 3886.4947556817997, 3910.452536644872, 3931.331673615435, 3986.621270979252, 1269.0862578943147, 1273.4715093236389, 1276.7765641012786, 1278.9360531973364, 1301.3363184775644, 1146.656003199037, 1149.6321177223208, 1151.7005578549638, 1363.9454421823016, 1428.7999680044802, 7781.06627204739, 7925.445218834741, 7948.0, 7962.084360298524, 8003.279704826477, 1133.7675776465805, 1144.5650652751901, 1192.4633914738229, 1218.7318789866392, 1260.04236780046, 6184.072389141665, 6222.439980401786, 6265.467435268053, 6289.245290565162, 6330.8100756977365, 9974.700758977242, 10234.130098941674, 10294.227932905062, 10369.118624916815, 10425.426601536989, 10262.897371026344, 10286.691399258034, 10407.53166107527, 10573.312734672376, 10596.364218146937, 7965.920340796633, 7978.880316795136, 7992.480488768211, 8006.559639219899, 8027.5584842834205, 7611.0, 7640.694372225049, 7691.269329413697, 7715.382769378549, 7763.961180194063, 8704.173103555153, 8950.0, 8955.865662014945, 9087.36388452802, 9105.499197544199, 9125.589348479201, 9133.817323653622, 9187.632494700405, 9222.723318300461, 9298.535073246447, 6261.186045814015, 6825.2151002633345, 7002.719891204295, 7165.677544510405, 7309.634518274172, 7987.361011119213, 8011.0, 8053.0, 8072.233137851895, 8117.878231826409, 7229.385502232369, 7394.186639469546, 7431.591262480615, 7460.962695186489, 7468.066491688539, 7326.597037163007, 7448.962755186406, 7596.354309883722, 7608.923910761043, 7629.809254768676, 7311.305425984523, 7374.926250737424, 7409.444291678038, 7468.5145465544565, 7480.925190748023, 6869.7595584154315, 6895.482838787011, 6906.412954898889, 6963.512554121163, 7123.287671233043, 6942.305769423017, 6955.547889387173, 6994.755183568551, 7007.194172670208, 7020.157581090302], "unit": "score", "name": "mootools.html", "value": 4839.558862628039}, {"lowerIsBetter": false, "replicates": [370.37961414630496, 371.5133175540041, 373.98878033658985, 374.57859907603944, 376.2099590859196, 6368.299487056426, 6422.839429014277, 6436.839079023015, 6457.773977910774, 6460.580062295948, 6272.9372706272925, 6314.0, 6320.525960552954, 6346.111544383778, 6361.332060133689, 4718.268668356393, 4792.496787837279, 4847.248676455138, 4867.099586576477, 4949.183384741522, 4825.758712064383, 4842.2252439609665, 4847.1032858921035, 4851.733154676492, 4874.415070191564, 4776.211925032366, 4818.25317075852, 4823.734694591796, 4824.517548245182, 4826.0, 4823.155947709154, 4826.5414785595485, 4834.975825120852, 4844.2733589961435, 4851.078295123915, 4237.618614324695, 4239.964816887911, 4246.044639956016, 4251.3622956556765, 4252.617264446215, 5807.506361959238, 5975.25309336333, 5991.161237426763, 5993.910091348634, 5998.220231369894, 5557.055300598908, 5559.16612508127, 5564.610477266593, 5576.358718747356, 5588.3573389060375, 6537.0, 6565.474762019075, 6590.945448728228, 6608.107905432753, 6613.537052406334, 4593.494715581283, 4636.0, 4639.420072490939, 4650.116477869194, 4656.604188643969, 6940.854758964765, 6997.965010174968, 7024.859502809915, 7026.543274687129, 7028.648567571652, 4671.719696818134, 4836.806527738921, 4846.951530484721, 4850.029994001214, 4853.684510506806, 4846.7818948147415, 4892.828750993698, 4896.632752543574, 4899.926501102486, 4905.994271174401, 5619.494245517923, 5629.296337957755, 5646.6047376684055, 5669.348024977098, 5673.914891276634, 6779.0, 6781.762638307636, 6788.219354774165, 6804.421624161902, 6816.250212476623, 1717.4761697682077, 1737.7480265361453, 1743.808181100078, 1744.7644567983414, 1748.5978225008064, 1107.617871834216, 1124.0670243697712, 1139.811931031387, 1147.552454542729, 1149.9310041397544, 6492.415682588589, 6506.642134682638, 6519.0, 6545.5090868184125, 6558.213014438298, 6962.547434416747, 6972.2330543640155, 6982.26686197952, 6995.8950615740805, 7009.649517524103, 6322.0, 6430.742770289229, 6443.838904027343, 6551.475881929529, 6598.175228096488, 3798.1074447504816, 3803.581606023335, 3820.4269359596283, 3823.636754508317, 3836.731428800013, 7252.709891604277, 7258.709651613876, 7259.201487836333, 7269.963650181715, 7280.41756659466, 7213.531120477257, 7219.855602887913, 7223.674934627954, 7254.093238345207, 7254.492185547067, 7083.220845706968, 7183.712651493986, 7280.708771649181, 7309.5248808827255, 7312.744053958192, 7010.789676309719, 7034.437245020284, 7035.366816986496, 7046.224915259215, 7079.8938015930835, 6923.307669233238, 6937.895931561132, 6945.2707465715375, 6949.235584085645, 6951.374376306157, 7212.7475538358, 7278.854422911406, 7284.781456556312, 7295.562266264041, 7348.1182258129365, 4697.953020469751, 4747.928781068218, 4843.104025755247, 4856.028794241095, 4876.902461950812, 6919.065926099912, 6927.168739751262, 6929.3070692930305, 6967.895481567679, 6970.372666460043, 6693.765718200035, 6765.154355705537, 6766.221884483348, 6780.050792889098, 6783.287754785873, 6756.358145976124, 6780.32196780318, 6788.626625535643, 6796.422304104207, 6799.762008329685, 6441.742330306735, 6544.738210471528, 6659.034440006075, 6666.433353165035, 6668.599884006975, 6825.897611535732, 6852.862942741018, 6871.828204294733, 6898.551594146365, 6902.206246281742, 6685.298043705341, 6717.899231511434, 6732.192136943598, 6735.562188457735, 6736.02327662496], "unit": "score", "name": "prototype.html", "value": 5108.483477752525}, {"lowerIsBetter": false, "replicates": [705.1573369823062, 786.3237615650543, 795.7811601809501, 797.2027972027972, 806.2904643913355, 13188.538401155962, 13376.933115334434, 13405.798913016288, 13408.195508269486, 13432.932835335834, 13178.472861085545, 13224.933875330635, 13260.204387736743, 13264.071514993955, 13276.46894124234, 758.3137260779004, 889.5863423508067, 904.055262250946, 924.2375040591505, 930.4928813796463, 913.3971578758021, 914.7347269291897, 918.669279059538, 919.737874705709, 920.4661296448078, 956.9760755981087, 958.1663952361455, 958.7267628725815, 959.9232061435068, 960.0207788056179, 946.1106559833735, 948.2698322291831, 948.3219498058921, 948.9715308540685, 950.6862735297335, 699.9755008574675, 703.8275622472476, 703.9225685174627, 704.0671110778188, 708.3129364516411, 13963.58109256724, 13988.230647314394, 14027.088239264518, 14066.929665351608, 14090.718185636333, 12227.633171004883, 12435.44040518179, 12609.180403273758, 12717.936410317981, 12777.680557986032, 9514.143727064597, 9547.140757331805, 9610.135087842127, 9612.66355677548, 9633.855492167622, 3306.553615261957, 3338.0987133473827, 3380.3577320309064, 3384.3569721752788, 3408.812515311657, 48702.756486217695, 48810.511894880954, 48976.75511622454, 49032.75483622595, 50112.24831627528, 9562.904370956341, 9674.70975870725, 9715.611375544899, 9776.657816976514, 9825.459599722084, 19053.7141942871, 19092.33176838804, 19129.713054304197, 19158.329458469165, 19214.615707685767, 7436.256374362521, 7599.848003039909, 7663.27198916102, 7734.729284475016, 7837.49056311338, 4018.6383225509844, 4023.5574086850424, 4042.8382864685673, 4071.2875246831686, 4112.712110152261, 3227.483602623569, 3229.9031029069165, 3279.9832052063934, 3282.3271229397915, 3283.737301015913, 592.8636413624804, 614.8094090831856, 621.5338496127904, 625.3121566277059, 625.8529245627274, 672.6031641331638, 741.33650382907, 747.637395863006, 751.4476859508258, 763.3625922354785, 8842.292616590657, 9107.426301025322, 9179.357444978787, 9250.352475326798, 9307.162355388047, 602.1960682488926, 628.9654069026155, 673.4780545077506, 714.5355518912644, 721.1490441279236, 3794.6015668354976, 3795.35478968576, 3883.10688541637, 3884.4173373993563, 3997.9805149686645, 58870.41129588735, 59242.40757592455, 59284.40715592789, 59409.405905941254, 60195.69902150461, 52362.0, 52427.47572524302, 52796.73601632044, 52974.205386919224, 53745.0, 49586.0, 49786.50213497891, 51216.0, 51243.23135152976, 57209.71395143081, 8969.865452018095, 8975.461472311683, 9167.49578773174, 9255.907440925504, 9292.62829486813, 36619.450708238866, 37606.435903462014, 38117.0, 38377.80811095927, 39079.218415632095, 46311.76844115893, 46468.7676561615, 47256.0, 47433.0, 47718.761406192745, 12698.492060317483, 12798.61604151877, 13002.0, 13031.869681303066, 13065.085444018827, 13380.531681391105, 13383.799243011168, 13519.662008449473, 13636.250006249355, 14057.789133162807, 11915.285082895054, 12076.879231207575, 12145.939270303592, 12241.387930603612, 12844.743105138032, 12116.757664846476, 12163.635090947286, 12243.69390765238, 12260.632181034583, 12283.69290767738, 11890.227135236182, 11951.22317049389, 12028.819567706667, 12295.200811947196, 12309.56916507918, 10016.546389697474, 10277.691669249934, 10770.353778772986, 10813.837792432962, 10856.945715271373, 10261.692149235534, 10698.8930110701, 10763.138948884227, 10787.568497260021, 10864.130869530576], "unit": "score", "name": "yui.html", "value": 6633.585704165244}], "name": "dromaeo_css", "value": 7276.828600419137}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [114.0, 117.0, 110.0, 111.0, 112.0, 123.0, 111.0, 119.0, 111.0, 113.0], "unit": "score", "name": "ai-astar", "value": 114.1}, {"lowerIsBetter": true, "replicates": [159.0, 139.0, 153.0, 167.0, 133.0, 159.0, 155.0, 132.0, 177.0, 128.0], "unit": "score", "name": "audio-beat-detection", "value": 150.2}, {"lowerIsBetter": true, "replicates": [164.0, 163.0, 163.0, 163.0, 163.0, 162.0, 163.0, 163.0, 166.0, 162.0], "unit": "score", "name": "audio-dft", "value": 163.2}, {"lowerIsBetter": true, "replicates": [76.0, 74.0, 75.0, 182.0, 78.0, 76.0, 74.0, 76.0, 165.0, 76.0], "unit": "score", "name": "audio-fft", "value": 95.2}, {"lowerIsBetter": true, "replicates": [94.0, 94.0, 138.0, 93.0, 92.0, 93.0, 145.0, 93.0, 93.0, 140.0], "unit": "score", "name": "audio-oscillator", "value": 107.5}, {"lowerIsBetter": true, "replicates": [118.0, 117.0, 118.0, 118.0, 118.0, 118.0, 118.0, 117.0, 117.0, 117.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 117.6}, {"lowerIsBetter": true, "replicates": [147.0, 147.0, 146.0, 147.0, 146.0, 147.0, 147.0, 147.0, 148.0, 147.0], "unit": "score", "name": "imaging-darkroom", "value": 146.9}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 96.0, 95.0, 97.0, 95.0, 96.0, 97.0, 96.0, 95.0], "unit": "score", "name": "imaging-desaturate", "value": 95.9}, {"lowerIsBetter": true, "replicates": [57.0, 59.0, 58.0, 59.0, 57.0, 59.0, 59.0, 67.0, 59.0, 56.0], "unit": "score", "name": "json-parse-financial", "value": 59.0}, {"lowerIsBetter": true, "replicates": [66.0, 63.0, 64.0, 64.0, 64.0, 65.0, 64.0, 64.0, 65.0, 67.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 64.6}, {"lowerIsBetter": true, "replicates": [77.0, 84.0, 80.0, 78.0, 81.0, 78.0, 78.0, 85.0, 77.0, 80.0], "unit": "score", "name": "stanford-crypto-aes", "value": 79.8}, {"lowerIsBetter": true, "replicates": [111.0, 116.0, 130.0, 121.0, 115.0, 112.0, 120.0, 114.0, 114.0, 131.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 118.4}, {"lowerIsBetter": true, "replicates": [139.0, 147.0, 148.0, 146.0, 142.0, 146.0, 149.0, 145.0, 146.0, 145.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 145.3}, {"lowerIsBetter": true, "replicates": [70.0, 67.0, 65.0, 67.0, 66.0, 65.0, 67.0, 65.0, 69.0, 66.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 66.7}], "name": "kraken", "value": 1524.3999999999999}]} 09:31:23 INFO - Return code: 0 09:31:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:31:23 ERROR - # TBPL SUCCESS # 09:31:23 INFO - Running post-action listener: _resource_record_post_action 09:31:23 INFO - Running post-run listener: _resource_record_post_run 09:31:24 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 278528; Write bytes: 480157696; Read time: 44; Write time: 459940 09:31:24 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 150495232; Read time: 0; Write time: 190000 09:31:24 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 278528; Write bytes: 253407232; Read time: 44; Write time: 168444 09:31:24 INFO - Running post-run listener: _upload_blobber_files 09:31:24 INFO - Blob upload gear active. 09:31:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:31:24 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 09:31:24 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'] 09:31:24 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 09:31:25 INFO - (blobuploader) - INFO - Open directory for files ... 09:31:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:31:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:31:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:31:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:31:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:31:26 INFO - (blobuploader) - INFO - Done attempting. 09:31:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:31:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:31:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:31:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:31:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:31:26 INFO - (blobuploader) - INFO - Done attempting. 09:31:26 INFO - (blobuploader) - INFO - Iteration through files over. 09:31:26 INFO - Return code: 0 09:31:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:31:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:31:26 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/118cd1355428a5a05daf72b8f30f490a273a1b8711be98361b2b0ff43c1f20a380ce413bdf21291f4fbdd9319649f34cbdf0492dbd7e2ccfc6c06ca68462c6ea", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7da66e6a468374cb81f0c10a2339d2cbc4e0fdb6cdf20cf8e1a676b4179bd21d7ad9eeec3e3c005c797df6e3948cd7cc9b949c778da762da326263d263eb94ff"} 09:31:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:31:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:31:26 INFO - Contents: 09:31:26 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/118cd1355428a5a05daf72b8f30f490a273a1b8711be98361b2b0ff43c1f20a380ce413bdf21291f4fbdd9319649f34cbdf0492dbd7e2ccfc6c06ca68462c6ea", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7da66e6a468374cb81f0c10a2339d2cbc4e0fdb6cdf20cf8e1a676b4179bd21d7ad9eeec3e3c005c797df6e3948cd7cc9b949c778da762da326263d263eb94ff"} 09:31:26 INFO - Running post-run listener: copy_logs_to_upload_dir 09:31:26 INFO - Copying logs to upload dir... 09:31:26 INFO - mkdir: /builds/slave/test/build/upload/logs 09:31:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1246.358671 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-04-25 09:31:26.744184) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-25 09:31:26.747444) ========= 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=c6927cf182745ad25b1d38b9000001fe-1461599278.47402-1034304102 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/118cd1355428a5a05daf72b8f30f490a273a1b8711be98361b2b0ff43c1f20a380ce413bdf21291f4fbdd9319649f34cbdf0492dbd7e2ccfc6c06ca68462c6ea", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7da66e6a468374cb81f0c10a2339d2cbc4e0fdb6cdf20cf8e1a676b4179bd21d7ad9eeec3e3c005c797df6e3948cd7cc9b949c778da762da326263d263eb94ff"} build_url:https://queue.taskcluster.net/v1/task/ZpuBLsfqRea_aIl5YentDQ/artifacts/public/build/firefox-47.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011455 build_url: 'https://queue.taskcluster.net/v1/task/ZpuBLsfqRea_aIl5YentDQ/artifacts/public/build/firefox-47.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/118cd1355428a5a05daf72b8f30f490a273a1b8711be98361b2b0ff43c1f20a380ce413bdf21291f4fbdd9319649f34cbdf0492dbd7e2ccfc6c06ca68462c6ea", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7da66e6a468374cb81f0c10a2339d2cbc4e0fdb6cdf20cf8e1a676b4179bd21d7ad9eeec3e3c005c797df6e3948cd7cc9b949c778da762da326263d263eb94ff"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-25 09:31:26.772331) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 09:31:26.772679) ========= 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=c6927cf182745ad25b1d38b9000001fe-1461599278.47402-1034304102 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004709 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 09:31:26.858076) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-25 09:31:26.858417) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-25 09:31:26.858789) ========= ========= Total master_lag: 0.17 =========