builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-037 starttime: 1461031164.65 results: success (0) buildid: 20160418163008 builduid: af71cc78be7a492ab9ea1f85b2527209 revision: 67ac40fb8f680ea5e03805552187ba1b5e8392a1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-18 18:59:24.653941) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-18 18:59:24.654500) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-18 18:59:24.654791) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-18 18:59:24.671668) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 18:59:24.671953) ========= 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=aa08bb06ff6271ef470a76a4000001be-1461025569.946708-128098684 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-04-18 18:59:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 218M=0s 2016-04-18 18:59:25 (218 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.071104 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 18:59:24.757120) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 18:59:24.757476) ========= 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=aa08bb06ff6271ef470a76a4000001be-1461025569.946708-128098684 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022956 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 18:59:24.808211) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 18:59:24.808612) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 67ac40fb8f680ea5e03805552187ba1b5e8392a1 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 67ac40fb8f680ea5e03805552187ba1b5e8392a1 --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=aa08bb06ff6271ef470a76a4000001be-1461025569.946708-128098684 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-04-18 18:59:25,318 truncating revision to first 12 chars 2016-04-18 18:59:25,318 Setting DEBUG logging. 2016-04-18 18:59:25,318 attempt 1/10 2016-04-18 18:59:25,318 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/67ac40fb8f68?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-18 18:59:26,001 unpacking tar archive at: mozilla-central-67ac40fb8f68/testing/mozharness/ program finished with exit code 0 elapsedTime=0.971035 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 18:59:25.792846) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-18 18:59:25.793196) ========= script_repo_revision: 67ac40fb8f680ea5e03805552187ba1b5e8392a1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-18 18:59:25.793621) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-18 18:59:25.793896) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-18 18:59:25.802208) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2016-04-18 18:59:25.802522) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=aa08bb06ff6271ef470a76a4000001be-1461025569.946708-128098684 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:59:26 INFO - MultiFileLogger online at 20160418 18:59:26 in /builds/slave/test 18:59:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 18:59:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:59:26 INFO - {'append_to_log': False, 18:59:26 INFO - 'base_work_dir': '/builds/slave/test', 18:59:26 INFO - 'blob_upload_branch': 'Firefox', 18:59:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:59:26 INFO - 'branch': 'Firefox', 18:59:26 INFO - 'buildbot_json_path': 'buildprops.json', 18:59:26 INFO - 'config_files': ('talos/linux_config.py',), 18:59:26 INFO - 'default_actions': ('clobber', 18:59:26 INFO - 'read-buildbot-config', 18:59:26 INFO - 'download-and-extract', 18:59:26 INFO - 'populate-webroot', 18:59:26 INFO - 'create-virtualenv', 18:59:26 INFO - 'install', 18:59:26 INFO - 'run-tests'), 18:59:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:59:26 INFO - 'download_minidump_stackwalk': True, 18:59:26 INFO - 'download_symbols': 'ondemand', 18:59:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:59:26 INFO - 'tooltool.py': '/tools/tooltool.py', 18:59:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:59:26 INFO - '/tools/misc-python/virtualenv.py')}, 18:59:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:59:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:59:26 INFO - 'installer_path': 'installer.exe', 18:59:26 INFO - 'log_level': 'info', 18:59:26 INFO - 'log_name': 'talos', 18:59:26 INFO - 'log_to_console': True, 18:59:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:59:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:59:26 INFO - 'opt_config_files': (), 18:59:26 INFO - 'pip_index': False, 18:59:26 INFO - 'sps_profile': False, 18:59:26 INFO - 'sps_profile_interval': 0, 18:59:26 INFO - 'suite': 'dromaeojs', 18:59:26 INFO - 'system_bits': '32', 18:59:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:59:26 INFO - 'title': 'talos-linux64-ix-037', 18:59:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:59:26 INFO - 'use_talos_json': True, 18:59:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:59:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:59:26 INFO - 'work_dir': 'build'} 18:59:26 INFO - ##### 18:59:26 INFO - ##### Running clobber step. 18:59:26 INFO - ##### 18:59:26 INFO - Running pre-action listener: _resource_record_pre_action 18:59:26 INFO - Running main action method: clobber 18:59:26 INFO - rmtree: /builds/slave/test/build 18:59:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:59:27 INFO - Running post-action listener: _resource_record_post_action 18:59:27 INFO - ##### 18:59:27 INFO - ##### Running read-buildbot-config step. 18:59:27 INFO - ##### 18:59:27 INFO - Running pre-action listener: _resource_record_pre_action 18:59:27 INFO - Running main action method: read_buildbot_config 18:59:27 INFO - Using buildbot properties: 18:59:27 INFO - { 18:59:27 INFO - "project": "", 18:59:27 INFO - "product": "firefox", 18:59:27 INFO - "script_repo_revision": "production", 18:59:27 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 18:59:27 INFO - "repository": "", 18:59:27 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 18:59:27 INFO - "buildid": "20160418163008", 18:59:27 INFO - "pgo_build": "True", 18:59:27 INFO - "basedir": "/builds/slave/test", 18:59:27 INFO - "buildnumber": 409, 18:59:27 INFO - "slavename": "talos-linux64-ix-037", 18:59:27 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 18:59:27 INFO - "platform": "ubuntu64_hw", 18:59:27 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 18:59:27 INFO - "branch": "mozilla-central", 18:59:27 INFO - "repo_path": "mozilla-central", 18:59:27 INFO - "revision": "67ac40fb8f680ea5e03805552187ba1b5e8392a1", 18:59:27 INFO - "stage_platform": "linux64-pgo", 18:59:27 INFO - "builduid": "af71cc78be7a492ab9ea1f85b2527209", 18:59:27 INFO - "slavebuilddir": "test" 18:59:27 INFO - } 18:59:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/SvZ111VOSHi-8TKXNFGGaA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2. 18:59:27 INFO - Running post-action listener: _resource_record_post_action 18:59:27 INFO - ##### 18:59:27 INFO - ##### Running download-and-extract step. 18:59:27 INFO - ##### 18:59:27 INFO - Running pre-action listener: _resource_record_pre_action 18:59:27 INFO - Running main action method: download_and_extract 18:59:27 INFO - mkdir: /builds/slave/test/build/tests 18:59:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:59:27 INFO - https://queue.taskcluster.net/v1/task/SvZ111VOSHi-8TKXNFGGaA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 18:59:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SvZ111VOSHi-8TKXNFGGaA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 18:59:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SvZ111VOSHi-8TKXNFGGaA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 18:59:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SvZ111VOSHi-8TKXNFGGaA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 18:59:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SvZ111VOSHi-8TKXNFGGaA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 18:59:31 INFO - Downloaded 1361 bytes. 18:59:31 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 18:59:31 INFO - Using the following test package requirements: 18:59:31 INFO - {u'common': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'], 18:59:31 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 18:59:31 INFO - u'firefox-48.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:59:31 INFO - u'gtest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 18:59:31 INFO - u'firefox-48.0a1.en-US.linux-x86_64.gtest.tests.zip'], 18:59:31 INFO - u'jittest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 18:59:31 INFO - u'jsshell-linux-x86_64.zip'], 18:59:31 INFO - u'mochitest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 18:59:31 INFO - u'firefox-48.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:59:31 INFO - u'mozbase': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'], 18:59:31 INFO - u'reftest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 18:59:31 INFO - u'firefox-48.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:59:31 INFO - u'talos': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 18:59:31 INFO - u'firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:59:31 INFO - u'web-platform': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 18:59:31 INFO - u'firefox-48.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:59:31 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 18:59:31 INFO - u'firefox-48.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:59:31 INFO - Downloading packages: [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:59: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')]} 18:59:31 INFO - https://queue.taskcluster.net/v1/task/SvZ111VOSHi-8TKXNFGGaA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:59:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SvZ111VOSHi-8TKXNFGGaA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 18:59:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SvZ111VOSHi-8TKXNFGGaA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 18:59:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SvZ111VOSHi-8TKXNFGGaA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 18:59:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SvZ111VOSHi-8TKXNFGGaA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:59:35 INFO - Downloaded 23780756 bytes. 18:59:35 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:59:35 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 18:59:36 INFO - Return code: 0 18:59:36 INFO - Downloading packages: [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 18:59:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:59:36 INFO - https://queue.taskcluster.net/v1/task/SvZ111VOSHi-8TKXNFGGaA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:59:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SvZ111VOSHi-8TKXNFGGaA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 18:59:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SvZ111VOSHi-8TKXNFGGaA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 18:59:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SvZ111VOSHi-8TKXNFGGaA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 18:59:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SvZ111VOSHi-8TKXNFGGaA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 18:59:39 INFO - Downloaded 23780756 bytes. 18:59:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:59:39 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 18:59:40 INFO - Return code: 0 18:59:40 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')]} 18:59:40 INFO - https://queue.taskcluster.net/v1/task/SvZ111VOSHi-8TKXNFGGaA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 18:59:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SvZ111VOSHi-8TKXNFGGaA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 18:59:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SvZ111VOSHi-8TKXNFGGaA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 18:59:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SvZ111VOSHi-8TKXNFGGaA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 18:59:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SvZ111VOSHi-8TKXNFGGaA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 18:59:41 INFO - Downloaded 10845952 bytes. 18:59:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:59:41 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 18:59:41 INFO - Return code: 0 18:59: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')]} 18:59:41 INFO - https://queue.taskcluster.net/v1/task/SvZ111VOSHi-8TKXNFGGaA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 18:59:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SvZ111VOSHi-8TKXNFGGaA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 18:59:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SvZ111VOSHi-8TKXNFGGaA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 18:59:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SvZ111VOSHi-8TKXNFGGaA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 18:59:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SvZ111VOSHi-8TKXNFGGaA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 18:59:42 INFO - Downloaded 58251738 bytes. 18:59:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SvZ111VOSHi-8TKXNFGGaA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 18:59:42 INFO - mkdir: /builds/slave/test/properties 18:59:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:59:42 INFO - Writing to file /builds/slave/test/properties/build_url 18:59:42 INFO - Contents: 18:59:42 INFO - build_url:https://queue.taskcluster.net/v1/task/SvZ111VOSHi-8TKXNFGGaA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 18:59:43 INFO - Running post-action listener: _resource_record_post_action 18:59:43 INFO - Running post-action listener: set_extra_try_arguments 18:59:43 INFO - ##### 18:59:43 INFO - ##### Running populate-webroot step. 18:59:43 INFO - ##### 18:59:43 INFO - Running pre-action listener: _resource_record_pre_action 18:59:43 INFO - Running main action method: populate_webroot 18:59:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:59:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:59:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:59:43 INFO - '--tppagecycles', 18:59:43 INFO - '7'], 18:59:43 INFO - 'tests': ['tsvgm']}}, 18:59:43 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:59:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:59:43 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:59:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:59:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:59:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:59:43 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:59:43 INFO - 'talos_options': ['--disable-e10s'], 18:59:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:59:43 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:59:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:59:43 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:59:43 INFO - 'talos_options': ['--disable-e10s'], 18:59:43 INFO - 'tests': ['damp', 'tps']}, 18:59:43 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:59:43 INFO - 'tests': ['damp', 'tps']}, 18:59:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:59:43 INFO - 'tests': ['dromaeo_dom']}, 18:59:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:59:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:59:43 INFO - 'tests': ['a11yr', 18:59:43 INFO - 'ts_paint', 18:59:43 INFO - 'tpaint', 18:59:43 INFO - 'sessionrestore', 18:59:43 INFO - 'sessionrestore_no_auto_restore', 18:59:43 INFO - 'tabpaint']}, 18:59:43 INFO - 'other-e10s': {'tests': ['a11yr', 18:59:43 INFO - 'ts_paint', 18:59:43 INFO - 'tpaint', 18:59:43 INFO - 'sessionrestore', 18:59:43 INFO - 'sessionrestore_no_auto_restore', 18:59:43 INFO - 'tabpaint']}, 18:59:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:59:43 INFO - 'ts_paint', 18:59:43 INFO - 'tpaint', 18:59:43 INFO - 'sessionrestore', 18:59:43 INFO - 'sessionrestore_no_auto_restore', 18:59:43 INFO - 'tabpaint']}, 18:59:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:59:43 INFO - 'ts_paint', 18:59:43 INFO - 'tpaint', 18:59:43 INFO - 'sessionrestore', 18:59:43 INFO - 'sessionrestore_no_auto_restore', 18:59:43 INFO - 'tabpaint']}, 18:59:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:59:43 INFO - 'tests': ['a11yr', 18:59:43 INFO - 'ts_paint', 18:59:43 INFO - 'tpaint', 18:59:43 INFO - 'sessionrestore', 18:59:43 INFO - 'sessionrestore_no_auto_restore', 18:59:43 INFO - 'tabpaint']}, 18:59:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:59:43 INFO - 'tests': ['a11yr', 18:59:43 INFO - 'ts_paint', 18:59:43 INFO - 'tpaint', 18:59:43 INFO - 'sessionrestore', 18:59:43 INFO - 'sessionrestore_no_auto_restore', 18:59:43 INFO - 'tabpaint']}, 18:59:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:59:43 INFO - 'tests': ['tsvgx', 18:59:43 INFO - 'tsvgr_opacity', 18:59:43 INFO - 'tart', 18:59:43 INFO - 'tscrollx', 18:59:43 INFO - 'cart']}, 18:59:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:59:43 INFO - 'tsvgr_opacity', 18:59:43 INFO - 'tart', 18:59:43 INFO - 'tscrollx', 18:59:43 INFO - 'cart']}, 18:59:43 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:59:43 INFO - 'talos_options': ['--disable-e10s'], 18:59:43 INFO - 'tests': ['tp5o']}, 18:59:43 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:59:43 INFO - 'tests': ['tp5o']}, 18:59:43 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:59:43 INFO - 'talos_options': ['--disable-e10s', 18:59:43 INFO - '--xperf_path', 18:59:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:59:43 INFO - 'tests': ['tp5n']}, 18:59:43 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:59:43 INFO - 'talos_options': ['--xperf_path', 18:59:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:59:43 INFO - 'tests': ['tp5n']}}, 18:59:43 INFO - 'talos.zip': {'path': '', 18:59:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:59:43 INFO - Running post-action listener: _resource_record_post_action 18:59:43 INFO - ##### 18:59:43 INFO - ##### Running create-virtualenv step. 18:59:43 INFO - ##### 18:59:43 INFO - Running pre-action listener: _resource_record_pre_action 18:59:43 INFO - Running main action method: create_virtualenv 18:59:43 INFO - Creating virtualenv /builds/slave/test/build/venv 18:59:43 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 18:59:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:59:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:59:43 INFO - Using real prefix '/usr' 18:59:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:59:44 INFO - Installing distribute.............................................................................................................................................................................................done. 18:59:46 INFO - Installing pip.................done. 18:59:46 INFO - Return code: 0 18:59:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:59:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:59:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:59:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:59:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:59:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:59:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:59:46 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 0x7f796fc2bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x185d9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18ec810>, '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 0x18ed710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18edba0>, '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': 'aa08bb06ff6271ef470a76a4000001be-1461025569.946708-128098684', '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 18:59:46 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 18:59:46 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 18:59:46 INFO - Using env: {'DISPLAY': ':0', 18:59:46 INFO - 'HOME': '/home/cltbld', 18:59:46 INFO - 'LANG': 'en_US.UTF-8', 18:59:46 INFO - 'LANGUAGE': 'en_US:en', 18:59:46 INFO - 'LOGNAME': 'cltbld', 18:59:46 INFO - 'MAIL': '/var/mail/cltbld', 18:59:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:59:46 INFO - 'MOZ_NO_REMOTE': '1', 18:59:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:59:46 INFO - 'NO_EM_RESTART': '1', 18:59:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:59:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:59:46 INFO - 'PWD': '/builds/slave/test', 18:59:46 INFO - 'SHELL': '/bin/bash', 18:59:46 INFO - 'SHLVL': '1', 18:59:46 INFO - 'TERM': 'linux', 18:59:46 INFO - 'TMOUT': '86400', 18:59:46 INFO - 'USER': 'cltbld', 18:59:46 INFO - 'XDG_SESSION_COOKIE': 'aa08bb06ff6271ef470a76a4000001be-1461025569.946708-128098684', 18:59:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:59:46 INFO - '_': '/tools/buildbot/bin/python'} 18:59:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:59:46 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 18:59:46 INFO - Cleaning up... 18:59:46 INFO - Return code: 0 18:59:46 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 18:59:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:59:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:59:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:59:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:59:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:59:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:59:46 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 0x7f796fc2bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x185d9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18ec810>, '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 0x18ed710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18edba0>, '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': 'aa08bb06ff6271ef470a76a4000001be-1461025569.946708-128098684', '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 18:59:46 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 18:59:46 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 18:59:46 INFO - Using env: {'DISPLAY': ':0', 18:59:46 INFO - 'HOME': '/home/cltbld', 18:59:46 INFO - 'LANG': 'en_US.UTF-8', 18:59:46 INFO - 'LANGUAGE': 'en_US:en', 18:59:46 INFO - 'LOGNAME': 'cltbld', 18:59:46 INFO - 'MAIL': '/var/mail/cltbld', 18:59:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:59:46 INFO - 'MOZ_NO_REMOTE': '1', 18:59:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:59:46 INFO - 'NO_EM_RESTART': '1', 18:59:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:59:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:59:46 INFO - 'PWD': '/builds/slave/test', 18:59:46 INFO - 'SHELL': '/bin/bash', 18:59:46 INFO - 'SHLVL': '1', 18:59:46 INFO - 'TERM': 'linux', 18:59:46 INFO - 'TMOUT': '86400', 18:59:46 INFO - 'USER': 'cltbld', 18:59:46 INFO - 'XDG_SESSION_COOKIE': 'aa08bb06ff6271ef470a76a4000001be-1461025569.946708-128098684', 18:59:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:59:46 INFO - '_': '/tools/buildbot/bin/python'} 18:59:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:59:46 INFO - Downloading/unpacking psutil>=0.7.1 18:59:46 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 18:59:46 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 18:59:46 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 18:59:46 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 18:59:46 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 18:59:46 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 18:59:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:59:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:59:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:59:48 INFO - Installing collected packages: psutil 18:59:48 INFO - Running setup.py install for psutil 18:59:48 INFO - building 'psutil._psutil_linux' extension 18:59:48 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 18:59:49 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 18:59:49 INFO - building 'psutil._psutil_posix' extension 18:59:49 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 18:59:49 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 18:59:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:59:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:59:49 INFO - Successfully installed psutil 18:59:49 INFO - Cleaning up... 18:59:49 INFO - Return code: 0 18:59:49 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 18:59: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')]} 18:59:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:59:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:59: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')]} 18:59:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:59:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:59:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f796fc2bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x185d9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18ec810>, '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 0x18ed710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18edba0>, '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': 'aa08bb06ff6271ef470a76a4000001be-1461025569.946708-128098684', '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 18:59:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 18:59:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 18:59:49 INFO - Using env: {'DISPLAY': ':0', 18:59:49 INFO - 'HOME': '/home/cltbld', 18:59:49 INFO - 'LANG': 'en_US.UTF-8', 18:59:49 INFO - 'LANGUAGE': 'en_US:en', 18:59:49 INFO - 'LOGNAME': 'cltbld', 18:59:49 INFO - 'MAIL': '/var/mail/cltbld', 18:59:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:59:49 INFO - 'MOZ_NO_REMOTE': '1', 18:59:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:59:49 INFO - 'NO_EM_RESTART': '1', 18:59:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:59:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:59:49 INFO - 'PWD': '/builds/slave/test', 18:59:49 INFO - 'SHELL': '/bin/bash', 18:59:49 INFO - 'SHLVL': '1', 18:59:49 INFO - 'TERM': 'linux', 18:59:49 INFO - 'TMOUT': '86400', 18:59:49 INFO - 'USER': 'cltbld', 18:59:49 INFO - 'XDG_SESSION_COOKIE': 'aa08bb06ff6271ef470a76a4000001be-1461025569.946708-128098684', 18:59:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:59:49 INFO - '_': '/tools/buildbot/bin/python'} 18:59:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:59:49 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 18:59:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:59:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:59:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:59:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:59:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:59:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:59:51 INFO - Downloading mozsystemmonitor-0.0.tar.gz 18:59:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:59:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 18:59:51 INFO - Installing collected packages: mozsystemmonitor 18:59:51 INFO - Running setup.py install for mozsystemmonitor 18:59:51 INFO - Successfully installed mozsystemmonitor 18:59:51 INFO - Cleaning up... 18:59:51 INFO - Return code: 0 18:59:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:59:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:59:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:59:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:59:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:59:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:59:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:59:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f796fc2bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x185d9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18ec810>, '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 0x18ed710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18edba0>, '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': 'aa08bb06ff6271ef470a76a4000001be-1461025569.946708-128098684', '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 18:59:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:59:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:59:51 INFO - Using env: {'DISPLAY': ':0', 18:59:51 INFO - 'HOME': '/home/cltbld', 18:59:51 INFO - 'LANG': 'en_US.UTF-8', 18:59:51 INFO - 'LANGUAGE': 'en_US:en', 18:59:51 INFO - 'LOGNAME': 'cltbld', 18:59:51 INFO - 'MAIL': '/var/mail/cltbld', 18:59:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:59:51 INFO - 'MOZ_NO_REMOTE': '1', 18:59:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:59:51 INFO - 'NO_EM_RESTART': '1', 18:59:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:59:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:59:51 INFO - 'PWD': '/builds/slave/test', 18:59:51 INFO - 'SHELL': '/bin/bash', 18:59:51 INFO - 'SHLVL': '1', 18:59:51 INFO - 'TERM': 'linux', 18:59:51 INFO - 'TMOUT': '86400', 18:59:51 INFO - 'USER': 'cltbld', 18:59:51 INFO - 'XDG_SESSION_COOKIE': 'aa08bb06ff6271ef470a76a4000001be-1461025569.946708-128098684', 18:59:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:59:51 INFO - '_': '/tools/buildbot/bin/python'} 18:59:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:59:51 INFO - Downloading/unpacking blobuploader==1.2.4 18:59:51 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 18:59:51 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 18:59:51 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 18:59:51 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 18:59:51 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 18:59:51 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 18:59:53 INFO - Downloading blobuploader-1.2.4.tar.gz 18:59:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:59:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:59:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:59:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:59:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:59:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:59:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:59:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:59:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:59:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:59:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:59:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:59:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:59:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:59:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:59:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:59:54 INFO - Downloading docopt-0.6.1.tar.gz 18:59:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:59:54 INFO - Installing collected packages: blobuploader, requests, docopt 18:59:54 INFO - Running setup.py install for blobuploader 18:59:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:59:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:59:54 INFO - Running setup.py install for requests 18:59:54 INFO - Running setup.py install for docopt 18:59:55 INFO - Successfully installed blobuploader requests docopt 18:59:55 INFO - Cleaning up... 18:59:55 INFO - Return code: 0 18:59:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:59: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')]} 18:59:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:59:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:59: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')]} 18:59:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:59:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:59:55 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 0x7f796fc2bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x185d9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18ec810>, '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 0x18ed710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18edba0>, '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': 'aa08bb06ff6271ef470a76a4000001be-1461025569.946708-128098684', '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 18:59:55 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 18:59:55 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 18:59:55 INFO - Using env: {'DISPLAY': ':0', 18:59:55 INFO - 'HOME': '/home/cltbld', 18:59:55 INFO - 'LANG': 'en_US.UTF-8', 18:59:55 INFO - 'LANGUAGE': 'en_US:en', 18:59:55 INFO - 'LOGNAME': 'cltbld', 18:59:55 INFO - 'MAIL': '/var/mail/cltbld', 18:59:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:59:55 INFO - 'MOZ_NO_REMOTE': '1', 18:59:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:59:55 INFO - 'NO_EM_RESTART': '1', 18:59:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:59:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:59:55 INFO - 'PWD': '/builds/slave/test', 18:59:55 INFO - 'SHELL': '/bin/bash', 18:59:55 INFO - 'SHLVL': '1', 18:59:55 INFO - 'TERM': 'linux', 18:59:55 INFO - 'TMOUT': '86400', 18:59:55 INFO - 'USER': 'cltbld', 18:59:55 INFO - 'XDG_SESSION_COOKIE': 'aa08bb06ff6271ef470a76a4000001be-1461025569.946708-128098684', 18:59:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:59:55 INFO - '_': '/tools/buildbot/bin/python'} 18:59:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:59:55 INFO - Running setup.py (path:/tmp/pip-Jh3cGg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:59:55 INFO - Running setup.py (path:/tmp/pip-tb7I49-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:59:55 INFO - Running setup.py (path:/tmp/pip-W2xtIE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:59:55 INFO - Running setup.py (path:/tmp/pip-BVxaes-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:59:55 INFO - Running setup.py (path:/tmp/pip-K81A2p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:59:55 INFO - Running setup.py (path:/tmp/pip-MD6Y1i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:59:55 INFO - Running setup.py (path:/tmp/pip-oWubnR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:59:55 INFO - Running setup.py (path:/tmp/pip-84ieHt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:59:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:59:55 INFO - Running setup.py (path:/tmp/pip-I84A2S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:59:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:59:56 INFO - Running setup.py (path:/tmp/pip-uFDtYl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:59:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:59:56 INFO - Running setup.py (path:/tmp/pip-VzV_lc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:59:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:59:56 INFO - Running setup.py (path:/tmp/pip-j6fuXH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:59:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:59:56 INFO - Running setup.py (path:/tmp/pip-Kn2vWK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:59:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:59:56 INFO - Running setup.py (path:/tmp/pip-KZs5Dw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:59:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:59:56 INFO - Running setup.py (path:/tmp/pip-FCJlBp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:59:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:59:56 INFO - Running setup.py (path:/tmp/pip-y0VtYE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:59:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:59:56 INFO - Running setup.py (path:/tmp/pip-zatBXg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:59:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:59:56 INFO - Running setup.py install for manifestparser 18:59:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:59:56 INFO - Running setup.py install for mozcrash 18:59:56 INFO - Running setup.py install for mozdebug 18:59:56 INFO - Running setup.py install for mozdevice 18:59:56 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:59:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:59:57 INFO - Running setup.py install for mozfile 18:59:57 INFO - Running setup.py install for mozhttpd 18:59:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:59:57 INFO - Running setup.py install for mozinfo 18:59:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:59:57 INFO - Running setup.py install for mozInstall 18:59:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:59:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:59:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:59:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:59:57 INFO - Running setup.py install for mozleak 18:59:57 INFO - Running setup.py install for mozlog 18:59:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:59:57 INFO - Running setup.py install for moznetwork 18:59:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:59:57 INFO - Running setup.py install for mozprocess 18:59:57 INFO - Running setup.py install for mozprofile 18:59:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:59:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:59:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:59:57 INFO - Running setup.py install for mozrunner 18:59:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:59:58 INFO - Running setup.py install for mozscreenshot 18:59:58 INFO - Running setup.py install for moztest 18:59:58 INFO - Running setup.py install for mozversion 18:59:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:59:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:59:58 INFO - Cleaning up... 18:59:58 INFO - Return code: 0 18:59:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:59:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:59:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:59:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:59:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:59:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:59:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:59:58 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 0x7f796fc2bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x185d9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18ec810>, '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 0x18ed710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18edba0>, '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': 'aa08bb06ff6271ef470a76a4000001be-1461025569.946708-128098684', '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 18:59:58 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 18:59:58 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 18:59:58 INFO - Using env: {'DISPLAY': ':0', 18:59:58 INFO - 'HOME': '/home/cltbld', 18:59:58 INFO - 'LANG': 'en_US.UTF-8', 18:59:58 INFO - 'LANGUAGE': 'en_US:en', 18:59:58 INFO - 'LOGNAME': 'cltbld', 18:59:58 INFO - 'MAIL': '/var/mail/cltbld', 18:59:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:59:58 INFO - 'MOZ_NO_REMOTE': '1', 18:59:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:59:58 INFO - 'NO_EM_RESTART': '1', 18:59:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:59:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:59:58 INFO - 'PWD': '/builds/slave/test', 18:59:58 INFO - 'SHELL': '/bin/bash', 18:59:58 INFO - 'SHLVL': '1', 18:59:58 INFO - 'TERM': 'linux', 18:59:58 INFO - 'TMOUT': '86400', 18:59:58 INFO - 'USER': 'cltbld', 18:59:58 INFO - 'XDG_SESSION_COOKIE': 'aa08bb06ff6271ef470a76a4000001be-1461025569.946708-128098684', 18:59:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:59:58 INFO - '_': '/tools/buildbot/bin/python'} 18:59:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:59:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:59:58 INFO - Running setup.py (path:/tmp/pip-mrkr1q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:59:58 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)) 18:59:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:59:58 INFO - Running setup.py (path:/tmp/pip-BVsjOK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:59:58 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)) 18:59:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:59:58 INFO - Running setup.py (path:/tmp/pip-NfIDjl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:59:58 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)) 18:59:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:59:58 INFO - Running setup.py (path:/tmp/pip-SPODAe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:59:58 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)) 18:59:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:59:58 INFO - Running setup.py (path:/tmp/pip-DYaC8g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:59:58 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)) 18:59:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:59:58 INFO - Running setup.py (path:/tmp/pip-wXcF_N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:59:59 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)) 18:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:59:59 INFO - Running setup.py (path:/tmp/pip-bIekRx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:59:59 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)) 18:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:59:59 INFO - Running setup.py (path:/tmp/pip-9i_oQ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:59:59 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)) 18:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:59:59 INFO - Running setup.py (path:/tmp/pip-TvfNaJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:59:59 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)) 18:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:59:59 INFO - Running setup.py (path:/tmp/pip-F1V5Wn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:59:59 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)) 18:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:59:59 INFO - Running setup.py (path:/tmp/pip-LvLYpk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:59:59 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)) 18:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:59:59 INFO - Running setup.py (path:/tmp/pip-wUEtAZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:59:59 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)) 18:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:59:59 INFO - Running setup.py (path:/tmp/pip-WFark5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:59:59 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)) 18:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:59:59 INFO - Running setup.py (path:/tmp/pip-nNqacm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:59:59 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)) 18:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:59:59 INFO - Running setup.py (path:/tmp/pip-96Bs_O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:59:59 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)) 18:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:59:59 INFO - Running setup.py (path:/tmp/pip-4Fr2if-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:59:59 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)) 18:59:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:59:59 INFO - Running setup.py (path:/tmp/pip-MkWU6Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:59:59 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)) 18:59:59 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)) 18:59:59 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)) 18:59:59 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)) 18:59:59 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)) 18:59:59 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)) 18:59:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:59:59 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 18:59:59 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 18:59:59 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 18:59:59 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 18:59:59 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 18:59:59 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 19:00:01 INFO - Downloading blessings-1.6.tar.gz 19:00:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:00:01 INFO - Installing collected packages: blessings 19:00:01 INFO - Running setup.py install for blessings 19:00:01 INFO - Successfully installed blessings 19:00:01 INFO - Cleaning up... 19:00:01 INFO - Return code: 0 19:00:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:00:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:00:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:00:02 INFO - Reading from file tmpfile_stdout 19:00:02 INFO - Current package versions: 19:00:02 INFO - argparse == 1.2.1 19:00:02 INFO - blessings == 1.6 19:00:02 INFO - blobuploader == 1.2.4 19:00:02 INFO - docopt == 0.6.1 19:00:02 INFO - manifestparser == 1.1 19:00:02 INFO - mozInstall == 1.12 19:00:02 INFO - mozcrash == 0.17 19:00:02 INFO - mozdebug == 0.1 19:00:02 INFO - mozdevice == 0.48 19:00:02 INFO - mozfile == 1.2 19:00:02 INFO - mozhttpd == 0.7 19:00:02 INFO - mozinfo == 0.9 19:00:02 INFO - mozleak == 0.1 19:00:02 INFO - mozlog == 3.1 19:00:02 INFO - moznetwork == 0.27 19:00:02 INFO - mozprocess == 0.22 19:00:02 INFO - mozprofile == 0.28 19:00:02 INFO - mozrunner == 6.11 19:00:02 INFO - mozscreenshot == 0.1 19:00:02 INFO - mozsystemmonitor == 0.0 19:00:02 INFO - moztest == 0.7 19:00:02 INFO - mozversion == 1.4 19:00:02 INFO - psutil == 3.1.1 19:00:02 INFO - requests == 1.2.3 19:00:02 INFO - wsgiref == 0.1.2 19:00:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:00:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:00:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:00:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:02 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 0x7f796fc2bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x185d9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18ec810>, '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 0x18ed710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18edba0>, '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': 'aa08bb06ff6271ef470a76a4000001be-1461025569.946708-128098684', '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 19:00:02 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 19:00:02 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 19:00:02 INFO - Using env: {'DISPLAY': ':0', 19:00:02 INFO - 'HOME': '/home/cltbld', 19:00:02 INFO - 'LANG': 'en_US.UTF-8', 19:00:02 INFO - 'LANGUAGE': 'en_US:en', 19:00:02 INFO - 'LOGNAME': 'cltbld', 19:00:02 INFO - 'MAIL': '/var/mail/cltbld', 19:00:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:02 INFO - 'MOZ_NO_REMOTE': '1', 19:00:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:02 INFO - 'NO_EM_RESTART': '1', 19:00:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:02 INFO - 'PWD': '/builds/slave/test', 19:00:02 INFO - 'SHELL': '/bin/bash', 19:00:02 INFO - 'SHLVL': '1', 19:00:02 INFO - 'TERM': 'linux', 19:00:02 INFO - 'TMOUT': '86400', 19:00:02 INFO - 'USER': 'cltbld', 19:00:02 INFO - 'XDG_SESSION_COOKIE': 'aa08bb06ff6271ef470a76a4000001be-1461025569.946708-128098684', 19:00:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:02 INFO - '_': '/tools/buildbot/bin/python'} 19:00:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:02 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)) 19:00:02 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)) 19:00:02 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)) 19:00:02 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)) 19:00:02 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)) 19:00:02 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)) 19:00:02 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)) 19:00:02 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)) 19:00:02 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)) 19:00:02 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)) 19:00:02 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)) 19:00:02 INFO - Cleaning up... 19:00:02 INFO - Return code: 0 19:00:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:00:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:00:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:00:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:02 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 0x7f796fc2bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x185d9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18ec810>, '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 0x18ed710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18edba0>, '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': 'aa08bb06ff6271ef470a76a4000001be-1461025569.946708-128098684', '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 19:00:02 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 19:00:02 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 19:00:02 INFO - Using env: {'DISPLAY': ':0', 19:00:02 INFO - 'HOME': '/home/cltbld', 19:00:02 INFO - 'LANG': 'en_US.UTF-8', 19:00:02 INFO - 'LANGUAGE': 'en_US:en', 19:00:02 INFO - 'LOGNAME': 'cltbld', 19:00:02 INFO - 'MAIL': '/var/mail/cltbld', 19:00:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:02 INFO - 'MOZ_NO_REMOTE': '1', 19:00:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:02 INFO - 'NO_EM_RESTART': '1', 19:00:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:02 INFO - 'PWD': '/builds/slave/test', 19:00:02 INFO - 'SHELL': '/bin/bash', 19:00:02 INFO - 'SHLVL': '1', 19:00:02 INFO - 'TERM': 'linux', 19:00:02 INFO - 'TMOUT': '86400', 19:00:02 INFO - 'USER': 'cltbld', 19:00:02 INFO - 'XDG_SESSION_COOKIE': 'aa08bb06ff6271ef470a76a4000001be-1461025569.946708-128098684', 19:00:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:02 INFO - '_': '/tools/buildbot/bin/python'} 19:00:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:02 INFO - Downloading/unpacking jsonschema 19:00:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:00:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:00:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:00:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:00:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:00:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:00:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:00:04 INFO - Downloading/unpacking functools32 (from jsonschema) 19:00:04 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 19:00:04 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 19:00:04 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 19:00:04 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 19:00:04 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 19:00:04 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 19:00:04 INFO - Downloading functools32-3.2.3-2.tar.gz 19:00:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:00:04 INFO - warning: no files found matching '*.txt' 19:00:04 INFO - no previously-included directories found matching 'build' 19:00:04 INFO - no previously-included directories found matching 'dist' 19:00:04 INFO - no previously-included directories found matching '.git*' 19:00:04 INFO - Installing collected packages: jsonschema, functools32 19:00:04 INFO - Running setup.py install for functools32 19:00:04 INFO - warning: no files found matching '*.txt' 19:00:04 INFO - no previously-included directories found matching 'build' 19:00:04 INFO - no previously-included directories found matching 'dist' 19:00:04 INFO - no previously-included directories found matching '.git*' 19:00:04 INFO - Successfully installed jsonschema functools32 19:00:04 INFO - Cleaning up... 19:00:04 INFO - Return code: 0 19:00:04 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 19:00:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:00:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:00:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:00:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:00:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:00:04 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 0x7f796fc2bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17928a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x185d9a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18ec810>, '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 0x18ed710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18edba0>, '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': 'aa08bb06ff6271ef470a76a4000001be-1461025569.946708-128098684', '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 19:00:04 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 19:00:04 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 19:00:04 INFO - Using env: {'DISPLAY': ':0', 19:00:04 INFO - 'HOME': '/home/cltbld', 19:00:04 INFO - 'LANG': 'en_US.UTF-8', 19:00:04 INFO - 'LANGUAGE': 'en_US:en', 19:00:04 INFO - 'LOGNAME': 'cltbld', 19:00:04 INFO - 'MAIL': '/var/mail/cltbld', 19:00:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:04 INFO - 'MOZ_NO_REMOTE': '1', 19:00:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:04 INFO - 'NO_EM_RESTART': '1', 19:00:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:04 INFO - 'PWD': '/builds/slave/test', 19:00:04 INFO - 'SHELL': '/bin/bash', 19:00:04 INFO - 'SHLVL': '1', 19:00:05 INFO - 'TERM': 'linux', 19:00:05 INFO - 'TMOUT': '86400', 19:00:05 INFO - 'USER': 'cltbld', 19:00:05 INFO - 'XDG_SESSION_COOKIE': 'aa08bb06ff6271ef470a76a4000001be-1461025569.946708-128098684', 19:00:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:05 INFO - '_': '/tools/buildbot/bin/python'} 19:00:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:00:05 INFO - Downloading/unpacking flake8 19:00:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:00:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:00:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:00:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:00:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:00:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:00:07 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 19:00:07 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 19:00:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:00:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:00:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:00:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:00:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:00:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:00:07 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 19:00:07 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 19:00:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:00:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:00:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:00:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:00:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:00:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:00:07 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 19:00:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:00:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:00:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:00:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:00:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:00:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:00:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 19:00:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:00:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:00:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:00:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:00:07 INFO - no previously-included directories found matching 'docs/_build' 19:00:07 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 19:00:07 INFO - Running setup.py install for pep8 19:00:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:00:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:00:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:00:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:00:07 INFO - no previously-included directories found matching 'docs/_build' 19:00:07 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 19:00:07 INFO - Successfully installed flake8 mccabe pyflakes pep8 19:00:07 INFO - Cleaning up... 19:00:08 INFO - Return code: 0 19:00:08 INFO - Running post-action listener: _resource_record_post_action 19:00:08 INFO - Running post-action listener: _start_resource_monitoring 19:00:08 INFO - Starting resource monitoring. 19:00:08 INFO - ##### 19:00:08 INFO - ##### Running install step. 19:00:08 INFO - ##### 19:00:08 INFO - Running pre-action listener: _resource_record_pre_action 19:00:08 INFO - Running main action method: install 19:00:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:00:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:00:08 INFO - Reading from file tmpfile_stdout 19:00:08 INFO - Detecting whether we're running mozinstall >=1.0... 19:00:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:00:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:00:08 INFO - Reading from file tmpfile_stdout 19:00:08 INFO - Output received: 19:00:08 INFO - Usage: mozinstall [options] installer 19:00:08 INFO - Options: 19:00:08 INFO - -h, --help show this help message and exit 19:00:08 INFO - -d DEST, --destination=DEST 19:00:08 INFO - Directory to install application into. [default: 19:00:08 INFO - "/builds/slave/test"] 19:00:08 INFO - --app=APP Application being installed. [default: firefox] 19:00:08 INFO - mkdir: /builds/slave/test/build/application 19:00:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 19:00:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 19:00:24 INFO - Reading from file tmpfile_stdout 19:00:24 INFO - Output received: 19:00:24 INFO - /builds/slave/test/build/application/firefox/firefox 19:00:24 INFO - Running post-action listener: _resource_record_post_action 19:00:24 INFO - ##### 19:00:24 INFO - ##### Running run-tests step. 19:00:24 INFO - ##### 19:00:24 INFO - Running pre-action listener: _resource_record_pre_action 19:00:24 INFO - Running main action method: run_tests 19:00:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:00:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:00:24 INFO - Python 2.7.3 19:00:24 INFO - Return code: 0 19:00:24 INFO - grabbing minidump binary from tooltool 19:00:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:00:24 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 0x18ec810>, '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 0x18ed710>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x18edba0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:00:24 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 19:00:24 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 19:00:24 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:00:24 INFO - Return code: 0 19:00:24 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:00:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:00:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:00:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:00:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:00:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 19:00:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 19:00:25 INFO - Return code: 0 19:00:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SvZ111VOSHi-8TKXNFGGaA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 19:00:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/SvZ111VOSHi-8TKXNFGGaA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-037 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 19:00:25 INFO - Using env: {'DISPLAY': ':0', 19:00:25 INFO - 'HOME': '/home/cltbld', 19:00:25 INFO - 'LANG': 'en_US.UTF-8', 19:00:25 INFO - 'LANGUAGE': 'en_US:en', 19:00:25 INFO - 'LOGNAME': 'cltbld', 19:00:25 INFO - 'MAIL': '/var/mail/cltbld', 19:00:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:00:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:00:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:00:26 INFO - 'MOZ_NO_REMOTE': '1', 19:00:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:00:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:00:26 INFO - 'NO_EM_RESTART': '1', 19:00:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:00:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:00:26 INFO - 'PWD': '/builds/slave/test', 19:00:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:00:26 INFO - 'SHELL': '/bin/bash', 19:00:26 INFO - 'SHLVL': '1', 19:00:26 INFO - 'TERM': 'linux', 19:00:26 INFO - 'TMOUT': '86400', 19:00:26 INFO - 'USER': 'cltbld', 19:00:26 INFO - 'XDG_SESSION_COOKIE': 'aa08bb06ff6271ef470a76a4000001be-1461025569.946708-128098684', 19:00:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:00:26 INFO - '_': '/tools/buildbot/bin/python'} 19:00:26 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SvZ111VOSHi-8TKXNFGGaA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-037', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 19:00:26 INFO - mozversion application_buildid: 20160418163008 19:00:26 INFO - mozversion application_changeset: 67ac40fb8f680ea5e03805552187ba1b5e8392a1 19:00:26 INFO - mozversion application_display_name: Nightly 19:00:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:00:26 INFO - mozversion application_name: Firefox 19:00:26 INFO - mozversion application_remotingname: firefox 19:00:26 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:00:26 INFO - mozversion application_vendor: Mozilla 19:00:26 INFO - mozversion application_version: 48.0a1 19:00:26 INFO - mozversion platform_buildid: 20160418163008 19:00:26 INFO - mozversion platform_changeset: 67ac40fb8f680ea5e03805552187ba1b5e8392a1 19:00:26 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:00:26 INFO - mozversion platform_version: 48.0a1 19:00:26 INFO - using testdate: 1461031226 19:00:26 INFO - actual date: 1461031226 19:00:26 INFO - starting webserver on 'localhost:33675' 19:00:26 INFO - SUITE-START | Running 2 tests 19:00:26 INFO - TEST-START | dromaeo_css 19:00:26 INFO - operating with platform_type : linux_ 19:00:26 INFO - Initialising browser for dromaeo_css test... 19:00:26 INFO - TEST-INFO | started process 18653 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTlZ7Hx/profile http://localhost:33675/getInfo.html) 19:00:30 INFO - PROCESS | 18653 | __metrics Screen width/height:1600/1200 19:00:30 INFO - PROCESS | 18653 | colorDepth:24 19:00:30 INFO - PROCESS | 18653 | Browser inner width/height: 1024/697 19:00:30 INFO - PROCESS | 18653 | __metrics 19:00:31 ERROR - PROCESS | 18653 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 19:00:32 INFO - TEST-INFO | 18653: exit 0 19:00:32 INFO - Browser initialized. 19:00:32 INFO - Running cycle 1/1 for dromaeo_css test... 19:00:32 INFO - TEST-INFO | started process 18733 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTlZ7Hx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:00:43 INFO - PROCESS | 18733 | RSS: Main: 153034752 19:00:43 INFO - PROCESS | 18733 | 19:01:34 INFO - PROCESS | 18733 | 1461031294807 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] 19:03:42 INFO - PROCESS | 18733 | Cycle 1(1): loaded http://localhost:33675/tests/dromaeo/cssquery-dojo.html (next: http://localhost:33675/tests/dromaeo/cssquery-ext.html) 19:03:43 INFO - PROCESS | 18733 | RSS: Main: 252170240 19:03:43 INFO - PROCESS | 18733 | 19:06:45 INFO - PROCESS | 18733 | Cycle 1(1): loaded http://localhost:33675/tests/dromaeo/cssquery-ext.html (next: http://localhost:33675/tests/dromaeo/cssquery-jquery.html) 19:06:46 INFO - PROCESS | 18733 | RSS: Main: 236961792 19:06:46 INFO - PROCESS | 18733 | 19:09:48 INFO - PROCESS | 18733 | Cycle 1(1): loaded http://localhost:33675/tests/dromaeo/cssquery-jquery.html (next: http://localhost:33675/tests/dromaeo/cssquery-mootools.html) 19:09:48 INFO - PROCESS | 18733 | RSS: Main: 238972928 19:09:48 INFO - PROCESS | 18733 | 19:12:50 INFO - PROCESS | 18733 | Cycle 1(1): loaded http://localhost:33675/tests/dromaeo/cssquery-mootools.html (next: http://localhost:33675/tests/dromaeo/cssquery-prototype.html) 19:12:51 INFO - PROCESS | 18733 | RSS: Main: 225824768 19:12:51 INFO - PROCESS | 18733 | 19:15:53 INFO - PROCESS | 18733 | Cycle 1(1): loaded http://localhost:33675/tests/dromaeo/cssquery-prototype.html (next: http://localhost:33675/tests/dromaeo/cssquery-yui.html) 19:15:54 INFO - PROCESS | 18733 | RSS: Main: 224739328 19:15:54 INFO - PROCESS | 18733 | 19:18:56 INFO - PROCESS | 18733 | Cycle 1(1): loaded http://localhost:33675/tests/dromaeo/cssquery-yui.html (next: http://localhost:33675/tests/dromaeo/cssquery-dojo.html) 19:18:57 INFO - PROCESS | 18733 | RSS: Main: 297148416 19:18:57 INFO - PROCESS | 18733 | 19:18:57 INFO - PROCESS | 18733 | __start_tp_report 19:18:57 INFO - PROCESS | 18733 | _x_x_mozilla_page_load 19:18:57 INFO - PROCESS | 18733 | _x_x_mozilla_page_load_details 19:18:57 INFO - PROCESS | 18733 | |i|pagename|runs| 19:18:57 INFO - PROCESS | 18733 | |0;dojo.html;3047.7866549341547;3103.6741142180085;3138.4978403455452;3145.9685403145977;3168.603924509436;5188.507091826271;5251.763670634821;5259.763310651025;5269.8946021079555;5270.420253772091;5210.895782084356;5233.188855727369;5249.973750131254;5275.235090911825;5278.788848446067;4698.506656801038;4734.431868175824;4734.526547345273;4844.346013288214;4888.413390393158;4843;4910.361652985107;4920.409550853902;4971.676841005341;4983.476734942833;4691.859244222679;4737.739424331678;4764.332993380929;4818.879528011793;4940.333055037578;4696.2955556666775;4700.29495575663;4700.388949436586;4705.364775755298;4727.621790256771;4664.090502352043;4685.648576356787;4700.130475861976;4706.10583989041;4711.104890070876;5160.200168973777;5218.060749065166;5219.217117432416;5249.632525723163;5268.894622107537;5462.262594549725;5505.476979686923;5506.91739623906;5507.7246137693355;5520.503154716095;5523.530499907512;5528.364238112588;5551.250581171531;5556.111022236463;5582.944170558283;5288.8413347599635;5290.365156181283;5297.205419187114;5301.761420736076;5348.598855085846;5789.768409263666;5797.391273916178;5797.536197104221;5798.82603521895;5799.768009279582;5356.544693701079;5558.082916318843;5559.555235581144;5565.9443405566235;5617.073182924772;5559.360673522516;5560.2493663355735;5567.081431563827;5568.1091025435735;5591.52472039873;5542.944570554324;5547.278853749068;5549.5837812164245;5552.222688823568;5557.249771280906;5374.032674118696;5505.03661859173;5538.944610553924;5595.244641973363;5621.325440947113;4265.247239080663;4266.466691663542;4274.871753847327;4275.636570891509;4295.871123866289;3825.7513261637905;3832.291026160169;3841.4814000110186;3856.903577410531;3862.748921320105;5069.062223488666;5145.07388670043;5255.842324730188;5298.337707786527;5318.22885681574;5504.724763761796;5529.806456773994;5533.3083364579425;5537.7784888604;5552.333719953632;5152.6650767699975;5158.74206289684;5165.225216217522;5166.095933211673;5180.145276029413;4763.309320148559;4780.689255198401;4793.904121917542;4821.686590371684;4832.855014349575;5737.483626473638;5739.139129130664;5748.9425105748405;5752.9712351437975;5753.280839895013;5461.5357694596405;5580.49775520197;5592.440755924537;5604.019296623156;5651.321841378896;5705.258316418839;5773;5795;5839.474447299764;5843.561732869966;5471.917921231265;5473.370562385377;5476.041692703681;5477.178423236546;5480.068388374066;5494.395616482264;5511.641743286674;5526.364468086222;5547.2511210986;5585.022621041383;5470.343558772973;5480.177973304037;5480.2875626169935;5496.6701997880255;5534.972325138348;5456.590755693418;5460.372057213472;5462.235287059894;5463;5464.890702186013;5247.265382846481;5348.545373643284;5385.8922821542565;5387.434319396407;5476.972615137058;5522.889542209214;5528.55771538284;5529.944700552943;5532.391436942014;5574.08027675429;5364.7585858635675;5404.189371594292;5406.486383793534;5414.620976531763;5417.43116972728 19:18:57 INFO - PROCESS | 18733 | |1;ext.html;597.6892016151601;639.6865535887415;650.4406210658834;652.2140820311525;656.69791895728;30031.549526757088;37256.25487490252;37577.43633845488;37677.43483847738;37685.24629507408;39729.60270397295;40384.79807600958;40399.39400908989;40479.79760101195;40518.18963620733;7862.213778622149;8096.149904260056;8498;8694.56527173642;8697.130286971316;10125;10509.947450262776;10531.21015923805;10538.156947444186;10542.736431589195;10203.387796732182;10280.971902809733;10287.948560257226;10294.124999375063;10294.176465882749;8072.071711753136;10171.898281017206;10327.122194613466;10687.465626718633;10698.358098514114;9948.10467057961;9951.651692190739;10029.448380339078;10041.246906482043;10070.597176112946;36899.81550092233;37381.62618373809;37577.43633845494;37660;37667.24665506702;3366.8148251846137;3391.5421418108485;3566.732495062881;3788;3909.6872250219913;31342.686573134204;31356.372872542648;31403.84298078518;31508.684913150806;31649.683503164903;4275.273203555403;4314.762688052156;4473.932891006638;4542.227821270392;4542.318652202163;322603;323191;324051;324338;324678;34388.65611343904;34970.650293497245;34987.82506087453;55922.72038639781;55945;33520.83239583787;33590.16024599405;33686.66313336884;33687.83156084204;33698.32603347969;13504.257265850472;13559.796603050961;13658.112222705691;13693.246871421969;13713.931430342784;11832.112591555668;11865.466054027589;11871.64385068432;11883.16817822744;11920.701982450508;8279.130691277449;8299.709510167115;8328.375371847136;8366.874496882552;8385.287250583646;2975.702429757007;3070.677578854188;3191.505316675919;3233.4341490239112;3266.5426840242385;1007.0161781290846;1079.4710591809956;1109.3454861631676;1122.259308856151;1142.080625096805;30963.380732385227;32273.51589726156;32281.35437291241;32287.677123228466;32351.35297294041;1116.8715597706205;1148.7989601819647;1157.1610582327912;1217.5921066442663;1230.4217018001525;16869;17083.91458042702;17149.399771008204;17151.881968982983;17205.397811076553;454552;462048;462191;462514;462888;54154.458455415726;55768.16347754787;55855;55875.161872571945;55884.441155588735;1345.8115863779078;1345.8977057881677;1354.0453979944116;1392.3664732546667;1394.5816255123423;30076.699233007388;31138.37723245477;34810.47784283289;34856.47715284323;35088.12279693028;54367;55119.72440137774;55686.16470752861;55844.44155558555;55895;424026;426706.32936707325;431154;431696;432053;36521.08697282589;42173.367399488416;42439;42787.7860610695;43374.78312608543;40610.18779624332;40667.7966610165;41411;41432.58567414287;41437;35817.82091089528;35911.461328079575;35968.8201559001;36611.450828237066;37971.240575188895;37972.81013594914;38179.42730859095;38453.4231986526;38652.420213697376;38904.80547597357;39603.207935841696;39769.60230397775;40186.19627607373;40663.59336406598;41441.37837932494;32208;34374.484382733775;34557.654423455446;35014.82492587521;35094.29811403707;26702.59946100771;26966.460670786866;27316.863415682794;27441.31396715098;27454 19:18:57 INFO - PROCESS | 18733 | |2;jquery.html;389.77393111995036;392.8134136285267;394.08375526899965;397.89058009047505;411.2371550773315;16841.74737378937;16848.49454516365;17540.824591754077;17569.47291581253;19772.30796922108;15156.772648410255;16279.918600406982;17093.23080461379;18448.72326915094;18775.81224187758;5426.782928682848;5485.149801780721;5675.006873797069;5707.600467967244;5714.171445140452;5582.748776305076;5591.580631452657;5606.299212598426;5623.887522249553;5628.690422026787;4020.2964481215818;5573.331200255955;5574.080276754331;5576.275084239043;5877.14781356703;6104.725287362035;6145;6176.691165441733;6177.907331390033;6178;5223.294855194538;5466.207399927028;5469.753861076261;5477.507024367826;5483.177523371526;20644;20657.690134647994;21025.369238922856;22827.77172228273;22831.77168228313;2460.200434858678;2482.5283196192668;2552.948941021169;2587.7671009609226;2731.576605626131;13366.665833354244;13798.93100534501;14020.29898505081;14022.929885350508;14609.342579583945;1876.7466392037036;1915.5019694879293;1927.9325223617107;1984.5336345958688;1994.9202031918705;86493.56753216256;86796.56601717015;89278.9297926373;91864;92128.53935730345;16864.662706745796;17101.572460688334;17229.91385043067;17348.826511734973;17393.826061739474;30984.22539436532;31113.377732445224;31240.375192496023;31330.68669313278;31345.216369590944;9605.519724013911;10070.295079344522;10144.391336519833;10149.137323327452;10234.744131396626;8335.022018811176;8347.666093356198;8350.206730360605;8413.873791893126;8589.699360522352;8456.661733530713;8498.830023399498;8615.91384086151;8763.46639338126;8815.206631403205;3263.116890899869;3343.4650455926935;3368.1411240133607;3379.374815659062;3420.247545539977;929.3448119076036;995.8904520502739;1034.4827586206898;1051.4059556350637;1059.9576016959236;17902.46292611224;18442.538936526427;18608.255669773058;18792.624147516974;18853.62292754137;1006.4464544500496;1038.1850247555633;1038.6260946059413;1110.2228440091967;1120.7254222715487;9958.551865166084;10203.94898025505;10288.434136122436;10386.128082717116;10393.324433911772;483178;483516;527459;540819;542430;81380.59309703413;84636;84860;86315.56842215749;101187.49406253069;1057.545255540125;1059.1368035051407;1085.5060947268973;1100.3672888089382;1106.2311693373028;16390.918045409697;16778.224899397537;16902.746458803373;17070.573235669206;17562.014457541994;37500.43749343707;37726.245475090895;38097.4285385725;38103.618963810004;38183.42724859185;53596.732016339665;55015.17477237925;55262.447375527336;55533.7223313897;55875.44124558865;7933;7940.483868548526;8021.438499304993;8064.55644939534;8204.466709663853;8221.79445513867;8273.627686753991;8338.332933365195;8583.570821459027;8857.33569982241;7679.116901556393;7749.496282741604;7763.844723105619;7793.454458187873;8129.552874591955;7544.547327160388;7643.88534171999;7663.731769388045;7699.538027718355;7809.062912450542;7529.924700752922;7994.680212791423;8084.110747817617;8132.878006829784;8156.225158609923;11736.295822250351;11995;12078.879211207775;12254.938725306316;12258.75482490363;11721.238119522204;11826;12065.336406497376;12074.93962530217;12221.75556488883 19:18:57 INFO - PROCESS | 18733 | |3;mootools.html;1276.15773589431;1462.1081140504293;1520.7262692715312;1619.9919000404996;1775.831296026877;7364.33720965113;7940.682372705092;8103.756887293383;8114.269715725583;8154.91845081549;7864.4101692373015;8008.519488830674;8034.718784842532;8036.879446808302;8060.3954703397185;2010.9698354524694;2171.81539569136;2221.622324204889;2248.9887550562266;2285.5086156476355;2265.478939843837;2275.180934863448;2277.464795772992;2278.316505048487;2279.555486680098;2140;2183.1376606240606;2183.192218879009;2185.1805572910157;2185.4099393163838;1971.3494546799593;2188.8248940084836;2225.922092726747;2231.129859354853;2236.284388995522;2236.0720301075035;2248.4828489447355;2266.7393249776324;2267.501149747053;2270.7956283934527;8069.757907262733;8144.389170812213;8160.5103693778565;8160.877586836202;8164.918350816534;4789.56893879549;4930.9013819723405;4935.80256789728;4955.90088198234;5033.974830125862;3153.8738450462183;3229.612446506411;3247.57781488405;3307.1236122427576;3338.6494418085995;1025.2208321675505;1036.4972988100765;1039.9896001039967;1049.1868801678684;1054.783769327286;10810.67567972962;11138.888611113865;11141;11166.274192177563;11176.329420234812;4246.320588705792;4316.072044510445;4356.3683265926265;4369.541198174209;4393.253146965024;9471.715848524555;9482;9506.239500839916;9508.001659825757;9513.476758778195;4649.465311489222;4982.8754281143265;4988.027334669777;4988.376452943382;5016.924746128738;4565.132624801344;4651.837185698484;4658.95341046592;4677.064587082529;4703.247480403187;3742.3825068863334;3785.337565925952;3833.2333533293454;3856.1709232515145;3858.594847541024;1255.491525931999;1259.1563652352763;1260.036072404611;1272.5864094169433;1279.9360031998363;863.4344504349662;952.5999080386255;967.2987084363921;984.9165917490703;996.7508122969258;5520.447955204528;5690.174924635905;5693.629914055573;5865.736041878125;5884.146798714162;400.5273776943235;400.9358502639622;401.44600451377494;403.1071177342201;410.21648651076896;3753.8686145985307;3770.472133901256;3833.750806197588;3872.96127038726;3886.9222615548097;9618;10063.64777232808;10072.395656260647;10074.14369778577;10088.243381746252;9428.151466367923;9818.361806482555;9933.552990115462;10009.949950250202;10032.39805611651;7556.508826926232;7747.612619369009;7759.883601746092;7794.6882124714375;7801.375889928792;5689.118186681071;5866.853328666817;5880.500157486662;5908.143319218775;5979.103134529855;9309.208717258838;9335.206507446943;9411.52942352866;9468.242540596599;9474.905250947402;9093.954530227307;9293.628254869729;9395.201407880406;9433.764155896157;9502;6690.498212633975;6781.186257648917;6848.863022739418;6985.720571177096;7080.221175670775;7678.078630564368;7680.692772289046;7715.189905059888;7719.768406947801;7722.420818438752;6901.275366086689;6998.475114366544;7049.29507049291;7056.294370562902;7161.355478007004;6874.4844136690945;6920.134983127109;6971.163460384786;7050.788476345718;7224.89162662571;6666.933330666631;6669.333066693292;6677.465802735866;6688.531802773759;6701.229358623626;4942.01159768055;4948.975255123845;4963.5780958618925;4968.8757781055765;4987.80048798044;4992.276119962513;5003.34956455673;5013.24801279811;5026.974865125651;5077.416097148727 19:18:57 INFO - PROCESS | 18733 | |4;prototype.html;299.36086455417677;299.5581517262039;301.2288541334186;313.7458658486626;314.34772846343833;5593.35676397214;5644.153376993453;5658.575606829489;5665.8583535411635;5680.801171958982;5522.420145884675;5571.777128914849;5579.637323573965;5611.8316450506445;5624.803131890385;4061.593840615937;4111.854431359997;4232.365145228225;4271.518712249523;4289.206496798087;4224.38746381776;4224.66202703783;4232;4254.085371645096;4258.318669012959;4135.648469880049;4220.683448741357;4228.640565551932;4240.936385954213;4255.425517555122;4175.832966681329;4281.593248641374;4292.957070429318;4307.418498502725;4319.049809042006;3726.217494326195;3731.2164445466483;3748.6813620842263;3752.981235093807;3762.3415902217002;5311.681299122065;5370.275012873251;5382.300300960889;5399.1091469907415;5402.270693456412;4912.680675756064;4954.7274899880495;4956.529129732669;4959.950400495985;4960;5810.157527158581;5812.651240925558;5813.186153938452;5813.79651712188;5849.64902105875;4034;4118.114605359862;4128.566500517432;4135.875923722293;4141.565135660742;5994.820155395301;6013.067974463965;6104.145419641254;6112.388761123896;6135.6625385603775;4224.345226489899;4240.660747140221;4241.4698162729655;4248.299030659969;4269;4246.44796176495;4323.978380108142;4332.241857674894;4339.284018137035;4345.652347812167;4922.75386230687;4929.359183306147;4943;4950.925736113961;4973.104841128632;5839.649621022667;5890.49930755882;5895.734691938873;5939.821805345847;5941.227640406806;1407.971840563183;1450.6808502129516;1459.1318165691396;1465.2234315812636;1488.5087920986048;941.3410612571228;989.5101924547395;991.9504024798875;1006.572206812102;1027.3117011602094;5536.667799932017;5547.41752116022;5566.940232114062;5577.386487486373;5590.664560126405;6021.096835474714;6041.395860414012;6049.334573196945;6053;6056.939430605726;5448.466463094601;5647.745851436613;5725.398833122462;5726.198332233491;5770;3286.802791832498;3288.391647545164;3298.86804527821;3309.9007029789145;3314.64099719114;6245.594036387621;6367.108604795239;6390.712417941204;6401.231852177769;6420.6789660517725;6284.5600807943;6296.622202667854;6336.1762970814425;6340.778072767431;6343.492520598341;6140.754369825157;6313.90529142054;6352.6812713359295;6359.713812878432;6377.107204991305;6095;6122.316363000211;6127.509799215963;6143.723532440961;6160.507159427146;6084.756609735561;6091.360407157362;6092.847678808065;6098.237720284965;6140.293866205444;6109.389061093855;6362.77730279438;6398.976163813767;6419.64691941929;6433.260175079926;4152.709310348247;4153.1070819773895;4161.271777438997;4171.70798044134;4174.958250417579;5932.228810254639;6213.099100630474;6227.719752611234;6255.655938923403;6277.874442511215;6010.819675409744;6020.157177994996;6028.487578555872;6042.939570604238;6070.423309785563;6006.249218847644;6007.549433792396;6014.759409623741;6042.123892035718;6063.969680151571;5691.8086536684505;5812.157237200498;5909.320428150818;5913.438223368773;5937.109433584997;5996.640201587744;6031.547633927561;6043.879122417615;6048.727807248596;6049.939500605114;5731.426857314402;5844.107166952385;5897.469227769521;5930.851728706817;5939.67331796738 19:18:57 INFO - PROCESS | 18733 | |5;yui.html;730.050933786078;736.3336180756414;748.9213632568584;749.8162950077228;753.5064532731063;13169.020419177827;13300.999999999987;13488.797668034975;13535;14672.339744711488;12792.040596955243;12836.67908302293;12929.353532323394;12945.870541294584;12949.41727622257;668.5153263883694;778.7585848387018;797.7925739307793;803.6945960534988;803.8352137811764;798.2257210505829;801.7995501124719;801.8516574433719;802.5265093594778;803.9236272554127;821.2198411509061;823.4894365493402;823.5305875650852;824.3652387661497;824.8020475085996;696.380221602774;786.8859015442786;822.0833770346051;823.5347028928696;824.9917500825005;595.9463648271676;596.8448203467086;596.8716725903952;597.9910301345483;598.5660396212754;13487.055906086578;13493.392797324046;13531.729365412637;13584.320783960762;13589;11418.371989540572;11779.446139217946;12092.274463532129;12094.516219351215;12145.392730363536;9878.753031174207;9909.554070066864;9942.353747006422;10110.24173187014;10250.7437314067;3607.0943516817106;3614.403623402162;3689.409694448875;3690.6862916652117;3720.069982504374;55722;55972.44027559713;56029.43970560283;57120.42879571192;57366.999999999585;9491.905080949102;9982.500874956224;10028.147607453448;10265.435401053013;10419.322744021614;16688.415905443253;16717.49847504577;17108.144592770313;17112.74330885038;17113.315467381413;7949.403794715419;7965.004374453193;8010.854118078226;8017.6792928283385;8045.637946292314;4601.401817763669;4673.859784206479;4685.297205419214;4832.758362081952;4892.143874821963;3074.9538756918664;3137.9215519611735;3148.2286839723956;3158.3999040182293;3159.0680749178587;523.1159340714181;531.5189753273216;550.1939658400463;557.6737608498993;585.5871610514577;526.8314139475408;592.4815786187087;617.8970537590379;639.5555089212952;646.9805905822833;8422.24199822019;8425.283850872622;8549.280616571867;8568.614412351459;8635.481871087755;647.7247169952751;648.3225029843825;662.9303923088103;663.1346093348227;688.6040526697169;3933.586973367755;4074.409210664496;4195.454590903224;4207.5161356444405;4253.425787518645;55612.47282365918;60486;61088.38911610916;61093.38906610877;61139.38860611426;51125.23312150321;52413;52577.737111314964;52927.735361323714;54024;48125.27812082822;48261.5173848264;48474.515254847705;48968.5103148964;49981.50018499841;8198.631061602124;8228.465149765247;8273.33813294947;8308.626111824862;8491.490510569385;30031.249218769706;30200.84899575488;30905.69094309118;31009.224769380948;31714.682853171173;39038.80480597693;41947.37078943757;43916.560834391246;44146.77926610346;44590.55409445864;9967.850482242917;10187.28688991793;10280.17758579297;10312.226583006155;11037.448127593749;11643.708907277385;11973.341466219441;12350.505979761068;12579.245245285312;12593.685157871127;11577.6526704199;11630.53477860876;11729.882701172879;11886.34625095628;11961.521539138337;11830.52677892909;11900;11959.701007474883;12011.699707507381;12247.510099595916;11651.067914566645;12287.815682764587;12440.502379904703;12571.811422828481;12578.496860125859;9978.101970822661;10009.24930630191;10249.897501025192;10409.716534489375;10493.52779124926;9884.802303954024;10549.261551691308;10566.366018038942;10613.836892061106;10706.304090234109 19:18:57 INFO - PROCESS | 18733 | __end_tp_report 19:18:57 INFO - PROCESS | 18733 | __start_cc_report 19:18:57 INFO - PROCESS | 18733 | _x_x_mozilla_cycle_collect,830 19:18:57 INFO - PROCESS | 18733 | __end_cc_report 19:18:57 INFO - PROCESS | 18733 | __startTimestamp1461032337112__endTimestamp 19:18:57 INFO - PROCESS | 18733 | 19:18:57 INFO - PROCESS | 18733 | ------- Summary: start ------- 19:18:57 INFO - PROCESS | 18733 | Number of tests: 6 19:18:57 INFO - PROCESS | 18733 | 19:18:57 INFO - PROCESS | 18733 | [#0] dojo.html Cycles:165 Average:5190.42 Median:5404.19 stddev:569.62 (10.5%) stddev-sans-first:546.00 19:18:57 INFO - PROCESS | 18733 | Values: 3047.8 3103.7 3138.5 3146.0 3168.6 5188.5 5251.8 5259.8 5269.9 5270.4 5210.9 5233.2 5250.0 5275.2 5278.8 4698.5 4734.4 4734.5 4844.3 4888.4 4843.0 4910.4 4920.4 4971.7 4983.5 4691.9 4737.7 4764.3 4818.9 4940.3 4696.3 4700.3 4700.4 4705.4 4727.6 4664.1 4685.6 4700.1 4706.1 4711.1 5160.2 5218.1 5219.2 5249.6 5268.9 5462.3 5505.5 5506.9 5507.7 5520.5 5523.5 5528.4 5551.3 5556.1 5582.9 5288.8 5290.4 5297.2 5301.8 5348.6 5789.8 5797.4 5797.5 5798.8 5799.8 5356.5 5558.1 5559.6 5565.9 5617.1 5559.4 5560.2 5567.1 5568.1 5591.5 5542.9 5547.3 5549.6 5552.2 5557.2 5374.0 5505.0 5538.9 5595.2 5621.3 4265.2 4266.5 4274.9 4275.6 4295.9 3825.8 3832.3 3841.5 3856.9 3862.7 5069.1 5145.1 5255.8 5298.3 5318.2 5504.7 5529.8 5533.3 5537.8 5552.3 5152.7 5158.7 5165.2 5166.1 5180.1 4763.3 4780.7 4793.9 4821.7 4832.9 5737.5 5739.1 5748.9 5753.0 5753.3 5461.5 5580.5 5592.4 5604.0 5651.3 5705.3 5773.0 5795.0 5839.5 5843.6 5471.9 5473.4 5476.0 5477.2 5480.1 5494.4 5511.6 5526.4 5547.3 5585.0 5470.3 5480.2 5480.3 5496.7 5535.0 5456.6 5460.4 5462.2 5463.0 5464.9 5247.3 5348.5 5385.9 5387.4 5477.0 5522.9 5528.6 5529.9 5532.4 5574.1 5364.8 5404.2 5406.5 5414.6 5417.4 19:18:57 INFO - PROCESS | 18733 | 19:18:57 INFO - PROCESS | 18733 | [#1] ext.html Cycles:180 Average:55184.94 Median:35051.47 stddev:108240.88 (308.8%) stddev-sans-first:108466.93 19:18:57 INFO - PROCESS | 18733 | Values: 597.7 639.7 650.4 652.2 656.7 30031.5 37256.3 37577.4 37677.4 37685.2 39729.6 40384.8 40399.4 40479.8 40518.2 7862.2 8096.1 8498.0 8694.6 8697.1 10125.0 10509.9 10531.2 10538.2 10542.7 10203.4 10281.0 10287.9 10294.1 10294.2 8072.1 10171.9 10327.1 10687.5 10698.4 9948.1 9951.7 10029.4 10041.2 10070.6 36899.8 37381.6 37577.4 37660.0 37667.2 3366.8 3391.5 3566.7 3788.0 3909.7 31342.7 31356.4 31403.8 31508.7 31649.7 4275.3 4314.8 4473.9 4542.2 4542.3 322603.0 323191.0 324051.0 324338.0 324678.0 34388.7 34970.7 34987.8 55922.7 55945.0 33520.8 33590.2 33686.7 33687.8 33698.3 13504.3 13559.8 13658.1 13693.2 13713.9 11832.1 11865.5 11871.6 11883.2 11920.7 8279.1 8299.7 8328.4 8366.9 8385.3 2975.7 3070.7 3191.5 3233.4 3266.5 1007.0 1079.5 1109.3 1122.3 1142.1 30963.4 32273.5 32281.4 32287.7 32351.4 1116.9 1148.8 1157.2 1217.6 1230.4 16869.0 17083.9 17149.4 17151.9 17205.4 454552.0 462048.0 462191.0 462514.0 462888.0 54154.5 55768.2 55855.0 55875.2 55884.4 1345.8 1345.9 1354.0 1392.4 1394.6 30076.7 31138.4 34810.5 34856.5 35088.1 54367.0 55119.7 55686.2 55844.4 55895.0 424026.0 426706.3 431154.0 431696.0 432053.0 36521.1 42173.4 42439.0 42787.8 43374.8 40610.2 40667.8 41411.0 41432.6 41437.0 35817.8 35911.5 35968.8 36611.5 37971.2 37972.8 38179.4 38453.4 38652.4 38904.8 39603.2 39769.6 40186.2 40663.6 41441.4 32208.0 34374.5 34557.7 35014.8 35094.3 26702.6 26966.5 27316.9 27441.3 27454.0 19:18:57 INFO - PROCESS | 18733 | 19:18:57 INFO - PROCESS | 18733 | [#2] jquery.html Cycles:180 Average:30016.26 Median:38143.52 stddev:84975.37 (222.8%) stddev-sans-first:85184.63 19:18:57 INFO - PROCESS | 18733 | Values: 389.8 392.8 394.1 397.9 411.2 16841.7 16848.5 17540.8 17569.5 19772.3 15156.8 16279.9 17093.2 18448.7 18775.8 5426.8 5485.1 5675.0 5707.6 5714.2 5582.7 5591.6 5606.3 5623.9 5628.7 4020.3 5573.3 5574.1 5576.3 5877.1 6104.7 6145.0 6176.7 6177.9 6178.0 5223.3 5466.2 5469.8 5477.5 5483.2 20644.0 20657.7 21025.4 22827.8 22831.8 2460.2 2482.5 2552.9 2587.8 2731.6 13366.7 13798.9 14020.3 14022.9 14609.3 1876.7 1915.5 1927.9 1984.5 1994.9 86493.6 86796.6 89278.9 91864.0 92128.5 16864.7 17101.6 17229.9 17348.8 17393.8 30984.2 31113.4 31240.4 31330.7 31345.2 9605.5 10070.3 10144.4 10149.1 10234.7 8335.0 8347.7 8350.2 8413.9 8589.7 8456.7 8498.8 8615.9 8763.5 8815.2 3263.1 3343.5 3368.1 3379.4 3420.2 929.3 995.9 1034.5 1051.4 1060.0 17902.5 18442.5 18608.3 18792.6 18853.6 1006.4 1038.2 1038.6 1110.2 1120.7 9958.6 10203.9 10288.4 10386.1 10393.3 483178.0 483516.0 527459.0 540819.0 542430.0 81380.6 84636.0 84860.0 86315.6 101187.5 1057.5 1059.1 1085.5 1100.4 1106.2 16390.9 16778.2 16902.7 17070.6 17562.0 37500.4 37726.2 38097.4 38103.6 38183.4 53596.7 55015.2 55262.4 55533.7 55875.4 7933.0 7940.5 8021.4 8064.6 8204.5 8221.8 8273.6 8338.3 8583.6 8857.3 7679.1 7749.5 7763.8 7793.5 8129.6 7544.5 7643.9 7663.7 7699.5 7809.1 7529.9 7994.7 8084.1 8132.9 8156.2 11736.3 11995.0 12078.9 12254.9 12258.8 11721.2 11826.0 12065.3 12074.9 12221.8 19:18:57 INFO - PROCESS | 18733 | 19:18:57 INFO - PROCESS | 18733 | [#3] mootools.html Cycles:180 Average:5384.53 Median:4985.34 stddev:2995.87 (60.1%) stddev-sans-first:2988.36 19:18:57 INFO - PROCESS | 18733 | Values: 1276.2 1462.1 1520.7 1620.0 1775.8 7364.3 7940.7 8103.8 8114.3 8154.9 7864.4 8008.5 8034.7 8036.9 8060.4 2011.0 2171.8 2221.6 2249.0 2285.5 2265.5 2275.2 2277.5 2278.3 2279.6 2140.0 2183.1 2183.2 2185.2 2185.4 1971.3 2188.8 2225.9 2231.1 2236.3 2236.1 2248.5 2266.7 2267.5 2270.8 8069.8 8144.4 8160.5 8160.9 8164.9 4789.6 4930.9 4935.8 4955.9 5034.0 3153.9 3229.6 3247.6 3307.1 3338.6 1025.2 1036.5 1040.0 1049.2 1054.8 10810.7 11138.9 11141.0 11166.3 11176.3 4246.3 4316.1 4356.4 4369.5 4393.3 9471.7 9482.0 9506.2 9508.0 9513.5 4649.5 4982.9 4988.0 4988.4 5016.9 4565.1 4651.8 4659.0 4677.1 4703.2 3742.4 3785.3 3833.2 3856.2 3858.6 1255.5 1259.2 1260.0 1272.6 1279.9 863.4 952.6 967.3 984.9 996.8 5520.4 5690.2 5693.6 5865.7 5884.1 400.5 400.9 401.4 403.1 410.2 3753.9 3770.5 3833.8 3873.0 3886.9 9618.0 10063.6 10072.4 10074.1 10088.2 9428.2 9818.4 9933.6 10009.9 10032.4 7556.5 7747.6 7759.9 7794.7 7801.4 5689.1 5866.9 5880.5 5908.1 5979.1 9309.2 9335.2 9411.5 9468.2 9474.9 9094.0 9293.6 9395.2 9433.8 9502.0 6690.5 6781.2 6848.9 6985.7 7080.2 7678.1 7680.7 7715.2 7719.8 7722.4 6901.3 6998.5 7049.3 7056.3 7161.4 6874.5 6920.1 6971.2 7050.8 7224.9 6666.9 6669.3 6677.5 6688.5 6701.2 4942.0 4949.0 4963.6 4968.9 4987.8 4992.3 5003.3 5013.2 5027.0 5077.4 19:18:57 INFO - PROCESS | 18733 | 19:18:57 INFO - PROCESS | 18733 | [#4] prototype.html Cycles:180 Average:4963.49 Median:5653.16 stddev:1505.76 (26.6%) stddev-sans-first:1468.73 19:18:57 INFO - PROCESS | 18733 | Values: 299.4 299.6 301.2 313.7 314.3 5593.4 5644.2 5658.6 5665.9 5680.8 5522.4 5571.8 5579.6 5611.8 5624.8 4061.6 4111.9 4232.4 4271.5 4289.2 4224.4 4224.7 4232.0 4254.1 4258.3 4135.6 4220.7 4228.6 4240.9 4255.4 4175.8 4281.6 4293.0 4307.4 4319.0 3726.2 3731.2 3748.7 3753.0 3762.3 5311.7 5370.3 5382.3 5399.1 5402.3 4912.7 4954.7 4956.5 4960.0 4960.0 5810.2 5812.7 5813.2 5813.8 5849.6 4034.0 4118.1 4128.6 4135.9 4141.6 5994.8 6013.1 6104.1 6112.4 6135.7 4224.3 4240.7 4241.5 4248.3 4269.0 4246.4 4324.0 4332.2 4339.3 4345.7 4922.8 4929.4 4943.0 4950.9 4973.1 5839.6 5890.5 5895.7 5939.8 5941.2 1408.0 1450.7 1459.1 1465.2 1488.5 941.3 989.5 992.0 1006.6 1027.3 5536.7 5547.4 5566.9 5577.4 5590.7 6021.1 6041.4 6049.3 6053.0 6056.9 5448.5 5647.7 5725.4 5726.2 5770.0 3286.8 3288.4 3298.9 3309.9 3314.6 6245.6 6367.1 6390.7 6401.2 6420.7 6284.6 6296.6 6336.2 6340.8 6343.5 6140.8 6313.9 6352.7 6359.7 6377.1 6095.0 6122.3 6127.5 6143.7 6160.5 6084.8 6091.4 6092.8 6098.2 6140.3 6109.4 6362.8 6399.0 6419.6 6433.3 4152.7 4153.1 4161.3 4171.7 4175.0 5932.2 6213.1 6227.7 6255.7 6277.9 6010.8 6020.2 6028.5 6042.9 6070.4 6006.2 6007.5 6014.8 6042.1 6064.0 5691.8 5812.2 5909.3 5913.4 5937.1 5996.6 6031.5 6043.9 6048.7 6049.9 5731.4 5844.1 5897.5 5930.9 5939.7 19:18:57 INFO - PROCESS | 18733 | 19:18:57 INFO - PROCESS | 18733 | [#5] yui.html Cycles:180 Average:14064.87 Median:48193.40 stddev:16649.07 (34.5%) stddev-sans-first:16665.66 19:18:57 INFO - PROCESS | 18733 | Values: 730.1 736.3 748.9 749.8 753.5 13169.0 13301.0 13488.8 13535.0 14672.3 12792.0 12836.7 12929.4 12945.9 12949.4 668.5 778.8 797.8 803.7 803.8 798.2 801.8 801.9 802.5 803.9 821.2 823.5 823.5 824.4 824.8 696.4 786.9 822.1 823.5 825.0 595.9 596.8 596.9 598.0 598.6 13487.1 13493.4 13531.7 13584.3 13589.0 11418.4 11779.4 12092.3 12094.5 12145.4 9878.8 9909.6 9942.4 10110.2 10250.7 3607.1 3614.4 3689.4 3690.7 3720.1 55722.0 55972.4 56029.4 57120.4 57367.0 9491.9 9982.5 10028.1 10265.4 10419.3 16688.4 16717.5 17108.1 17112.7 17113.3 7949.4 7965.0 8010.9 8017.7 8045.6 4601.4 4673.9 4685.3 4832.8 4892.1 3075.0 3137.9 3148.2 3158.4 3159.1 523.1 531.5 550.2 557.7 585.6 526.8 592.5 617.9 639.6 647.0 8422.2 8425.3 8549.3 8568.6 8635.5 647.7 648.3 662.9 663.1 688.6 3933.6 4074.4 4195.5 4207.5 4253.4 55612.5 60486.0 61088.4 61093.4 61139.4 51125.2 52413.0 52577.7 52927.7 54024.0 48125.3 48261.5 48474.5 48968.5 49981.5 8198.6 8228.5 8273.3 8308.6 8491.5 30031.2 30200.8 30905.7 31009.2 31714.7 39038.8 41947.4 43916.6 44146.8 44590.6 9967.9 10187.3 10280.2 10312.2 11037.4 11643.7 11973.3 12350.5 12579.2 12593.7 11577.7 11630.5 11729.9 11886.3 11961.5 11830.5 11900.0 11959.7 12011.7 12247.5 11651.1 12287.8 12440.5 12571.8 12578.5 9978.1 10009.2 10249.9 10409.7 10493.5 9884.8 10549.3 10566.4 10613.8 10706.3 19:18:57 INFO - PROCESS | 18733 | -------- Summary: end -------- 19:18:57 INFO - PROCESS | 18733 | 19:18:57 INFO - TEST-INFO | 18733: exit 0 19:18:57 INFO - TEST-OK | dromaeo_css | took 1111638ms 19:18:57 INFO - TEST-START | kraken 19:18:57 INFO - operating with platform_type : linux_ 19:18:57 INFO - Initialising browser for kraken test... 19:18:57 INFO - TEST-INFO | started process 18937 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0xTCmO/profile http://localhost:33675/getInfo.html) 19:19:01 INFO - PROCESS | 18937 | __metrics Screen width/height:1600/1200 19:19:01 INFO - PROCESS | 18937 | colorDepth:24 19:19:01 INFO - PROCESS | 18937 | Browser inner width/height: 1024/697 19:19:01 INFO - PROCESS | 18937 | __metrics 19:19:02 ERROR - PROCESS | 18937 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 19:19:03 INFO - TEST-INFO | 18937: exit 0 19:19:03 INFO - Browser initialized. 19:19:03 INFO - Running cycle 1/1 for kraken test... 19:19:03 INFO - TEST-INFO | started process 19019 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0xTCmO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:19:15 INFO - PROCESS | 19019 | RSS: Main: 153063424 19:19:15 INFO - PROCESS | 19019 | 19:19:17 INFO - PROCESS | 19019 | Cycle 1(1): loaded http://localhost:33675/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:33675/tests/kraken/driver.html?testName=audio-beat-detection) 19:19:18 INFO - PROCESS | 19019 | RSS: Main: 207441920 19:19:18 INFO - PROCESS | 19019 | 19:19:21 INFO - PROCESS | 19019 | Cycle 1(1): loaded http://localhost:33675/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:33675/tests/kraken/driver.html?testName=audio-dft) 19:19:21 INFO - PROCESS | 19019 | RSS: Main: 194093056 19:19:21 INFO - PROCESS | 19019 | 19:19:24 INFO - PROCESS | 19019 | Cycle 1(1): loaded http://localhost:33675/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:33675/tests/kraken/driver.html?testName=audio-fft) 19:19:25 INFO - PROCESS | 19019 | RSS: Main: 263499776 19:19:25 INFO - PROCESS | 19019 | 19:19:27 INFO - PROCESS | 19019 | Cycle 1(1): loaded http://localhost:33675/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:33675/tests/kraken/driver.html?testName=audio-oscillator) 19:19:28 INFO - PROCESS | 19019 | RSS: Main: 173019136 19:19:28 INFO - PROCESS | 19019 | 19:19:30 INFO - PROCESS | 19019 | Cycle 1(1): loaded http://localhost:33675/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:33675/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:19:30 INFO - PROCESS | 19019 | RSS: Main: 176619520 19:19:30 INFO - PROCESS | 19019 | 19:19:33 INFO - PROCESS | 19019 | Cycle 1(1): loaded http://localhost:33675/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:33675/tests/kraken/driver.html?testName=imaging-darkroom) 19:19:34 INFO - PROCESS | 19019 | RSS: Main: 212910080 19:19:34 INFO - PROCESS | 19019 | 19:19:37 INFO - PROCESS | 19019 | Cycle 1(1): loaded http://localhost:33675/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:33675/tests/kraken/driver.html?testName=imaging-desaturate) 19:19:38 INFO - PROCESS | 19019 | RSS: Main: 213491712 19:19:38 INFO - PROCESS | 19019 | 19:19:41 INFO - PROCESS | 19019 | Cycle 1(1): loaded http://localhost:33675/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:33675/tests/kraken/driver.html?testName=json-parse-financial) 19:19:41 INFO - PROCESS | 19019 | RSS: Main: 213327872 19:19:41 INFO - PROCESS | 19019 | 19:19:43 INFO - PROCESS | 19019 | Cycle 1(1): loaded http://localhost:33675/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:33675/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:19:43 INFO - PROCESS | 19019 | RSS: Main: 201342976 19:19:43 INFO - PROCESS | 19019 | 19:19:45 INFO - PROCESS | 19019 | Cycle 1(1): loaded http://localhost:33675/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:33675/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:19:46 INFO - PROCESS | 19019 | RSS: Main: 203034624 19:19:46 INFO - PROCESS | 19019 | 19:19:48 INFO - PROCESS | 19019 | Cycle 1(1): loaded http://localhost:33675/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:33675/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:19:48 INFO - PROCESS | 19019 | RSS: Main: 217124864 19:19:48 INFO - PROCESS | 19019 | 19:19:51 INFO - PROCESS | 19019 | Cycle 1(1): loaded http://localhost:33675/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:33675/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:19:52 INFO - PROCESS | 19019 | RSS: Main: 223469568 19:19:52 INFO - PROCESS | 19019 | 19:19:54 INFO - PROCESS | 19019 | Cycle 1(1): loaded http://localhost:33675/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:33675/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:19:55 INFO - PROCESS | 19019 | RSS: Main: 218308608 19:19:55 INFO - PROCESS | 19019 | 19:19:57 INFO - PROCESS | 19019 | Cycle 1(1): loaded http://localhost:33675/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:33675/tests/kraken/driver.html?testName=ai-astar) 19:19:57 INFO - PROCESS | 19019 | RSS: Main: 281788416 19:19:57 INFO - PROCESS | 19019 | 19:19:57 INFO - PROCESS | 19019 | __start_tp_report 19:19:57 INFO - PROCESS | 19019 | _x_x_mozilla_page_load 19:19:57 INFO - PROCESS | 19019 | _x_x_mozilla_page_load_details 19:19:57 INFO - PROCESS | 19019 | |i|pagename|runs| 19:19:57 INFO - PROCESS | 19019 | |0;ai-astar;109;111;106;111;112;111;101;110;111;111 19:19:57 INFO - PROCESS | 19019 | |1;audio-beat-detection;165;127;161;184;126;157;151;129;184;125 19:19:57 INFO - PROCESS | 19019 | |2;audio-dft;161;162;164;161;161;164;161;163;161;162 19:19:57 INFO - PROCESS | 19019 | |3;audio-fft;73;76;96;110;77;73;73;110;106;76 19:19:57 INFO - PROCESS | 19019 | |4;audio-oscillator;93;92;156;91;92;92;154;92;92;141 19:19:57 INFO - PROCESS | 19019 | |5;imaging-gaussian-blur;118;117;116;117;117;117;117;116;117;116 19:19:57 INFO - PROCESS | 19019 | |6;imaging-darkroom;145;145;146;145;145;145;145;146;145;145 19:19:57 INFO - PROCESS | 19019 | |7;imaging-desaturate;94;94;95;95;95;95;95;95;95;95 19:19:57 INFO - PROCESS | 19019 | |8;json-parse-financial;58;58;58;59;59;57;57;79;57;55 19:19:57 INFO - PROCESS | 19019 | |9;json-stringify-tinderbox;61;62;62;62;62;61;61;63;62;62 19:19:57 INFO - PROCESS | 19019 | |10;stanford-crypto-aes;76;75;76;76;76;76;76;77;85;76 19:19:57 INFO - PROCESS | 19019 | |11;stanford-crypto-ccm;134;125;124;121;124;121;123;127;161;131 19:19:57 INFO - PROCESS | 19019 | |12;stanford-crypto-pbkdf2;164;161;165;168;168;162;164;164;164;165 19:19:57 INFO - PROCESS | 19019 | |13;stanford-crypto-sha256-iterative;68;67;69;69;67;67;68;70;68;68 19:19:57 INFO - PROCESS | 19019 | __end_tp_report 19:19:57 INFO - PROCESS | 19019 | __start_cc_report 19:19:57 INFO - PROCESS | 19019 | _x_x_mozilla_cycle_collect,1620 19:19:57 INFO - PROCESS | 19019 | __end_cc_report 19:19:57 INFO - PROCESS | 19019 | __startTimestamp1461032397402__endTimestamp 19:19:57 INFO - PROCESS | 19019 | 19:19:57 INFO - PROCESS | 19019 | ------- Summary: start ------- 19:19:57 INFO - PROCESS | 19019 | Number of tests: 14 19:19:57 INFO - PROCESS | 19019 | 19:19:57 INFO - PROCESS | 19019 | [#0] ai-astar Cycles:10 Average:109.30 Median:111.00 stddev:3.37 (3.0%) stddev-sans-first:3.57 19:19:57 INFO - PROCESS | 19019 | Values: 109.0 111.0 106.0 111.0 112.0 111.0 101.0 110.0 111.0 111.0 19:19:57 INFO - PROCESS | 19019 | 19:19:57 INFO - PROCESS | 19019 | [#1] audio-beat-detection Cycles:10 Average:150.90 Median:159.00 stddev:23.26 (14.6%) stddev-sans-first:24.11 19:19:57 INFO - PROCESS | 19019 | Values: 165.0 127.0 161.0 184.0 126.0 157.0 151.0 129.0 184.0 125.0 19:19:57 INFO - PROCESS | 19019 | 19:19:57 INFO - PROCESS | 19019 | [#2] audio-dft Cycles:10 Average:162.00 Median:162.00 stddev:1.25 (0.8%) stddev-sans-first:1.27 19:19:57 INFO - PROCESS | 19019 | Values: 161.0 162.0 164.0 161.0 161.0 164.0 161.0 163.0 161.0 162.0 19:19:57 INFO - PROCESS | 19019 | 19:19:57 INFO - PROCESS | 19019 | [#3] audio-fft Cycles:10 Average:87.00 Median:74.50 stddev:16.43 (22.1%) stddev-sans-first:16.63 19:19:57 INFO - PROCESS | 19019 | Values: 73.0 76.0 96.0 110.0 77.0 73.0 73.0 110.0 106.0 76.0 19:19:57 INFO - PROCESS | 19019 | 19:19:57 INFO - PROCESS | 19019 | [#4] audio-oscillator Cycles:10 Average:109.50 Median:92.00 stddev:28.44 (30.9%) stddev-sans-first:29.53 19:19:57 INFO - PROCESS | 19019 | Values: 93.0 92.0 156.0 91.0 92.0 92.0 154.0 92.0 92.0 141.0 19:19:57 INFO - PROCESS | 19019 | 19:19:57 INFO - PROCESS | 19019 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.63 (0.5%) stddev-sans-first:0.50 19:19:57 INFO - PROCESS | 19019 | Values: 118.0 117.0 116.0 117.0 117.0 117.0 117.0 116.0 117.0 116.0 19:19:57 INFO - PROCESS | 19019 | 19:19:57 INFO - PROCESS | 19019 | [#6] imaging-darkroom Cycles:10 Average:145.20 Median:145.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 19:19:57 INFO - PROCESS | 19019 | Values: 145.0 145.0 146.0 145.0 145.0 145.0 145.0 146.0 145.0 145.0 19:19:57 INFO - PROCESS | 19019 | 19:19:57 INFO - PROCESS | 19019 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 19:19:57 INFO - PROCESS | 19019 | Values: 94.0 94.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 19:19:57 INFO - PROCESS | 19019 | 19:19:57 INFO - PROCESS | 19019 | [#8] json-parse-financial Cycles:10 Average:59.70 Median:58.00 stddev:6.88 (11.9%) stddev-sans-first:7.27 19:19:57 INFO - PROCESS | 19019 | Values: 58.0 58.0 58.0 59.0 59.0 57.0 57.0 79.0 57.0 55.0 19:19:57 INFO - PROCESS | 19019 | 19:19:57 INFO - PROCESS | 19019 | [#9] json-stringify-tinderbox Cycles:10 Average:61.80 Median:62.00 stddev:0.63 (1.0%) stddev-sans-first:0.60 19:19:57 INFO - PROCESS | 19019 | Values: 61.0 62.0 62.0 62.0 62.0 61.0 61.0 63.0 62.0 62.0 19:19:57 INFO - PROCESS | 19019 | 19:19:57 INFO - PROCESS | 19019 | [#10] stanford-crypto-aes Cycles:10 Average:76.90 Median:76.00 stddev:2.88 (3.8%) stddev-sans-first:3.04 19:19:57 INFO - PROCESS | 19019 | Values: 76.0 75.0 76.0 76.0 76.0 76.0 76.0 77.0 85.0 76.0 19:19:57 INFO - PROCESS | 19019 | 19:19:57 INFO - PROCESS | 19019 | [#11] stanford-crypto-ccm Cycles:10 Average:129.10 Median:126.00 stddev:11.96 (9.5%) stddev-sans-first:12.55 19:19:57 INFO - PROCESS | 19019 | Values: 134.0 125.0 124.0 121.0 124.0 121.0 123.0 127.0 161.0 131.0 19:19:57 INFO - PROCESS | 19019 | 19:19:57 INFO - PROCESS | 19019 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.50 Median:164.50 stddev:2.22 (1.4%) stddev-sans-first:2.35 19:19:57 INFO - PROCESS | 19019 | Values: 164.0 161.0 165.0 168.0 168.0 162.0 164.0 164.0 164.0 165.0 19:19:57 INFO - PROCESS | 19019 | 19:19:57 INFO - PROCESS | 19019 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.10 Median:68.00 stddev:0.99 (1.5%) stddev-sans-first:1.05 19:19:57 INFO - PROCESS | 19019 | Values: 68.0 67.0 69.0 69.0 67.0 67.0 68.0 70.0 68.0 68.0 19:19:57 INFO - PROCESS | 19019 | -------- Summary: end -------- 19:19:57 INFO - PROCESS | 19019 | 19:19:58 INFO - TEST-INFO | 19019: exit 0 19:19:58 INFO - TEST-OK | kraken | took 60441ms 19:19:58 INFO - SUITE-END | took 1172s 19:19:58 INFO - Completed test suite (00:19:32) 19:19:58 INFO - javascript benchmark 19:19:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3047.7866549341547, 3103.6741142180085, 3138.4978403455452, 3145.9685403145977, 3168.603924509436, 5188.507091826271, 5251.763670634821, 5259.763310651025, 5269.8946021079555, 5270.420253772091, 5210.895782084356, 5233.188855727369, 5249.973750131254, 5275.235090911825, 5278.788848446067, 4698.506656801038, 4734.431868175824, 4734.526547345273, 4844.346013288214, 4888.413390393158, 4843.0, 4910.361652985107, 4920.409550853902, 4971.676841005341, 4983.476734942833, 4691.859244222679, 4737.739424331678, 4764.332993380929, 4818.879528011793, 4940.333055037578, 4696.2955556666775, 4700.29495575663, 4700.388949436586, 4705.364775755298, 4727.621790256771, 4664.090502352043, 4685.648576356787, 4700.130475861976, 4706.10583989041, 4711.104890070876, 5160.200168973777, 5218.060749065166, 5219.217117432416, 5249.632525723163, 5268.894622107537, 5462.262594549725, 5505.476979686923, 5506.91739623906, 5507.7246137693355, 5520.503154716095, 5523.530499907512, 5528.364238112588, 5551.250581171531, 5556.111022236463, 5582.944170558283, 5288.8413347599635, 5290.365156181283, 5297.205419187114, 5301.761420736076, 5348.598855085846, 5789.768409263666, 5797.391273916178, 5797.536197104221, 5798.82603521895, 5799.768009279582, 5356.544693701079, 5558.082916318843, 5559.555235581144, 5565.9443405566235, 5617.073182924772, 5559.360673522516, 5560.2493663355735, 5567.081431563827, 5568.1091025435735, 5591.52472039873, 5542.944570554324, 5547.278853749068, 5549.5837812164245, 5552.222688823568, 5557.249771280906, 5374.032674118696, 5505.03661859173, 5538.944610553924, 5595.244641973363, 5621.325440947113, 4265.247239080663, 4266.466691663542, 4274.871753847327, 4275.636570891509, 4295.871123866289, 3825.7513261637905, 3832.291026160169, 3841.4814000110186, 3856.903577410531, 3862.748921320105, 5069.062223488666, 5145.07388670043, 5255.842324730188, 5298.337707786527, 5318.22885681574, 5504.724763761796, 5529.806456773994, 5533.3083364579425, 5537.7784888604, 5552.333719953632, 5152.6650767699975, 5158.74206289684, 5165.225216217522, 5166.095933211673, 5180.145276029413, 4763.309320148559, 4780.689255198401, 4793.904121917542, 4821.686590371684, 4832.855014349575, 5737.483626473638, 5739.139129130664, 5748.9425105748405, 5752.9712351437975, 5753.280839895013, 5461.5357694596405, 5580.49775520197, 5592.440755924537, 5604.019296623156, 5651.321841378896, 5705.258316418839, 5773.0, 5795.0, 5839.474447299764, 5843.561732869966, 5471.917921231265, 5473.370562385377, 5476.041692703681, 5477.178423236546, 5480.068388374066, 5494.395616482264, 5511.641743286674, 5526.364468086222, 5547.2511210986, 5585.022621041383, 5470.343558772973, 5480.177973304037, 5480.2875626169935, 5496.6701997880255, 5534.972325138348, 5456.590755693418, 5460.372057213472, 5462.235287059894, 5463.0, 5464.890702186013, 5247.265382846481, 5348.545373643284, 5385.8922821542565, 5387.434319396407, 5476.972615137058, 5522.889542209214, 5528.55771538284, 5529.944700552943, 5532.391436942014, 5574.08027675429, 5364.7585858635675, 5404.189371594292, 5406.486383793534, 5414.620976531763, 5417.43116972728], "value": 5153.63052411638, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [597.6892016151601, 639.6865535887415, 650.4406210658834, 652.2140820311525, 656.69791895728, 30031.549526757088, 37256.25487490252, 37577.43633845488, 37677.43483847738, 37685.24629507408, 39729.60270397295, 40384.79807600958, 40399.39400908989, 40479.79760101195, 40518.18963620733, 7862.213778622149, 8096.149904260056, 8498.0, 8694.56527173642, 8697.130286971316, 10125.0, 10509.947450262776, 10531.21015923805, 10538.156947444186, 10542.736431589195, 10203.387796732182, 10280.971902809733, 10287.948560257226, 10294.124999375063, 10294.176465882749, 8072.071711753136, 10171.898281017206, 10327.122194613466, 10687.465626718633, 10698.358098514114, 9948.10467057961, 9951.651692190739, 10029.448380339078, 10041.246906482043, 10070.597176112946, 36899.81550092233, 37381.62618373809, 37577.43633845494, 37660.0, 37667.24665506702, 3366.8148251846137, 3391.5421418108485, 3566.732495062881, 3788.0, 3909.6872250219913, 31342.686573134204, 31356.372872542648, 31403.84298078518, 31508.684913150806, 31649.683503164903, 4275.273203555403, 4314.762688052156, 4473.932891006638, 4542.227821270392, 4542.318652202163, 322603.0, 323191.0, 324051.0, 324338.0, 324678.0, 34388.65611343904, 34970.650293497245, 34987.82506087453, 55922.72038639781, 55945.0, 33520.83239583787, 33590.16024599405, 33686.66313336884, 33687.83156084204, 33698.32603347969, 13504.257265850472, 13559.796603050961, 13658.112222705691, 13693.246871421969, 13713.931430342784, 11832.112591555668, 11865.466054027589, 11871.64385068432, 11883.16817822744, 11920.701982450508, 8279.130691277449, 8299.709510167115, 8328.375371847136, 8366.874496882552, 8385.287250583646, 2975.702429757007, 3070.677578854188, 3191.505316675919, 3233.4341490239112, 3266.5426840242385, 1007.0161781290846, 1079.4710591809956, 1109.3454861631676, 1122.259308856151, 1142.080625096805, 30963.380732385227, 32273.51589726156, 32281.35437291241, 32287.677123228466, 32351.35297294041, 1116.8715597706205, 1148.7989601819647, 1157.1610582327912, 1217.5921066442663, 1230.4217018001525, 16869.0, 17083.91458042702, 17149.399771008204, 17151.881968982983, 17205.397811076553, 454552.0, 462048.0, 462191.0, 462514.0, 462888.0, 54154.458455415726, 55768.16347754787, 55855.0, 55875.161872571945, 55884.441155588735, 1345.8115863779078, 1345.8977057881677, 1354.0453979944116, 1392.3664732546667, 1394.5816255123423, 30076.699233007388, 31138.37723245477, 34810.47784283289, 34856.47715284323, 35088.12279693028, 54367.0, 55119.72440137774, 55686.16470752861, 55844.44155558555, 55895.0, 424026.0, 426706.32936707325, 431154.0, 431696.0, 432053.0, 36521.08697282589, 42173.367399488416, 42439.0, 42787.7860610695, 43374.78312608543, 40610.18779624332, 40667.7966610165, 41411.0, 41432.58567414287, 41437.0, 35817.82091089528, 35911.461328079575, 35968.8201559001, 36611.450828237066, 37971.240575188895, 37972.81013594914, 38179.42730859095, 38453.4231986526, 38652.420213697376, 38904.80547597357, 39603.207935841696, 39769.60230397775, 40186.19627607373, 40663.59336406598, 41441.37837932494, 32208.0, 34374.484382733775, 34557.654423455446, 35014.82492587521, 35094.29811403707, 26702.59946100771, 26966.460670786866, 27316.863415682794, 27441.31396715098, 27454.0], "value": 18641.725811677134, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [389.77393111995036, 392.8134136285267, 394.08375526899965, 397.89058009047505, 411.2371550773315, 16841.74737378937, 16848.49454516365, 17540.824591754077, 17569.47291581253, 19772.30796922108, 15156.772648410255, 16279.918600406982, 17093.23080461379, 18448.72326915094, 18775.81224187758, 5426.782928682848, 5485.149801780721, 5675.006873797069, 5707.600467967244, 5714.171445140452, 5582.748776305076, 5591.580631452657, 5606.299212598426, 5623.887522249553, 5628.690422026787, 4020.2964481215818, 5573.331200255955, 5574.080276754331, 5576.275084239043, 5877.14781356703, 6104.725287362035, 6145.0, 6176.691165441733, 6177.907331390033, 6178.0, 5223.294855194538, 5466.207399927028, 5469.753861076261, 5477.507024367826, 5483.177523371526, 20644.0, 20657.690134647994, 21025.369238922856, 22827.77172228273, 22831.77168228313, 2460.200434858678, 2482.5283196192668, 2552.948941021169, 2587.7671009609226, 2731.576605626131, 13366.665833354244, 13798.93100534501, 14020.29898505081, 14022.929885350508, 14609.342579583945, 1876.7466392037036, 1915.5019694879293, 1927.9325223617107, 1984.5336345958688, 1994.9202031918705, 86493.56753216256, 86796.56601717015, 89278.9297926373, 91864.0, 92128.53935730345, 16864.662706745796, 17101.572460688334, 17229.91385043067, 17348.826511734973, 17393.826061739474, 30984.22539436532, 31113.377732445224, 31240.375192496023, 31330.68669313278, 31345.216369590944, 9605.519724013911, 10070.295079344522, 10144.391336519833, 10149.137323327452, 10234.744131396626, 8335.022018811176, 8347.666093356198, 8350.206730360605, 8413.873791893126, 8589.699360522352, 8456.661733530713, 8498.830023399498, 8615.91384086151, 8763.46639338126, 8815.206631403205, 3263.116890899869, 3343.4650455926935, 3368.1411240133607, 3379.374815659062, 3420.247545539977, 929.3448119076036, 995.8904520502739, 1034.4827586206898, 1051.4059556350637, 1059.9576016959236, 17902.46292611224, 18442.538936526427, 18608.255669773058, 18792.624147516974, 18853.62292754137, 1006.4464544500496, 1038.1850247555633, 1038.6260946059413, 1110.2228440091967, 1120.7254222715487, 9958.551865166084, 10203.94898025505, 10288.434136122436, 10386.128082717116, 10393.324433911772, 483178.0, 483516.0, 527459.0, 540819.0, 542430.0, 81380.59309703413, 84636.0, 84860.0, 86315.56842215749, 101187.49406253069, 1057.545255540125, 1059.1368035051407, 1085.5060947268973, 1100.3672888089382, 1106.2311693373028, 16390.918045409697, 16778.224899397537, 16902.746458803373, 17070.573235669206, 17562.014457541994, 37500.43749343707, 37726.245475090895, 38097.4285385725, 38103.618963810004, 38183.42724859185, 53596.732016339665, 55015.17477237925, 55262.447375527336, 55533.7223313897, 55875.44124558865, 7933.0, 7940.483868548526, 8021.438499304993, 8064.55644939534, 8204.466709663853, 8221.79445513867, 8273.627686753991, 8338.332933365195, 8583.570821459027, 8857.33569982241, 7679.116901556393, 7749.496282741604, 7763.844723105619, 7793.454458187873, 8129.552874591955, 7544.547327160388, 7643.88534171999, 7663.731769388045, 7699.538027718355, 7809.062912450542, 7529.924700752922, 7994.680212791423, 8084.110747817617, 8132.878006829784, 8156.225158609923, 11736.295822250351, 11995.0, 12078.879211207775, 12254.938725306316, 12258.75482490363, 11721.238119522204, 11826.0, 12065.336406497376, 12074.93962530217, 12221.75556488883], "value": 9559.821719776062, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1276.15773589431, 1462.1081140504293, 1520.7262692715312, 1619.9919000404996, 1775.831296026877, 7364.33720965113, 7940.682372705092, 8103.756887293383, 8114.269715725583, 8154.91845081549, 7864.4101692373015, 8008.519488830674, 8034.718784842532, 8036.879446808302, 8060.3954703397185, 2010.9698354524694, 2171.81539569136, 2221.622324204889, 2248.9887550562266, 2285.5086156476355, 2265.478939843837, 2275.180934863448, 2277.464795772992, 2278.316505048487, 2279.555486680098, 2140.0, 2183.1376606240606, 2183.192218879009, 2185.1805572910157, 2185.4099393163838, 1971.3494546799593, 2188.8248940084836, 2225.922092726747, 2231.129859354853, 2236.284388995522, 2236.0720301075035, 2248.4828489447355, 2266.7393249776324, 2267.501149747053, 2270.7956283934527, 8069.757907262733, 8144.389170812213, 8160.5103693778565, 8160.877586836202, 8164.918350816534, 4789.56893879549, 4930.9013819723405, 4935.80256789728, 4955.90088198234, 5033.974830125862, 3153.8738450462183, 3229.612446506411, 3247.57781488405, 3307.1236122427576, 3338.6494418085995, 1025.2208321675505, 1036.4972988100765, 1039.9896001039967, 1049.1868801678684, 1054.783769327286, 10810.67567972962, 11138.888611113865, 11141.0, 11166.274192177563, 11176.329420234812, 4246.320588705792, 4316.072044510445, 4356.3683265926265, 4369.541198174209, 4393.253146965024, 9471.715848524555, 9482.0, 9506.239500839916, 9508.001659825757, 9513.476758778195, 4649.465311489222, 4982.8754281143265, 4988.027334669777, 4988.376452943382, 5016.924746128738, 4565.132624801344, 4651.837185698484, 4658.95341046592, 4677.064587082529, 4703.247480403187, 3742.3825068863334, 3785.337565925952, 3833.2333533293454, 3856.1709232515145, 3858.594847541024, 1255.491525931999, 1259.1563652352763, 1260.036072404611, 1272.5864094169433, 1279.9360031998363, 863.4344504349662, 952.5999080386255, 967.2987084363921, 984.9165917490703, 996.7508122969258, 5520.447955204528, 5690.174924635905, 5693.629914055573, 5865.736041878125, 5884.146798714162, 400.5273776943235, 400.9358502639622, 401.44600451377494, 403.1071177342201, 410.21648651076896, 3753.8686145985307, 3770.472133901256, 3833.750806197588, 3872.96127038726, 3886.9222615548097, 9618.0, 10063.64777232808, 10072.395656260647, 10074.14369778577, 10088.243381746252, 9428.151466367923, 9818.361806482555, 9933.552990115462, 10009.949950250202, 10032.39805611651, 7556.508826926232, 7747.612619369009, 7759.883601746092, 7794.6882124714375, 7801.375889928792, 5689.118186681071, 5866.853328666817, 5880.500157486662, 5908.143319218775, 5979.103134529855, 9309.208717258838, 9335.206507446943, 9411.52942352866, 9468.242540596599, 9474.905250947402, 9093.954530227307, 9293.628254869729, 9395.201407880406, 9433.764155896157, 9502.0, 6690.498212633975, 6781.186257648917, 6848.863022739418, 6985.720571177096, 7080.221175670775, 7678.078630564368, 7680.692772289046, 7715.189905059888, 7719.768406947801, 7722.420818438752, 6901.275366086689, 6998.475114366544, 7049.29507049291, 7056.294370562902, 7161.355478007004, 6874.4844136690945, 6920.134983127109, 6971.163460384786, 7050.788476345718, 7224.89162662571, 6666.933330666631, 6669.333066693292, 6677.465802735866, 6688.531802773759, 6701.229358623626, 4942.01159768055, 4948.975255123845, 4963.5780958618925, 4968.8757781055765, 4987.80048798044, 4992.276119962513, 5003.34956455673, 5013.24801279811, 5026.974865125651, 5077.416097148727], "value": 4273.257642454212, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.36086455417677, 299.5581517262039, 301.2288541334186, 313.7458658486626, 314.34772846343833, 5593.35676397214, 5644.153376993453, 5658.575606829489, 5665.8583535411635, 5680.801171958982, 5522.420145884675, 5571.777128914849, 5579.637323573965, 5611.8316450506445, 5624.803131890385, 4061.593840615937, 4111.854431359997, 4232.365145228225, 4271.518712249523, 4289.206496798087, 4224.38746381776, 4224.66202703783, 4232.0, 4254.085371645096, 4258.318669012959, 4135.648469880049, 4220.683448741357, 4228.640565551932, 4240.936385954213, 4255.425517555122, 4175.832966681329, 4281.593248641374, 4292.957070429318, 4307.418498502725, 4319.049809042006, 3726.217494326195, 3731.2164445466483, 3748.6813620842263, 3752.981235093807, 3762.3415902217002, 5311.681299122065, 5370.275012873251, 5382.300300960889, 5399.1091469907415, 5402.270693456412, 4912.680675756064, 4954.7274899880495, 4956.529129732669, 4959.950400495985, 4960.0, 5810.157527158581, 5812.651240925558, 5813.186153938452, 5813.79651712188, 5849.64902105875, 4034.0, 4118.114605359862, 4128.566500517432, 4135.875923722293, 4141.565135660742, 5994.820155395301, 6013.067974463965, 6104.145419641254, 6112.388761123896, 6135.6625385603775, 4224.345226489899, 4240.660747140221, 4241.4698162729655, 4248.299030659969, 4269.0, 4246.44796176495, 4323.978380108142, 4332.241857674894, 4339.284018137035, 4345.652347812167, 4922.75386230687, 4929.359183306147, 4943.0, 4950.925736113961, 4973.104841128632, 5839.649621022667, 5890.49930755882, 5895.734691938873, 5939.821805345847, 5941.227640406806, 1407.971840563183, 1450.6808502129516, 1459.1318165691396, 1465.2234315812636, 1488.5087920986048, 941.3410612571228, 989.5101924547395, 991.9504024798875, 1006.572206812102, 1027.3117011602094, 5536.667799932017, 5547.41752116022, 5566.940232114062, 5577.386487486373, 5590.664560126405, 6021.096835474714, 6041.395860414012, 6049.334573196945, 6053.0, 6056.939430605726, 5448.466463094601, 5647.745851436613, 5725.398833122462, 5726.198332233491, 5770.0, 3286.802791832498, 3288.391647545164, 3298.86804527821, 3309.9007029789145, 3314.64099719114, 6245.594036387621, 6367.108604795239, 6390.712417941204, 6401.231852177769, 6420.6789660517725, 6284.5600807943, 6296.622202667854, 6336.1762970814425, 6340.778072767431, 6343.492520598341, 6140.754369825157, 6313.90529142054, 6352.6812713359295, 6359.713812878432, 6377.107204991305, 6095.0, 6122.316363000211, 6127.509799215963, 6143.723532440961, 6160.507159427146, 6084.756609735561, 6091.360407157362, 6092.847678808065, 6098.237720284965, 6140.293866205444, 6109.389061093855, 6362.77730279438, 6398.976163813767, 6419.64691941929, 6433.260175079926, 4152.709310348247, 4153.1070819773895, 4161.271777438997, 4171.70798044134, 4174.958250417579, 5932.228810254639, 6213.099100630474, 6227.719752611234, 6255.655938923403, 6277.874442511215, 6010.819675409744, 6020.157177994996, 6028.487578555872, 6042.939570604238, 6070.423309785563, 6006.249218847644, 6007.549433792396, 6014.759409623741, 6042.123892035718, 6063.969680151571, 5691.8086536684505, 5812.157237200498, 5909.320428150818, 5913.438223368773, 5937.109433584997, 5996.640201587744, 6031.547633927561, 6043.879122417615, 6048.727807248596, 6049.939500605114, 5731.426857314402, 5844.107166952385, 5897.469227769521, 5930.851728706817, 5939.67331796738], "value": 4468.042777596554, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [730.050933786078, 736.3336180756414, 748.9213632568584, 749.8162950077228, 753.5064532731063, 13169.020419177827, 13300.999999999987, 13488.797668034975, 13535.0, 14672.339744711488, 12792.040596955243, 12836.67908302293, 12929.353532323394, 12945.870541294584, 12949.41727622257, 668.5153263883694, 778.7585848387018, 797.7925739307793, 803.6945960534988, 803.8352137811764, 798.2257210505829, 801.7995501124719, 801.8516574433719, 802.5265093594778, 803.9236272554127, 821.2198411509061, 823.4894365493402, 823.5305875650852, 824.3652387661497, 824.8020475085996, 696.380221602774, 786.8859015442786, 822.0833770346051, 823.5347028928696, 824.9917500825005, 595.9463648271676, 596.8448203467086, 596.8716725903952, 597.9910301345483, 598.5660396212754, 13487.055906086578, 13493.392797324046, 13531.729365412637, 13584.320783960762, 13589.0, 11418.371989540572, 11779.446139217946, 12092.274463532129, 12094.516219351215, 12145.392730363536, 9878.753031174207, 9909.554070066864, 9942.353747006422, 10110.24173187014, 10250.7437314067, 3607.0943516817106, 3614.403623402162, 3689.409694448875, 3690.6862916652117, 3720.069982504374, 55722.0, 55972.44027559713, 56029.43970560283, 57120.42879571192, 57366.999999999585, 9491.905080949102, 9982.500874956224, 10028.147607453448, 10265.435401053013, 10419.322744021614, 16688.415905443253, 16717.49847504577, 17108.144592770313, 17112.74330885038, 17113.315467381413, 7949.403794715419, 7965.004374453193, 8010.854118078226, 8017.6792928283385, 8045.637946292314, 4601.401817763669, 4673.859784206479, 4685.297205419214, 4832.758362081952, 4892.143874821963, 3074.9538756918664, 3137.9215519611735, 3148.2286839723956, 3158.3999040182293, 3159.0680749178587, 523.1159340714181, 531.5189753273216, 550.1939658400463, 557.6737608498993, 585.5871610514577, 526.8314139475408, 592.4815786187087, 617.8970537590379, 639.5555089212952, 646.9805905822833, 8422.24199822019, 8425.283850872622, 8549.280616571867, 8568.614412351459, 8635.481871087755, 647.7247169952751, 648.3225029843825, 662.9303923088103, 663.1346093348227, 688.6040526697169, 3933.586973367755, 4074.409210664496, 4195.454590903224, 4207.5161356444405, 4253.425787518645, 55612.47282365918, 60486.0, 61088.38911610916, 61093.38906610877, 61139.38860611426, 51125.23312150321, 52413.0, 52577.737111314964, 52927.735361323714, 54024.0, 48125.27812082822, 48261.5173848264, 48474.515254847705, 48968.5103148964, 49981.50018499841, 8198.631061602124, 8228.465149765247, 8273.33813294947, 8308.626111824862, 8491.490510569385, 30031.249218769706, 30200.84899575488, 30905.69094309118, 31009.224769380948, 31714.682853171173, 39038.80480597693, 41947.37078943757, 43916.560834391246, 44146.77926610346, 44590.55409445864, 9967.850482242917, 10187.28688991793, 10280.17758579297, 10312.226583006155, 11037.448127593749, 11643.708907277385, 11973.341466219441, 12350.505979761068, 12579.245245285312, 12593.685157871127, 11577.6526704199, 11630.53477860876, 11729.882701172879, 11886.34625095628, 11961.521539138337, 11830.52677892909, 11900.0, 11959.701007474883, 12011.699707507381, 12247.510099595916, 11651.067914566645, 12287.815682764587, 12440.502379904703, 12571.811422828481, 12578.496860125859, 9978.101970822661, 10009.24930630191, 10249.897501025192, 10409.716534489375, 10493.52779124926, 9884.802303954024, 10549.261551691308, 10566.366018038942, 10613.836892061106, 10706.304090234109], "value": 6315.867952117088, "unit": "score"}], "name": "dromaeo_css", "value": 6930.010074128052, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 111.0, 106.0, 111.0, 112.0, 111.0, 101.0, 110.0, 111.0, 111.0], "value": 109.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 127.0, 161.0, 184.0, 126.0, 157.0, 151.0, 129.0, 184.0, 125.0], "value": 150.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 164.0, 161.0, 161.0, 164.0, 161.0, 163.0, 161.0, 162.0], "value": 162.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 76.0, 96.0, 110.0, 77.0, 73.0, 73.0, 110.0, 106.0, 76.0], "value": 87.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 156.0, 91.0, 92.0, 92.0, 154.0, 92.0, 92.0, 141.0], "value": 109.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 116.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 146.0, 145.0, 145.0, 145.0, 145.0, 146.0, 145.0, 145.0], "value": 145.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 58.0, 59.0, 59.0, 57.0, 57.0, 79.0, 57.0, 55.0], "value": 59.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 62.0, 62.0, 62.0, 61.0, 61.0, 63.0, 62.0, 62.0], "value": 61.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 76.0, 76.0, 76.0, 76.0, 76.0, 77.0, 85.0, 76.0], "value": 76.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 125.0, 124.0, 121.0, 124.0, 121.0, 123.0, 127.0, 161.0, 131.0], "value": 129.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 161.0, 165.0, 168.0, 168.0, 162.0, 164.0, 164.0, 164.0, 165.0], "value": 164.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 69.0, 69.0, 67.0, 67.0, 68.0, 70.0, 68.0, 68.0], "value": 68.1, "unit": "score"}], "name": "kraken", "value": 1535.6, "alertThreshold": 2.0}]} 19:19:58 INFO - Return code: 0 19:19:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 19:19:58 ERROR - # TBPL SUCCESS # 19:19:58 INFO - Running post-action listener: _resource_record_post_action 19:19:58 INFO - Running post-run listener: _resource_record_post_run 19:19:59 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 12288; Write bytes: 535629824; Read time: 128; Write time: 381620 19:19:59 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 244142080; Read time: 0; Write time: 203632 19:19:59 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 12288; Write bytes: 291487744; Read time: 128; Write time: 177988 19:19:59 INFO - Running post-run listener: _upload_blobber_files 19:19:59 INFO - Blob upload gear active. 19:19:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:19:59 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 19:19:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:19:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:20:00 INFO - (blobuploader) - INFO - Open directory for files ... 19:20:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:20:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:20:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:20:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:20:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:20:00 INFO - (blobuploader) - INFO - Done attempting. 19:20:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:20:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:20:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:20:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:20:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:20:01 INFO - (blobuploader) - INFO - Done attempting. 19:20:01 INFO - (blobuploader) - INFO - Iteration through files over. 19:20:01 INFO - Return code: 0 19:20:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:20:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:20:01 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7c97b6bed43226feb6d5f000d0d12edff5001f7ad27b856c4253f837e86ad6a4e7d26c24997852678122daf67bb74caba66f6c53c17a623033dba21778e9e805", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/785fb95aa3a86672cb15fc7d2545c7216663137ed108e2a83ac182a52595ee5ef6dc0d62633360b3514303ca07000cb768fca604797621260e63e1c7dcc1d331"} 19:20:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:20:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:20:01 INFO - Contents: 19:20:01 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7c97b6bed43226feb6d5f000d0d12edff5001f7ad27b856c4253f837e86ad6a4e7d26c24997852678122daf67bb74caba66f6c53c17a623033dba21778e9e805", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/785fb95aa3a86672cb15fc7d2545c7216663137ed108e2a83ac182a52595ee5ef6dc0d62633360b3514303ca07000cb768fca604797621260e63e1c7dcc1d331"} 19:20:01 INFO - Running post-run listener: copy_logs_to_upload_dir 19:20:01 INFO - Copying logs to upload dir... 19:20:01 INFO - mkdir: /builds/slave/test/build/upload/logs 19:20:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1235.698784 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2016-04-18 19:20:01.528017) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-18 19:20:01.531446) ========= 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=aa08bb06ff6271ef470a76a4000001be-1461025569.946708-128098684 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7c97b6bed43226feb6d5f000d0d12edff5001f7ad27b856c4253f837e86ad6a4e7d26c24997852678122daf67bb74caba66f6c53c17a623033dba21778e9e805", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/785fb95aa3a86672cb15fc7d2545c7216663137ed108e2a83ac182a52595ee5ef6dc0d62633360b3514303ca07000cb768fca604797621260e63e1c7dcc1d331"} build_url:https://queue.taskcluster.net/v1/task/SvZ111VOSHi-8TKXNFGGaA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011483 build_url: 'https://queue.taskcluster.net/v1/task/SvZ111VOSHi-8TKXNFGGaA/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7c97b6bed43226feb6d5f000d0d12edff5001f7ad27b856c4253f837e86ad6a4e7d26c24997852678122daf67bb74caba66f6c53c17a623033dba21778e9e805", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/785fb95aa3a86672cb15fc7d2545c7216663137ed108e2a83ac182a52595ee5ef6dc0d62633360b3514303ca07000cb768fca604797621260e63e1c7dcc1d331"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-18 19:20:01.559411) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 19:20:01.559769) ========= 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=aa08bb06ff6271ef470a76a4000001be-1461025569.946708-128098684 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004588 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 19:20:01.642058) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-18 19:20:01.642412) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-18 19:20:01.642745) ========= ========= Total master_lag: 0.18 =========